builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0209 starttime: 1481922141.16 results: success (0) buildid: 20161216101750 builduid: 749e515c776049d99af80169d652d63c revision: 75b7b263b98c350a33810febb078f7370c8623b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 13:02:21.162916) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 13:02:21.163494) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 13:02:21.163800) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 13:02:21.190788) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 13:02:21.191196) ========= 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.EEnY4XRbJS/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.ar22sCV78r/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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-12-16 13:02:21-- 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% 30.6M=0s 2016-12-16 13:02:21 (30.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101341 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 13:02:21.315987) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 13:02:21.316302) ========= 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.EEnY4XRbJS/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.ar22sCV78r/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.025369 ========= master_lag: 0.11 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 13:02:21.449305) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-16 13:02:21.449688) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 75b7b263b98c350a33810febb078f7370c8623b0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 75b7b263b98c350a33810febb078f7370c8623b0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EEnY4XRbJS/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.ar22sCV78r/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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-12-16 13:02:21,548 truncating revision to first 12 chars 2016-12-16 13:02:21,548 Setting DEBUG logging. 2016-12-16 13:02:21,548 attempt 1/10 2016-12-16 13:02:21,548 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/75b7b263b98c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-16 13:02:22,631 unpacking tar archive at: mozilla-aurora-75b7b263b98c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.335371 ========= master_lag: 0.31 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-16 13:02:23.094043) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 13:02:23.094433) ========= script_repo_revision: 75b7b263b98c350a33810febb078f7370c8623b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 13:02:23.094853) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 13:02:23.095132) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 13:02:23.837918) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-16 13:02:23.838236) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EEnY4XRbJS/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.ar22sCV78r/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 13:02:24 INFO - MultiFileLogger online at 20161216 13:02:24 in /builds/slave/test 13:02:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:02:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:02:24 INFO - {'append_to_log': False, 13:02:24 INFO - 'base_work_dir': '/builds/slave/test', 13:02:24 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:02:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:02:24 INFO - 'branch': 'Mozilla-Aurora', 13:02:24 INFO - 'buildbot_json_path': 'buildprops.json', 13:02:24 INFO - 'config_files': ('talos/mac_config.py',), 13:02:24 INFO - 'default_actions': ('clobber', 13:02:24 INFO - 'read-buildbot-config', 13:02:24 INFO - 'download-and-extract', 13:02:24 INFO - 'populate-webroot', 13:02:24 INFO - 'create-virtualenv', 13:02:24 INFO - 'install', 13:02:24 INFO - 'run-tests'), 13:02:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:02:24 INFO - 'download_minidump_stackwalk': True, 13:02:24 INFO - 'download_symbols': 'ondemand', 13:02:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:02:24 INFO - 'tooltool.py': '/tools/tooltool.py', 13:02:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:02:24 INFO - '/tools/misc-python/virtualenv.py')}, 13:02:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:02:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:02:24 INFO - 'installer_path': 'installer.exe', 13:02:24 INFO - 'log_level': 'info', 13:02:24 INFO - 'log_name': 'talos', 13:02:24 INFO - 'log_to_console': True, 13:02:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:02:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:02:24 INFO - 'opt_config_files': (), 13:02:24 INFO - 'pip_index': False, 13:02:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:02:24 INFO - 'cmd': ('bash', 13:02:24 INFO - '-c', 13:02:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:02:24 INFO - 'enabled': True, 13:02:24 INFO - 'halt_on_failure': False, 13:02:24 INFO - 'name': 'check_screen_resolution'},), 13:02:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:02:24 INFO - 'cmd': ('bash', 13:02:24 INFO - '-c', 13:02:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:02:24 INFO - 'enabled': True, 13:02:24 INFO - 'halt_on_failure': False, 13:02:24 INFO - 'name': 'check_screen_resolution'},), 13:02:24 INFO - 'run_cmd_checks_enabled': True, 13:02:24 INFO - 'sps_profile': False, 13:02:24 INFO - 'sps_profile_interval': 0, 13:02:24 INFO - 'suite': 'dromaeojs', 13:02:24 INFO - 'system_bits': '32', 13:02:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:02:24 INFO - 'title': 't-yosemite-r7-0209', 13:02:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:02:24 INFO - 'use_talos_json': True, 13:02:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:02:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:02:24 INFO - 'work_dir': 'build'} 13:02:24 INFO - [mozharness: 2016-12-16 21:02:24.046913Z] Running clobber step. 13:02:24 INFO - Running pre-action listener: _resource_record_pre_action 13:02:24 INFO - Running main action method: clobber 13:02:24 INFO - rmtree: /builds/slave/test/build 13:02:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:02:26 INFO - Running post-action listener: _resource_record_post_action 13:02:26 INFO - [mozharness: 2016-12-16 21:02:26.472474Z] Finished clobber step (success) 13:02:26 INFO - [mozharness: 2016-12-16 21:02:26.472659Z] Running read-buildbot-config step. 13:02:26 INFO - Running pre-action listener: _resource_record_pre_action 13:02:26 INFO - Running main action method: read_buildbot_config 13:02:26 INFO - Using buildbot properties: 13:02:26 INFO - { 13:02:26 INFO - "project": "", 13:02:26 INFO - "product": "firefox", 13:02:26 INFO - "script_repo_revision": "production", 13:02:26 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 13:02:26 INFO - "repository": "", 13:02:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 13:02:26 INFO - "buildid": "20161216101750", 13:02:26 INFO - "stage_platform": "macosx64", 13:02:26 INFO - "basedir": "/builds/slave/test", 13:02:26 INFO - "nightly_build": "True", 13:02:26 INFO - "buildnumber": 65, 13:02:26 INFO - "slavename": "t-yosemite-r7-0209", 13:02:26 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 13:02:26 INFO - "platform": "yosemite_r7", 13:02:26 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:02:26 INFO - "branch": "mozilla-aurora", 13:02:26 INFO - "repo_path": "releases/mozilla-aurora", 13:02:26 INFO - "revision": "75b7b263b98c350a33810febb078f7370c8623b0", 13:02:26 INFO - "pgo_build": "False", 13:02:26 INFO - "builduid": "749e515c776049d99af80169d652d63c", 13:02:26 INFO - "slavebuilddir": "test" 13:02:26 INFO - } 13:02:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 13:02:26 INFO - Running post-action listener: _resource_record_post_action 13:02:26 INFO - [mozharness: 2016-12-16 21:02:26.476907Z] Finished read-buildbot-config step (success) 13:02:26 INFO - [mozharness: 2016-12-16 21:02:26.477044Z] Running download-and-extract step. 13:02:26 INFO - Running pre-action listener: _resource_record_pre_action 13:02:26 INFO - Running main action method: download_and_extract 13:02:26 INFO - mkdir: /builds/slave/test/build/tests 13:02:26 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')]} 13:02:26 INFO - trying https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 13:02:26 INFO - Downloading https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 13:02:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 13:02:27 INFO - Downloaded 1199 bytes. 13:02:27 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 13:02:27 INFO - Using the following test package requirements: 13:02:27 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 13:02:27 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:02:27 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 13:02:27 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:02:27 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 13:02:27 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:02:27 INFO - u'jsshell-mac.zip'], 13:02:27 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:02:27 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 13:02:27 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 13:02:27 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:02:27 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 13:02:27 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:02:27 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 13:02:27 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:02:27 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 13:02:27 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 13:02:27 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 13:02:27 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:02:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 13:02:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:02:27 INFO - Fetch https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 13:02:28 INFO - Http code: 200 13:02:28 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 13:02:28 INFO - x-amz-cf-id: bmC2Ly3SEMwh3x6OH6u6FtgtYBYW6fJxEZH_axqU3ww-ZxqWSJploA== 13:02:28 INFO - x-amz-version-id: .IFwhOrhwTi6Qoj5p7xXfA4may4kYiYv 13:02:28 INFO - Expected file size: 16280557 13:02:28 INFO - Obtained file size: 16280557 13:02:35 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:02:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 13:02:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:02:35 INFO - Fetch https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 13:02:35 INFO - Http code: 200 13:02:35 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 13:02:35 INFO - x-amz-cf-id: CYWURF5O5Go_Hl_WGgWXSadFojO-3cDRz4QSKO4HyEmG4avDWSPGOg== 13:02:35 INFO - x-amz-version-id: .IFwhOrhwTi6Qoj5p7xXfA4may4kYiYv 13:02:36 INFO - Expected file size: 16280557 13:02:36 INFO - Obtained file size: 16280557 13:02:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 13:02:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:02:42 INFO - Fetch https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 13:02:43 INFO - Http code: 200 13:02:43 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 13:02:43 INFO - x-amz-cf-id: NvtYYccbxyHicb7DC_70arqiPIBBhSDMBsn4T07gU01xFn-SSN7kIw== 13:02:43 INFO - x-amz-version-id: oeeCVlMlWQzqm7hc0ts6wjUch15UfYF6 13:02:43 INFO - Expected file size: 14409038 13:02:43 INFO - Obtained file size: 14409038 13:02:43 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')]} 13:02:43 INFO - trying https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 13:02:43 INFO - Downloading https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 13:02:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 13:02:46 INFO - Downloaded 109826481 bytes. 13:02:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 13:02:46 INFO - mkdir: /builds/slave/test/properties 13:02:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:02:46 INFO - Writing to file /builds/slave/test/properties/build_url 13:02:46 INFO - Contents: 13:02:46 INFO - build_url:https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 13:02:47 INFO - Running post-action listener: _resource_record_post_action 13:02:47 INFO - Running post-action listener: set_extra_try_arguments 13:02:47 INFO - [mozharness: 2016-12-16 21:02:47.589925Z] Finished download-and-extract step (success) 13:02:47 INFO - [mozharness: 2016-12-16 21:02:47.590033Z] Running populate-webroot step. 13:02:47 INFO - Running pre-action listener: _resource_record_pre_action 13:02:47 INFO - Running main action method: populate_webroot 13:02:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:02:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:02:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:02:47 INFO - '--tppagecycles', 13:02:47 INFO - '7'], 13:02:47 INFO - 'tests': ['tsvgm']}}, 13:02:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:02:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:02:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:02:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:02:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:02:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:02:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:47 INFO - 'talos_options': ['--disable-e10s'], 13:02:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:02:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:02:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:47 INFO - 'talos_options': ['--disable-e10s'], 13:02:47 INFO - 'tests': ['damp', 'tps']}, 13:02:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:47 INFO - 'tests': ['damp', 'tps']}, 13:02:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:02:47 INFO - 'tests': ['dromaeo_dom']}, 13:02:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:02:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:02:47 INFO - 'tests': ['basic_compositor_video']}, 13:02:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:02:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:02:47 INFO - 'tests': ['a11yr', 13:02:47 INFO - 'ts_paint', 13:02:47 INFO - 'tpaint', 13:02:47 INFO - 'sessionrestore', 13:02:47 INFO - 'sessionrestore_no_auto_restore', 13:02:47 INFO - 'tabpaint']}, 13:02:47 INFO - 'other-e10s': {'tests': ['a11yr', 13:02:47 INFO - 'ts_paint', 13:02:47 INFO - 'tpaint', 13:02:47 INFO - 'sessionrestore', 13:02:47 INFO - 'sessionrestore_no_auto_restore', 13:02:47 INFO - 'tabpaint']}, 13:02:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:02:47 INFO - 'ts_paint', 13:02:47 INFO - 'tpaint', 13:02:47 INFO - 'sessionrestore', 13:02:47 INFO - 'sessionrestore_no_auto_restore', 13:02:47 INFO - 'tabpaint']}, 13:02:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:02:47 INFO - 'ts_paint', 13:02:47 INFO - 'tpaint', 13:02:47 INFO - 'sessionrestore', 13:02:47 INFO - 'sessionrestore_no_auto_restore', 13:02:47 INFO - 'tabpaint']}, 13:02:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:02:47 INFO - 'tests': ['a11yr', 13:02:47 INFO - 'ts_paint', 13:02:47 INFO - 'tpaint', 13:02:47 INFO - 'sessionrestore', 13:02:47 INFO - 'sessionrestore_no_auto_restore', 13:02:47 INFO - 'tabpaint']}, 13:02:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:02:47 INFO - 'tests': ['a11yr', 13:02:47 INFO - 'ts_paint', 13:02:47 INFO - 'tpaint', 13:02:47 INFO - 'sessionrestore', 13:02:47 INFO - 'sessionrestore_no_auto_restore', 13:02:47 INFO - 'tabpaint']}, 13:02:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:02:47 INFO - 'tests': ['tsvgx', 13:02:47 INFO - 'tsvgr_opacity', 13:02:47 INFO - 'tart', 13:02:47 INFO - 'tscrollx', 13:02:47 INFO - 'cart']}, 13:02:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:02:47 INFO - 'tsvgr_opacity', 13:02:47 INFO - 'tart', 13:02:47 INFO - 'tscrollx', 13:02:47 INFO - 'cart']}, 13:02:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:47 INFO - 'talos_options': ['--disable-e10s'], 13:02:47 INFO - 'tests': ['tp5o']}, 13:02:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:47 INFO - 'tests': ['tp5o']}, 13:02:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:47 INFO - 'talos_options': ['--disable-e10s', 13:02:47 INFO - '--xperf_path', 13:02:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:02:47 INFO - 'tests': ['tp5n']}, 13:02:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:47 INFO - 'talos_options': ['--xperf_path', 13:02:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:02:47 INFO - 'tests': ['tp5n']}}, 13:02:47 INFO - 'talos.zip': {'path': '', 13:02:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:02:47 INFO - Running post-action listener: _resource_record_post_action 13:02:47 INFO - [mozharness: 2016-12-16 21:02:47.608611Z] Finished populate-webroot step (success) 13:02:47 INFO - [mozharness: 2016-12-16 21:02:47.608722Z] Running create-virtualenv step. 13:02:47 INFO - Running pre-action listener: _resource_record_pre_action 13:02:47 INFO - Running main action method: create_virtualenv 13:02:47 INFO - Creating virtualenv /builds/slave/test/build/venv 13:02:47 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 13:02:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:02:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:02:47 INFO - Using real prefix '/tools/python27' 13:02:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:02:48 INFO - Installing distribute.............................................................................................................................................................................................done. 13:02:51 INFO - Installing pip.................done. 13:02:51 INFO - Return code: 0 13:02:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:02:51 INFO - Reading from file tmpfile_stdout 13:02:51 INFO - Output received: 13:02:51 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) 13:02:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:02:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:02:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:02:51 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 0x108609490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10857f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff15157d190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841dd30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108415b20>, '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.EEnY4XRbJS/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.ar22sCV78r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 13:02:51 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 13:02:51 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 13:02:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EEnY4XRbJS/Render', 13:02:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:02:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:02:51 INFO - 'HOME': '/Users/cltbld', 13:02:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:02:51 INFO - 'LOGNAME': 'cltbld', 13:02:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:51 INFO - 'MOZ_NO_REMOTE': '1', 13:02:51 INFO - 'NO_EM_RESTART': '1', 13:02:51 INFO - 'PAGER': '/bin/cat', 13:02:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:02:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:02:51 INFO - 'PWD': '/builds/slave/test', 13:02:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:02:51 INFO - 'SHELL': '/bin/bash', 13:02:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ar22sCV78r/Listeners', 13:02:51 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 13:02:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:02:51 INFO - 'USER': 'cltbld', 13:02:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:02:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:02:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:02:51 INFO - 'XPC_FLAGS': '0x0', 13:02:51 INFO - 'XPC_SERVICE_NAME': '0', 13:02:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:02:51 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 13:02:51 INFO - Cleaning up... 13:02:51 INFO - Return code: 0 13:02:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:02:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:02:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:02:51 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 0x108609490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10857f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff15157d190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841dd30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108415b20>, '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.EEnY4XRbJS/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.ar22sCV78r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 13:02:51 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 13:02:51 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 13:02:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EEnY4XRbJS/Render', 13:02:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:02:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:02:51 INFO - 'HOME': '/Users/cltbld', 13:02:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:02:51 INFO - 'LOGNAME': 'cltbld', 13:02:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:51 INFO - 'MOZ_NO_REMOTE': '1', 13:02:51 INFO - 'NO_EM_RESTART': '1', 13:02:51 INFO - 'PAGER': '/bin/cat', 13:02:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:02:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:02:51 INFO - 'PWD': '/builds/slave/test', 13:02:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:02:51 INFO - 'SHELL': '/bin/bash', 13:02:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ar22sCV78r/Listeners', 13:02:51 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 13:02:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:02:51 INFO - 'USER': 'cltbld', 13:02:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:02:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:02:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:02:51 INFO - 'XPC_FLAGS': '0x0', 13:02:51 INFO - 'XPC_SERVICE_NAME': '0', 13:02:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:02:52 INFO - Downloading/unpacking psutil>=3.1.1 13:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:02:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:02:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:02:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:02:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:02:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:02:55 INFO - Installing collected packages: psutil 13:02:55 INFO - Running setup.py install for psutil 13:02:55 INFO - building 'psutil._psutil_osx' extension 13:02:55 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 13:02:56 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 13:02:56 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 13:02:56 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 13:02:56 INFO - building 'psutil._psutil_posix' extension 13:02:56 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 13:02:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:02:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:02:56 INFO - ^ 13:02:56 INFO - 1 warning generated. 13:02:56 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 13:02:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:02:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:02:56 INFO - Successfully installed psutil 13:02:56 INFO - Cleaning up... 13:02:56 INFO - Return code: 0 13:02:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:02:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:02:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:02:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108609490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10857f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff15157d190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841dd30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108415b20>, '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.EEnY4XRbJS/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.ar22sCV78r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 13:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:02:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EEnY4XRbJS/Render', 13:02:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:02:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:02:56 INFO - 'HOME': '/Users/cltbld', 13:02:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:02:56 INFO - 'LOGNAME': 'cltbld', 13:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:56 INFO - 'MOZ_NO_REMOTE': '1', 13:02:56 INFO - 'NO_EM_RESTART': '1', 13:02:56 INFO - 'PAGER': '/bin/cat', 13:02:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:02:56 INFO - 'PWD': '/builds/slave/test', 13:02:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:02:56 INFO - 'SHELL': '/bin/bash', 13:02:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ar22sCV78r/Listeners', 13:02:56 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 13:02:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:02:56 INFO - 'USER': 'cltbld', 13:02:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:02:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:02:56 INFO - 'XPC_FLAGS': '0x0', 13:02:56 INFO - 'XPC_SERVICE_NAME': '0', 13:02:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:02:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:02:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:02:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:02:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:02:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:02:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:02:59 INFO - Installing collected packages: mozsystemmonitor 13:02:59 INFO - Running setup.py install for mozsystemmonitor 13:02:59 INFO - Successfully installed mozsystemmonitor 13:02:59 INFO - Cleaning up... 13:02:59 INFO - Return code: 0 13:02:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:02:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:02:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:02:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108609490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10857f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff15157d190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841dd30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108415b20>, '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.EEnY4XRbJS/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.ar22sCV78r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 13:02:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:02:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EEnY4XRbJS/Render', 13:02:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:02:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:02:59 INFO - 'HOME': '/Users/cltbld', 13:02:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:02:59 INFO - 'LOGNAME': 'cltbld', 13:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:59 INFO - 'MOZ_NO_REMOTE': '1', 13:02:59 INFO - 'NO_EM_RESTART': '1', 13:02:59 INFO - 'PAGER': '/bin/cat', 13:02:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:02:59 INFO - 'PWD': '/builds/slave/test', 13:02:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:02:59 INFO - 'SHELL': '/bin/bash', 13:02:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ar22sCV78r/Listeners', 13:02:59 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 13:02:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:02:59 INFO - 'USER': 'cltbld', 13:02:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:02:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:02:59 INFO - 'XPC_FLAGS': '0x0', 13:02:59 INFO - 'XPC_SERVICE_NAME': '0', 13:02:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:00 INFO - Downloading/unpacking jsonschema==2.5.1 13:03:00 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 13:03:00 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 13:03:00 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 13:03:00 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 13:03:00 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 13:03:00 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 13:03:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:03:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:03:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:03:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:03:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:03:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:03:03 INFO - Downloading functools32-3.2.3-2.tar.gz 13:03:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:03:03 INFO - warning: no files found matching '*.txt' 13:03:03 INFO - no previously-included directories found matching 'build' 13:03:03 INFO - no previously-included directories found matching 'dist' 13:03:03 INFO - no previously-included directories found matching '.git*' 13:03:03 INFO - Installing collected packages: jsonschema, functools32 13:03:03 INFO - Running setup.py install for functools32 13:03:03 INFO - warning: no files found matching '*.txt' 13:03:03 INFO - no previously-included directories found matching 'build' 13:03:03 INFO - no previously-included directories found matching 'dist' 13:03:03 INFO - no previously-included directories found matching '.git*' 13:03:03 INFO - Successfully installed jsonschema functools32 13:03:03 INFO - Cleaning up... 13:03:03 INFO - Return code: 0 13:03:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:03:03 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')]} 13:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:03:03 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')]} 13:03:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:03:03 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 0x108609490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10857f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff15157d190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841dd30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108415b20>, '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.EEnY4XRbJS/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.ar22sCV78r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 13:03:03 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 13:03:03 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 13:03:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EEnY4XRbJS/Render', 13:03:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:03:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:03:03 INFO - 'HOME': '/Users/cltbld', 13:03:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:03:03 INFO - 'LOGNAME': 'cltbld', 13:03:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:03 INFO - 'MOZ_NO_REMOTE': '1', 13:03:03 INFO - 'NO_EM_RESTART': '1', 13:03:03 INFO - 'PAGER': '/bin/cat', 13:03:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:03:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:03 INFO - 'PWD': '/builds/slave/test', 13:03:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:03:03 INFO - 'SHELL': '/bin/bash', 13:03:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ar22sCV78r/Listeners', 13:03:03 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 13:03:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:03:03 INFO - 'USER': 'cltbld', 13:03:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:03:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:03:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:03 INFO - 'XPC_FLAGS': '0x0', 13:03:03 INFO - 'XPC_SERVICE_NAME': '0', 13:03:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:03:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:03:03 INFO - Cleaning up... 13:03:03 INFO - Return code: 0 13:03:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:03:03 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')]} 13:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:03:03 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')]} 13:03:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:03:03 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 0x108609490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10857f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff15157d190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841dd30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108415b20>, '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.EEnY4XRbJS/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.ar22sCV78r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 13:03:03 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 13:03:03 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 13:03:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EEnY4XRbJS/Render', 13:03:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:03:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:03:03 INFO - 'HOME': '/Users/cltbld', 13:03:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:03:03 INFO - 'LOGNAME': 'cltbld', 13:03:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:03 INFO - 'MOZ_NO_REMOTE': '1', 13:03:03 INFO - 'NO_EM_RESTART': '1', 13:03:03 INFO - 'PAGER': '/bin/cat', 13:03:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:03:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:03 INFO - 'PWD': '/builds/slave/test', 13:03:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:03:03 INFO - 'SHELL': '/bin/bash', 13:03:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ar22sCV78r/Listeners', 13:03:03 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 13:03:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:03:03 INFO - 'USER': 'cltbld', 13:03:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:03:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:03:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:03 INFO - 'XPC_FLAGS': '0x0', 13:03:03 INFO - 'XPC_SERVICE_NAME': '0', 13:03:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:03:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:03 INFO - Downloading/unpacking blobuploader==1.2.4 13:03:03 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 13:03:03 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 13:03:03 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 13:03:03 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 13:03:03 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 13:03:03 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 13:03:06 INFO - Downloading blobuploader-1.2.4.tar.gz 13:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:03:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:03:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:03:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:03:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:03:07 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 13:03:07 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 13:03:07 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 13:03:07 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 13:03:07 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 13:03:07 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 13:03:07 INFO - Downloading docopt-0.6.1.tar.gz 13:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:03:07 INFO - Installing collected packages: blobuploader, requests, docopt 13:03:07 INFO - Running setup.py install for blobuploader 13:03:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:03:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:03:07 INFO - Running setup.py install for requests 13:03:08 INFO - Running setup.py install for docopt 13:03:08 INFO - Successfully installed blobuploader requests docopt 13:03:08 INFO - Cleaning up... 13:03:08 INFO - Return code: 0 13:03:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:03:08 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 0x108609490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10857f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff15157d190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841dd30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108415b20>, '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.EEnY4XRbJS/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.ar22sCV78r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 13:03:08 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 13:03:08 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 13:03:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EEnY4XRbJS/Render', 13:03:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:03:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:03:08 INFO - 'HOME': '/Users/cltbld', 13:03:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:03:08 INFO - 'LOGNAME': 'cltbld', 13:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:08 INFO - 'MOZ_NO_REMOTE': '1', 13:03:08 INFO - 'NO_EM_RESTART': '1', 13:03:08 INFO - 'PAGER': '/bin/cat', 13:03:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:08 INFO - 'PWD': '/builds/slave/test', 13:03:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:03:08 INFO - 'SHELL': '/bin/bash', 13:03:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ar22sCV78r/Listeners', 13:03:08 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 13:03:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:03:08 INFO - 'USER': 'cltbld', 13:03:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:03:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:08 INFO - 'XPC_FLAGS': '0x0', 13:03:08 INFO - 'XPC_SERVICE_NAME': '0', 13:03:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:03:08 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-vXHUcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:03:09 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-28wtcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:03:09 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-jSa06x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:03:09 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-GC6i9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:03:09 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-wgdDBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:03:09 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-7fg4bS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:03:09 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-YOTMcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:03:09 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-52nHrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:03:09 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Bwtl0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:03:09 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-RlnEFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:03:10 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-fzsj5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:03:10 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Ep48Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:03:10 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Gw3owt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:03:10 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-7E9e4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:03:10 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-OJkdFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:03:10 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-WGJMbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:03:10 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-cQmta4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:03:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:03:10 INFO - Running setup.py install for manifestparser 13:03:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:03:10 INFO - Running setup.py install for mozcrash 13:03:11 INFO - Running setup.py install for mozdebug 13:03:11 INFO - Running setup.py install for mozdevice 13:03:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:03:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:03:11 INFO - Running setup.py install for mozfile 13:03:11 INFO - Running setup.py install for mozhttpd 13:03:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:03:11 INFO - Running setup.py install for mozinfo 13:03:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:03:11 INFO - Running setup.py install for mozInstall 13:03:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:03:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:03:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:03:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:03:11 INFO - Running setup.py install for mozleak 13:03:12 INFO - Running setup.py install for mozlog 13:03:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:03:12 INFO - Running setup.py install for moznetwork 13:03:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:03:12 INFO - Running setup.py install for mozprocess 13:03:12 INFO - Running setup.py install for mozprofile 13:03:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:03:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:03:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:03:12 INFO - Running setup.py install for mozrunner 13:03:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:03:12 INFO - Running setup.py install for mozscreenshot 13:03:13 INFO - Running setup.py install for moztest 13:03:13 INFO - Running setup.py install for mozversion 13:03:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:03:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:03:13 INFO - Cleaning up... 13:03:13 INFO - Return code: 0 13:03:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:03:13 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')]} 13:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:03:13 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')]} 13:03:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:03:13 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 0x108609490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10857f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff15157d190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841dd30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108415b20>, '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.EEnY4XRbJS/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.ar22sCV78r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 13:03:13 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 13:03:13 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 13:03:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EEnY4XRbJS/Render', 13:03:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:03:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:03:13 INFO - 'HOME': '/Users/cltbld', 13:03:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:03:13 INFO - 'LOGNAME': 'cltbld', 13:03:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:13 INFO - 'MOZ_NO_REMOTE': '1', 13:03:13 INFO - 'NO_EM_RESTART': '1', 13:03:13 INFO - 'PAGER': '/bin/cat', 13:03:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:03:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:13 INFO - 'PWD': '/builds/slave/test', 13:03:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:03:13 INFO - 'SHELL': '/bin/bash', 13:03:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ar22sCV78r/Listeners', 13:03:13 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 13:03:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:03:13 INFO - 'USER': 'cltbld', 13:03:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:03:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:03:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:13 INFO - 'XPC_FLAGS': '0x0', 13:03:13 INFO - 'XPC_SERVICE_NAME': '0', 13:03:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:03:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:03:13 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-LRmZjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:03:13 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)) 13:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:03:13 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-KVBIeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:03:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 13:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:03:13 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-QaC2Nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:03:13 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)) 13:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:03:13 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-MDykEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:03:14 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)) 13:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:03:14 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-a1P8cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:03:14 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)) 13:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:03:14 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-INRKyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:03:14 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)) 13:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:03:14 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-T719XC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:03:14 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)) 13:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:03:14 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-fNzawM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:03:14 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)) 13:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:03:14 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-WGvdxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:03:14 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)) 13:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:03:14 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-XAz0uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:03:14 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)) 13:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:03:14 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-VrhTVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:03:14 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)) 13:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:03:14 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-dMVqYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:03:14 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)) 13:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:03:14 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-_rZxjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:03:15 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)) 13:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:03:15 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-MFN4e3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 13:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:03:15 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-iLcpY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:03:15 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)) 13:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:03:15 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-X2CtpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:03:15 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)) 13:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:03:15 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-kTOC64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:03:15 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)) 13:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:03:15 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)) 13:03:15 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)) 13:03:15 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)) 13:03:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:03:15 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 13:03:15 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 13:03:15 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 13:03:15 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 13:03:15 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 13:03:15 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 13:03:18 INFO - Downloading blessings-1.6.tar.gz 13:03:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:03:18 INFO - Installing collected packages: blessings 13:03:18 INFO - Running setup.py install for blessings 13:03:18 INFO - Successfully installed blessings 13:03:18 INFO - Cleaning up... 13:03:18 INFO - Return code: 0 13:03:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:03:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:03:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:03:19 INFO - Reading from file tmpfile_stdout 13:03:19 INFO - Current package versions: 13:03:19 INFO - blessings == 1.6 13:03:19 INFO - blobuploader == 1.2.4 13:03:19 INFO - docopt == 0.6.1 13:03:19 INFO - functools32 == 3.2.3-2 13:03:19 INFO - jsonschema == 2.5.1 13:03:19 INFO - manifestparser == 1.1 13:03:19 INFO - mozInstall == 1.12 13:03:19 INFO - mozcrash == 1.0 13:03:19 INFO - mozdebug == 0.1 13:03:19 INFO - mozdevice == 0.48 13:03:19 INFO - mozfile == 1.2 13:03:19 INFO - mozhttpd == 0.7 13:03:19 INFO - mozinfo == 0.9 13:03:19 INFO - mozleak == 0.1 13:03:19 INFO - mozlog == 3.4 13:03:19 INFO - moznetwork == 0.27 13:03:19 INFO - mozprocess == 0.23 13:03:19 INFO - mozprofile == 0.28 13:03:19 INFO - mozrunner == 6.13 13:03:19 INFO - mozscreenshot == 0.1 13:03:19 INFO - mozsystemmonitor == 0.3 13:03:19 INFO - moztest == 0.7 13:03:19 INFO - mozversion == 1.4 13:03:19 INFO - psutil == 3.1.1 13:03:19 INFO - requests == 1.2.3 13:03:19 INFO - wsgiref == 0.1.2 13:03:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:03:19 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')]} 13:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:03:19 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')]} 13:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:03:19 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 0x108609490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10857f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff15157d190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841dd30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108415b20>, '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.EEnY4XRbJS/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.ar22sCV78r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 13:03:19 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 13:03:19 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 13:03:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EEnY4XRbJS/Render', 13:03:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:03:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:03:19 INFO - 'HOME': '/Users/cltbld', 13:03:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:03:19 INFO - 'LOGNAME': 'cltbld', 13:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:19 INFO - 'MOZ_NO_REMOTE': '1', 13:03:19 INFO - 'NO_EM_RESTART': '1', 13:03:19 INFO - 'PAGER': '/bin/cat', 13:03:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:19 INFO - 'PWD': '/builds/slave/test', 13:03:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:03:19 INFO - 'SHELL': '/bin/bash', 13:03:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ar22sCV78r/Listeners', 13:03:19 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 13:03:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:03:19 INFO - 'USER': 'cltbld', 13:03:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:03:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:19 INFO - 'XPC_FLAGS': '0x0', 13:03:19 INFO - 'XPC_SERVICE_NAME': '0', 13:03:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:03:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:19 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)) 13:03:19 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)) 13:03:19 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)) 13:03:19 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)) 13:03:19 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)) 13:03:19 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)) 13:03:19 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)) 13:03:19 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)) 13:03:19 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)) 13:03:19 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)) 13:03:19 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)) 13:03:19 INFO - Cleaning up... 13:03:19 INFO - Return code: 0 13:03:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:03:19 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')]} 13:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:03:19 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')]} 13:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:03:19 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 0x108609490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10857f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff15157d190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841dd30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108415b20>, '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.EEnY4XRbJS/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.ar22sCV78r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 13:03:19 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 13:03:19 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 13:03:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EEnY4XRbJS/Render', 13:03:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:03:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:03:19 INFO - 'HOME': '/Users/cltbld', 13:03:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:03:19 INFO - 'LOGNAME': 'cltbld', 13:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:19 INFO - 'MOZ_NO_REMOTE': '1', 13:03:19 INFO - 'NO_EM_RESTART': '1', 13:03:19 INFO - 'PAGER': '/bin/cat', 13:03:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:19 INFO - 'PWD': '/builds/slave/test', 13:03:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:03:19 INFO - 'SHELL': '/bin/bash', 13:03:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ar22sCV78r/Listeners', 13:03:19 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 13:03:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:03:19 INFO - 'USER': 'cltbld', 13:03:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:03:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:19 INFO - 'XPC_FLAGS': '0x0', 13:03:19 INFO - 'XPC_SERVICE_NAME': '0', 13:03:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:03:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:03:19 INFO - Cleaning up... 13:03:19 INFO - Return code: 0 13:03:19 INFO - Running post-action listener: _resource_record_post_action 13:03:19 INFO - Running post-action listener: _start_resource_monitoring 13:03:19 INFO - Starting resource monitoring. 13:03:19 INFO - [mozharness: 2016-12-16 21:03:19.585924Z] Finished create-virtualenv step (success) 13:03:19 INFO - [mozharness: 2016-12-16 21:03:19.586910Z] Running install step. 13:03:19 INFO - Running pre-action listener: _resource_record_pre_action 13:03:19 INFO - Running main action method: install 13:03:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:03:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:03:19 INFO - Reading from file tmpfile_stdout 13:03:19 INFO - Detecting whether we're running mozinstall >=1.0... 13:03:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:03:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:03:19 INFO - Reading from file tmpfile_stdout 13:03:19 INFO - Output received: 13:03:19 INFO - Usage: mozinstall [options] installer 13:03:19 INFO - Options: 13:03:19 INFO - -h, --help show this help message and exit 13:03:19 INFO - -d DEST, --destination=DEST 13:03:19 INFO - Directory to install application into. [default: 13:03:19 INFO - "/builds/slave/test"] 13:03:19 INFO - --app=APP Application being installed. [default: firefox] 13:03:19 INFO - mkdir: /builds/slave/test/build/application 13:03:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:03:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:03:50 INFO - Reading from file tmpfile_stdout 13:03:50 INFO - Output received: 13:03:50 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:03:50 INFO - Running post-action listener: _resource_record_post_action 13:03:50 INFO - [mozharness: 2016-12-16 21:03:50.984477Z] Finished install step (success) 13:03:50 INFO - [mozharness: 2016-12-16 21:03:50.984614Z] Running run-tests step. 13:03:50 INFO - Running pre-action listener: _resource_record_pre_action 13:03:50 INFO - Running main action method: run_tests 13:03:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:03:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:03:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:03:51 INFO - 2016-12-16 13:03:50.999 screenresolution[1617:7481] starting screenresolution argv=screenresolution get 13:03:51 INFO - 2016-12-16 13:03:51.001 screenresolution[1617:7481] Display 0: 1600x1200x32@60 13:03:51 INFO - 2016-12-16 13:03:51.007 screenresolution[1618:7484] starting screenresolution argv=screenresolution list 13:03:51 INFO - Available Modes on Display 0 13:03:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:03:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:03:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:03:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:03:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:03:51 INFO - Intel Iris: 13:03:51 INFO - Chipset Model: Intel Iris 13:03:51 INFO - Type: GPU 13:03:51 INFO - Bus: Built-In 13:03:51 INFO - VRAM (Dynamic, Max): 1536 MB 13:03:51 INFO - Vendor: Intel (0x8086) 13:03:51 INFO - Device ID: 0x0a2e 13:03:51 INFO - Revision ID: 0x0009 13:03:51 INFO - Displays: 13:03:51 INFO - Display: 13:03:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:03:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:03:51 INFO - Display Serial Number: 1600x1200 60 13:03:51 INFO - Main Display: Yes 13:03:51 INFO - Mirror: Off 13:03:51 INFO - Online: Yes 13:03:51 INFO - Rotation: Supported 13:03:51 INFO - Return code: 0 13:03:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:03:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:03:51 INFO - Python 2.7.3 13:03:51 INFO - Return code: 0 13:03:51 INFO - grabbing minidump binary from tooltool 13:03:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:51 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841dd30>, '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 0x108283db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108415b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:03:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:03:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:03:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 13:03:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:03:51 INFO - Return code: 0 13:03:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:03:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:03:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:03:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:03:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:03:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--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 13:03:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0209 --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 13:03:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EEnY4XRbJS/Render', 13:03:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:03:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:03:51 INFO - 'HOME': '/Users/cltbld', 13:03:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:03:51 INFO - 'LOGNAME': 'cltbld', 13:03:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:03:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:03:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:51 INFO - 'MOZ_NO_REMOTE': '1', 13:03:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:03:51 INFO - 'NO_EM_RESTART': '1', 13:03:51 INFO - 'PAGER': '/bin/cat', 13:03:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:03:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:03:51 INFO - 'PWD': '/builds/slave/test', 13:03:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:03:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:03:51 INFO - 'SHELL': '/bin/bash', 13:03:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ar22sCV78r/Listeners', 13:03:51 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 13:03:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:03:51 INFO - 'USER': 'cltbld', 13:03:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:03:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:03:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:03:51 INFO - 'XPC_FLAGS': '0x0', 13:03:51 INFO - 'XPC_SERVICE_NAME': '0', 13:03:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:03:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--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 13:03:51 INFO - mozversion application_buildid: 20161216101750 13:03:51 INFO - mozversion application_changeset: 75b7b263b98c350a33810febb078f7370c8623b0 13:03:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:03:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:03:51 INFO - mozversion application_name: Firefox 13:03:51 INFO - mozversion application_remotingname: firefox-dev 13:03:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:03:51 INFO - mozversion application_vendor: Mozilla 13:03:51 INFO - mozversion application_version: 52.0a2 13:03:51 INFO - mozversion platform_buildid: 20161216101750 13:03:51 INFO - mozversion platform_changeset: 75b7b263b98c350a33810febb078f7370c8623b0 13:03:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:03:51 INFO - mozversion platform_version: 52.0a2 13:03:51 INFO - using testdate: 1481922231 13:03:51 INFO - actual date: 1481922231 13:03:51 INFO - starting webserver on 'localhost:49236' 13:03:51 INFO - SUITE-START | Running 2 tests 13:03:51 INFO - TEST-START | dromaeo_css 13:03:51 INFO - operating with platform_type : mac_ 13:03:51 INFO - Initialising browser for dromaeo_css test... 13:03:51 INFO - TEST-INFO | started process 1628 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpL7uCCo/profile http://localhost:49236/getInfo.html) 13:03:54 INFO - PROCESS | 1628 | __metrics Screen width/height:1600/1200 13:03:54 INFO - PROCESS | 1628 | colorDepth:24 13:03:54 INFO - PROCESS | 1628 | Browser inner width/height: 1024/689 13:03:54 INFO - PROCESS | 1628 | __metrics 13:03:54 INFO - PROCESS | 1628 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:03:54 INFO - PROCESS | 1628 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:03:55 INFO - TEST-INFO | 1628: exit 0 13:03:55 INFO - Browser initialized. 13:03:55 INFO - Running cycle 1/1 for dromaeo_css test... 13:03:55 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpL7uCCo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:04:07 INFO - PROCESS | 1631 | RSS: Main: 222756864 13:04:07 INFO - PROCESS | 1631 | 13:04:59 INFO - PROCESS | 1631 | 1481922299341 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] 13:07:12 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 13:07:14 INFO - PROCESS | 1631 | RSS: Main: 345989120 13:07:14 INFO - PROCESS | 1631 | 13:10:17 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 13:10:18 INFO - PROCESS | 1631 | RSS: Main: 349245440 13:10:18 INFO - PROCESS | 1631 | 13:13:20 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 13:13:21 INFO - PROCESS | 1631 | RSS: Main: 322277376 13:13:21 INFO - PROCESS | 1631 | 13:16:23 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 13:16:23 INFO - PROCESS | 1631 | RSS: Main: 317341696 13:16:23 INFO - PROCESS | 1631 | 13:19:27 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 13:19:27 INFO - PROCESS | 1631 | RSS: Main: 306749440 13:19:27 INFO - PROCESS | 1631 | 13:22:31 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 13:22:31 INFO - PROCESS | 1631 | RSS: Main: 406724608 13:22:31 INFO - PROCESS | 1631 | 13:22:31 INFO - PROCESS | 1631 | __start_tp_report 13:22:31 INFO - PROCESS | 1631 | _x_x_mozilla_page_load 13:22:31 INFO - PROCESS | 1631 | _x_x_mozilla_page_load_details 13:22:31 INFO - PROCESS | 1631 | |i|pagename|runs| 13:22:31 INFO - PROCESS | 1631 | |0;dojo.html;2690.596410538419;3320.335932813438;3325.567676202094;3337.0989832745145;3384.915377115572;5633.4648208420185;5695.601307908448;5739.971300143498;5745.511631511326;5752.884942301157;5663.801766938158;5711.742971566279;5716.799912003081;5740.856478588027;5787.421257874211;5119.2577076324005;5164.122099243119;5177.378714554259;5189.896202075956;5224.895502089956;5139.537441630253;5142.794288228466;5170.560502357304;5279.788808447657;5331.706756128411;5150.922736158942;5193.402758682762;5203.869903252411;5216.634835561512;5219.269302297681;5212.973935130338;5230.633855630107;5257.132573125468;5276.657017293864;5314.362276526803;5071.873203169914;5101.489851014869;5189.117849965515;5190.948090519084;5196.506331898464;4798.47216806151;5596.692181929992;5599.272094627713;5630.239917611152;5682;6119.847003824895;6139.723712432951;6164.137020817089;6193.0710393440895;6202.906956395657;6262.459134040355;6292.087647291117;6312.842178945517;6313;6320.273168585625;5633.915491267634;5698.544116470673;5719.170720245625;5725.2843394575675;5728.1694154348215;6257.8748425031245;6341.841453963688;6349.904751428732;6357.332480089616;6375.840603984938;5091.9236211456855;6080.817575472743;6125.601835880653;6134.202553667995;6158.969205153946;6064.151018857364;6116.223169985209;6141.986572215624;6209.658468784261;6221.2845522764555;6061.909071363933;6097.969510152509;6103.267607886994;6125.479334256549;6125.785597504066;6084.087386892002;6108.926787320877;6145.525073982212;6145.617236121837;6155.569110162335;4908.730019848943;4909.7790599423115;4917.311576379309;4919.729414882144;4947.678400904002;4421.982943922916;4434;4450.376947227391;4450.799714012877;4452.48796388413;5607.074832652566;5685.545156387479;5716.799912003143;5746.913796293059;5762.596618236683;6171.197744293303;6192.659403732748;6249.468795152372;6255.687215639291;6300.684965751694;5767.279090113736;5797.681127538026;5815.621984571074;5833.212516310244;5868.061110222344;5385.192221166778;5390.272313237662;5399.811006614749;5413.188021796762;5414.214938833847;6192.876142477216;6227.159333489921;6234.438900498977;6240.687965601793;6286.968565157145;5043.52086551777;6001.7599296027665;6151.96924015377;6270;6288.654124023223;6236.781712640036;6257.998720204745;6298.842528936813;6303.905441418291;6315.463185629089;6040.214772079602;6109.48069414087;6117.401149625394;6190.597611155261;6195.597286176384;4937.308776771327;5198.870028249324;6306.369363063657;6318.62088274705;6322.33615470369;5408.729563521887;5846.473817356458;6060.878782424239;6131;6134.35589263121;5899.115132730125;5976.940230597638;6055.091736239599;6110.991686371699;6137.754489820357;5881.7647294107755;5903;5930.495907847881;5941.910871336847;5971.28344598651;6064.939350606437;6076.179715738319;6082.057281121433;6100.389961003864;6102.542309326729;5726.455986681259;5928.584999050197;5936.525077993664;5946.256717910262;5951.702414879325 13:22:31 INFO - PROCESS | 1631 | |1;ext.html;490.7717911171306;494.70070607282594;513.2404042017811;516.8526969813604;519.4260342321737;30367;40035;41169.38245926306;41307.58692413075;42799.78600107003;40931.59068409315;41796.791016044874;42165;42211;42221;8457.365697572675;9321.953390233057;9512.33413661044;9517.619295228182;9522.047795220491;8610.741677749678;11119.499622517005;11400.372979486125;11436.656900293005;11564.248323858965;11252.718682032933;11293.378864162467;11296.548138074468;11300.095992320594;11339.716507087307;11306;11363.772724545463;11383.715407114805;11385.48765305559;11390.71523211918;9551.856722149174;11142.387168705718;11168.497417616227;11204.719882002933;11208.103351731841;39220.80389598034;39453.816385508195;39553.40669889954;39565.604343956766;39614.405783913266;3211.843736254946;3299.8185099819502;3311.387393332217;3312.9171770705684;3322.401967645823;29436.55845162324;29820.85089574538;29841;29940.550891736642;30594.08217753471;3183.2519357950864;3187.8406079695924;3220.372027454647;3239.6112466503932;3240.319532898094;519088;529545;530322;581310;625685.8715706437;58488.12267815986;58845.117323240185;59332.813343733746;59383.70308148432;59412.10881836776;35022.29955400929;35516.46725299123;35912.46131308033;62752.68623656852;63035.68482157651;15889.364425422853;15980.600484987968;15988.920055399882;15990.76013859793;16002.919985399998;11156;11441.828372574419;11454.198206125659;12124.39378031095;12194.268343899394;14226.928865355607;16910.577235568962;16970;18666.160022799006;19139.23443062262;6423.756003218345;7363.963180184065;7534.924650753532;7568.4323675723435;7635.198304178092;857.3741168946704;958.3914214473859;975.9268054895912;980.5538479991586;991.6777047459605;28587.28531786722;29540.852295738383;29652.703472964993;30041.24896877552;30090;1016.1312078173056;1069.005824583145;1071.919606029551;1085.4626959655022;1094.2832444748703;14982.925085374503;15353.848461365442;15356.692866142615;15442.424000479114;15654.295556699975;680259;683868;684294;685823;687338;57881.710591446776;58494.8301033977;58507.074646267516;58512.41487585155;58570.70714646485;1213.4963989944074;1261.400834603556;1264.3488603369271;1342.8321459817523;1367.3915107777143;25934.092306769173;30562.235944101576;31444.842775785975;31531.684683152875;32174.517382239752;54567.45432545624;55625.721871390386;55708.72145639246;55754.16368754553;55817.1627425597;605806;610218;611324;611392;612947;26698.932042718072;26845.194644160707;27111.59332609973;27131.72868271292;27243.455130896873;37615.43576846404;38887;44255;47169;47455.52544474511;38095.80952095333;38356.616433835305;38467.03832404212;38974.610253897095;40132.19735605213;38112.42831357476;38378.42432363573;38844.80577597094;39802.402963954984;39903.80048099741;37487;37732.81133594424;38293;38594;38718.032049199;32806.83596582002;33858.49212261769;34353.828230858686;34828.82585587056;34857.825710871286;21267;24417.51164976655;25312.86092125822;25570.476474683728;26119 13:22:31 INFO - PROCESS | 1631 | |2;jquery.html;344.6915011065095;365.211143929113;365.5010910107703;373.1734208727668;395.8812356293111;13520.661983450418;13937.303134843269;15082.245887705612;19875;20215.797842021577;18317.633647327046;18967.956762378064;19925.103370348334;20160.899195504004;20161.294354697588;5108.540231379176;5345.893082138355;5371.435999220084;5382.946170538303;5389.218563308318;4566.40636717228;5282.498162674539;5845.649261044329;5892.734826932777;5930.525557955353;5764.913526297109;5882.499987501046;5883.558733095014;5890.234269544953;5890.499307558863;5578.246936663539;5758.222639943617;5788.073908174714;5791.82624521265;5806.709664516799;4926.876828079291;5125.718085505333;5153.819616313411;5155.458676838915;5160.200168973815;13222;13398.330083495786;22332.995015224355;24894.751052489424;25537.106201282866;2180.3349978256624;2274.6815445837597;2367.9936027188546;2525.9873700631565;2743.8079334446597;9881.209503239723;9883.110520053158;9953.800923981553;9964.750881227956;10018.398896066186;1843.7603111595406;1889.1461418705824;1890.1588792987127;1913.5885784556249;1944.3000519812856;69765.2558686037;103038.48480757549;123534;125486.37256813749;129134.3543282287;10303.3817970922;11589.24669896454;11691.649250522498;11802.11484138693;11859.34773587461;13171.209727416386;13730.862691373159;13732.588022359345;14832.11007339563;14902.478413255483;7115.786526404217;8019.278264956182;8236.711715089943;8460.746177614681;8510;8294.212049855254;8312.792180195544;8315.542645154454;8422.368322375723;8487.193716596914;4553.886152846206;4563.38394316763;4588.49526552079;4590.632749380042;4608.815647374135;1880.1351378365832;1937.3413039566333;1944.9611007780047;1963.3226536844766;1970.0937568718264;817.3093735793245;919.3748251189255;930.5533343994784;964.7877466957258;1007.1388962437157;16270.593235168979;20697.896510517352;20838.895805520875;21066;21171.8941405292;822.383212590557;887.8579427291602;912.4434095201922;916.4272329793879;923.8152369526122;10386.06661835622;10718.249722519504;10769.569217231223;10773.676789696321;10817.783644327226;590884;591926;602120;603266;626507;126353;132765.00852487222;133358.33320833335;137192;138941.3052934729;852.957352132391;953.2040745977187;963.3064193780607;991.6380521109743;995.2535598301274;17834.643307134043;17905.015224162795;18097;18178.636427271646;18297.725534116733;48731.02537949292;49049.26426103683;49571;49823.27032391176;50161.24758128704;63034.684826575576;75774;77051;78659;79745;6244.937550624436;7502.962485187539;7719.498232614862;7952.165022672768;8277.503349799032;11342;12161.33112678811;12299;12397.380130993595;12490.500379984698;10590.8411373831;10740.30188037775;10903.945480272549;11108.389038602954;11246.606368777053;10478.37129772216;10577.9471102644;10658.200634952565;10781.086898976519;11057.391843448688;10928.289661171999;10977.890221097687;11032.007119359296;11173.664790056311;11415.315081095161;7913.287804097658;7938.24586664266;8046;8114.472559283628;8156.592170391338;8147.470414423044;10312;10572.524236409534;10721.98141176587;10986.340819550853 13:22:31 INFO - PROCESS | 1631 | |3;mootools.html;1291.5996041227227;1428.4286285485807;1462.6124077119562;1571.300771156835;1777.8666600005004;7392.445566582508;7742.535447873124;8090.352771778251;8307.00315962084;8330.042045164808;8194.508329500219;8281.875771863426;8283.378746593999;8291.543965081933;8294;2251.1783199132365;2297.6094064009076;2302.26327575176;2303.6314189729646;2305.4351683837485;2278.4303924018996;2333.603287441139;2345.542619189259;2348.1898744933055;2355.0462062864567;2278.5215104828007;2281.0533628544135;2310.4570425949973;2315.2012555668352;2321.628539433691;2191.627423338028;2246.2587346175887;2271.034810205656;2273.0680421027464;2274.363178310076;2287.336672365029;2314.271004633535;2316.4440534271653;2317.9768202317928;2322.8954697038675;8206.753797386087;8242.670293188265;8304.792380190484;8334.249917507454;8337.624806883705;5035.748212589356;5037.798488060474;5199.4800519948085;5200.6619569727845;5204.3234379530795;3503.019154636706;3585.8386372613295;3592.3533763922483;3597.406427939387;3605.9903227096192;1055.2560444886337;1080.330195278932;1103.5475455063465;1106.115107913666;1110.3337997201616;10760.78478430435;10790.460476976199;10797.974192451704;10799.4060326682;10811.513481893333;4260.637845783143;4469;4486.75322857246;4497.460304763384;4499.820007199741;7431;8532.488050716976;8619.1811777881;8645.394822362374;8651.6971905983;5090.032893750175;5090.287359769635;5093.032323858455;5095.668781529189;5097;4078.938815917764;4118.6499147572795;4703.2004559225015;4737.715737055719;4747.192977193886;3255.283837555734;3578.874739384109;3607.603163651996;3708.6291370862696;3726.8136593170234;944.3908678902206;950.4582388038751;1142.4230763464564;1145.2613064573304;1149.545929357899;752.3379426104992;833.2667252817482;860.9139086091466;883.5228976352697;890.9866352004725;5399.676019438846;5423.511883930465;5455.8363249102595;5599.524040456526;5798.420157984167;849.3120572336427;894.1505569708803;922.4096578189964;922.898481167071;941.7692665297046;2998.3553535989868;3497.8251087445524;3661.4324779659196;3679.6504332088407;3690.538682664667;9799.559019844124;10104.09063184317;10212.846807297896;10216.438095904648;10233.181345492343;9996.550155243032;10068.546915388824;10074.143697785623;10137.594496220216;10157.593696252216;7290;7635.274648908345;7649.120351159577;7713.8457230855065;7745.264199901113;4753.287006948985;5386.192071189353;5578.60949733531;5596.664200148004;5597.468240517144;9187.816243675223;9189.632414703337;9389.201917836786;9422.670206542738;9573.9042609573;7985.161558036322;9603.231741460806;9642.373245739005;9643.903560964582;9663.873947731488;6541.70562324706;6742.898856517058;6765.357291057342;7005.194402643761;7163.892541611775;7596.696132154873;7822.569758663328;7850.097238817609;7945.364370850433;7954.801129971797;6980.092587963532;7030.367266945999;7044.964775176091;7052.541584796972;7207.954846547326;6854.897176542256;6941.3058694132205;6957.373836354753;6986.266442023718;6989.96505017492;6571.769988050395;6613.206415230203;6713.966430167818;6729.158855143107;6766.15423072116;4753.952460475351;4776.880577985578;4800.087983283165;4828.275758636232;4850.830220942391;4733.556265339104;4749.216379297515;4777.976110119427;4907.55831975124;5036.068327359304 13:22:31 INFO - PROCESS | 1631 | |4;prototype.html;299.54618752589823;302.196158219137;307.40670505923566;312.8185652321654;315.2166865339632;5040.613831196423;5349.619419358387;5368.32895888014;5387.090970875727;5456.372517160527;5132.97433512832;5211.817586384468;5263.631545791796;5274.340707411574;5325.227841962923;3529.4645930937377;3922.666573341269;4069.697696737045;4074.7962601869935;4081.8979525511804;3400.4729266963536;3751.4560388743616;3928.5678575356687;4106.589341065899;4144.23331683638;3666.578343490493;4024.953512086864;4066.857659981901;4081.693872959525;4101.487314085739;3375.8480868360984;3977.4630424892557;4028.879133625996;4033.495813023372;4048.75707457552;3100.953485697716;3505.94741078884;3524.8942531724088;3536.1003976738825;3557.94663080054;4257.76582287974;4326.987950602985;4338.891527711801;5098.745062746848;5216.243644671501;4311.348520659772;4337.002489427449;4567.977160114211;4571.977140114312;4643.512431194709;5505.256790333294;5516.806911758109;5523.558115350762;5535.335759708821;5541.838682913667;2899.8260104393594;3780.640839120279;3860.6718428933573;3903.6877049835944;3919.7256192066566;4925.630577706686;5020.196768517019;5136;5847.473727364558;5863.237779088691;3383.5939687237196;3864.613538646113;3873.32216862048;3892.4161375793856;3903.657805316844;3472.177107018835;3744.5693745219105;3783.5270591176104;3932;3947.822347994347;3868.955382046832;4521.773911304422;4553.795079221443;4558;4609.7695115244105;5640.830775076754;5641.182028605829;5654.776777237018;5668.8866222675315;5682.687452190086;1031.4224034540682;1088.8475613414128;1194.9103817213743;1230.1143176912608;1248.6628610275175;765.6516285090271;786.0214033528293;811.5739236900674;855.2687452228255;881.6341218394423;4869.756512174377;4904.43598986114;4908.061315780247;4918.213085906238;4992.475790041994;5038.28698242592;5164.295782391801;5447.346318441812;5488.835334939959;5523.392426833045;4647.883802904955;4816.686915350491;5085.771140298695;5114.079465696136;5364.8122315718765;2656.216416157238;2783.512885245074;2875.4552221284316;2880.985595072011;2884.437534680759;5373.246431095029;5432.626735477899;5463.360991702473;5722.570807189478;5749.568782341257;4434.379186913835;5554.056080371918;5595.132754423071;5596.873188188462;5637.238972738628;4586.449626044896;4659.5806377425515;5485.807996720096;5597.916031259616;5648.604597678123;5035.564864013739;5438.428964958623;5451.454854514517;5459.945400545944;5483.040467918178;4848.903021939612;4860.854174374775;5391.460853914734;5398.973005134949;5479.863003424946;4706.929396058993;5278.656887302313;5467.736986409798;5644.125160600113;5684.459976302244;3285.8849940251976;3572.8392222349535;3641.816409666816;3648.543932008499;3658.48781170631;4719.693219940693;5394.32570928634;5442.666546696085;5464.3442786865835;5468.511827118572;4796.904061918673;5334.719147724044;5337.359516858002;5339.919901201562;5358.892822143457;4242.151569686013;4736.573708366263;5385.569154467555;5391.6866506795195;5436.266104075899;5183.289514460294;5284.445133260952;5379.757910893941;5382.4886635769535;5449.455054494519;4834.830780922651;5529.640573362719;5590.993621148233;5594.216809646734;5599.832005039855;4958.578520825626;5138.794448222029;5474.72626368672;5478.589105817;5544.67515338037 13:22:31 INFO - PROCESS | 1631 | |5;yui.html;691.4261163234514;715.2132654080513;764.4572353628927;773.848700612298;804.9736585853038;13881.375338109785;14279.643008924782;14482.782758258634;15501.379944802195;16603.169841507937;15087.09477431355;15171.696566068673;15202.847971520281;15234.54296371108;15284.388624455009;652.5366989437505;674.2044387622603;800.6477150053972;824.389951435936;827.7103013945132;673.1451057157407;675.4596322941652;676.918769747631;678.5555461172914;787.6494959742888;854.5129276312505;855.5337341149088;869.2915274051628;869.3002133282705;869.8216865542548;859.1923591823697;860.4536119564059;860.7417774667638;861.328164032056;861.7716305179133;505.1866494943175;522.7072839210055;639.6002498438476;640.0271587187495;640.314863096487;13755.31223438824;14239.359228834624;14311.212883291415;14556.0538564994;14676.853231467656;13018.609441716764;13372.799408008888;13373.86626133736;13424.463021479129;13462.865371346259;10459.372437653688;10579.523921423555;10660.573577056908;10684.572617095308;10694.197935154894;3538.451540011277;3580.856765729368;3631.8547258109907;3641.4901913732097;3747.1568896997956;86578.13421865764;87268.6909696355;90084.54957725169;91006.08993910042;92751.14497710076;9469.863616365943;9795;10161.644342447978;10315.484225788681;10380.117689996285;19933.700994485094;19933.700994485094;19998.1000854959;20137.39587812368;20249;7412;7511.248875112555;7734.92265077342;7762.728304509428;7775.4168437367425;4272.551382104896;4330.956690433055;4378.146261478981;4413.073254616566;4498;3373.527706121145;3469.2367679110557;3484.5470088888283;3526.241858000491;3526.365254254259;652.4780175859388;653.1541653558653;659.8284446043967;664.7374287156546;671.5500614588237;587.3128439725531;591.8609126855185;605.857623458487;674.7301079568211;675.9188897332351;7942.0911954372195;8686.131386861263;8902.287816974627;8977.281817454588;9121;547.6960287040731;566.8412844403574;597.967111808846;601.5458328961606;656.5141795071613;4046.6964977626794;4264;4429.53489883564;4435.512093669694;4463.330500424897;53522.19716704253;69792.65103674449;81595.5920220395;109527.45236273928;112874;64267.67866160639;64580.677096614214;82036.58981705054;99851;100713;87667;88655.55672221597;90070;91736;92779;8451.15456480253;8660.130718954098;8669.263112635497;8753.47479151253;8892.421992570462;46279.61161165171;52556;53979;55193;55741.721291394904;71345.64327178331;78563.60718196373;81101.18898810937;82175.589122054;84682;9911.65309214174;11130.721731956766;11314.03118040089;11317.886821132013;11761.88238117608;12048.337341446304;12217;13057.673558161123;13694.178349299074;14210.147391156564;12245.877541224474;12338;12544.874551254736;12999.480020799063;13872.861271387561;13203.933980330037;13244.072914895865;13259.734805304033;13413.932930335286;13540.526081587097;10971.780564388828;11405.574303212099;11993.64019079429;12775;13371.264580448169;10889.891101088888;11022.889771102507;11046.613368532062;11151.498182581641;11642.5342986283;11068.391238481961;11177.832332514856;11274.478010327668;11364.772704546029;11627.476763545492 13:22:31 INFO - PROCESS | 1631 | __end_tp_report 13:22:31 INFO - PROCESS | 1631 | __start_cc_report 13:22:31 INFO - PROCESS | 1631 | _x_x_mozilla_cycle_collect,1389 13:22:31 INFO - PROCESS | 1631 | __end_cc_report 13:22:31 INFO - PROCESS | 1631 | __startTimestamp1481923351303__endTimestamp 13:22:31 INFO - PROCESS | 1631 | 13:22:31 INFO - PROCESS | 1631 | ------- Summary: start ------- 13:22:31 INFO - PROCESS | 1631 | Number of tests: 6 13:22:31 INFO - PROCESS | 1631 | 13:22:31 INFO - PROCESS | 1631 | [#0] dojo.html Cycles:165 Average:5687.91 Median:5881.76 stddev:653.94 (11.1%) stddev-sans-first:612.22 13:22:31 INFO - PROCESS | 1631 | Values: 2690.6 3320.3 3325.6 3337.1 3384.9 5633.5 5695.6 5740.0 5745.5 5752.9 5663.8 5711.7 5716.8 5740.9 5787.4 5119.3 5164.1 5177.4 5189.9 5224.9 5139.5 5142.8 5170.6 5279.8 5331.7 5150.9 5193.4 5203.9 5216.6 5219.3 5213.0 5230.6 5257.1 5276.7 5314.4 5071.9 5101.5 5189.1 5190.9 5196.5 4798.5 5596.7 5599.3 5630.2 5682.0 6119.8 6139.7 6164.1 6193.1 6202.9 6262.5 6292.1 6312.8 6313.0 6320.3 5633.9 5698.5 5719.2 5725.3 5728.2 6257.9 6341.8 6349.9 6357.3 6375.8 5091.9 6080.8 6125.6 6134.2 6159.0 6064.2 6116.2 6142.0 6209.7 6221.3 6061.9 6098.0 6103.3 6125.5 6125.8 6084.1 6108.9 6145.5 6145.6 6155.6 4908.7 4909.8 4917.3 4919.7 4947.7 4422.0 4434.0 4450.4 4450.8 4452.5 5607.1 5685.5 5716.8 5746.9 5762.6 6171.2 6192.7 6249.5 6255.7 6300.7 5767.3 5797.7 5815.6 5833.2 5868.1 5385.2 5390.3 5399.8 5413.2 5414.2 6192.9 6227.2 6234.4 6240.7 6287.0 5043.5 6001.8 6152.0 6270.0 6288.7 6236.8 6258.0 6298.8 6303.9 6315.5 6040.2 6109.5 6117.4 6190.6 6195.6 4937.3 5198.9 6306.4 6318.6 6322.3 5408.7 5846.5 6060.9 6131.0 6134.4 5899.1 5976.9 6055.1 6111.0 6137.8 5881.8 5903.0 5930.5 5941.9 5971.3 6064.9 6076.2 6082.1 6100.4 6102.5 5726.5 5928.6 5936.5 5946.3 5951.7 13:22:31 INFO - PROCESS | 1631 | 13:22:31 INFO - PROCESS | 1631 | [#1] ext.html Cycles:180 Average:73826.57 Median:35714.46 stddev:166043.66 (464.9%) stddev-sans-first:166418.16 13:22:31 INFO - PROCESS | 1631 | Values: 490.8 494.7 513.2 516.9 519.4 30367.0 40035.0 41169.4 41307.6 42799.8 40931.6 41796.8 42165.0 42211.0 42221.0 8457.4 9322.0 9512.3 9517.6 9522.0 8610.7 11119.5 11400.4 11436.7 11564.2 11252.7 11293.4 11296.5 11300.1 11339.7 11306.0 11363.8 11383.7 11385.5 11390.7 9551.9 11142.4 11168.5 11204.7 11208.1 39220.8 39453.8 39553.4 39565.6 39614.4 3211.8 3299.8 3311.4 3312.9 3322.4 29436.6 29820.9 29841.0 29940.6 30594.1 3183.3 3187.8 3220.4 3239.6 3240.3 519088.0 529545.0 530322.0 581310.0 625685.9 58488.1 58845.1 59332.8 59383.7 59412.1 35022.3 35516.5 35912.5 62752.7 63035.7 15889.4 15980.6 15988.9 15990.8 16002.9 11156.0 11441.8 11454.2 12124.4 12194.3 14226.9 16910.6 16970.0 18666.2 19139.2 6423.8 7364.0 7534.9 7568.4 7635.2 857.4 958.4 975.9 980.6 991.7 28587.3 29540.9 29652.7 30041.2 30090.0 1016.1 1069.0 1071.9 1085.5 1094.3 14982.9 15353.8 15356.7 15442.4 15654.3 680259.0 683868.0 684294.0 685823.0 687338.0 57881.7 58494.8 58507.1 58512.4 58570.7 1213.5 1261.4 1264.3 1342.8 1367.4 25934.1 30562.2 31444.8 31531.7 32174.5 54567.5 55625.7 55708.7 55754.2 55817.2 605806.0 610218.0 611324.0 611392.0 612947.0 26698.9 26845.2 27111.6 27131.7 27243.5 37615.4 38887.0 44255.0 47169.0 47455.5 38095.8 38356.6 38467.0 38974.6 40132.2 38112.4 38378.4 38844.8 39802.4 39903.8 37487.0 37732.8 38293.0 38594.0 38718.0 32806.8 33858.5 34353.8 34828.8 34857.8 21267.0 24417.5 25312.9 25570.5 26119.0 13:22:31 INFO - PROCESS | 1631 | 13:22:31 INFO - PROCESS | 1631 | [#2] jquery.html Cycles:180 Average:34440.28 Median:354.95 stddev:100701.23 (28370.4%) stddev-sans-first:100951.18 13:22:31 INFO - PROCESS | 1631 | Values: 344.7 365.2 365.5 373.2 395.9 13520.7 13937.3 15082.2 19875.0 20215.8 18317.6 18968.0 19925.1 20160.9 20161.3 5108.5 5345.9 5371.4 5382.9 5389.2 4566.4 5282.5 5845.6 5892.7 5930.5 5764.9 5882.5 5883.6 5890.2 5890.5 5578.2 5758.2 5788.1 5791.8 5806.7 4926.9 5125.7 5153.8 5155.5 5160.2 13222.0 13398.3 22333.0 24894.8 25537.1 2180.3 2274.7 2368.0 2526.0 2743.8 9881.2 9883.1 9953.8 9964.8 10018.4 1843.8 1889.1 1890.2 1913.6 1944.3 69765.3 103038.5 123534.0 125486.4 129134.4 10303.4 11589.2 11691.6 11802.1 11859.3 13171.2 13730.9 13732.6 14832.1 14902.5 7115.8 8019.3 8236.7 8460.7 8510.0 8294.2 8312.8 8315.5 8422.4 8487.2 4553.9 4563.4 4588.5 4590.6 4608.8 1880.1 1937.3 1945.0 1963.3 1970.1 817.3 919.4 930.6 964.8 1007.1 16270.6 20697.9 20838.9 21066.0 21171.9 822.4 887.9 912.4 916.4 923.8 10386.1 10718.2 10769.6 10773.7 10817.8 590884.0 591926.0 602120.0 603266.0 626507.0 126353.0 132765.0 133358.3 137192.0 138941.3 853.0 953.2 963.3 991.6 995.3 17834.6 17905.0 18097.0 18178.6 18297.7 48731.0 49049.3 49571.0 49823.3 50161.2 63034.7 75774.0 77051.0 78659.0 79745.0 6244.9 7503.0 7719.5 7952.2 8277.5 11342.0 12161.3 12299.0 12397.4 12490.5 10590.8 10740.3 10903.9 11108.4 11246.6 10478.4 10577.9 10658.2 10781.1 11057.4 10928.3 10977.9 11032.0 11173.7 11415.3 7913.3 7938.2 8046.0 8114.5 8156.6 8147.5 10312.0 10572.5 10722.0 10986.3 13:22:31 INFO - PROCESS | 1631 | 13:22:31 INFO - PROCESS | 1631 | [#3] mootools.html Cycles:180 Average:5355.49 Median:5091.66 stddev:2950.08 (57.9%) stddev-sans-first:2942.55 13:22:31 INFO - PROCESS | 1631 | Values: 1291.6 1428.4 1462.6 1571.3 1777.9 7392.4 7742.5 8090.4 8307.0 8330.0 8194.5 8281.9 8283.4 8291.5 8294.0 2251.2 2297.6 2302.3 2303.6 2305.4 2278.4 2333.6 2345.5 2348.2 2355.0 2278.5 2281.1 2310.5 2315.2 2321.6 2191.6 2246.3 2271.0 2273.1 2274.4 2287.3 2314.3 2316.4 2318.0 2322.9 8206.8 8242.7 8304.8 8334.2 8337.6 5035.7 5037.8 5199.5 5200.7 5204.3 3503.0 3585.8 3592.4 3597.4 3606.0 1055.3 1080.3 1103.5 1106.1 1110.3 10760.8 10790.5 10798.0 10799.4 10811.5 4260.6 4469.0 4486.8 4497.5 4499.8 7431.0 8532.5 8619.2 8645.4 8651.7 5090.0 5090.3 5093.0 5095.7 5097.0 4078.9 4118.6 4703.2 4737.7 4747.2 3255.3 3578.9 3607.6 3708.6 3726.8 944.4 950.5 1142.4 1145.3 1149.5 752.3 833.3 860.9 883.5 891.0 5399.7 5423.5 5455.8 5599.5 5798.4 849.3 894.2 922.4 922.9 941.8 2998.4 3497.8 3661.4 3679.7 3690.5 9799.6 10104.1 10212.8 10216.4 10233.2 9996.6 10068.5 10074.1 10137.6 10157.6 7290.0 7635.3 7649.1 7713.8 7745.3 4753.3 5386.2 5578.6 5596.7 5597.5 9187.8 9189.6 9389.2 9422.7 9573.9 7985.2 9603.2 9642.4 9643.9 9663.9 6541.7 6742.9 6765.4 7005.2 7163.9 7596.7 7822.6 7850.1 7945.4 7954.8 6980.1 7030.4 7045.0 7052.5 7208.0 6854.9 6941.3 6957.4 6986.3 6990.0 6571.8 6613.2 6714.0 6729.2 6766.2 4754.0 4776.9 4800.1 4828.3 4850.8 4733.6 4749.2 4778.0 4907.6 5036.1 13:22:31 INFO - PROCESS | 1631 | 13:22:31 INFO - PROCESS | 1631 | [#4] prototype.html Cycles:180 Average:4406.37 Median:4942.10 stddev:1354.20 (27.4%) stddev-sans-first:1322.45 13:22:31 INFO - PROCESS | 1631 | Values: 299.5 302.2 307.4 312.8 315.2 5040.6 5349.6 5368.3 5387.1 5456.4 5133.0 5211.8 5263.6 5274.3 5325.2 3529.5 3922.7 4069.7 4074.8 4081.9 3400.5 3751.5 3928.6 4106.6 4144.2 3666.6 4025.0 4066.9 4081.7 4101.5 3375.8 3977.5 4028.9 4033.5 4048.8 3101.0 3505.9 3524.9 3536.1 3557.9 4257.8 4327.0 4338.9 5098.7 5216.2 4311.3 4337.0 4568.0 4572.0 4643.5 5505.3 5516.8 5523.6 5535.3 5541.8 2899.8 3780.6 3860.7 3903.7 3919.7 4925.6 5020.2 5136.0 5847.5 5863.2 3383.6 3864.6 3873.3 3892.4 3903.7 3472.2 3744.6 3783.5 3932.0 3947.8 3869.0 4521.8 4553.8 4558.0 4609.8 5640.8 5641.2 5654.8 5668.9 5682.7 1031.4 1088.8 1194.9 1230.1 1248.7 765.7 786.0 811.6 855.3 881.6 4869.8 4904.4 4908.1 4918.2 4992.5 5038.3 5164.3 5447.3 5488.8 5523.4 4647.9 4816.7 5085.8 5114.1 5364.8 2656.2 2783.5 2875.5 2881.0 2884.4 5373.2 5432.6 5463.4 5722.6 5749.6 4434.4 5554.1 5595.1 5596.9 5637.2 4586.4 4659.6 5485.8 5597.9 5648.6 5035.6 5438.4 5451.5 5459.9 5483.0 4848.9 4860.9 5391.5 5399.0 5479.9 4706.9 5278.7 5467.7 5644.1 5684.5 3285.9 3572.8 3641.8 3648.5 3658.5 4719.7 5394.3 5442.7 5464.3 5468.5 4796.9 5334.7 5337.4 5339.9 5358.9 4242.2 4736.6 5385.6 5391.7 5436.3 5183.3 5284.4 5379.8 5382.5 5449.5 4834.8 5529.6 5591.0 5594.2 5599.8 4958.6 5138.8 5474.7 5478.6 5544.7 13:22:31 INFO - PROCESS | 1631 | 13:22:31 INFO - PROCESS | 1631 | [#5] yui.html Cycles:180 Average:19732.00 Median:23392.40 stddev:28417.63 (121.5%) stddev-sans-first:28461.38 13:22:31 INFO - PROCESS | 1631 | Values: 691.4 715.2 764.5 773.8 805.0 13881.4 14279.6 14482.8 15501.4 16603.2 15087.1 15171.7 15202.8 15234.5 15284.4 652.5 674.2 800.6 824.4 827.7 673.1 675.5 676.9 678.6 787.6 854.5 855.5 869.3 869.3 869.8 859.2 860.5 860.7 861.3 861.8 505.2 522.7 639.6 640.0 640.3 13755.3 14239.4 14311.2 14556.1 14676.9 13018.6 13372.8 13373.9 13424.5 13462.9 10459.4 10579.5 10660.6 10684.6 10694.2 3538.5 3580.9 3631.9 3641.5 3747.2 86578.1 87268.7 90084.5 91006.1 92751.1 9469.9 9795.0 10161.6 10315.5 10380.1 19933.7 19933.7 19998.1 20137.4 20249.0 7412.0 7511.2 7734.9 7762.7 7775.4 4272.6 4331.0 4378.1 4413.1 4498.0 3373.5 3469.2 3484.5 3526.2 3526.4 652.5 653.2 659.8 664.7 671.6 587.3 591.9 605.9 674.7 675.9 7942.1 8686.1 8902.3 8977.3 9121.0 547.7 566.8 598.0 601.5 656.5 4046.7 4264.0 4429.5 4435.5 4463.3 53522.2 69792.7 81595.6 109527.5 112874.0 64267.7 64580.7 82036.6 99851.0 100713.0 87667.0 88655.6 90070.0 91736.0 92779.0 8451.2 8660.1 8669.3 8753.5 8892.4 46279.6 52556.0 53979.0 55193.0 55741.7 71345.6 78563.6 81101.2 82175.6 84682.0 9911.7 11130.7 11314.0 11317.9 11761.9 12048.3 12217.0 13057.7 13694.2 14210.1 12245.9 12338.0 12544.9 12999.5 13872.9 13203.9 13244.1 13259.7 13413.9 13540.5 10971.8 11405.6 11993.6 12775.0 13371.3 10889.9 11022.9 11046.6 11151.5 11642.5 11068.4 11177.8 11274.5 11364.8 11627.5 13:22:31 INFO - PROCESS | 1631 | -------- Summary: end -------- 13:22:31 INFO - PROCESS | 1631 | 13:22:31 INFO - TEST-INFO | 1631: exit 0 13:22:31 INFO - TEST-OK | dromaeo_css | took 1120347ms 13:22:31 INFO - TEST-START | kraken 13:22:31 INFO - operating with platform_type : mac_ 13:22:31 INFO - Initialising browser for kraken test... 13:22:31 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpeqnQUA/profile http://localhost:49236/getInfo.html) 13:22:34 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 13:22:34 INFO - PROCESS | 1641 | colorDepth:24 13:22:34 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 13:22:34 INFO - PROCESS | 1641 | __metrics 13:22:34 INFO - PROCESS | 1641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:22:34 INFO - PROCESS | 1641 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:22:35 INFO - TEST-INFO | 1641: exit 0 13:22:35 INFO - Browser initialized. 13:22:35 INFO - Running cycle 1/1 for kraken test... 13:22:35 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpeqnQUA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:22:47 INFO - PROCESS | 1642 | RSS: Main: 222441472 13:22:47 INFO - PROCESS | 1642 | 13:22:50 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 13:22:51 INFO - PROCESS | 1642 | RSS: Main: 273018880 13:22:51 INFO - PROCESS | 1642 | 13:22:54 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 13:22:54 INFO - PROCESS | 1642 | RSS: Main: 283770880 13:22:54 INFO - PROCESS | 1642 | 13:22:57 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 13:22:58 INFO - PROCESS | 1642 | RSS: Main: 380981248 13:22:58 INFO - PROCESS | 1642 | 13:23:00 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 13:23:01 INFO - PROCESS | 1642 | RSS: Main: 283996160 13:23:01 INFO - PROCESS | 1642 | 13:23:03 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:23:03 INFO - PROCESS | 1642 | RSS: Main: 284979200 13:23:03 INFO - PROCESS | 1642 | 13:23:06 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 13:23:07 INFO - PROCESS | 1642 | RSS: Main: 326496256 13:23:07 INFO - PROCESS | 1642 | 13:23:09 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 13:23:10 INFO - PROCESS | 1642 | RSS: Main: 326443008 13:23:10 INFO - PROCESS | 1642 | 13:23:13 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 13:23:13 INFO - PROCESS | 1642 | RSS: Main: 326078464 13:23:13 INFO - PROCESS | 1642 | 13:23:15 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:23:16 INFO - PROCESS | 1642 | RSS: Main: 300998656 13:23:16 INFO - PROCESS | 1642 | 13:23:18 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:23:18 INFO - PROCESS | 1642 | RSS: Main: 302264320 13:23:18 INFO - PROCESS | 1642 | 13:23:20 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:23:21 INFO - PROCESS | 1642 | RSS: Main: 304230400 13:23:21 INFO - PROCESS | 1642 | 13:23:24 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:23:25 INFO - PROCESS | 1642 | RSS: Main: 319000576 13:23:25 INFO - PROCESS | 1642 | 13:23:27 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:23:28 INFO - PROCESS | 1642 | RSS: Main: 312881152 13:23:28 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 13:23:30 INFO - PROCESS | 1642 | RSS: Main: 356806656 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | __start_tp_report 13:23:30 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 13:23:30 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 13:23:30 INFO - PROCESS | 1642 | |i|pagename|runs| 13:23:30 INFO - PROCESS | 1642 | |0;ai-astar;116;117;117;116;116;114;115;116;117;116 13:23:30 INFO - PROCESS | 1642 | |1;audio-beat-detection;147;114;129;173;110;109;110;113;138;114 13:23:30 INFO - PROCESS | 1642 | |2;audio-dft;183;183;185;182;182;183;185;185;183;185 13:23:30 INFO - PROCESS | 1642 | |3;audio-fft;81;60;59;89;62;59;64;58;84;58 13:23:30 INFO - PROCESS | 1642 | |4;audio-oscillator;81;71;93;69;68;69;85;67;68;90 13:23:30 INFO - PROCESS | 1642 | |5;imaging-gaussian-blur;87;86;87;84;87;84;87;85;88;85 13:23:30 INFO - PROCESS | 1642 | |6;imaging-darkroom;97;97;97;97;118;159;107;98;96;96 13:23:30 INFO - PROCESS | 1642 | |7;imaging-desaturate;81;81;81;79;83;84;83;87;84;80 13:23:30 INFO - PROCESS | 1642 | |8;json-parse-financial;56;55;54;54;57;57;58;58;58;57 13:23:30 INFO - PROCESS | 1642 | |9;json-stringify-tinderbox;64;74;68;80;72;72;69;70;69;69 13:23:30 INFO - PROCESS | 1642 | |10;stanford-crypto-aes;84;84;84;83;84;86;82;84;83;82 13:23:30 INFO - PROCESS | 1642 | |11;stanford-crypto-ccm;140;139;144;139;140;148;152;156;193;136 13:23:30 INFO - PROCESS | 1642 | |12;stanford-crypto-pbkdf2;164;165;165;166;163;164;164;163;164;164 13:23:30 INFO - PROCESS | 1642 | |13;stanford-crypto-sha256-iterative;68;80;64;67;68;68;66;67;67;66 13:23:30 INFO - PROCESS | 1642 | __end_tp_report 13:23:30 INFO - PROCESS | 1642 | __start_cc_report 13:23:30 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,1987 13:23:30 INFO - PROCESS | 1642 | __end_cc_report 13:23:30 INFO - PROCESS | 1642 | __startTimestamp1481923410464__endTimestamp 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | ------- Summary: start ------- 13:23:30 INFO - PROCESS | 1642 | Number of tests: 14 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#0] ai-astar Cycles:10 Average:116.00 Median:116.00 stddev:0.94 (0.8%) stddev-sans-first:1.00 13:23:30 INFO - PROCESS | 1642 | Values: 116.0 117.0 117.0 116.0 116.0 114.0 115.0 116.0 117.0 116.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#1] audio-beat-detection Cycles:10 Average:125.70 Median:121.50 stddev:21.24 (17.5%) stddev-sans-first:21.08 13:23:30 INFO - PROCESS | 1642 | Values: 147.0 114.0 129.0 173.0 110.0 109.0 110.0 113.0 138.0 114.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.26 (0.7%) stddev-sans-first:1.32 13:23:30 INFO - PROCESS | 1642 | Values: 183.0 183.0 185.0 182.0 182.0 183.0 185.0 185.0 183.0 185.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#3] audio-fft Cycles:10 Average:67.40 Median:63.00 stddev:12.20 (19.4%) stddev-sans-first:11.91 13:23:30 INFO - PROCESS | 1642 | Values: 81.0 60.0 59.0 89.0 62.0 59.0 64.0 58.0 84.0 58.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:76.00 stddev:10.13 (13.3%) stddev-sans-first:10.58 13:23:30 INFO - PROCESS | 1642 | Values: 81.0 71.0 93.0 69.0 68.0 69.0 85.0 67.0 68.0 90.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.41 (1.6%) stddev-sans-first:1.45 13:23:30 INFO - PROCESS | 1642 | Values: 87.0 86.0 87.0 84.0 87.0 84.0 87.0 85.0 88.0 85.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#6] imaging-darkroom Cycles:10 Average:106.20 Median:97.00 stddev:19.84 (20.5%) stddev-sans-first:20.76 13:23:30 INFO - PROCESS | 1642 | Values: 97.0 97.0 97.0 97.0 118.0 159.0 107.0 98.0 96.0 96.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#7] imaging-desaturate Cycles:10 Average:82.30 Median:83.00 stddev:2.36 (2.8%) stddev-sans-first:2.46 13:23:30 INFO - PROCESS | 1642 | Values: 81.0 81.0 81.0 79.0 83.0 84.0 83.0 87.0 84.0 80.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:57.00 stddev:1.58 (2.8%) stddev-sans-first:1.67 13:23:30 INFO - PROCESS | 1642 | Values: 56.0 55.0 54.0 54.0 57.0 57.0 58.0 58.0 58.0 57.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.00 stddev:4.24 (6.0%) stddev-sans-first:3.75 13:23:30 INFO - PROCESS | 1642 | Values: 64.0 74.0 68.0 80.0 72.0 72.0 69.0 70.0 69.0 69.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:1.17 (1.4%) stddev-sans-first:1.24 13:23:30 INFO - PROCESS | 1642 | Values: 84.0 84.0 84.0 83.0 84.0 86.0 82.0 84.0 83.0 82.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:146.00 stddev:16.83 (11.5%) stddev-sans-first:17.56 13:23:30 INFO - PROCESS | 1642 | Values: 140.0 139.0 144.0 139.0 140.0 148.0 152.0 156.0 193.0 136.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.20 Median:164.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 13:23:30 INFO - PROCESS | 1642 | Values: 164.0 165.0 165.0 166.0 163.0 164.0 164.0 163.0 164.0 164.0 13:23:30 INFO - PROCESS | 1642 | 13:23:30 INFO - PROCESS | 1642 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:67.50 stddev:4.36 (6.5%) stddev-sans-first:4.62 13:23:30 INFO - PROCESS | 1642 | Values: 68.0 80.0 64.0 67.0 68.0 68.0 66.0 67.0 67.0 66.0 13:23:30 INFO - PROCESS | 1642 | -------- Summary: end -------- 13:23:30 INFO - PROCESS | 1642 | 13:23:31 INFO - TEST-INFO | 1642: exit 0 13:23:31 INFO - TEST-OK | kraken | took 59577ms 13:23:31 INFO - SUITE-END | took 1179s 13:23:31 INFO - Completed test suite (00:19:40) 13:23:31 INFO - javascript benchmark 13:23:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2690.596410538419, 3320.335932813438, 3325.567676202094, 3337.0989832745145, 3384.915377115572, 5633.4648208420185, 5695.601307908448, 5739.971300143498, 5745.511631511326, 5752.884942301157, 5663.801766938158, 5711.742971566279, 5716.799912003081, 5740.856478588027, 5787.421257874211, 5119.2577076324005, 5164.122099243119, 5177.378714554259, 5189.896202075956, 5224.895502089956, 5139.537441630253, 5142.794288228466, 5170.560502357304, 5279.788808447657, 5331.706756128411, 5150.922736158942, 5193.402758682762, 5203.869903252411, 5216.634835561512, 5219.269302297681, 5212.973935130338, 5230.633855630107, 5257.132573125468, 5276.657017293864, 5314.362276526803, 5071.873203169914, 5101.489851014869, 5189.117849965515, 5190.948090519084, 5196.506331898464, 4798.47216806151, 5596.692181929992, 5599.272094627713, 5630.239917611152, 5682.0, 6119.847003824895, 6139.723712432951, 6164.137020817089, 6193.0710393440895, 6202.906956395657, 6262.459134040355, 6292.087647291117, 6312.842178945517, 6313.0, 6320.273168585625, 5633.915491267634, 5698.544116470673, 5719.170720245625, 5725.2843394575675, 5728.1694154348215, 6257.8748425031245, 6341.841453963688, 6349.904751428732, 6357.332480089616, 6375.840603984938, 5091.9236211456855, 6080.817575472743, 6125.601835880653, 6134.202553667995, 6158.969205153946, 6064.151018857364, 6116.223169985209, 6141.986572215624, 6209.658468784261, 6221.2845522764555, 6061.909071363933, 6097.969510152509, 6103.267607886994, 6125.479334256549, 6125.785597504066, 6084.087386892002, 6108.926787320877, 6145.525073982212, 6145.617236121837, 6155.569110162335, 4908.730019848943, 4909.7790599423115, 4917.311576379309, 4919.729414882144, 4947.678400904002, 4421.982943922916, 4434.0, 4450.376947227391, 4450.799714012877, 4452.48796388413, 5607.074832652566, 5685.545156387479, 5716.799912003143, 5746.913796293059, 5762.596618236683, 6171.197744293303, 6192.659403732748, 6249.468795152372, 6255.687215639291, 6300.684965751694, 5767.279090113736, 5797.681127538026, 5815.621984571074, 5833.212516310244, 5868.061110222344, 5385.192221166778, 5390.272313237662, 5399.811006614749, 5413.188021796762, 5414.214938833847, 6192.876142477216, 6227.159333489921, 6234.438900498977, 6240.687965601793, 6286.968565157145, 5043.52086551777, 6001.7599296027665, 6151.96924015377, 6270.0, 6288.654124023223, 6236.781712640036, 6257.998720204745, 6298.842528936813, 6303.905441418291, 6315.463185629089, 6040.214772079602, 6109.48069414087, 6117.401149625394, 6190.597611155261, 6195.597286176384, 4937.308776771327, 5198.870028249324, 6306.369363063657, 6318.62088274705, 6322.33615470369, 5408.729563521887, 5846.473817356458, 6060.878782424239, 6131.0, 6134.35589263121, 5899.115132730125, 5976.940230597638, 6055.091736239599, 6110.991686371699, 6137.754489820357, 5881.7647294107755, 5903.0, 5930.495907847881, 5941.910871336847, 5971.28344598651, 6064.939350606437, 6076.179715738319, 6082.057281121433, 6100.389961003864, 6102.542309326729, 5726.455986681259, 5928.584999050197, 5936.525077993664, 5946.256717910262, 5951.702414879325], "value": 5645.8319133577525, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.7717911171306, 494.70070607282594, 513.2404042017811, 516.8526969813604, 519.4260342321737, 30367.0, 40035.0, 41169.38245926306, 41307.58692413075, 42799.78600107003, 40931.59068409315, 41796.791016044874, 42165.0, 42211.0, 42221.0, 8457.365697572675, 9321.953390233057, 9512.33413661044, 9517.619295228182, 9522.047795220491, 8610.741677749678, 11119.499622517005, 11400.372979486125, 11436.656900293005, 11564.248323858965, 11252.718682032933, 11293.378864162467, 11296.548138074468, 11300.095992320594, 11339.716507087307, 11306.0, 11363.772724545463, 11383.715407114805, 11385.48765305559, 11390.71523211918, 9551.856722149174, 11142.387168705718, 11168.497417616227, 11204.719882002933, 11208.103351731841, 39220.80389598034, 39453.816385508195, 39553.40669889954, 39565.604343956766, 39614.405783913266, 3211.843736254946, 3299.8185099819502, 3311.387393332217, 3312.9171770705684, 3322.401967645823, 29436.55845162324, 29820.85089574538, 29841.0, 29940.550891736642, 30594.08217753471, 3183.2519357950864, 3187.8406079695924, 3220.372027454647, 3239.6112466503932, 3240.319532898094, 519088.0, 529545.0, 530322.0, 581310.0, 625685.8715706437, 58488.12267815986, 58845.117323240185, 59332.813343733746, 59383.70308148432, 59412.10881836776, 35022.29955400929, 35516.46725299123, 35912.46131308033, 62752.68623656852, 63035.68482157651, 15889.364425422853, 15980.600484987968, 15988.920055399882, 15990.76013859793, 16002.919985399998, 11156.0, 11441.828372574419, 11454.198206125659, 12124.39378031095, 12194.268343899394, 14226.928865355607, 16910.577235568962, 16970.0, 18666.160022799006, 19139.23443062262, 6423.756003218345, 7363.963180184065, 7534.924650753532, 7568.4323675723435, 7635.198304178092, 857.3741168946704, 958.3914214473859, 975.9268054895912, 980.5538479991586, 991.6777047459605, 28587.28531786722, 29540.852295738383, 29652.703472964993, 30041.24896877552, 30090.0, 1016.1312078173056, 1069.005824583145, 1071.919606029551, 1085.4626959655022, 1094.2832444748703, 14982.925085374503, 15353.848461365442, 15356.692866142615, 15442.424000479114, 15654.295556699975, 680259.0, 683868.0, 684294.0, 685823.0, 687338.0, 57881.710591446776, 58494.8301033977, 58507.074646267516, 58512.41487585155, 58570.70714646485, 1213.4963989944074, 1261.400834603556, 1264.3488603369271, 1342.8321459817523, 1367.3915107777143, 25934.092306769173, 30562.235944101576, 31444.842775785975, 31531.684683152875, 32174.517382239752, 54567.45432545624, 55625.721871390386, 55708.72145639246, 55754.16368754553, 55817.1627425597, 605806.0, 610218.0, 611324.0, 611392.0, 612947.0, 26698.932042718072, 26845.194644160707, 27111.59332609973, 27131.72868271292, 27243.455130896873, 37615.43576846404, 38887.0, 44255.0, 47169.0, 47455.52544474511, 38095.80952095333, 38356.616433835305, 38467.03832404212, 38974.610253897095, 40132.19735605213, 38112.42831357476, 38378.42432363573, 38844.80577597094, 39802.402963954984, 39903.80048099741, 37487.0, 37732.81133594424, 38293.0, 38594.0, 38718.032049199, 32806.83596582002, 33858.49212261769, 34353.828230858686, 34828.82585587056, 34857.825710871286, 21267.0, 24417.51164976655, 25312.86092125822, 25570.476474683728, 26119.0], "value": 20025.372089886692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.6915011065095, 365.211143929113, 365.5010910107703, 373.1734208727668, 395.8812356293111, 13520.661983450418, 13937.303134843269, 15082.245887705612, 19875.0, 20215.797842021577, 18317.633647327046, 18967.956762378064, 19925.103370348334, 20160.899195504004, 20161.294354697588, 5108.540231379176, 5345.893082138355, 5371.435999220084, 5382.946170538303, 5389.218563308318, 4566.40636717228, 5282.498162674539, 5845.649261044329, 5892.734826932777, 5930.525557955353, 5764.913526297109, 5882.499987501046, 5883.558733095014, 5890.234269544953, 5890.499307558863, 5578.246936663539, 5758.222639943617, 5788.073908174714, 5791.82624521265, 5806.709664516799, 4926.876828079291, 5125.718085505333, 5153.819616313411, 5155.458676838915, 5160.200168973815, 13222.0, 13398.330083495786, 22332.995015224355, 24894.751052489424, 25537.106201282866, 2180.3349978256624, 2274.6815445837597, 2367.9936027188546, 2525.9873700631565, 2743.8079334446597, 9881.209503239723, 9883.110520053158, 9953.800923981553, 9964.750881227956, 10018.398896066186, 1843.7603111595406, 1889.1461418705824, 1890.1588792987127, 1913.5885784556249, 1944.3000519812856, 69765.2558686037, 103038.48480757549, 123534.0, 125486.37256813749, 129134.3543282287, 10303.3817970922, 11589.24669896454, 11691.649250522498, 11802.11484138693, 11859.34773587461, 13171.209727416386, 13730.862691373159, 13732.588022359345, 14832.11007339563, 14902.478413255483, 7115.786526404217, 8019.278264956182, 8236.711715089943, 8460.746177614681, 8510.0, 8294.212049855254, 8312.792180195544, 8315.542645154454, 8422.368322375723, 8487.193716596914, 4553.886152846206, 4563.38394316763, 4588.49526552079, 4590.632749380042, 4608.815647374135, 1880.1351378365832, 1937.3413039566333, 1944.9611007780047, 1963.3226536844766, 1970.0937568718264, 817.3093735793245, 919.3748251189255, 930.5533343994784, 964.7877466957258, 1007.1388962437157, 16270.593235168979, 20697.896510517352, 20838.895805520875, 21066.0, 21171.8941405292, 822.383212590557, 887.8579427291602, 912.4434095201922, 916.4272329793879, 923.8152369526122, 10386.06661835622, 10718.249722519504, 10769.569217231223, 10773.676789696321, 10817.783644327226, 590884.0, 591926.0, 602120.0, 603266.0, 626507.0, 126353.0, 132765.00852487222, 133358.33320833335, 137192.0, 138941.3052934729, 852.957352132391, 953.2040745977187, 963.3064193780607, 991.6380521109743, 995.2535598301274, 17834.643307134043, 17905.015224162795, 18097.0, 18178.636427271646, 18297.725534116733, 48731.02537949292, 49049.26426103683, 49571.0, 49823.27032391176, 50161.24758128704, 63034.684826575576, 75774.0, 77051.0, 78659.0, 79745.0, 6244.937550624436, 7502.962485187539, 7719.498232614862, 7952.165022672768, 8277.503349799032, 11342.0, 12161.33112678811, 12299.0, 12397.380130993595, 12490.500379984698, 10590.8411373831, 10740.30188037775, 10903.945480272549, 11108.389038602954, 11246.606368777053, 10478.37129772216, 10577.9471102644, 10658.200634952565, 10781.086898976519, 11057.391843448688, 10928.289661171999, 10977.890221097687, 11032.007119359296, 11173.664790056311, 11415.315081095161, 7913.287804097658, 7938.24586664266, 8046.0, 8114.472559283628, 8156.592170391338, 8147.470414423044, 10312.0, 10572.524236409534, 10721.98141176587, 10986.340819550853], "value": 9207.453392236095, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1291.5996041227227, 1428.4286285485807, 1462.6124077119562, 1571.300771156835, 1777.8666600005004, 7392.445566582508, 7742.535447873124, 8090.352771778251, 8307.00315962084, 8330.042045164808, 8194.508329500219, 8281.875771863426, 8283.378746593999, 8291.543965081933, 8294.0, 2251.1783199132365, 2297.6094064009076, 2302.26327575176, 2303.6314189729646, 2305.4351683837485, 2278.4303924018996, 2333.603287441139, 2345.542619189259, 2348.1898744933055, 2355.0462062864567, 2278.5215104828007, 2281.0533628544135, 2310.4570425949973, 2315.2012555668352, 2321.628539433691, 2191.627423338028, 2246.2587346175887, 2271.034810205656, 2273.0680421027464, 2274.363178310076, 2287.336672365029, 2314.271004633535, 2316.4440534271653, 2317.9768202317928, 2322.8954697038675, 8206.753797386087, 8242.670293188265, 8304.792380190484, 8334.249917507454, 8337.624806883705, 5035.748212589356, 5037.798488060474, 5199.4800519948085, 5200.6619569727845, 5204.3234379530795, 3503.019154636706, 3585.8386372613295, 3592.3533763922483, 3597.406427939387, 3605.9903227096192, 1055.2560444886337, 1080.330195278932, 1103.5475455063465, 1106.115107913666, 1110.3337997201616, 10760.78478430435, 10790.460476976199, 10797.974192451704, 10799.4060326682, 10811.513481893333, 4260.637845783143, 4469.0, 4486.75322857246, 4497.460304763384, 4499.820007199741, 7431.0, 8532.488050716976, 8619.1811777881, 8645.394822362374, 8651.6971905983, 5090.032893750175, 5090.287359769635, 5093.032323858455, 5095.668781529189, 5097.0, 4078.938815917764, 4118.6499147572795, 4703.2004559225015, 4737.715737055719, 4747.192977193886, 3255.283837555734, 3578.874739384109, 3607.603163651996, 3708.6291370862696, 3726.8136593170234, 944.3908678902206, 950.4582388038751, 1142.4230763464564, 1145.2613064573304, 1149.545929357899, 752.3379426104992, 833.2667252817482, 860.9139086091466, 883.5228976352697, 890.9866352004725, 5399.676019438846, 5423.511883930465, 5455.8363249102595, 5599.524040456526, 5798.420157984167, 849.3120572336427, 894.1505569708803, 922.4096578189964, 922.898481167071, 941.7692665297046, 2998.3553535989868, 3497.8251087445524, 3661.4324779659196, 3679.6504332088407, 3690.538682664667, 9799.559019844124, 10104.09063184317, 10212.846807297896, 10216.438095904648, 10233.181345492343, 9996.550155243032, 10068.546915388824, 10074.143697785623, 10137.594496220216, 10157.593696252216, 7290.0, 7635.274648908345, 7649.120351159577, 7713.8457230855065, 7745.264199901113, 4753.287006948985, 5386.192071189353, 5578.60949733531, 5596.664200148004, 5597.468240517144, 9187.816243675223, 9189.632414703337, 9389.201917836786, 9422.670206542738, 9573.9042609573, 7985.161558036322, 9603.231741460806, 9642.373245739005, 9643.903560964582, 9663.873947731488, 6541.70562324706, 6742.898856517058, 6765.357291057342, 7005.194402643761, 7163.892541611775, 7596.696132154873, 7822.569758663328, 7850.097238817609, 7945.364370850433, 7954.801129971797, 6980.092587963532, 7030.367266945999, 7044.964775176091, 7052.541584796972, 7207.954846547326, 6854.897176542256, 6941.3058694132205, 6957.373836354753, 6986.266442023718, 6989.96505017492, 6571.769988050395, 6613.206415230203, 6713.966430167818, 6729.158855143107, 6766.15423072116, 4753.952460475351, 4776.880577985578, 4800.087983283165, 4828.275758636232, 4850.830220942391, 4733.556265339104, 4749.216379297515, 4777.976110119427, 4907.55831975124, 5036.068327359304], "value": 4329.319749393271, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.54618752589823, 302.196158219137, 307.40670505923566, 312.8185652321654, 315.2166865339632, 5040.613831196423, 5349.619419358387, 5368.32895888014, 5387.090970875727, 5456.372517160527, 5132.97433512832, 5211.817586384468, 5263.631545791796, 5274.340707411574, 5325.227841962923, 3529.4645930937377, 3922.666573341269, 4069.697696737045, 4074.7962601869935, 4081.8979525511804, 3400.4729266963536, 3751.4560388743616, 3928.5678575356687, 4106.589341065899, 4144.23331683638, 3666.578343490493, 4024.953512086864, 4066.857659981901, 4081.693872959525, 4101.487314085739, 3375.8480868360984, 3977.4630424892557, 4028.879133625996, 4033.495813023372, 4048.75707457552, 3100.953485697716, 3505.94741078884, 3524.8942531724088, 3536.1003976738825, 3557.94663080054, 4257.76582287974, 4326.987950602985, 4338.891527711801, 5098.745062746848, 5216.243644671501, 4311.348520659772, 4337.002489427449, 4567.977160114211, 4571.977140114312, 4643.512431194709, 5505.256790333294, 5516.806911758109, 5523.558115350762, 5535.335759708821, 5541.838682913667, 2899.8260104393594, 3780.640839120279, 3860.6718428933573, 3903.6877049835944, 3919.7256192066566, 4925.630577706686, 5020.196768517019, 5136.0, 5847.473727364558, 5863.237779088691, 3383.5939687237196, 3864.613538646113, 3873.32216862048, 3892.4161375793856, 3903.657805316844, 3472.177107018835, 3744.5693745219105, 3783.5270591176104, 3932.0, 3947.822347994347, 3868.955382046832, 4521.773911304422, 4553.795079221443, 4558.0, 4609.7695115244105, 5640.830775076754, 5641.182028605829, 5654.776777237018, 5668.8866222675315, 5682.687452190086, 1031.4224034540682, 1088.8475613414128, 1194.9103817213743, 1230.1143176912608, 1248.6628610275175, 765.6516285090271, 786.0214033528293, 811.5739236900674, 855.2687452228255, 881.6341218394423, 4869.756512174377, 4904.43598986114, 4908.061315780247, 4918.213085906238, 4992.475790041994, 5038.28698242592, 5164.295782391801, 5447.346318441812, 5488.835334939959, 5523.392426833045, 4647.883802904955, 4816.686915350491, 5085.771140298695, 5114.079465696136, 5364.8122315718765, 2656.216416157238, 2783.512885245074, 2875.4552221284316, 2880.985595072011, 2884.437534680759, 5373.246431095029, 5432.626735477899, 5463.360991702473, 5722.570807189478, 5749.568782341257, 4434.379186913835, 5554.056080371918, 5595.132754423071, 5596.873188188462, 5637.238972738628, 4586.449626044896, 4659.5806377425515, 5485.807996720096, 5597.916031259616, 5648.604597678123, 5035.564864013739, 5438.428964958623, 5451.454854514517, 5459.945400545944, 5483.040467918178, 4848.903021939612, 4860.854174374775, 5391.460853914734, 5398.973005134949, 5479.863003424946, 4706.929396058993, 5278.656887302313, 5467.736986409798, 5644.125160600113, 5684.459976302244, 3285.8849940251976, 3572.8392222349535, 3641.816409666816, 3648.543932008499, 3658.48781170631, 4719.693219940693, 5394.32570928634, 5442.666546696085, 5464.3442786865835, 5468.511827118572, 4796.904061918673, 5334.719147724044, 5337.359516858002, 5339.919901201562, 5358.892822143457, 4242.151569686013, 4736.573708366263, 5385.569154467555, 5391.6866506795195, 5436.266104075899, 5183.289514460294, 5284.445133260952, 5379.757910893941, 5382.4886635769535, 5449.455054494519, 4834.830780922651, 5529.640573362719, 5590.993621148233, 5594.216809646734, 5599.832005039855, 4958.578520825626, 5138.794448222029, 5474.72626368672, 5478.589105817, 5544.67515338037], "value": 3967.9612563117926, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.4261163234514, 715.2132654080513, 764.4572353628927, 773.848700612298, 804.9736585853038, 13881.375338109785, 14279.643008924782, 14482.782758258634, 15501.379944802195, 16603.169841507937, 15087.09477431355, 15171.696566068673, 15202.847971520281, 15234.54296371108, 15284.388624455009, 652.5366989437505, 674.2044387622603, 800.6477150053972, 824.389951435936, 827.7103013945132, 673.1451057157407, 675.4596322941652, 676.918769747631, 678.5555461172914, 787.6494959742888, 854.5129276312505, 855.5337341149088, 869.2915274051628, 869.3002133282705, 869.8216865542548, 859.1923591823697, 860.4536119564059, 860.7417774667638, 861.328164032056, 861.7716305179133, 505.1866494943175, 522.7072839210055, 639.6002498438476, 640.0271587187495, 640.314863096487, 13755.31223438824, 14239.359228834624, 14311.212883291415, 14556.0538564994, 14676.853231467656, 13018.609441716764, 13372.799408008888, 13373.86626133736, 13424.463021479129, 13462.865371346259, 10459.372437653688, 10579.523921423555, 10660.573577056908, 10684.572617095308, 10694.197935154894, 3538.451540011277, 3580.856765729368, 3631.8547258109907, 3641.4901913732097, 3747.1568896997956, 86578.13421865764, 87268.6909696355, 90084.54957725169, 91006.08993910042, 92751.14497710076, 9469.863616365943, 9795.0, 10161.644342447978, 10315.484225788681, 10380.117689996285, 19933.700994485094, 19933.700994485094, 19998.1000854959, 20137.39587812368, 20249.0, 7412.0, 7511.248875112555, 7734.92265077342, 7762.728304509428, 7775.4168437367425, 4272.551382104896, 4330.956690433055, 4378.146261478981, 4413.073254616566, 4498.0, 3373.527706121145, 3469.2367679110557, 3484.5470088888283, 3526.241858000491, 3526.365254254259, 652.4780175859388, 653.1541653558653, 659.8284446043967, 664.7374287156546, 671.5500614588237, 587.3128439725531, 591.8609126855185, 605.857623458487, 674.7301079568211, 675.9188897332351, 7942.0911954372195, 8686.131386861263, 8902.287816974627, 8977.281817454588, 9121.0, 547.6960287040731, 566.8412844403574, 597.967111808846, 601.5458328961606, 656.5141795071613, 4046.6964977626794, 4264.0, 4429.53489883564, 4435.512093669694, 4463.330500424897, 53522.19716704253, 69792.65103674449, 81595.5920220395, 109527.45236273928, 112874.0, 64267.67866160639, 64580.677096614214, 82036.58981705054, 99851.0, 100713.0, 87667.0, 88655.55672221597, 90070.0, 91736.0, 92779.0, 8451.15456480253, 8660.130718954098, 8669.263112635497, 8753.47479151253, 8892.421992570462, 46279.61161165171, 52556.0, 53979.0, 55193.0, 55741.721291394904, 71345.64327178331, 78563.60718196373, 81101.18898810937, 82175.589122054, 84682.0, 9911.65309214174, 11130.721731956766, 11314.03118040089, 11317.886821132013, 11761.88238117608, 12048.337341446304, 12217.0, 13057.673558161123, 13694.178349299074, 14210.147391156564, 12245.877541224474, 12338.0, 12544.874551254736, 12999.480020799063, 13872.861271387561, 13203.933980330037, 13244.072914895865, 13259.734805304033, 13413.932930335286, 13540.526081587097, 10971.780564388828, 11405.574303212099, 11993.64019079429, 12775.0, 13371.264580448169, 10889.891101088888, 11022.889771102507, 11046.613368532062, 11151.498182581641, 11642.5342986283, 11068.391238481961, 11177.832332514856, 11274.478010327668, 11364.772704546029, 11627.476763545492], "value": 7122.500969300218, "unit": "score"}], "name": "dromaeo_css", "value": 7093.373101518228, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 116.0, 114.0, 115.0, 116.0, 117.0, 116.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 129.0, 173.0, 110.0, 109.0, 110.0, 113.0, 138.0, 114.0], "value": 125.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 185.0, 182.0, 182.0, 183.0, 185.0, 185.0, 183.0, 185.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 59.0, 89.0, 62.0, 59.0, 64.0, 58.0, 84.0, 58.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 71.0, 93.0, 69.0, 68.0, 69.0, 85.0, 67.0, 68.0, 90.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 84.0, 87.0, 84.0, 87.0, 85.0, 88.0, 85.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 97.0, 118.0, 159.0, 107.0, 98.0, 96.0, 96.0], "value": 106.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 79.0, 83.0, 84.0, 83.0, 87.0, 84.0, 80.0], "value": 82.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 54.0, 57.0, 57.0, 58.0, 58.0, 58.0, 57.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 68.0, 80.0, 72.0, 72.0, 69.0, 70.0, 69.0, 69.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 83.0, 84.0, 86.0, 82.0, 84.0, 83.0, 82.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 144.0, 139.0, 140.0, 148.0, 152.0, 156.0, 193.0, 136.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 165.0, 166.0, 163.0, 164.0, 164.0, 163.0, 164.0, 164.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 80.0, 64.0, 67.0, 68.0, 68.0, 66.0, 67.0, 67.0, 66.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1435.0, "alertThreshold": 2.0}]} 13:23:31 INFO - Return code: 0 13:23:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:23:31 INFO - # TBPL SUCCESS # 13:23:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:23:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:23:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:23:31 INFO - 2016-12-16 13:23:31.785 screenresolution[1646:10121] starting screenresolution argv=screenresolution get 13:23:31 INFO - 2016-12-16 13:23:31.788 screenresolution[1646:10121] Display 0: 1600x1200x32@60 13:23:31 INFO - 2016-12-16 13:23:31.794 screenresolution[1647:10124] starting screenresolution argv=screenresolution list 13:23:31 INFO - Available Modes on Display 0 13:23:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:23:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:23:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:23:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:23:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:23:32 INFO - Intel Iris: 13:23:32 INFO - Chipset Model: Intel Iris 13:23:32 INFO - Type: GPU 13:23:32 INFO - Bus: Built-In 13:23:32 INFO - VRAM (Dynamic, Max): 1536 MB 13:23:32 INFO - Vendor: Intel (0x8086) 13:23:32 INFO - Device ID: 0x0a2e 13:23:32 INFO - Revision ID: 0x0009 13:23:32 INFO - Displays: 13:23:32 INFO - Display: 13:23:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:23:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:23:32 INFO - Display Serial Number: 1600x1200 60 13:23:32 INFO - Main Display: Yes 13:23:32 INFO - Mirror: Off 13:23:32 INFO - Online: Yes 13:23:32 INFO - Rotation: Supported 13:23:32 INFO - Return code: 0 13:23:32 INFO - Running post-action listener: _resource_record_post_action 13:23:32 INFO - [mozharness: 2016-12-16 21:23:32.035677Z] Finished run-tests step (success) 13:23:32 INFO - Running post-run listener: _resource_record_post_run 13:23:32 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37741568; Write bytes: 658161664; Read time: 244; Write time: 1934 13:23:32 INFO - TinderboxPrint: CPU usage
53.7% 13:23:32 INFO - TinderboxPrint: I/O read bytes / time
37,741,568 / 244 13:23:32 INFO - TinderboxPrint: I/O write bytes / time
658,161,664 / 1,934 13:23:32 INFO - TinderboxPrint: CPU idle
2,242.6 (46.3%) 13:23:32 INFO - TinderboxPrint: CPU system
974.8 (20.1%) 13:23:32 INFO - TinderboxPrint: CPU user
1,628.3 (33.6%) 13:23:32 INFO - TinderboxPrint: Swap in / out
386,547,712 / 0 13:23:32 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345823744; Write bytes: 347166720; Read time: 26852; Write time: 732 13:23:32 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35611648; Write bytes: 303847424; Read time: 220; Write time: 1173 13:23:32 INFO - Running post-run listener: _upload_blobber_files 13:23:32 INFO - Blob upload gear active. 13:23:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:23:32 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 13:23:32 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:23:32 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:23:32 INFO - (blobuploader) - INFO - Open directory for files ... 13:23:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:23:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:23:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:23:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:23:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:23:34 INFO - (blobuploader) - INFO - Done attempting. 13:23:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:23:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:23:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:23:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:23:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:23:35 INFO - (blobuploader) - INFO - Done attempting. 13:23:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:23:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:23:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:23:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:23:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:23:37 INFO - (blobuploader) - INFO - Done attempting. 13:23:37 INFO - (blobuploader) - INFO - Iteration through files over. 13:23:37 INFO - Return code: 0 13:23:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:23:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:23:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c993c784887a79909dd7fa2b6332691cbb37fba4ae30cbe6f8bc0f7f14923a3103c5684183e705efa348482017a3ad7be46bd7a9b3ba69e8acf61839ce16dd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1cad7945f93f04d81108350cda66b8e66d1097ce629db06a8ecbce553bf835dff1d4b3cd332e939b134466f03397b820a754a67c18f189b63cc298c5cdd1d25f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e55e431dec6b8f83ffce8296282543ff751cf824d5ded3e5796a4b9cbff24f6bdb14d3ecdc59361f20736b6b49a4feaebf2f3d8798fefa67eaa51dd7d388e4dc"} 13:23:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:23:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:23:37 INFO - Contents: 13:23:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c993c784887a79909dd7fa2b6332691cbb37fba4ae30cbe6f8bc0f7f14923a3103c5684183e705efa348482017a3ad7be46bd7a9b3ba69e8acf61839ce16dd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1cad7945f93f04d81108350cda66b8e66d1097ce629db06a8ecbce553bf835dff1d4b3cd332e939b134466f03397b820a754a67c18f189b63cc298c5cdd1d25f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e55e431dec6b8f83ffce8296282543ff751cf824d5ded3e5796a4b9cbff24f6bdb14d3ecdc59361f20736b6b49a4feaebf2f3d8798fefa67eaa51dd7d388e4dc"} 13:23:37 INFO - Running post-run listener: copy_logs_to_upload_dir 13:23:37 INFO - Copying logs to upload dir... 13:23:37 INFO - mkdir: /builds/slave/test/build/upload/logs 13:23:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.619380 ========= master_lag: 0.21 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-16 13:23:37.669767) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 13:23:37.676092) ========= 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.EEnY4XRbJS/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.ar22sCV78r/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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/Mozilla-Aurora/sha512/0c993c784887a79909dd7fa2b6332691cbb37fba4ae30cbe6f8bc0f7f14923a3103c5684183e705efa348482017a3ad7be46bd7a9b3ba69e8acf61839ce16dd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1cad7945f93f04d81108350cda66b8e66d1097ce629db06a8ecbce553bf835dff1d4b3cd332e939b134466f03397b820a754a67c18f189b63cc298c5cdd1d25f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e55e431dec6b8f83ffce8296282543ff751cf824d5ded3e5796a4b9cbff24f6bdb14d3ecdc59361f20736b6b49a4feaebf2f3d8798fefa67eaa51dd7d388e4dc"} build_url:https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010990 build_url: 'https://queue.taskcluster.net/v1/task/P7M9cg4KTsa73jTLud_aFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c993c784887a79909dd7fa2b6332691cbb37fba4ae30cbe6f8bc0f7f14923a3103c5684183e705efa348482017a3ad7be46bd7a9b3ba69e8acf61839ce16dd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1cad7945f93f04d81108350cda66b8e66d1097ce629db06a8ecbce553bf835dff1d4b3cd332e939b134466f03397b820a754a67c18f189b63cc298c5cdd1d25f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e55e431dec6b8f83ffce8296282543ff751cf824d5ded3e5796a4b9cbff24f6bdb14d3ecdc59361f20736b6b49a4feaebf2f3d8798fefa67eaa51dd7d388e4dc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 13:23:37.710673) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 13:23:37.711014) ========= 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.EEnY4XRbJS/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.ar22sCV78r/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.005378 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 13:23:37.759863) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 13:23:37.760265) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 13:23:37.760653) ========= ========= Total master_lag: 0.72 =========