builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0209 starttime: 1477936387.31 results: success (0) buildid: 20161031084722 builduid: 67893dc2d0d5491496ae3cb1295160bf revision: 8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:07.310133) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:07.310584) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:07.310911) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:07.336393) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:07.337139) ========= 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.Out2Tauc9c/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.2HDcM5n4ej/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-10-31 10:53:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.6M=0.001s 2016-10-31 10:53:07 (14.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119515 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:07.481230) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:07.481592) ========= 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.Out2Tauc9c/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.2HDcM5n4ej/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.025988 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:07.531319) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:07.531719) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Out2Tauc9c/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.2HDcM5n4ej/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-10-31 10:53:07,619 truncating revision to first 12 chars 2016-10-31 10:53:07,619 Setting DEBUG logging. 2016-10-31 10:53:07,620 attempt 1/10 2016-10-31 10:53:07,620 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c9eed5227f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-31 10:53:08,330 unpacking tar archive at: mozilla-central-8c9eed5227f8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.970315 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:08.524681) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:08.525135) ========= script_repo_revision: 8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:08.525576) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:08.525890) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 10:53:08.544799) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-31 10:53:08.545259) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Out2Tauc9c/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.2HDcM5n4ej/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 10:53:08 INFO - MultiFileLogger online at 20161031 10:53:08 in /builds/slave/test 10:53:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:53:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:53:08 INFO - {'append_to_log': False, 10:53:08 INFO - 'base_work_dir': '/builds/slave/test', 10:53:08 INFO - 'blob_upload_branch': 'Firefox', 10:53:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:53:08 INFO - 'branch': 'Firefox', 10:53:08 INFO - 'buildbot_json_path': 'buildprops.json', 10:53:08 INFO - 'config_files': ('talos/mac_config.py',), 10:53:08 INFO - 'default_actions': ('clobber', 10:53:08 INFO - 'read-buildbot-config', 10:53:08 INFO - 'download-and-extract', 10:53:08 INFO - 'populate-webroot', 10:53:08 INFO - 'create-virtualenv', 10:53:08 INFO - 'install', 10:53:08 INFO - 'run-tests'), 10:53:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:53:08 INFO - 'download_minidump_stackwalk': True, 10:53:08 INFO - 'download_symbols': 'ondemand', 10:53:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:53:08 INFO - 'tooltool.py': '/tools/tooltool.py', 10:53:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:53:08 INFO - '/tools/misc-python/virtualenv.py')}, 10:53:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:53:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:53:08 INFO - 'installer_path': 'installer.exe', 10:53:08 INFO - 'log_level': 'info', 10:53:08 INFO - 'log_name': 'talos', 10:53:08 INFO - 'log_to_console': True, 10:53:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:53:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:53:08 INFO - 'opt_config_files': (), 10:53:08 INFO - 'pip_index': False, 10:53:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:53:08 INFO - 'cmd': ('bash', 10:53:08 INFO - '-c', 10:53:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:53:08 INFO - 'enabled': True, 10:53:08 INFO - 'halt_on_failure': False, 10:53:08 INFO - 'name': 'check_screen_resolution'},), 10:53:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:53:08 INFO - 'cmd': ('bash', 10:53:08 INFO - '-c', 10:53:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:53:08 INFO - 'enabled': True, 10:53:08 INFO - 'halt_on_failure': False, 10:53:08 INFO - 'name': 'check_screen_resolution'},), 10:53:08 INFO - 'run_cmd_checks_enabled': True, 10:53:08 INFO - 'sps_profile': False, 10:53:08 INFO - 'sps_profile_interval': 0, 10:53:08 INFO - 'suite': 'dromaeojs', 10:53:08 INFO - 'system_bits': '32', 10:53:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:53:08 INFO - 'title': 't-yosemite-r7-0209', 10:53:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:53:08 INFO - 'use_talos_json': True, 10:53:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:53:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:53:08 INFO - 'work_dir': 'build'} 10:53:08 INFO - [mozharness: 2016-10-31 17:53:08.753898Z] Running clobber step. 10:53:08 INFO - Running pre-action listener: _resource_record_pre_action 10:53:08 INFO - Running main action method: clobber 10:53:08 INFO - rmtree: /builds/slave/test/build 10:53:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:53:10 INFO - Running post-action listener: _resource_record_post_action 10:53:10 INFO - [mozharness: 2016-10-31 17:53:10.889281Z] Finished clobber step (success) 10:53:10 INFO - [mozharness: 2016-10-31 17:53:10.889454Z] Running read-buildbot-config step. 10:53:10 INFO - Running pre-action listener: _resource_record_pre_action 10:53:10 INFO - Running main action method: read_buildbot_config 10:53:10 INFO - Using buildbot properties: 10:53:10 INFO - { 10:53:10 INFO - "project": "", 10:53:10 INFO - "product": "firefox", 10:53:10 INFO - "script_repo_revision": "production", 10:53:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:53:10 INFO - "repository": "", 10:53:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:53:10 INFO - "buildid": "20161031084722", 10:53:10 INFO - "pgo_build": "False", 10:53:10 INFO - "basedir": "/builds/slave/test", 10:53:10 INFO - "buildnumber": 68, 10:53:10 INFO - "slavename": "t-yosemite-r7-0209", 10:53:10 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 10:53:10 INFO - "platform": "yosemite_r7", 10:53:10 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:53:10 INFO - "branch": "mozilla-central", 10:53:10 INFO - "repo_path": "mozilla-central", 10:53:10 INFO - "revision": "8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096", 10:53:10 INFO - "stage_platform": "macosx64", 10:53:10 INFO - "builduid": "67893dc2d0d5491496ae3cb1295160bf", 10:53:10 INFO - "slavebuilddir": "test" 10:53:10 INFO - } 10:53:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 10:53:10 INFO - Running post-action listener: _resource_record_post_action 10:53:10 INFO - [mozharness: 2016-10-31 17:53:10.895122Z] Finished read-buildbot-config step (success) 10:53:10 INFO - [mozharness: 2016-10-31 17:53:10.895308Z] Running download-and-extract step. 10:53:10 INFO - Running pre-action listener: _resource_record_pre_action 10:53:10 INFO - Running main action method: download_and_extract 10:53:10 INFO - mkdir: /builds/slave/test/build/tests 10:53:10 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')]} 10:53:10 INFO - trying https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 10:53:10 INFO - Downloading https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:53:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 10:53:12 INFO - Downloaded 1199 bytes. 10:53:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:53:12 INFO - Using the following test package requirements: 10:53:12 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:53:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:53:12 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 10:53:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:53:12 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 10:53:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:53:12 INFO - u'jsshell-mac.zip'], 10:53:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:53:12 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 10:53:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:53:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:53:12 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 10:53:12 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:53:12 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 10:53:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:53:12 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 10:53:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:53:12 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 10:53:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:53:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:53:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:53:12 INFO - Fetch https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:53:12 INFO - Http code: 200 10:53:12 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 10:53:12 INFO - x-amz-cf-id: _pjzpI6eJzLkttG05eswggmb8V4eh5Tn_ufQO4629__xvLXItjZxTg== 10:53:12 INFO - x-amz-version-id: hMu_fxVK.AuOgiZGYj578w09GIT1F4Na 10:53:13 INFO - Expected file size: 16404116 10:53:13 INFO - Obtained file size: 16404116 10:53:19 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:53:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:53:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:53:19 INFO - Fetch https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:53:20 INFO - Http code: 200 10:53:20 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 10:53:20 INFO - x-amz-cf-id: 19YFq8lJ9FvSgt5GWuzzq7fW1n7JXYuX_A0o8kKjOP5AK_JVvYF-ew== 10:53:20 INFO - x-amz-version-id: hMu_fxVK.AuOgiZGYj578w09GIT1F4Na 10:53:20 INFO - Expected file size: 16404116 10:53:20 INFO - Obtained file size: 16404116 10:53:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 10:53:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:53:27 INFO - Fetch https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 10:53:27 INFO - Http code: 200 10:53:27 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 10:53:27 INFO - x-amz-cf-id: Y9e6rdaOepHAYyYtLhUdOXYsiAvqohl9u-EqPSQ4PpBtNHM2GkMWjQ== 10:53:27 INFO - x-amz-version-id: 10lUHbJDNB9QgTsspDyc.5RAaqCN7BZq 10:53:27 INFO - Expected file size: 14409038 10:53:27 INFO - Obtained file size: 14409038 10:53:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:28 INFO - trying https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:53:28 INFO - Downloading https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 10:53:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 10:53:31 INFO - Downloaded 112073077 bytes. 10:53:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:53:31 INFO - mkdir: /builds/slave/test/properties 10:53:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:53:31 INFO - Writing to file /builds/slave/test/properties/build_url 10:53:31 INFO - Contents: 10:53:31 INFO - build_url:https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:53:32 INFO - Running post-action listener: _resource_record_post_action 10:53:32 INFO - Running post-action listener: set_extra_try_arguments 10:53:32 INFO - [mozharness: 2016-10-31 17:53:32.161666Z] Finished download-and-extract step (success) 10:53:32 INFO - [mozharness: 2016-10-31 17:53:32.161826Z] Running populate-webroot step. 10:53:32 INFO - Running pre-action listener: _resource_record_pre_action 10:53:32 INFO - Running main action method: populate_webroot 10:53:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:53:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:53:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:53:32 INFO - '--tppagecycles', 10:53:32 INFO - '7'], 10:53:32 INFO - 'tests': ['tsvgm']}}, 10:53:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:53:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:53:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:53:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:53:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:53:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:53:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:53:32 INFO - 'talos_options': ['--disable-e10s'], 10:53:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:53:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:53:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:53:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:53:32 INFO - 'talos_options': ['--disable-e10s'], 10:53:32 INFO - 'tests': ['damp', 'tps']}, 10:53:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:53:32 INFO - 'tests': ['damp', 'tps']}, 10:53:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:53:32 INFO - 'tests': ['dromaeo_dom']}, 10:53:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:53:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:53:32 INFO - 'tests': ['basic_compositor_video']}, 10:53:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:53:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:53:32 INFO - 'tests': ['a11yr', 10:53:32 INFO - 'ts_paint', 10:53:32 INFO - 'tpaint', 10:53:32 INFO - 'sessionrestore', 10:53:32 INFO - 'sessionrestore_no_auto_restore', 10:53:32 INFO - 'tabpaint']}, 10:53:32 INFO - 'other-e10s': {'tests': ['a11yr', 10:53:32 INFO - 'ts_paint', 10:53:32 INFO - 'tpaint', 10:53:32 INFO - 'sessionrestore', 10:53:32 INFO - 'sessionrestore_no_auto_restore', 10:53:32 INFO - 'tabpaint']}, 10:53:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:53:32 INFO - 'ts_paint', 10:53:32 INFO - 'tpaint', 10:53:32 INFO - 'sessionrestore', 10:53:32 INFO - 'sessionrestore_no_auto_restore', 10:53:32 INFO - 'tabpaint']}, 10:53:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:53:32 INFO - 'ts_paint', 10:53:32 INFO - 'tpaint', 10:53:32 INFO - 'sessionrestore', 10:53:32 INFO - 'sessionrestore_no_auto_restore', 10:53:32 INFO - 'tabpaint']}, 10:53:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:53:32 INFO - 'tests': ['a11yr', 10:53:32 INFO - 'ts_paint', 10:53:32 INFO - 'tpaint', 10:53:32 INFO - 'sessionrestore', 10:53:32 INFO - 'sessionrestore_no_auto_restore', 10:53:32 INFO - 'tabpaint']}, 10:53:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:53:32 INFO - 'tests': ['a11yr', 10:53:32 INFO - 'ts_paint', 10:53:32 INFO - 'tpaint', 10:53:32 INFO - 'sessionrestore', 10:53:32 INFO - 'sessionrestore_no_auto_restore', 10:53:32 INFO - 'tabpaint']}, 10:53:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:53:32 INFO - 'tests': ['tsvgx', 10:53:32 INFO - 'tsvgr_opacity', 10:53:32 INFO - 'tart', 10:53:32 INFO - 'tscrollx', 10:53:32 INFO - 'cart']}, 10:53:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:53:32 INFO - 'tsvgr_opacity', 10:53:32 INFO - 'tart', 10:53:32 INFO - 'tscrollx', 10:53:32 INFO - 'cart']}, 10:53:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:53:32 INFO - 'talos_options': ['--disable-e10s'], 10:53:32 INFO - 'tests': ['tp5o']}, 10:53:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:53:32 INFO - 'tests': ['tp5o']}, 10:53:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:53:32 INFO - 'talos_options': ['--disable-e10s', 10:53:32 INFO - '--xperf_path', 10:53:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:53:32 INFO - 'tests': ['tp5n']}, 10:53:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:53:32 INFO - 'talos_options': ['--xperf_path', 10:53:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:53:32 INFO - 'tests': ['tp5n']}}, 10:53:32 INFO - 'talos.zip': {'path': '', 10:53:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:53:32 INFO - Running post-action listener: _resource_record_post_action 10:53:32 INFO - [mozharness: 2016-10-31 17:53:32.180953Z] Finished populate-webroot step (success) 10:53:32 INFO - [mozharness: 2016-10-31 17:53:32.181063Z] Running create-virtualenv step. 10:53:32 INFO - Running pre-action listener: _resource_record_pre_action 10:53:32 INFO - Running main action method: create_virtualenv 10:53:32 INFO - Creating virtualenv /builds/slave/test/build/venv 10:53:32 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 10:53:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:53:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:53:32 INFO - Using real prefix '/tools/python27' 10:53:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:53:33 INFO - Installing distribute.............................................................................................................................................................................................done. 10:53:35 INFO - Installing pip.................done. 10:53:35 INFO - Return code: 0 10:53:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:53:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:53:36 INFO - Reading from file tmpfile_stdout 10:53:36 INFO - Output received: 10:53:36 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) 10:53:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:53:36 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')]} 10:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:36 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')]} 10:53:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:36 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 0x10cc70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9629462a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84d30>, '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 0x10c8aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca7cb20>, '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.Out2Tauc9c/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.2HDcM5n4ej/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 10:53:36 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 10:53:36 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 10:53:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Out2Tauc9c/Render', 10:53:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:36 INFO - 'HOME': '/Users/cltbld', 10:53:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:36 INFO - 'LOGNAME': 'cltbld', 10:53:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:36 INFO - 'MOZ_NO_REMOTE': '1', 10:53:36 INFO - 'NO_EM_RESTART': '1', 10:53:36 INFO - 'PAGER': '/bin/cat', 10:53:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:36 INFO - 'PWD': '/builds/slave/test', 10:53:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:36 INFO - 'SHELL': '/bin/bash', 10:53:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2HDcM5n4ej/Listeners', 10:53:36 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 10:53:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:36 INFO - 'USER': 'cltbld', 10:53:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:36 INFO - 'XPC_FLAGS': '0x0', 10:53:36 INFO - 'XPC_SERVICE_NAME': '0', 10:53:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:36 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 10:53:36 INFO - Cleaning up... 10:53:36 INFO - Return code: 0 10:53:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:53:36 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')]} 10:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:36 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')]} 10:53:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:36 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 0x10cc70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9629462a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84d30>, '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 0x10c8aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca7cb20>, '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.Out2Tauc9c/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.2HDcM5n4ej/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 10:53:36 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 10:53:36 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 10:53:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Out2Tauc9c/Render', 10:53:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:36 INFO - 'HOME': '/Users/cltbld', 10:53:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:36 INFO - 'LOGNAME': 'cltbld', 10:53:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:36 INFO - 'MOZ_NO_REMOTE': '1', 10:53:36 INFO - 'NO_EM_RESTART': '1', 10:53:36 INFO - 'PAGER': '/bin/cat', 10:53:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:36 INFO - 'PWD': '/builds/slave/test', 10:53:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:36 INFO - 'SHELL': '/bin/bash', 10:53:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2HDcM5n4ej/Listeners', 10:53:36 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 10:53:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:36 INFO - 'USER': 'cltbld', 10:53:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:36 INFO - 'XPC_FLAGS': '0x0', 10:53:36 INFO - 'XPC_SERVICE_NAME': '0', 10:53:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:36 INFO - Downloading/unpacking psutil>=3.1.1 10:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:53:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:53:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:53:40 INFO - Installing collected packages: psutil 10:53:40 INFO - Running setup.py install for psutil 10:53:40 INFO - building 'psutil._psutil_osx' extension 10:53:40 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 10:53:40 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 10:53:40 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 10:53:41 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 10:53:41 INFO - building 'psutil._psutil_posix' extension 10:53:41 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 10:53:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:53:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:53:41 INFO - ^ 10:53:41 INFO - 1 warning generated. 10:53:41 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 10:53:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:53:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:53:41 INFO - Successfully installed psutil 10:53:41 INFO - Cleaning up... 10:53:41 INFO - Return code: 0 10:53:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:53:41 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')]} 10:53:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:41 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')]} 10:53:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:41 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 0x10cc70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9629462a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84d30>, '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 0x10c8aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca7cb20>, '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.Out2Tauc9c/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.2HDcM5n4ej/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 10:53:41 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 10:53:41 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 10:53:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Out2Tauc9c/Render', 10:53:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:41 INFO - 'HOME': '/Users/cltbld', 10:53:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:41 INFO - 'LOGNAME': 'cltbld', 10:53:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:41 INFO - 'MOZ_NO_REMOTE': '1', 10:53:41 INFO - 'NO_EM_RESTART': '1', 10:53:41 INFO - 'PAGER': '/bin/cat', 10:53:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:41 INFO - 'PWD': '/builds/slave/test', 10:53:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:41 INFO - 'SHELL': '/bin/bash', 10:53:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2HDcM5n4ej/Listeners', 10:53:41 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 10:53:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:41 INFO - 'USER': 'cltbld', 10:53:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:41 INFO - 'XPC_FLAGS': '0x0', 10:53:41 INFO - 'XPC_SERVICE_NAME': '0', 10:53:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:53:41 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 10:53:41 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 10:53:41 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 10:53:41 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 10:53:41 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 10:53:41 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 10:53:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:53:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:53:44 INFO - Installing collected packages: mozsystemmonitor 10:53:44 INFO - Running setup.py install for mozsystemmonitor 10:53:44 INFO - Successfully installed mozsystemmonitor 10:53:44 INFO - Cleaning up... 10:53:44 INFO - Return code: 0 10:53:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:53:44 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')]} 10:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:44 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')]} 10:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:44 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 0x10cc70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9629462a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84d30>, '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 0x10c8aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca7cb20>, '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.Out2Tauc9c/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.2HDcM5n4ej/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 10:53:44 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 10:53:44 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 10:53:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Out2Tauc9c/Render', 10:53:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:44 INFO - 'HOME': '/Users/cltbld', 10:53:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:44 INFO - 'LOGNAME': 'cltbld', 10:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:44 INFO - 'MOZ_NO_REMOTE': '1', 10:53:44 INFO - 'NO_EM_RESTART': '1', 10:53:44 INFO - 'PAGER': '/bin/cat', 10:53:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:44 INFO - 'PWD': '/builds/slave/test', 10:53:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:44 INFO - 'SHELL': '/bin/bash', 10:53:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2HDcM5n4ej/Listeners', 10:53:44 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 10:53:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:44 INFO - 'USER': 'cltbld', 10:53:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:44 INFO - 'XPC_FLAGS': '0x0', 10:53:44 INFO - 'XPC_SERVICE_NAME': '0', 10:53:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:44 INFO - Downloading/unpacking jsonschema==2.5.1 10:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:53:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:53:48 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 10:53:48 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 10:53:48 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 10:53:48 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 10:53:48 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 10:53:48 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 10:53:48 INFO - Downloading functools32-3.2.3-2.tar.gz 10:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:53:48 INFO - warning: no files found matching '*.txt' 10:53:48 INFO - no previously-included directories found matching 'build' 10:53:48 INFO - no previously-included directories found matching 'dist' 10:53:48 INFO - no previously-included directories found matching '.git*' 10:53:48 INFO - Installing collected packages: jsonschema, functools32 10:53:48 INFO - Running setup.py install for functools32 10:53:48 INFO - warning: no files found matching '*.txt' 10:53:48 INFO - no previously-included directories found matching 'build' 10:53:48 INFO - no previously-included directories found matching 'dist' 10:53:48 INFO - no previously-included directories found matching '.git*' 10:53:49 INFO - Successfully installed jsonschema functools32 10:53:49 INFO - Cleaning up... 10:53:49 INFO - Return code: 0 10:53:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:53:49 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')]} 10:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:49 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')]} 10:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:49 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 0x10cc70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9629462a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84d30>, '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 0x10c8aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca7cb20>, '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.Out2Tauc9c/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.2HDcM5n4ej/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 10:53:49 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 10:53:49 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 10:53:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Out2Tauc9c/Render', 10:53:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:49 INFO - 'HOME': '/Users/cltbld', 10:53:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:49 INFO - 'LOGNAME': 'cltbld', 10:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:49 INFO - 'MOZ_NO_REMOTE': '1', 10:53:49 INFO - 'NO_EM_RESTART': '1', 10:53:49 INFO - 'PAGER': '/bin/cat', 10:53:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:49 INFO - 'PWD': '/builds/slave/test', 10:53:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:49 INFO - 'SHELL': '/bin/bash', 10:53:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2HDcM5n4ej/Listeners', 10:53:49 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 10:53:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:49 INFO - 'USER': 'cltbld', 10:53:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:49 INFO - 'XPC_FLAGS': '0x0', 10:53:49 INFO - 'XPC_SERVICE_NAME': '0', 10:53:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:53:49 INFO - Cleaning up... 10:53:49 INFO - Return code: 0 10:53:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:53:49 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')]} 10:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:49 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')]} 10:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:49 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 0x10cc70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9629462a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84d30>, '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 0x10c8aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca7cb20>, '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.Out2Tauc9c/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.2HDcM5n4ej/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 10:53:49 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 10:53:49 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 10:53:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Out2Tauc9c/Render', 10:53:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:49 INFO - 'HOME': '/Users/cltbld', 10:53:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:49 INFO - 'LOGNAME': 'cltbld', 10:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:49 INFO - 'MOZ_NO_REMOTE': '1', 10:53:49 INFO - 'NO_EM_RESTART': '1', 10:53:49 INFO - 'PAGER': '/bin/cat', 10:53:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:49 INFO - 'PWD': '/builds/slave/test', 10:53:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:49 INFO - 'SHELL': '/bin/bash', 10:53:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2HDcM5n4ej/Listeners', 10:53:49 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 10:53:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:49 INFO - 'USER': 'cltbld', 10:53:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:49 INFO - 'XPC_FLAGS': '0x0', 10:53:49 INFO - 'XPC_SERVICE_NAME': '0', 10:53:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:49 INFO - Downloading/unpacking blobuploader==1.2.4 10:53:49 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 10:53:49 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 10:53:49 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 10:53:49 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 10:53:49 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 10:53:49 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 10:53:52 INFO - Downloading blobuploader-1.2.4.tar.gz 10:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:53:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:53:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53:53 INFO - Downloading docopt-0.6.1.tar.gz 10:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:53:53 INFO - Installing collected packages: blobuploader, requests, docopt 10:53:53 INFO - Running setup.py install for blobuploader 10:53:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:53:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:53:53 INFO - Running setup.py install for requests 10:53:54 INFO - Running setup.py install for docopt 10:53:54 INFO - Successfully installed blobuploader requests docopt 10:53:54 INFO - Cleaning up... 10:53:54 INFO - Return code: 0 10:53:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53:54 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')]} 10:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:54 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')]} 10:53:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:54 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 0x10cc70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9629462a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84d30>, '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 0x10c8aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca7cb20>, '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.Out2Tauc9c/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.2HDcM5n4ej/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 10:53:54 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 10:53:54 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 10:53:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Out2Tauc9c/Render', 10:53:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:54 INFO - 'HOME': '/Users/cltbld', 10:53:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:54 INFO - 'LOGNAME': 'cltbld', 10:53:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:54 INFO - 'MOZ_NO_REMOTE': '1', 10:53:54 INFO - 'NO_EM_RESTART': '1', 10:53:54 INFO - 'PAGER': '/bin/cat', 10:53:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:54 INFO - 'PWD': '/builds/slave/test', 10:53:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:54 INFO - 'SHELL': '/bin/bash', 10:53:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2HDcM5n4ej/Listeners', 10:53:54 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 10:53:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:54 INFO - 'USER': 'cltbld', 10:53:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:54 INFO - 'XPC_FLAGS': '0x0', 10:53:54 INFO - 'XPC_SERVICE_NAME': '0', 10:53:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:53:54 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-aHuFoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:53:54 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-a_B7Ef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:53:54 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-01tF7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:53:54 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-soZ3WS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:53:54 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-0rNmP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:53:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-EL0Kp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:53:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-1VujDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:53:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-L2QyaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:53:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-kHL6Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:53:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-hG11DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:53:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-j6b2aC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:53:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-SWKIUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:53:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-qEeIg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:53:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-RK28AB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-wlP89Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:53:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-2ONTUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:53:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-jmDGmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:53:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:53:56 INFO - Running setup.py install for manifestparser 10:53:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:53:56 INFO - Running setup.py install for mozcrash 10:53:56 INFO - Running setup.py install for mozdebug 10:53:56 INFO - Running setup.py install for mozdevice 10:53:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:53:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:53:56 INFO - Running setup.py install for mozfile 10:53:57 INFO - Running setup.py install for mozhttpd 10:53:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:53:57 INFO - Running setup.py install for mozinfo 10:53:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:53:57 INFO - Running setup.py install for mozInstall 10:53:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:53:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:53:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:53:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:53:57 INFO - Running setup.py install for mozleak 10:53:57 INFO - Running setup.py install for mozlog 10:53:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:53:57 INFO - Running setup.py install for moznetwork 10:53:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:53:57 INFO - Running setup.py install for mozprocess 10:53:58 INFO - Running setup.py install for mozprofile 10:53:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:53:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:53:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:53:58 INFO - Running setup.py install for mozrunner 10:53:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:53:58 INFO - Running setup.py install for mozscreenshot 10:53:58 INFO - Running setup.py install for moztest 10:53:58 INFO - Running setup.py install for mozversion 10:53:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:53:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:53:58 INFO - Cleaning up... 10:53:58 INFO - Return code: 0 10:53:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53:58 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')]} 10:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:58 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')]} 10:53:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:58 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 0x10cc70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9629462a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84d30>, '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 0x10c8aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca7cb20>, '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.Out2Tauc9c/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.2HDcM5n4ej/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 10:53:58 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 10:53:58 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 10:53:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Out2Tauc9c/Render', 10:53:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:58 INFO - 'HOME': '/Users/cltbld', 10:53:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:58 INFO - 'LOGNAME': 'cltbld', 10:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:58 INFO - 'MOZ_NO_REMOTE': '1', 10:53:58 INFO - 'NO_EM_RESTART': '1', 10:53:58 INFO - 'PAGER': '/bin/cat', 10:53:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:58 INFO - 'PWD': '/builds/slave/test', 10:53:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:58 INFO - 'SHELL': '/bin/bash', 10:53:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2HDcM5n4ej/Listeners', 10:53:58 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 10:53:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:58 INFO - 'USER': 'cltbld', 10:53:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:58 INFO - 'XPC_FLAGS': '0x0', 10:53:58 INFO - 'XPC_SERVICE_NAME': '0', 10:53:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:53:59 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-dpCRWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:53:59 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)) 10:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:53:59 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-yWaDLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:53:59 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-NYxJTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:53:59 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)) 10:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:53:59 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-LB7k6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:53:59 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)) 10:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:53:59 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-ksDHpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:53:59 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)) 10:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:53:59 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-GfMir5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:53:59 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)) 10:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:53:59 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-eizC73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:53:59 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)) 10:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:53:59 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-GzUwCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:54:00 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)) 10:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:54:00 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-iCNV2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:54:00 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)) 10:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:54:00 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-5YCMHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:54:00 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)) 10:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:54:00 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Q4TD8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:54:00 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)) 10:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:54:00 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-z3b1nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:54:00 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)) 10:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:54:00 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-6dGxqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:54:00 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)) 10:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:54:00 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-1mcIVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:54:00 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-lGvLkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:54:00 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)) 10:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:54:00 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-9n8CT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:54:00 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)) 10:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:54:00 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-KSBsCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:54:01 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)) 10:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:54:01 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)) 10:54:01 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)) 10:54:01 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)) 10:54:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:54:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:03 INFO - Downloading blessings-1.6.tar.gz 10:54:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:54:03 INFO - Installing collected packages: blessings 10:54:03 INFO - Running setup.py install for blessings 10:54:03 INFO - Successfully installed blessings 10:54:03 INFO - Cleaning up... 10:54:03 INFO - Return code: 0 10:54:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:54:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:54:04 INFO - Reading from file tmpfile_stdout 10:54:04 INFO - Current package versions: 10:54:04 INFO - blessings == 1.6 10:54:04 INFO - blobuploader == 1.2.4 10:54:04 INFO - docopt == 0.6.1 10:54:04 INFO - functools32 == 3.2.3-2 10:54:04 INFO - jsonschema == 2.5.1 10:54:04 INFO - manifestparser == 1.1 10:54:04 INFO - mozInstall == 1.12 10:54:04 INFO - mozcrash == 0.17 10:54:04 INFO - mozdebug == 0.1 10:54:04 INFO - mozdevice == 0.48 10:54:04 INFO - mozfile == 1.2 10:54:04 INFO - mozhttpd == 0.7 10:54:04 INFO - mozinfo == 0.9 10:54:04 INFO - mozleak == 0.1 10:54:04 INFO - mozlog == 3.4 10:54:04 INFO - moznetwork == 0.27 10:54:04 INFO - mozprocess == 0.23 10:54:04 INFO - mozprofile == 0.28 10:54:04 INFO - mozrunner == 6.12 10:54:04 INFO - mozscreenshot == 0.1 10:54:04 INFO - mozsystemmonitor == 0.3 10:54:04 INFO - moztest == 0.7 10:54:04 INFO - mozversion == 1.4 10:54:04 INFO - psutil == 3.1.1 10:54:04 INFO - requests == 1.2.3 10:54:04 INFO - wsgiref == 0.1.2 10:54:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:54:04 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 0x10cc70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9629462a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84d30>, '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 0x10c8aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca7cb20>, '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.Out2Tauc9c/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.2HDcM5n4ej/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 10:54:04 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 10:54:04 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 10:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Out2Tauc9c/Render', 10:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:04 INFO - 'HOME': '/Users/cltbld', 10:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:04 INFO - 'LOGNAME': 'cltbld', 10:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:04 INFO - 'MOZ_NO_REMOTE': '1', 10:54:04 INFO - 'NO_EM_RESTART': '1', 10:54:04 INFO - 'PAGER': '/bin/cat', 10:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:04 INFO - 'PWD': '/builds/slave/test', 10:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:04 INFO - 'SHELL': '/bin/bash', 10:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2HDcM5n4ej/Listeners', 10:54:04 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 10:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:04 INFO - 'USER': 'cltbld', 10:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:04 INFO - 'XPC_FLAGS': '0x0', 10:54:04 INFO - 'XPC_SERVICE_NAME': '0', 10:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:05 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)) 10:54:05 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)) 10:54:05 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)) 10:54:05 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)) 10:54:05 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)) 10:54:05 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)) 10:54:05 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)) 10:54:05 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)) 10:54:05 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)) 10:54:05 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)) 10:54:05 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)) 10:54:05 INFO - Cleaning up... 10:54:05 INFO - Return code: 0 10:54:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:54:05 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')]} 10:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:54:05 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')]} 10:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:54:05 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 0x10cc70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbe6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9629462a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca84d30>, '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 0x10c8aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca7cb20>, '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.Out2Tauc9c/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.2HDcM5n4ej/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 10:54:05 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 10:54:05 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 10:54:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Out2Tauc9c/Render', 10:54:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:05 INFO - 'HOME': '/Users/cltbld', 10:54:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:05 INFO - 'LOGNAME': 'cltbld', 10:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:05 INFO - 'MOZ_NO_REMOTE': '1', 10:54:05 INFO - 'NO_EM_RESTART': '1', 10:54:05 INFO - 'PAGER': '/bin/cat', 10:54:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:05 INFO - 'PWD': '/builds/slave/test', 10:54:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:05 INFO - 'SHELL': '/bin/bash', 10:54:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2HDcM5n4ej/Listeners', 10:54:05 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 10:54:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:05 INFO - 'USER': 'cltbld', 10:54:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:05 INFO - 'XPC_FLAGS': '0x0', 10:54:05 INFO - 'XPC_SERVICE_NAME': '0', 10:54:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:54:05 INFO - Cleaning up... 10:54:05 INFO - Return code: 0 10:54:05 INFO - Running post-action listener: _resource_record_post_action 10:54:05 INFO - Running post-action listener: _start_resource_monitoring 10:54:05 INFO - Starting resource monitoring. 10:54:05 INFO - [mozharness: 2016-10-31 17:54:05.772145Z] Finished create-virtualenv step (success) 10:54:05 INFO - [mozharness: 2016-10-31 17:54:05.773188Z] Running install step. 10:54:05 INFO - Running pre-action listener: _resource_record_pre_action 10:54:05 INFO - Running main action method: install 10:54:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:54:06 INFO - Reading from file tmpfile_stdout 10:54:06 INFO - Detecting whether we're running mozinstall >=1.0... 10:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:54:06 INFO - Reading from file tmpfile_stdout 10:54:06 INFO - Output received: 10:54:06 INFO - Usage: mozinstall [options] installer 10:54:06 INFO - Options: 10:54:06 INFO - -h, --help show this help message and exit 10:54:06 INFO - -d DEST, --destination=DEST 10:54:06 INFO - Directory to install application into. [default: 10:54:06 INFO - "/builds/slave/test"] 10:54:06 INFO - --app=APP Application being installed. [default: firefox] 10:54:06 INFO - mkdir: /builds/slave/test/build/application 10:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:54:40 INFO - Reading from file tmpfile_stdout 10:54:40 INFO - Output received: 10:54:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:54:40 INFO - Running post-action listener: _resource_record_post_action 10:54:40 INFO - [mozharness: 2016-10-31 17:54:40.761148Z] Finished install step (success) 10:54:40 INFO - [mozharness: 2016-10-31 17:54:40.761277Z] Running run-tests step. 10:54:40 INFO - Running pre-action listener: _resource_record_pre_action 10:54:40 INFO - Running main action method: run_tests 10:54:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:54:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:54:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:54:40 INFO - 2016-10-31 10:54:40.775 screenresolution[1630:7429] starting screenresolution argv=screenresolution get 10:54:40 INFO - 2016-10-31 10:54:40.778 screenresolution[1630:7429] Display 0: 1600x1200x32@60 10:54:40 INFO - 2016-10-31 10:54:40.784 screenresolution[1631:7431] starting screenresolution argv=screenresolution list 10:54:40 INFO - Available Modes on Display 0 10:54:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:54:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:54:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:54:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:54:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:54:41 INFO - Intel Iris: 10:54:41 INFO - Chipset Model: Intel Iris 10:54:41 INFO - Type: GPU 10:54:41 INFO - Bus: Built-In 10:54:41 INFO - VRAM (Dynamic, Max): 1536 MB 10:54:41 INFO - Vendor: Intel (0x8086) 10:54:41 INFO - Device ID: 0x0a2e 10:54:41 INFO - Revision ID: 0x0009 10:54:41 INFO - Displays: 10:54:41 INFO - Display: 10:54:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:54:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:54:41 INFO - Display Serial Number: 1600x1200 60 10:54:41 INFO - Main Display: Yes 10:54:41 INFO - Mirror: Off 10:54:41 INFO - Online: Yes 10:54:41 INFO - Rotation: Supported 10:54:41 INFO - Return code: 0 10:54:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:54:41 INFO - Python 2.7.3 10:54:41 INFO - Return code: 0 10:54:41 INFO - grabbing minidump binary from tooltool 10:54:41 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')]} 10:54:41 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 0x10ca84d30>, '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 0x10c8aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca7cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:54:41 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 10:54:41 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 10:54:41 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 10:54:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:54:41 INFO - Return code: 0 10:54:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:54:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:54:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:54:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:54:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:54:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.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 10:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.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 10:54:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Out2Tauc9c/Render', 10:54:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:41 INFO - 'HOME': '/Users/cltbld', 10:54:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:41 INFO - 'LOGNAME': 'cltbld', 10:54:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:54:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:54:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:41 INFO - 'MOZ_NO_REMOTE': '1', 10:54:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:54:41 INFO - 'NO_EM_RESTART': '1', 10:54:41 INFO - 'PAGER': '/bin/cat', 10:54:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:41 INFO - 'PWD': '/builds/slave/test', 10:54:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:54:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:41 INFO - 'SHELL': '/bin/bash', 10:54:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2HDcM5n4ej/Listeners', 10:54:41 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 10:54:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:41 INFO - 'USER': 'cltbld', 10:54:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:41 INFO - 'XPC_FLAGS': '0x0', 10:54:41 INFO - 'XPC_SERVICE_NAME': '0', 10:54:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.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 10:54:41 INFO - mozversion application_buildid: 20161031084722 10:54:41 INFO - mozversion application_changeset: 8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096 10:54:41 INFO - mozversion application_display_name: Nightly 10:54:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:54:41 INFO - mozversion application_name: Firefox 10:54:41 INFO - mozversion application_remotingname: firefox 10:54:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:54:41 INFO - mozversion application_vendor: Mozilla 10:54:41 INFO - mozversion application_version: 52.0a1 10:54:41 INFO - mozversion platform_buildid: 20161031084722 10:54:41 INFO - mozversion platform_changeset: 8c9eed5227f8681cf08d2ee8fb3bfd5d743e4096 10:54:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:54:41 INFO - mozversion platform_version: 52.0a1 10:54:41 INFO - using testdate: 1477936481 10:54:41 INFO - actual date: 1477936481 10:54:41 INFO - starting webserver on 'localhost:49273' 10:54:41 INFO - SUITE-START | Running 2 tests 10:54:41 INFO - TEST-START | dromaeo_css 10:54:41 INFO - operating with platform_type : mac_ 10:54:41 INFO - Initialising browser for dromaeo_css test... 10:54:41 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp8pUmXj/profile http://localhost:49273/getInfo.html) 10:54:43 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 10:54:43 INFO - PROCESS | 1641 | colorDepth:24 10:54:43 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 10:54:43 INFO - PROCESS | 1641 | __metrics 10:54:43 INFO - PROCESS | 1641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:54:43 INFO - PROCESS | 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:54:44 INFO - TEST-INFO | 1641: exit 0 10:54:44 INFO - Browser initialized. 10:54:44 INFO - Running cycle 1/1 for dromaeo_css test... 10:54:44 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp8pUmXj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:54:56 INFO - PROCESS | 1643 | RSS: Main: 233086976 10:54:56 INFO - PROCESS | 1643 | 10:55:48 INFO - PROCESS | 1643 | 1477936548123 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] 10:58:02 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 10:58:03 INFO - PROCESS | 1643 | RSS: Main: 358891520 10:58:03 INFO - PROCESS | 1643 | 11:01:06 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 11:01:07 INFO - PROCESS | 1643 | RSS: Main: 368496640 11:01:07 INFO - PROCESS | 1643 | 11:04:09 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 11:04:10 INFO - PROCESS | 1643 | RSS: Main: 333201408 11:04:10 INFO - PROCESS | 1643 | 11:07:12 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 11:07:13 INFO - PROCESS | 1643 | RSS: Main: 343875584 11:07:13 INFO - PROCESS | 1643 | 11:10:16 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 11:10:17 INFO - PROCESS | 1643 | RSS: Main: 329175040 11:10:17 INFO - PROCESS | 1643 | 11:13:20 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 11:13:20 INFO - PROCESS | 1643 | RSS: Main: 470212608 11:13:20 INFO - PROCESS | 1643 | 11:13:20 INFO - PROCESS | 1643 | __start_tp_report 11:13:20 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 11:13:20 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 11:13:20 INFO - PROCESS | 1643 | |i|pagename|runs| 11:13:20 INFO - PROCESS | 1643 | |0;dojo.html;3313.536104945307;3314.5193946877716;3341.7159541438978;3348.11275012122;3367.2592029753446;5542.4457554244555;5557.638753481021;5618.634788738729;5640.943590564093;5674.290713660793;5599.720013999304;5613.663180209191;5683.318001839774;5707.086866101425;5708.514776244025;5029.446760856303;5049.823256186034;5054.1913293872985;5100.311457953185;5128;5171.250168725533;5171.793128274864;5183.974080129613;5206;5233.921491177635;5066.41736200338;5094.363204599425;5154.793808247666;5165.04446677364;5175.094358487249;5113.718745468997;5121.513456221653;5135.460776618439;5141.922871156936;5144.845654630367;5076.796928122871;5096.311997880276;5099.770510327045;5108.4125325587665;5112.846614601568;5555.083411237141;5572.693501857396;5577.079781835992;5577.386487486373;5594.944050559483;6085.9695701521205;6087.695615219265;6098.908516372258;6100.633961962297;6105.755769769204;6115.7859474918605;6124.910294234916;6126.090206827809;6139.430809034335;6142.266691322363;5604.411536788619;5675.858103547403;5697.6296540724725;5700.629459085146;5718.199452076713;6184.598001130003;6198.907016394758;6206.038064100072;6219.844503887439;6224.408681175281;6091.299500557493;6095.6952152392205;6097.908531372033;6098.176746139303;6114.571979961449;5005.424376196784;6092.299385570716;6105.581053426346;6110.445431087209;6135.601185922901;6058.1518587397795;6074.848128796815;6079.8480037998515;6087.452129308295;6116.755329786759;6133.233345831771;6134.6625935573975;6136.6011209271255;6140.324564297924;6142.293636231801;4767.4517430495935;4819.108464933998;4824.324594556765;4845.370101886732;4847.927281090787;3960.584138665456;4272.316429371285;4389.736615803062;4396.494403143615;4407.228734971367;5680.659160450304;5689;5707.914381284284;5709.400512946164;5720.2849643794525;6110.771402862501;6129.417705317987;6135.110179431545;6142.682951206299;6183.536234782409;5721.39925307845;5726.455986681259;5757.798477053367;5775.27809023872;5776.133579962955;5324.627276090637;5347.117725575236;5366.356037275552;5370.301860758076;5374.785008599612;5929.703514824241;6158;6234.407731265523;6254.468370188585;6260.060990851227;6131.315499379051;6169.043798211284;6182;6186.566940314315;6229.190205273286;6168.845778855564;6223.37766223374;6239.96880015597;6242.250929888442;6261.530385221217;5951.256092988377;6104.4200800923845;6145.938540614537;6155.446009859134;6161.9691901542;6103.572749907464;6116.914073580098;6128.233970753656;6150.692465376624;6215.844603884758;5011.847275126668;5320.669832541865;5789.189513468202;6097.2683278006925;6098.024316109401;6049.969750151221;6055.122007308827;6059.000264956409;6061.060535616986;6073.99779036454;5699.857503562444;5812.476877081083;5825.126231065374;5827.883442331215;5880.794172203952;6040.909386359121;6046.818595442144;6057.4851137653795;6060.969695151496;6067.908981365194;5729.455701708331;5811.535077193899;5857.619254748428;5891.941080589139;5948.226730525004 11:13:20 INFO - PROCESS | 1643 | |1;ext.html;444.51103785835573;468.1736734663322;474.7175430618781;520.5159201942192;525;29319.267018324554;38447.42328865066;39624;39744.80765577034;40016;39840.40239396411;40348.19303613926;40767.796161019156;40810.183796324054;40856.38715419271;9341.205997490222;9517.714468565953;9538.523073846314;9541.093596108358;9579.042095790435;9440.763980900463;9562.235021198287;9565.426074435556;9566.426014439156;9567.808643827118;9529.42823430593;9580.281478889112;9580.56887440067;9596.28027897907;9661.130498255156;9527.952360238223;9601.231901447867;9608.471534065624;9612.95193524035;9674.613015479372;9314.580843862042;9408;9411.62353505859;9424.575894084783;9435.386699864555;38986.61013389887;39302.60697393018;39325.60674393248;39348.60651393478;39366.40950385747;3328.234506063619;3504.088936876405;3565.6612621800887;3851.5763266040713;3862.671672907806;30003.849980750176;30063.549046764318;30270.54594181089;31248.843755781076;31270;3127.202563346355;3129.5931528901324;3149.794784586143;3163.7943533670236;3242.7567932405163;476600;480710;481397.5930120327;481610.5919470415;493170;56524.43475565192;56935.007274745185;56942;57047;57105;33147.17132071669;33853.83073084619;33926.49110263348;59313.70343148343;59423;12829.422675979604;14928.32822522989;15463.994840335343;15524.37902483888;15586;11697;11828.053755699524;11834.704132396759;11835.99394051498;11839.28964262147;15508.689826203412;17345.525630321565;18127.09364531768;18204.45386638403;18260.543486412946;6255.311915689271;7415.739324314879;7437.665305061285;7486.5508069516;7490.775276741707;828.7099515169642;887.3611000079932;932.1461223516194;1044.6239353832614;1068.967930962057;29321.85339073291;29644.555331670043;29757;29932.40135197284;30185.849070754946;972.8005758819424;976.8730065091493;1034.8874959418504;1039.9064084232455;1081.4592703648175;14715.411383544539;15162;15249.695006099815;15269.694606107816;15670.529884103495;646013;652625;654226;654365;656182.7190864015;54341;54848;54865.451345486836;54908.725456372464;54969.72515137479;1151.7984352738238;1198.1912209258714;1277.444311724403;1310.4037662863375;1329.1825527300448;23843.642345364486;28947.421051579273;29403.11790646284;30300.69699302979;30935.91724289639;47650.52349476599;51565.742171288905;53150.734246328524;53226.73386633042;55277;517615;523598;524030;524187;524329;33432.1641958953;34035.14912127217;38075;39159.216815662956;39766.60233397745;44810.327845083004;44944.77527612341;45731.68293073092;45922;46443.76778116088;37828;38341.80829095837;38385.4242186373;38758.224835502566;40053.99865003398;31481.898133565217;35553.933381998584;37239.62760372362;38240.61759382371;38364.61635383722;34867.30265394627;37979;38005.42991855069;38039;38925;28902.132936011953;29578.46562082317;31814.840925795223;33768.83115584406;33801.15497112592;21657.675134872676;24316;24349.173811964527;25256;25998 11:13:20 INFO - PROCESS | 1643 | |2;jquery.html;293.39414109863117;369.2467366572193;369.37391122048126;378.40023562653187;400.2355500993104;14495.420183192673;14801.925990370046;15545.300461479232;19828.603427931434;20440;14210.644733881658;18445.354412595563;19361.709574356362;19576.510587235327;19652.60694786107;5725.026745453272;6097.634141951475;6119.3880611938675;6120.724567394478;6123.050927106284;5774.913376299359;5980.521558275349;5987.43119403656;6051.062085376773;6069.30203026651;5949.791757288495;6004.939950600482;6004.96997515014;6013.609115407507;6019.337872834007;4877.268409738532;5354.705491197982;6043;6047.213862197887;6076.301225359096;5019.14674505335;5243.79024839006;5245.134552798783;5247.501487358695;5252.6323157379;24631.38421539458;24917.501649967082;24979;25292.620610690854;25368.492630147295;2183.235867446397;2321.3964369263945;2530.936726581832;2623.73762623738;2922.3716900866416;14724.558263252013;15031.549053528412;15289.464868729541;15623.843761562353;15911.60220994473;1752.4304601004596;1830.6155707301482;1849.5098798818324;1862.245790454868;1894.9526261843425;83396;105280;106205.46897265542;110809;114553;10758.73103172427;10831.729206769736;11054.170937179582;11057.336559806437;11214.158938079676;13834.93082534581;13865.861341386457;13867.23730194849;13897.583072507841;13903.791443128159;8246.299064579458;8404.579771011426;8419.494830310201;8560.871586926201;8572.228499435081;8304.88225502493;8362.867468795539;8384.790380240544;8474.830503389898;8503.489790612583;6342.778002769881;6593.934060659428;6602.801915942529;6607.240167380716;6616.437602803816;1791.6327152933618;1829.5609053826986;1835.5686413692772;1839.4481655503296;1840.355875443584;847.6651722569674;856.1438561438562;902.5171533229822;902.5487256371814;907.8365894138987;20641.793582063987;21765.891170544044;21800.89099554492;21806;21832.126714931226;889;953.3231405702029;970.8543718442149;994.3735446668552;1003.0721582536125;10930.234883558234;11383.943080284545;11507.76984460306;11560.959513643644;11583.884161158281;548503;551952;562773;570480;575017;80681.59659201666;103456.48271758592;105858;109195.36206956902;110752.4462377683;913.1188403190953;965.8937516872998;987.5654711926729;992.7468495533593;995.0447570332561;18961.43115706531;19355.903220483808;19379.806201938365;19440.902795486498;19442.708359374905;48496.5150348492;54626.72686636541;55655;56636;57078.42921570897;74325.14187145364;75867.62066189633;77720.2227977713;77799;78749;7823.8435231293915;7972.880406794019;8076.232757887991;8152.470089444168;8227.670893164206;12085.456154473246;12626.810597840857;12840.61478155657;13247.602571922858;13325.13386629866;10969.012788849042;11015.669529914116;11433.371164586028;11461.598844040753;11600.535978560763;8845.911540884767;10994.230403871652;11086.22396432242;11168.441577921234;11378.658640240807;11448.566819704678;11571.6528504145;11623.6512904613;11842.703932401759;11848.170628055954;6762.830929226809;7989.440739148204;8114.06688230861;8231.42380033392;8341.24928756415;10347;11251.324920504796;11530.76938461243;11568.421578921188;11633.709157270798 11:13:20 INFO - PROCESS | 1643 | |3;mootools.html;1228.5085965613757;1280.2958372894905;1420.7513685105105;1744.1540852686437;1770.1060964213063;7600.2779735925;8080.39397045221;8506.489610623366;8512.70205542806;8530.019047809496;7639.427042971785;7800.0639923209155;8159.6736130554855;8395.664173433055;8400.621972011259;2168.8481806273567;2307.5615633029756;2337.497438050819;2341.1103780563362;2347.1550241912983;2114.154338264691;2314.826388020897;2357.5874222011166;2359.893804778789;2361.929142125739;1847.9168437420349;2301.1945818963395;2307.7692230776956;2324.755900630435;2337.5091230841545;2288.027588274993;2292.358139720881;2310.2260742651233;2311.306608017588;2315.0508291600363;1944.2417457191707;2309.168699268262;2329.452578644017;2332.720073591163;2339.2865176121277;8421.621027053798;8525.147485251488;8527.530985795778;8547.273481754059;8562.143785621389;5055.69665820052;5193.506616871391;5199.012187684328;5201.635885488017;5209.635325527177;3223.17808958714;3314.254292784128;3514.7539672222956;3533.9293214135832;3584.8566057357675;1021.7956408718212;1040.95836166554;1041.2242879055152;1059.819830628795;1066.8133076711622;9345.766355841159;11026.724331891686;11053.834192487038;11232.269902456314;11239.213255072149;4062.055010117641;4420.80106395213;4643.767811609406;4669.509701481364;4670;9478.95260523693;9485.383450075724;9498;9505.42967421957;9512.476813775309;5166.715830629351;5172;5180.197069454241;5180.326557547494;5200.557952573998;4251.574842515785;4380.890477738082;4597.862064138081;4745.1221524018165;4753.881152971203;2928.6192794936524;3697;3752.718546108998;3798.4872042273937;3825.5218097737784;1208.3535308610028;1208.6434501821807;1213.4842691855927;1218.4943248551929;1220.4934951994824;852.4885068958699;870.9346798990101;872.3936863879669;906.2161230535539;912.0241341764248;4484.013517026249;5675.120356344773;5790.668146326284;5874.676892770939;6095.207623009069;896.8341156496529;918.4810582021137;931.4816848536841;941.2422999485343;966.9613215471302;3721.1999420124803;3763.7365384423374;3815.378464152724;3823.235352929425;3841.4429907663234;9577.664781732745;10212.795744085226;10389.636362727268;10391.636292729869;10417.583296668048;9991.750206244902;10231.693049208536;10362.015608517178;10371.377717336984;10378.896211037792;7898.960505197438;8044.155363686846;8044.235797599219;8070.717524886718;8078.192180781874;5511.669299842022;5559.972200138974;5637.49262566371;5690.089585666439;5716.228309178207;9496.667616633385;9559.474228917476;9581.089796469325;9589.993050729572;9613.327067105236;9463.479508627091;9611;9679;9709.951450242703;9864.605415783288;6638.76764313266;6754.932450675627;6796.864062718817;6904.27505111956;6943.409810166192;7883.960580197062;7991.760247192594;7994.960025199837;7996.720114795954;8011.198880112175;6990.895136573057;7129.224037833882;7132.108486439195;7312.853742925218;7432.182459929295;7002.929970700432;7084.64576771149;7111.786646400409;7134.250903655042;7305.671244794104;5567.498925096761;6670.899936500859;6800.591964482147;6822.11312529368;6834.555753875982;4338.848140315074;4771.23660214364;4779.784909679003;4799.808007679654;4939.407271127488;4901.41183058021;4902.166631672553;4906;4921.753912304442;4981.128302547112 11:13:20 INFO - PROCESS | 1643 | |4;prototype.html;296.21059875431973;296.6810678520591;304.8400834823497;310.52644716806867;312.8029341514845;4518.661100417466;4799.928001079987;5091.592672586194;5113.565346945509;5138.203578445342;4738.312944623028;4886.51134886512;4891.266310053494;5019.29729837823;5061.746912654371;3044.5433185022257;3642.271545690859;3656.835442405098;3948.498711468928;3991.5210174779068;3850.653441190292;3978.3833505806647;3991.1419044905338;3995.8601448949294;4001.2997725398086;3162.905112846618;3908.159745654684;3915.4322623219614;3943.7830919299427;3956.3867600521967;3745.550533935918;3810.79040652764;3926.725129240954;3938.8621398251066;3949.64453199212;2885.653721553406;3455;3479.425894727367;3484.790912545255;3487.4420092785285;4526.79629416677;4671.626269898434;4738.95261047392;4963.716690322456;4998.500149985009;4422.181896349153;4430.379746835446;4440.511543730187;4449.087936972913;4470.195364834329;4783.856484305476;4809.975950120227;5352.411234764173;5396.271503347077;5402.081646120129;3483.5471388719557;3731.81340932955;3735.252949410102;3740.532433445819;3748.756330838487;5169.327987361619;5544.750486228129;5636.520895723868;5645.233835420697;5655.971720141373;3286.260591366923;3652.8721494747556;3777.1690228149764;3778.112143646241;3804.4483549885113;3598.370285200132;3707.647773461517;3843.404272337792;3859.7877116758846;3864.9806750966063;3763.158815094586;4195.559466256061;4234.470691163605;4330.588594083557;4398.934015989763;4826.104337397604;5040.521150490697;5060.746962651852;5460.099083651233;5467.726613669301;1070.848837499691;1275.432432567517;1277.6550331410654;1287.3370214339623;1309.463120120746;749.5952185819597;824.2169938558394;842.3682238321259;848.8005318750089;849.4436144325476;4949.802007919643;4956.281339205867;5035.446100928895;5037.697738135724;5043.52086551777;5100.464451232641;5386.380566234933;5395.487428694268;5426.213199086111;5433.945660543423;4940.629452791055;4953.306537084811;4956.959038601862;4965.577925876341;4976.42771081323;2820.8857501286875;2844.6728626207837;2845.2317874174273;2852.800303978702;2860.3850172213074;4892.755362231874;5360.276362691065;5567.693776842231;5598.608097433222;5728.570357223225;5188.714620695822;5600.467955544217;5755.65466072037;5777.913331300034;5826.708664566755;5299.330710826078;5776.393478684761;5825.126231065289;5844.240248767633;5916.408359164049;4261.806694125526;4632.837850675211;5374.946250537445;5545.611807173459;5645.943540564706;4990.552739705445;5109.3613298337705;5174.508421699932;5350.545203657733;5478.506934375925;4894.706317620954;5168.095583272988;5507.201455788968;5543.667379957215;5669.922714684197;3575.9604336313923;3668.7248456365346;3707.7404581679025;3745.5692595350793;3757.8308976096687;4603.631709463302;4764.952350476451;5562.944370556243;5594.916076258941;5601.551875849841;5251.921221181762;5432.348118225838;5439.5648348132845;5440.401555828935;5454.563634909277;4913.877153071059;5320.201969704575;5451.48210919962;5469.917951230815;5487.42382049895;4667.929981050219;5083.2384035813975;5168.250603662373;5281.102212623787;5398.352197736297;4530.27515597516;5374.7312634367345;5385.649932754359;5408.8377348679605;5430.072324324808;4850.393700787402;5221.530062294411;5232.136697445031;5232.476752324889;5235.973820130875 11:13:20 INFO - PROCESS | 1643 | |5;yui.html;577.9797707080252;625.9937400625993;646.7671638210244;747.514115824714;759.525296689569;14232.786508202385;14384.42462301508;14734.557963261093;14742.484013059546;14751.336189871468;12683.873161268384;13039.282839443827;13277.40251688674;13284;13368.930485561155;601.7111786342558;620.7051650466037;759.6391713935864;772.0927909706073;773.9845203095934;611.5505103748741;698.6646409723336;720.1574158234852;776.7786180938434;778.2062296457638;806.657170702452;807.0355924669992;807.0436532708729;807.2169995104769;811.7564730580832;802.7150361621657;803.6062329458582;803.702630026888;804.851102546028;804.9597520123999;467.66561908235434;473.59744217415266;573.8106424879779;586.050598031188;592.1413949772804;13861.653458663513;13864.653383665389;13887.722245555133;13893;13908.165510069328;12462.43919023646;12497.125201235916;12728.554500592527;12825.16636418639;12838.165519241313;9844.310898237127;11512.194146409754;11683.30592064146;11729.70675733105;12037.819432708517;4263.104748002923;4304.33282841157;4354.847580334705;4461.193216747222;4519.706219095749;65627.01559476611;85266;85948.57025714831;86320.8419789504;86438.70341944876;10016;10054.195664346835;10073.345232559861;10103.595856165817;10509;18773;18784.248630054644;18814.341498047503;18820.43538693814;18854.52863678419;7250.528715633467;7304.160021597478;7324.523905946096;7415.629218539051;7538.585377804274;4080.81636326366;4146.91706165881;4163.146554956226;4165.708400411942;4168.2080404723;3157.258044359574;3213.1485156433564;3236.385086833494;3246.62663793662;3251.3009702913514;518.9532942035235;604.1813342920368;608.0817964873073;614.5390956782413;619.4115590189339;537.1888448442879;548.5721137512746;562.9943700562942;582.6533212738414;593.4777395891588;8215.507069575844;8305.875411868827;8313.958430207811;8424.283935865398;8939.418937769155;516.8837011672343;527.9683219006872;542.0514100324432;568.5054003017401;608.1273372710175;3549.7870127792416;4108.4864391951005;4122.9381559276635;4210.536840947493;4273.7008409411665;106617.46691266494;108208;109127;109324.4533777342;113925;79677.60161199272;81160;84699.72950405748;92199.53900230456;99645;72440.55118897592;74099;75879.62060189663;80998;83599.16400836036;9278.443293402417;9444.36716281461;9481.004494528252;9553.61785528571;9567.856482152913;47618.761906190244;51553;52713.7364313176;53163.93672126458;54132.72933635306;71703.64148179226;74066.25933740809;74366.88449673151;75243;76585;11782.52869885195;11874.287542747463;12147.45336459879;12188.878111219128;12285.57000504978;13413.932930335286;13533.729325413633;13609.591712248648;13614.863851361359;14135.15189088658;12544.274054534531;12858.485660573473;12929.676758081123;13237.205767653972;13253.271070091238;12227.938860305641;12310.358911014431;12310.81533777012;12416.503339866666;12605.369731513205;12049;12415.62753117408;12435.564755233523;12958.22250664963;12979.610611681665;9320.648505966732;9984.35101718386;10585.417802020962;10774.892251077703;11125.944370278097;10809.45952702346;10935.45322733876;10969.122470202525;10999.395033273247;11005.449727513753 11:13:20 INFO - PROCESS | 1643 | __end_tp_report 11:13:20 INFO - PROCESS | 1643 | __start_cc_report 11:13:20 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,1343 11:13:20 INFO - PROCESS | 1643 | __end_cc_report 11:13:20 INFO - PROCESS | 1643 | __startTimestamp1477937600734__endTimestamp 11:13:20 INFO - PROCESS | 1643 | 11:13:20 INFO - PROCESS | 1643 | ------- Summary: start ------- 11:13:20 INFO - PROCESS | 1643 | Number of tests: 6 11:13:20 INFO - PROCESS | 1643 | 11:13:20 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5649.78 Median:5812.48 stddev:635.40 (10.9%) stddev-sans-first:610.34 11:13:20 INFO - PROCESS | 1643 | Values: 3313.5 3314.5 3341.7 3348.1 3367.3 5542.4 5557.6 5618.6 5640.9 5674.3 5599.7 5613.7 5683.3 5707.1 5708.5 5029.4 5049.8 5054.2 5100.3 5128.0 5171.3 5171.8 5184.0 5206.0 5233.9 5066.4 5094.4 5154.8 5165.0 5175.1 5113.7 5121.5 5135.5 5141.9 5144.8 5076.8 5096.3 5099.8 5108.4 5112.8 5555.1 5572.7 5577.1 5577.4 5594.9 6086.0 6087.7 6098.9 6100.6 6105.8 6115.8 6124.9 6126.1 6139.4 6142.3 5604.4 5675.9 5697.6 5700.6 5718.2 6184.6 6198.9 6206.0 6219.8 6224.4 6091.3 6095.7 6097.9 6098.2 6114.6 5005.4 6092.3 6105.6 6110.4 6135.6 6058.2 6074.8 6079.8 6087.5 6116.8 6133.2 6134.7 6136.6 6140.3 6142.3 4767.5 4819.1 4824.3 4845.4 4847.9 3960.6 4272.3 4389.7 4396.5 4407.2 5680.7 5689.0 5707.9 5709.4 5720.3 6110.8 6129.4 6135.1 6142.7 6183.5 5721.4 5726.5 5757.8 5775.3 5776.1 5324.6 5347.1 5366.4 5370.3 5374.8 5929.7 6158.0 6234.4 6254.5 6260.1 6131.3 6169.0 6182.0 6186.6 6229.2 6168.8 6223.4 6240.0 6242.3 6261.5 5951.3 6104.4 6145.9 6155.4 6162.0 6103.6 6116.9 6128.2 6150.7 6215.8 5011.8 5320.7 5789.2 6097.3 6098.0 6050.0 6055.1 6059.0 6061.1 6074.0 5699.9 5812.5 5825.1 5827.9 5880.8 6040.9 6046.8 6057.5 6061.0 6067.9 5729.5 5811.5 5857.6 5891.9 5948.2 11:13:20 INFO - PROCESS | 1643 | 11:13:20 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:67991.04 Median:39337.11 stddev:149009.58 (378.8%) stddev-sans-first:149341.29 11:13:20 INFO - PROCESS | 1643 | Values: 444.5 468.2 474.7 520.5 525.0 29319.3 38447.4 39624.0 39744.8 40016.0 39840.4 40348.2 40767.8 40810.2 40856.4 9341.2 9517.7 9538.5 9541.1 9579.0 9440.8 9562.2 9565.4 9566.4 9567.8 9529.4 9580.3 9580.6 9596.3 9661.1 9528.0 9601.2 9608.5 9613.0 9674.6 9314.6 9408.0 9411.6 9424.6 9435.4 38986.6 39302.6 39325.6 39348.6 39366.4 3328.2 3504.1 3565.7 3851.6 3862.7 30003.8 30063.5 30270.5 31248.8 31270.0 3127.2 3129.6 3149.8 3163.8 3242.8 476600.0 480710.0 481397.6 481610.6 493170.0 56524.4 56935.0 56942.0 57047.0 57105.0 33147.2 33853.8 33926.5 59313.7 59423.0 12829.4 14928.3 15464.0 15524.4 15586.0 11697.0 11828.1 11834.7 11836.0 11839.3 15508.7 17345.5 18127.1 18204.5 18260.5 6255.3 7415.7 7437.7 7486.6 7490.8 828.7 887.4 932.1 1044.6 1069.0 29321.9 29644.6 29757.0 29932.4 30185.8 972.8 976.9 1034.9 1039.9 1081.5 14715.4 15162.0 15249.7 15269.7 15670.5 646013.0 652625.0 654226.0 654365.0 656182.7 54341.0 54848.0 54865.5 54908.7 54969.7 1151.8 1198.2 1277.4 1310.4 1329.2 23843.6 28947.4 29403.1 30300.7 30935.9 47650.5 51565.7 53150.7 53226.7 55277.0 517615.0 523598.0 524030.0 524187.0 524329.0 33432.2 34035.1 38075.0 39159.2 39766.6 44810.3 44944.8 45731.7 45922.0 46443.8 37828.0 38341.8 38385.4 38758.2 40054.0 31481.9 35553.9 37239.6 38240.6 38364.6 34867.3 37979.0 38005.4 38039.0 38925.0 28902.1 29578.5 31814.8 33768.8 33801.2 21657.7 24316.0 24349.2 25256.0 25998.0 11:13:20 INFO - PROCESS | 1643 | 11:13:20 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:32979.76 Median:13996.12 stddev:93176.49 (665.7%) stddev-sans-first:93405.55 11:13:20 INFO - PROCESS | 1643 | Values: 293.4 369.2 369.4 378.4 400.2 14495.4 14801.9 15545.3 19828.6 20440.0 14210.6 18445.4 19361.7 19576.5 19652.6 5725.0 6097.6 6119.4 6120.7 6123.1 5774.9 5980.5 5987.4 6051.1 6069.3 5949.8 6004.9 6005.0 6013.6 6019.3 4877.3 5354.7 6043.0 6047.2 6076.3 5019.1 5243.8 5245.1 5247.5 5252.6 24631.4 24917.5 24979.0 25292.6 25368.5 2183.2 2321.4 2530.9 2623.7 2922.4 14724.6 15031.5 15289.5 15623.8 15911.6 1752.4 1830.6 1849.5 1862.2 1895.0 83396.0 105280.0 106205.5 110809.0 114553.0 10758.7 10831.7 11054.2 11057.3 11214.2 13834.9 13865.9 13867.2 13897.6 13903.8 8246.3 8404.6 8419.5 8560.9 8572.2 8304.9 8362.9 8384.8 8474.8 8503.5 6342.8 6593.9 6602.8 6607.2 6616.4 1791.6 1829.6 1835.6 1839.4 1840.4 847.7 856.1 902.5 902.5 907.8 20641.8 21765.9 21800.9 21806.0 21832.1 889.0 953.3 970.9 994.4 1003.1 10930.2 11383.9 11507.8 11561.0 11583.9 548503.0 551952.0 562773.0 570480.0 575017.0 80681.6 103456.5 105858.0 109195.4 110752.4 913.1 965.9 987.6 992.7 995.0 18961.4 19355.9 19379.8 19440.9 19442.7 48496.5 54626.7 55655.0 56636.0 57078.4 74325.1 75867.6 77720.2 77799.0 78749.0 7823.8 7972.9 8076.2 8152.5 8227.7 12085.5 12626.8 12840.6 13247.6 13325.1 10969.0 11015.7 11433.4 11461.6 11600.5 8845.9 10994.2 11086.2 11168.4 11378.7 11448.6 11571.7 11623.7 11842.7 11848.2 6762.8 7989.4 8114.1 8231.4 8341.2 10347.0 11251.3 11530.8 11568.4 11633.7 11:13:20 INFO - PROCESS | 1643 | 11:13:20 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5458.61 Median:5176.10 stddev:3040.13 (58.7%) stddev-sans-first:3032.03 11:13:20 INFO - PROCESS | 1643 | Values: 1228.5 1280.3 1420.8 1744.2 1770.1 7600.3 8080.4 8506.5 8512.7 8530.0 7639.4 7800.1 8159.7 8395.7 8400.6 2168.8 2307.6 2337.5 2341.1 2347.2 2114.2 2314.8 2357.6 2359.9 2361.9 1847.9 2301.2 2307.8 2324.8 2337.5 2288.0 2292.4 2310.2 2311.3 2315.1 1944.2 2309.2 2329.5 2332.7 2339.3 8421.6 8525.1 8527.5 8547.3 8562.1 5055.7 5193.5 5199.0 5201.6 5209.6 3223.2 3314.3 3514.8 3533.9 3584.9 1021.8 1041.0 1041.2 1059.8 1066.8 9345.8 11026.7 11053.8 11232.3 11239.2 4062.1 4420.8 4643.8 4669.5 4670.0 9479.0 9485.4 9498.0 9505.4 9512.5 5166.7 5172.0 5180.2 5180.3 5200.6 4251.6 4380.9 4597.9 4745.1 4753.9 2928.6 3697.0 3752.7 3798.5 3825.5 1208.4 1208.6 1213.5 1218.5 1220.5 852.5 870.9 872.4 906.2 912.0 4484.0 5675.1 5790.7 5874.7 6095.2 896.8 918.5 931.5 941.2 967.0 3721.2 3763.7 3815.4 3823.2 3841.4 9577.7 10212.8 10389.6 10391.6 10417.6 9991.8 10231.7 10362.0 10371.4 10378.9 7899.0 8044.2 8044.2 8070.7 8078.2 5511.7 5560.0 5637.5 5690.1 5716.2 9496.7 9559.5 9581.1 9590.0 9613.3 9463.5 9611.0 9679.0 9710.0 9864.6 6638.8 6754.9 6796.9 6904.3 6943.4 7884.0 7991.8 7995.0 7996.7 8011.2 6990.9 7129.2 7132.1 7312.9 7432.2 7002.9 7084.6 7111.8 7134.3 7305.7 5567.5 6670.9 6800.6 6822.1 6834.6 4338.8 4771.2 4779.8 4799.8 4939.4 4901.4 4902.2 4906.0 4921.8 4981.1 11:13:20 INFO - PROCESS | 1643 | 11:13:20 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4361.12 Median:4927.25 stddev:1332.69 (27.0%) stddev-sans-first:1301.03 11:13:20 INFO - PROCESS | 1643 | Values: 296.2 296.7 304.8 310.5 312.8 4518.7 4799.9 5091.6 5113.6 5138.2 4738.3 4886.5 4891.3 5019.3 5061.7 3044.5 3642.3 3656.8 3948.5 3991.5 3850.7 3978.4 3991.1 3995.9 4001.3 3162.9 3908.2 3915.4 3943.8 3956.4 3745.6 3810.8 3926.7 3938.9 3949.6 2885.7 3455.0 3479.4 3484.8 3487.4 4526.8 4671.6 4739.0 4963.7 4998.5 4422.2 4430.4 4440.5 4449.1 4470.2 4783.9 4810.0 5352.4 5396.3 5402.1 3483.5 3731.8 3735.3 3740.5 3748.8 5169.3 5544.8 5636.5 5645.2 5656.0 3286.3 3652.9 3777.2 3778.1 3804.4 3598.4 3707.6 3843.4 3859.8 3865.0 3763.2 4195.6 4234.5 4330.6 4398.9 4826.1 5040.5 5060.7 5460.1 5467.7 1070.8 1275.4 1277.7 1287.3 1309.5 749.6 824.2 842.4 848.8 849.4 4949.8 4956.3 5035.4 5037.7 5043.5 5100.5 5386.4 5395.5 5426.2 5433.9 4940.6 4953.3 4957.0 4965.6 4976.4 2820.9 2844.7 2845.2 2852.8 2860.4 4892.8 5360.3 5567.7 5598.6 5728.6 5188.7 5600.5 5755.7 5777.9 5826.7 5299.3 5776.4 5825.1 5844.2 5916.4 4261.8 4632.8 5374.9 5545.6 5645.9 4990.6 5109.4 5174.5 5350.5 5478.5 4894.7 5168.1 5507.2 5543.7 5669.9 3576.0 3668.7 3707.7 3745.6 3757.8 4603.6 4765.0 5562.9 5594.9 5601.6 5251.9 5432.3 5439.6 5440.4 5454.6 4913.9 5320.2 5451.5 5469.9 5487.4 4667.9 5083.2 5168.3 5281.1 5398.4 4530.3 5374.7 5385.6 5408.8 5430.1 4850.4 5221.5 5232.1 5232.5 5236.0 11:13:20 INFO - PROCESS | 1643 | 11:13:20 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:19694.36 Median:4490.45 stddev:28651.92 (638.1%) stddev-sans-first:28696.34 11:13:20 INFO - PROCESS | 1643 | Values: 578.0 626.0 646.8 747.5 759.5 14232.8 14384.4 14734.6 14742.5 14751.3 12683.9 13039.3 13277.4 13284.0 13368.9 601.7 620.7 759.6 772.1 774.0 611.6 698.7 720.2 776.8 778.2 806.7 807.0 807.0 807.2 811.8 802.7 803.6 803.7 804.9 805.0 467.7 473.6 573.8 586.1 592.1 13861.7 13864.7 13887.7 13893.0 13908.2 12462.4 12497.1 12728.6 12825.2 12838.2 9844.3 11512.2 11683.3 11729.7 12037.8 4263.1 4304.3 4354.8 4461.2 4519.7 65627.0 85266.0 85948.6 86320.8 86438.7 10016.0 10054.2 10073.3 10103.6 10509.0 18773.0 18784.2 18814.3 18820.4 18854.5 7250.5 7304.2 7324.5 7415.6 7538.6 4080.8 4146.9 4163.1 4165.7 4168.2 3157.3 3213.1 3236.4 3246.6 3251.3 519.0 604.2 608.1 614.5 619.4 537.2 548.6 563.0 582.7 593.5 8215.5 8305.9 8314.0 8424.3 8939.4 516.9 528.0 542.1 568.5 608.1 3549.8 4108.5 4122.9 4210.5 4273.7 106617.5 108208.0 109127.0 109324.5 113925.0 79677.6 81160.0 84699.7 92199.5 99645.0 72440.6 74099.0 75879.6 80998.0 83599.2 9278.4 9444.4 9481.0 9553.6 9567.9 47618.8 51553.0 52713.7 53163.9 54132.7 71703.6 74066.3 74366.9 75243.0 76585.0 11782.5 11874.3 12147.5 12188.9 12285.6 13413.9 13533.7 13609.6 13614.9 14135.2 12544.3 12858.5 12929.7 13237.2 13253.3 12227.9 12310.4 12310.8 12416.5 12605.4 12049.0 12415.6 12435.6 12958.2 12979.6 9320.6 9984.4 10585.4 10774.9 11125.9 10809.5 10935.5 10969.1 10999.4 11005.4 11:13:20 INFO - PROCESS | 1643 | -------- Summary: end -------- 11:13:20 INFO - PROCESS | 1643 | 11:13:21 INFO - TEST-INFO | 1643: exit 0 11:13:21 INFO - TEST-OK | dromaeo_css | took 1120017ms 11:13:21 INFO - TEST-START | kraken 11:13:21 INFO - operating with platform_type : mac_ 11:13:21 INFO - Initialising browser for kraken test... 11:13:21 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp8ExNhk/profile http://localhost:49273/getInfo.html) 11:13:23 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 11:13:23 INFO - PROCESS | 1653 | colorDepth:24 11:13:23 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 11:13:23 INFO - PROCESS | 1653 | __metrics 11:13:23 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:13:23 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:13:24 INFO - TEST-INFO | 1653: exit 0 11:13:24 INFO - Browser initialized. 11:13:24 INFO - Running cycle 1/1 for kraken test... 11:13:24 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp8ExNhk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:13:36 INFO - PROCESS | 1654 | RSS: Main: 241168384 11:13:36 INFO - PROCESS | 1654 | 11:13:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 11:13:40 INFO - PROCESS | 1654 | RSS: Main: 292548608 11:13:40 INFO - PROCESS | 1654 | 11:13:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 11:13:43 INFO - PROCESS | 1654 | RSS: Main: 294498304 11:13:43 INFO - PROCESS | 1654 | 11:13:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 11:13:47 INFO - PROCESS | 1654 | RSS: Main: 386187264 11:13:47 INFO - PROCESS | 1654 | 11:13:49 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 11:13:50 INFO - PROCESS | 1654 | RSS: Main: 289918976 11:13:50 INFO - PROCESS | 1654 | 11:13:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:13:53 INFO - PROCESS | 1654 | RSS: Main: 291827712 11:13:53 INFO - PROCESS | 1654 | 11:13:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 11:13:56 INFO - PROCESS | 1654 | RSS: Main: 328523776 11:13:56 INFO - PROCESS | 1654 | 11:13:59 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 11:14:00 INFO - PROCESS | 1654 | RSS: Main: 330129408 11:14:00 INFO - PROCESS | 1654 | 11:14:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 11:14:03 INFO - PROCESS | 1654 | RSS: Main: 327987200 11:14:03 INFO - PROCESS | 1654 | 11:14:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:14:05 INFO - PROCESS | 1654 | RSS: Main: 304750592 11:14:05 INFO - PROCESS | 1654 | 11:14:07 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:14:08 INFO - PROCESS | 1654 | RSS: Main: 310956032 11:14:08 INFO - PROCESS | 1654 | 11:14:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:14:11 INFO - PROCESS | 1654 | RSS: Main: 314929152 11:14:11 INFO - PROCESS | 1654 | 11:14:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:14:14 INFO - PROCESS | 1654 | RSS: Main: 318844928 11:14:14 INFO - PROCESS | 1654 | 11:14:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:14:18 INFO - PROCESS | 1654 | RSS: Main: 318840832 11:14:18 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 11:14:20 INFO - PROCESS | 1654 | RSS: Main: 362823680 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | __start_tp_report 11:14:20 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 11:14:20 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 11:14:20 INFO - PROCESS | 1654 | |i|pagename|runs| 11:14:20 INFO - PROCESS | 1654 | |0;ai-astar;115;115;114;114;114;115;114;168;171;120 11:14:20 INFO - PROCESS | 1654 | |1;audio-beat-detection;149;111;111;149;113;121;115;96;170;140 11:14:20 INFO - PROCESS | 1654 | |2;audio-dft;181;186;183;184;176;220;263;182;180;182 11:14:20 INFO - PROCESS | 1654 | |3;audio-fft;83;59;59;89;62;58;61;75;110;82 11:14:20 INFO - PROCESS | 1654 | |4;audio-oscillator;89;72;93;71;90;98;130;73;68;91 11:14:20 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;90;87;89;87;90;86;90;87;88;87 11:14:20 INFO - PROCESS | 1654 | |6;imaging-darkroom;98;98;99;98;98;99;97;98;98;97 11:14:20 INFO - PROCESS | 1654 | |7;imaging-desaturate;80;84;82;79;82;84;87;83;96;123 11:14:20 INFO - PROCESS | 1654 | |8;json-parse-financial;59;56;56;56;56;55;56;56;55;55 11:14:20 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;69;65;68;81;74;72;71;69;70;68 11:14:20 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;87;91;87;103;82;90;87;89;92;95 11:14:20 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;147;147;151;145;154;135;153;152;216;148 11:14:20 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;176;232;197;174;175;177;232;202;171;170 11:14:20 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;68;68;66;67;67;67;66;68;66;67 11:14:20 INFO - PROCESS | 1654 | __end_tp_report 11:14:20 INFO - PROCESS | 1654 | __start_cc_report 11:14:20 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2094 11:14:20 INFO - PROCESS | 1654 | __end_cc_report 11:14:20 INFO - PROCESS | 1654 | __startTimestamp1477937660603__endTimestamp 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | ------- Summary: start ------- 11:14:20 INFO - PROCESS | 1654 | Number of tests: 14 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:126.00 Median:115.00 stddev:23.01 (20.0%) stddev-sans-first:24.06 11:14:20 INFO - PROCESS | 1654 | Values: 115.0 115.0 114.0 114.0 114.0 115.0 114.0 168.0 171.0 120.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:144.50 stddev:23.17 (16.0%) stddev-sans-first:23.23 11:14:20 INFO - PROCESS | 1654 | Values: 149.0 111.0 111.0 149.0 113.0 121.0 115.0 96.0 170.0 140.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:193.70 Median:183.50 stddev:27.28 (14.9%) stddev-sans-first:28.55 11:14:20 INFO - PROCESS | 1654 | Values: 181.0 186.0 183.0 184.0 176.0 220.0 263.0 182.0 180.0 182.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:73.80 Median:68.50 stddev:17.27 (25.2%) stddev-sans-first:18.00 11:14:20 INFO - PROCESS | 1654 | Values: 83.0 59.0 59.0 89.0 62.0 58.0 61.0 75.0 110.0 82.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:87.50 Median:89.50 stddev:18.47 (20.6%) stddev-sans-first:19.58 11:14:20 INFO - PROCESS | 1654 | Values: 89.0 72.0 93.0 71.0 90.0 98.0 130.0 73.0 68.0 91.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:88.50 stddev:1.52 (1.7%) stddev-sans-first:1.45 11:14:20 INFO - PROCESS | 1654 | Values: 90.0 87.0 89.0 87.0 90.0 86.0 90.0 87.0 88.0 87.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:98.00 Median:98.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 11:14:20 INFO - PROCESS | 1654 | Values: 98.0 98.0 99.0 98.0 98.0 99.0 97.0 98.0 98.0 97.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:88.00 Median:83.50 stddev:13.18 (15.8%) stddev-sans-first:13.66 11:14:20 INFO - PROCESS | 1654 | Values: 80.0 84.0 82.0 79.0 82.0 84.0 87.0 83.0 96.0 123.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:56.00 stddev:1.15 (2.1%) stddev-sans-first:0.50 11:14:20 INFO - PROCESS | 1654 | Values: 59.0 56.0 56.0 56.0 56.0 55.0 56.0 56.0 55.0 55.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.50 stddev:4.37 (6.2%) stddev-sans-first:4.59 11:14:20 INFO - PROCESS | 1654 | Values: 69.0 65.0 68.0 81.0 74.0 72.0 71.0 69.0 70.0 68.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:89.50 stddev:5.68 (6.3%) stddev-sans-first:5.89 11:14:20 INFO - PROCESS | 1654 | Values: 87.0 91.0 87.0 103.0 82.0 90.0 87.0 89.0 92.0 95.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:154.80 Median:151.50 stddev:22.18 (14.6%) stddev-sans-first:23.35 11:14:20 INFO - PROCESS | 1654 | Values: 147.0 147.0 151.0 145.0 154.0 135.0 153.0 152.0 216.0 148.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.60 Median:187.00 stddev:24.32 (13.0%) stddev-sans-first:25.22 11:14:20 INFO - PROCESS | 1654 | Values: 176.0 232.0 197.0 174.0 175.0 177.0 232.0 202.0 171.0 170.0 11:14:20 INFO - PROCESS | 1654 | 11:14:20 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 11:14:20 INFO - PROCESS | 1654 | Values: 68.0 68.0 66.0 67.0 67.0 67.0 66.0 68.0 66.0 67.0 11:14:20 INFO - PROCESS | 1654 | -------- Summary: end -------- 11:14:20 INFO - PROCESS | 1654 | 11:14:21 INFO - TEST-INFO | 1654: exit 0 11:14:21 INFO - TEST-OK | kraken | took 60108ms 11:14:21 INFO - SUITE-END | took 1180s 11:14:21 INFO - Completed test suite (00:19:40) 11:14:21 INFO - javascript benchmark 11:14:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.536104945307, 3314.5193946877716, 3341.7159541438978, 3348.11275012122, 3367.2592029753446, 5542.4457554244555, 5557.638753481021, 5618.634788738729, 5640.943590564093, 5674.290713660793, 5599.720013999304, 5613.663180209191, 5683.318001839774, 5707.086866101425, 5708.514776244025, 5029.446760856303, 5049.823256186034, 5054.1913293872985, 5100.311457953185, 5128.0, 5171.250168725533, 5171.793128274864, 5183.974080129613, 5206.0, 5233.921491177635, 5066.41736200338, 5094.363204599425, 5154.793808247666, 5165.04446677364, 5175.094358487249, 5113.718745468997, 5121.513456221653, 5135.460776618439, 5141.922871156936, 5144.845654630367, 5076.796928122871, 5096.311997880276, 5099.770510327045, 5108.4125325587665, 5112.846614601568, 5555.083411237141, 5572.693501857396, 5577.079781835992, 5577.386487486373, 5594.944050559483, 6085.9695701521205, 6087.695615219265, 6098.908516372258, 6100.633961962297, 6105.755769769204, 6115.7859474918605, 6124.910294234916, 6126.090206827809, 6139.430809034335, 6142.266691322363, 5604.411536788619, 5675.858103547403, 5697.6296540724725, 5700.629459085146, 5718.199452076713, 6184.598001130003, 6198.907016394758, 6206.038064100072, 6219.844503887439, 6224.408681175281, 6091.299500557493, 6095.6952152392205, 6097.908531372033, 6098.176746139303, 6114.571979961449, 5005.424376196784, 6092.299385570716, 6105.581053426346, 6110.445431087209, 6135.601185922901, 6058.1518587397795, 6074.848128796815, 6079.8480037998515, 6087.452129308295, 6116.755329786759, 6133.233345831771, 6134.6625935573975, 6136.6011209271255, 6140.324564297924, 6142.293636231801, 4767.4517430495935, 4819.108464933998, 4824.324594556765, 4845.370101886732, 4847.927281090787, 3960.584138665456, 4272.316429371285, 4389.736615803062, 4396.494403143615, 4407.228734971367, 5680.659160450304, 5689.0, 5707.914381284284, 5709.400512946164, 5720.2849643794525, 6110.771402862501, 6129.417705317987, 6135.110179431545, 6142.682951206299, 6183.536234782409, 5721.39925307845, 5726.455986681259, 5757.798477053367, 5775.27809023872, 5776.133579962955, 5324.627276090637, 5347.117725575236, 5366.356037275552, 5370.301860758076, 5374.785008599612, 5929.703514824241, 6158.0, 6234.407731265523, 6254.468370188585, 6260.060990851227, 6131.315499379051, 6169.043798211284, 6182.0, 6186.566940314315, 6229.190205273286, 6168.845778855564, 6223.37766223374, 6239.96880015597, 6242.250929888442, 6261.530385221217, 5951.256092988377, 6104.4200800923845, 6145.938540614537, 6155.446009859134, 6161.9691901542, 6103.572749907464, 6116.914073580098, 6128.233970753656, 6150.692465376624, 6215.844603884758, 5011.847275126668, 5320.669832541865, 5789.189513468202, 6097.2683278006925, 6098.024316109401, 6049.969750151221, 6055.122007308827, 6059.000264956409, 6061.060535616986, 6073.99779036454, 5699.857503562444, 5812.476877081083, 5825.126231065374, 5827.883442331215, 5880.794172203952, 6040.909386359121, 6046.818595442144, 6057.4851137653795, 6060.969695151496, 6067.908981365194, 5729.455701708331, 5811.535077193899, 5857.619254748428, 5891.941080589139, 5948.226730525004], "value": 5608.9778830626565, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.51103785835573, 468.1736734663322, 474.7175430618781, 520.5159201942192, 525.0, 29319.267018324554, 38447.42328865066, 39624.0, 39744.80765577034, 40016.0, 39840.40239396411, 40348.19303613926, 40767.796161019156, 40810.183796324054, 40856.38715419271, 9341.205997490222, 9517.714468565953, 9538.523073846314, 9541.093596108358, 9579.042095790435, 9440.763980900463, 9562.235021198287, 9565.426074435556, 9566.426014439156, 9567.808643827118, 9529.42823430593, 9580.281478889112, 9580.56887440067, 9596.28027897907, 9661.130498255156, 9527.952360238223, 9601.231901447867, 9608.471534065624, 9612.95193524035, 9674.613015479372, 9314.580843862042, 9408.0, 9411.62353505859, 9424.575894084783, 9435.386699864555, 38986.61013389887, 39302.60697393018, 39325.60674393248, 39348.60651393478, 39366.40950385747, 3328.234506063619, 3504.088936876405, 3565.6612621800887, 3851.5763266040713, 3862.671672907806, 30003.849980750176, 30063.549046764318, 30270.54594181089, 31248.843755781076, 31270.0, 3127.202563346355, 3129.5931528901324, 3149.794784586143, 3163.7943533670236, 3242.7567932405163, 476600.0, 480710.0, 481397.5930120327, 481610.5919470415, 493170.0, 56524.43475565192, 56935.007274745185, 56942.0, 57047.0, 57105.0, 33147.17132071669, 33853.83073084619, 33926.49110263348, 59313.70343148343, 59423.0, 12829.422675979604, 14928.32822522989, 15463.994840335343, 15524.37902483888, 15586.0, 11697.0, 11828.053755699524, 11834.704132396759, 11835.99394051498, 11839.28964262147, 15508.689826203412, 17345.525630321565, 18127.09364531768, 18204.45386638403, 18260.543486412946, 6255.311915689271, 7415.739324314879, 7437.665305061285, 7486.5508069516, 7490.775276741707, 828.7099515169642, 887.3611000079932, 932.1461223516194, 1044.6239353832614, 1068.967930962057, 29321.85339073291, 29644.555331670043, 29757.0, 29932.40135197284, 30185.849070754946, 972.8005758819424, 976.8730065091493, 1034.8874959418504, 1039.9064084232455, 1081.4592703648175, 14715.411383544539, 15162.0, 15249.695006099815, 15269.694606107816, 15670.529884103495, 646013.0, 652625.0, 654226.0, 654365.0, 656182.7190864015, 54341.0, 54848.0, 54865.451345486836, 54908.725456372464, 54969.72515137479, 1151.7984352738238, 1198.1912209258714, 1277.444311724403, 1310.4037662863375, 1329.1825527300448, 23843.642345364486, 28947.421051579273, 29403.11790646284, 30300.69699302979, 30935.91724289639, 47650.52349476599, 51565.742171288905, 53150.734246328524, 53226.73386633042, 55277.0, 517615.0, 523598.0, 524030.0, 524187.0, 524329.0, 33432.1641958953, 34035.14912127217, 38075.0, 39159.216815662956, 39766.60233397745, 44810.327845083004, 44944.77527612341, 45731.68293073092, 45922.0, 46443.76778116088, 37828.0, 38341.80829095837, 38385.4242186373, 38758.224835502566, 40053.99865003398, 31481.898133565217, 35553.933381998584, 37239.62760372362, 38240.61759382371, 38364.61635383722, 34867.30265394627, 37979.0, 38005.42991855069, 38039.0, 38925.0, 28902.132936011953, 29578.46562082317, 31814.840925795223, 33768.83115584406, 33801.15497112592, 21657.675134872676, 24316.0, 24349.173811964527, 25256.0, 25998.0], "value": 19387.571131164397, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.39414109863117, 369.2467366572193, 369.37391122048126, 378.40023562653187, 400.2355500993104, 14495.420183192673, 14801.925990370046, 15545.300461479232, 19828.603427931434, 20440.0, 14210.644733881658, 18445.354412595563, 19361.709574356362, 19576.510587235327, 19652.60694786107, 5725.026745453272, 6097.634141951475, 6119.3880611938675, 6120.724567394478, 6123.050927106284, 5774.913376299359, 5980.521558275349, 5987.43119403656, 6051.062085376773, 6069.30203026651, 5949.791757288495, 6004.939950600482, 6004.96997515014, 6013.609115407507, 6019.337872834007, 4877.268409738532, 5354.705491197982, 6043.0, 6047.213862197887, 6076.301225359096, 5019.14674505335, 5243.79024839006, 5245.134552798783, 5247.501487358695, 5252.6323157379, 24631.38421539458, 24917.501649967082, 24979.0, 25292.620610690854, 25368.492630147295, 2183.235867446397, 2321.3964369263945, 2530.936726581832, 2623.73762623738, 2922.3716900866416, 14724.558263252013, 15031.549053528412, 15289.464868729541, 15623.843761562353, 15911.60220994473, 1752.4304601004596, 1830.6155707301482, 1849.5098798818324, 1862.245790454868, 1894.9526261843425, 83396.0, 105280.0, 106205.46897265542, 110809.0, 114553.0, 10758.73103172427, 10831.729206769736, 11054.170937179582, 11057.336559806437, 11214.158938079676, 13834.93082534581, 13865.861341386457, 13867.23730194849, 13897.583072507841, 13903.791443128159, 8246.299064579458, 8404.579771011426, 8419.494830310201, 8560.871586926201, 8572.228499435081, 8304.88225502493, 8362.867468795539, 8384.790380240544, 8474.830503389898, 8503.489790612583, 6342.778002769881, 6593.934060659428, 6602.801915942529, 6607.240167380716, 6616.437602803816, 1791.6327152933618, 1829.5609053826986, 1835.5686413692772, 1839.4481655503296, 1840.355875443584, 847.6651722569674, 856.1438561438562, 902.5171533229822, 902.5487256371814, 907.8365894138987, 20641.793582063987, 21765.891170544044, 21800.89099554492, 21806.0, 21832.126714931226, 889.0, 953.3231405702029, 970.8543718442149, 994.3735446668552, 1003.0721582536125, 10930.234883558234, 11383.943080284545, 11507.76984460306, 11560.959513643644, 11583.884161158281, 548503.0, 551952.0, 562773.0, 570480.0, 575017.0, 80681.59659201666, 103456.48271758592, 105858.0, 109195.36206956902, 110752.4462377683, 913.1188403190953, 965.8937516872998, 987.5654711926729, 992.7468495533593, 995.0447570332561, 18961.43115706531, 19355.903220483808, 19379.806201938365, 19440.902795486498, 19442.708359374905, 48496.5150348492, 54626.72686636541, 55655.0, 56636.0, 57078.42921570897, 74325.14187145364, 75867.62066189633, 77720.2227977713, 77799.0, 78749.0, 7823.8435231293915, 7972.880406794019, 8076.232757887991, 8152.470089444168, 8227.670893164206, 12085.456154473246, 12626.810597840857, 12840.61478155657, 13247.602571922858, 13325.13386629866, 10969.012788849042, 11015.669529914116, 11433.371164586028, 11461.598844040753, 11600.535978560763, 8845.911540884767, 10994.230403871652, 11086.22396432242, 11168.441577921234, 11378.658640240807, 11448.566819704678, 11571.6528504145, 11623.6512904613, 11842.703932401759, 11848.170628055954, 6762.830929226809, 7989.440739148204, 8114.06688230861, 8231.42380033392, 8341.24928756415, 10347.0, 11251.324920504796, 11530.76938461243, 11568.421578921188, 11633.709157270798], "value": 9575.947324658831, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1228.5085965613757, 1280.2958372894905, 1420.7513685105105, 1744.1540852686437, 1770.1060964213063, 7600.2779735925, 8080.39397045221, 8506.489610623366, 8512.70205542806, 8530.019047809496, 7639.427042971785, 7800.0639923209155, 8159.6736130554855, 8395.664173433055, 8400.621972011259, 2168.8481806273567, 2307.5615633029756, 2337.497438050819, 2341.1103780563362, 2347.1550241912983, 2114.154338264691, 2314.826388020897, 2357.5874222011166, 2359.893804778789, 2361.929142125739, 1847.9168437420349, 2301.1945818963395, 2307.7692230776956, 2324.755900630435, 2337.5091230841545, 2288.027588274993, 2292.358139720881, 2310.2260742651233, 2311.306608017588, 2315.0508291600363, 1944.2417457191707, 2309.168699268262, 2329.452578644017, 2332.720073591163, 2339.2865176121277, 8421.621027053798, 8525.147485251488, 8527.530985795778, 8547.273481754059, 8562.143785621389, 5055.69665820052, 5193.506616871391, 5199.012187684328, 5201.635885488017, 5209.635325527177, 3223.17808958714, 3314.254292784128, 3514.7539672222956, 3533.9293214135832, 3584.8566057357675, 1021.7956408718212, 1040.95836166554, 1041.2242879055152, 1059.819830628795, 1066.8133076711622, 9345.766355841159, 11026.724331891686, 11053.834192487038, 11232.269902456314, 11239.213255072149, 4062.055010117641, 4420.80106395213, 4643.767811609406, 4669.509701481364, 4670.0, 9478.95260523693, 9485.383450075724, 9498.0, 9505.42967421957, 9512.476813775309, 5166.715830629351, 5172.0, 5180.197069454241, 5180.326557547494, 5200.557952573998, 4251.574842515785, 4380.890477738082, 4597.862064138081, 4745.1221524018165, 4753.881152971203, 2928.6192794936524, 3697.0, 3752.718546108998, 3798.4872042273937, 3825.5218097737784, 1208.3535308610028, 1208.6434501821807, 1213.4842691855927, 1218.4943248551929, 1220.4934951994824, 852.4885068958699, 870.9346798990101, 872.3936863879669, 906.2161230535539, 912.0241341764248, 4484.013517026249, 5675.120356344773, 5790.668146326284, 5874.676892770939, 6095.207623009069, 896.8341156496529, 918.4810582021137, 931.4816848536841, 941.2422999485343, 966.9613215471302, 3721.1999420124803, 3763.7365384423374, 3815.378464152724, 3823.235352929425, 3841.4429907663234, 9577.664781732745, 10212.795744085226, 10389.636362727268, 10391.636292729869, 10417.583296668048, 9991.750206244902, 10231.693049208536, 10362.015608517178, 10371.377717336984, 10378.896211037792, 7898.960505197438, 8044.155363686846, 8044.235797599219, 8070.717524886718, 8078.192180781874, 5511.669299842022, 5559.972200138974, 5637.49262566371, 5690.089585666439, 5716.228309178207, 9496.667616633385, 9559.474228917476, 9581.089796469325, 9589.993050729572, 9613.327067105236, 9463.479508627091, 9611.0, 9679.0, 9709.951450242703, 9864.605415783288, 6638.76764313266, 6754.932450675627, 6796.864062718817, 6904.27505111956, 6943.409810166192, 7883.960580197062, 7991.760247192594, 7994.960025199837, 7996.720114795954, 8011.198880112175, 6990.895136573057, 7129.224037833882, 7132.108486439195, 7312.853742925218, 7432.182459929295, 7002.929970700432, 7084.64576771149, 7111.786646400409, 7134.250903655042, 7305.671244794104, 5567.498925096761, 6670.899936500859, 6800.591964482147, 6822.11312529368, 6834.555753875982, 4338.848140315074, 4771.23660214364, 4779.784909679003, 4799.808007679654, 4939.407271127488, 4901.41183058021, 4902.166631672553, 4906.0, 4921.753912304442, 4981.128302547112], "value": 4400.593287263274, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.21059875431973, 296.6810678520591, 304.8400834823497, 310.52644716806867, 312.8029341514845, 4518.661100417466, 4799.928001079987, 5091.592672586194, 5113.565346945509, 5138.203578445342, 4738.312944623028, 4886.51134886512, 4891.266310053494, 5019.29729837823, 5061.746912654371, 3044.5433185022257, 3642.271545690859, 3656.835442405098, 3948.498711468928, 3991.5210174779068, 3850.653441190292, 3978.3833505806647, 3991.1419044905338, 3995.8601448949294, 4001.2997725398086, 3162.905112846618, 3908.159745654684, 3915.4322623219614, 3943.7830919299427, 3956.3867600521967, 3745.550533935918, 3810.79040652764, 3926.725129240954, 3938.8621398251066, 3949.64453199212, 2885.653721553406, 3455.0, 3479.425894727367, 3484.790912545255, 3487.4420092785285, 4526.79629416677, 4671.626269898434, 4738.95261047392, 4963.716690322456, 4998.500149985009, 4422.181896349153, 4430.379746835446, 4440.511543730187, 4449.087936972913, 4470.195364834329, 4783.856484305476, 4809.975950120227, 5352.411234764173, 5396.271503347077, 5402.081646120129, 3483.5471388719557, 3731.81340932955, 3735.252949410102, 3740.532433445819, 3748.756330838487, 5169.327987361619, 5544.750486228129, 5636.520895723868, 5645.233835420697, 5655.971720141373, 3286.260591366923, 3652.8721494747556, 3777.1690228149764, 3778.112143646241, 3804.4483549885113, 3598.370285200132, 3707.647773461517, 3843.404272337792, 3859.7877116758846, 3864.9806750966063, 3763.158815094586, 4195.559466256061, 4234.470691163605, 4330.588594083557, 4398.934015989763, 4826.104337397604, 5040.521150490697, 5060.746962651852, 5460.099083651233, 5467.726613669301, 1070.848837499691, 1275.432432567517, 1277.6550331410654, 1287.3370214339623, 1309.463120120746, 749.5952185819597, 824.2169938558394, 842.3682238321259, 848.8005318750089, 849.4436144325476, 4949.802007919643, 4956.281339205867, 5035.446100928895, 5037.697738135724, 5043.52086551777, 5100.464451232641, 5386.380566234933, 5395.487428694268, 5426.213199086111, 5433.945660543423, 4940.629452791055, 4953.306537084811, 4956.959038601862, 4965.577925876341, 4976.42771081323, 2820.8857501286875, 2844.6728626207837, 2845.2317874174273, 2852.800303978702, 2860.3850172213074, 4892.755362231874, 5360.276362691065, 5567.693776842231, 5598.608097433222, 5728.570357223225, 5188.714620695822, 5600.467955544217, 5755.65466072037, 5777.913331300034, 5826.708664566755, 5299.330710826078, 5776.393478684761, 5825.126231065289, 5844.240248767633, 5916.408359164049, 4261.806694125526, 4632.837850675211, 5374.946250537445, 5545.611807173459, 5645.943540564706, 4990.552739705445, 5109.3613298337705, 5174.508421699932, 5350.545203657733, 5478.506934375925, 4894.706317620954, 5168.095583272988, 5507.201455788968, 5543.667379957215, 5669.922714684197, 3575.9604336313923, 3668.7248456365346, 3707.7404581679025, 3745.5692595350793, 3757.8308976096687, 4603.631709463302, 4764.952350476451, 5562.944370556243, 5594.916076258941, 5601.551875849841, 5251.921221181762, 5432.348118225838, 5439.5648348132845, 5440.401555828935, 5454.563634909277, 4913.877153071059, 5320.201969704575, 5451.48210919962, 5469.917951230815, 5487.42382049895, 4667.929981050219, 5083.2384035813975, 5168.250603662373, 5281.102212623787, 5398.352197736297, 4530.27515597516, 5374.7312634367345, 5385.649932754359, 5408.8377348679605, 5430.072324324808, 4850.393700787402, 5221.530062294411, 5232.136697445031, 5232.476752324889, 5235.973820130875], "value": 3934.181461537733, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.9797707080252, 625.9937400625993, 646.7671638210244, 747.514115824714, 759.525296689569, 14232.786508202385, 14384.42462301508, 14734.557963261093, 14742.484013059546, 14751.336189871468, 12683.873161268384, 13039.282839443827, 13277.40251688674, 13284.0, 13368.930485561155, 601.7111786342558, 620.7051650466037, 759.6391713935864, 772.0927909706073, 773.9845203095934, 611.5505103748741, 698.6646409723336, 720.1574158234852, 776.7786180938434, 778.2062296457638, 806.657170702452, 807.0355924669992, 807.0436532708729, 807.2169995104769, 811.7564730580832, 802.7150361621657, 803.6062329458582, 803.702630026888, 804.851102546028, 804.9597520123999, 467.66561908235434, 473.59744217415266, 573.8106424879779, 586.050598031188, 592.1413949772804, 13861.653458663513, 13864.653383665389, 13887.722245555133, 13893.0, 13908.165510069328, 12462.43919023646, 12497.125201235916, 12728.554500592527, 12825.16636418639, 12838.165519241313, 9844.310898237127, 11512.194146409754, 11683.30592064146, 11729.70675733105, 12037.819432708517, 4263.104748002923, 4304.33282841157, 4354.847580334705, 4461.193216747222, 4519.706219095749, 65627.01559476611, 85266.0, 85948.57025714831, 86320.8419789504, 86438.70341944876, 10016.0, 10054.195664346835, 10073.345232559861, 10103.595856165817, 10509.0, 18773.0, 18784.248630054644, 18814.341498047503, 18820.43538693814, 18854.52863678419, 7250.528715633467, 7304.160021597478, 7324.523905946096, 7415.629218539051, 7538.585377804274, 4080.81636326366, 4146.91706165881, 4163.146554956226, 4165.708400411942, 4168.2080404723, 3157.258044359574, 3213.1485156433564, 3236.385086833494, 3246.62663793662, 3251.3009702913514, 518.9532942035235, 604.1813342920368, 608.0817964873073, 614.5390956782413, 619.4115590189339, 537.1888448442879, 548.5721137512746, 562.9943700562942, 582.6533212738414, 593.4777395891588, 8215.507069575844, 8305.875411868827, 8313.958430207811, 8424.283935865398, 8939.418937769155, 516.8837011672343, 527.9683219006872, 542.0514100324432, 568.5054003017401, 608.1273372710175, 3549.7870127792416, 4108.4864391951005, 4122.9381559276635, 4210.536840947493, 4273.7008409411665, 106617.46691266494, 108208.0, 109127.0, 109324.4533777342, 113925.0, 79677.60161199272, 81160.0, 84699.72950405748, 92199.53900230456, 99645.0, 72440.55118897592, 74099.0, 75879.62060189663, 80998.0, 83599.16400836036, 9278.443293402417, 9444.36716281461, 9481.004494528252, 9553.61785528571, 9567.856482152913, 47618.761906190244, 51553.0, 52713.7364313176, 53163.93672126458, 54132.72933635306, 71703.64148179226, 74066.25933740809, 74366.88449673151, 75243.0, 76585.0, 11782.52869885195, 11874.287542747463, 12147.45336459879, 12188.878111219128, 12285.57000504978, 13413.932930335286, 13533.729325413633, 13609.591712248648, 13614.863851361359, 14135.15189088658, 12544.274054534531, 12858.485660573473, 12929.676758081123, 13237.205767653972, 13253.271070091238, 12227.938860305641, 12310.358911014431, 12310.81533777012, 12416.503339866666, 12605.369731513205, 12049.0, 12415.62753117408, 12435.564755233523, 12958.22250664963, 12979.610611681665, 9320.648505966732, 9984.35101718386, 10585.417802020962, 10774.892251077703, 11125.944370278097, 10809.45952702346, 10935.45322733876, 10969.122470202525, 10999.395033273247, 11005.449727513753], "value": 6945.171883913519, "unit": "score"}], "name": "dromaeo_css", "value": 7073.173526992923, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 114.0, 114.0, 115.0, 114.0, 168.0, 171.0, 120.0], "value": 126.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 111.0, 111.0, 149.0, 113.0, 121.0, 115.0, 96.0, 170.0, 140.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 186.0, 183.0, 184.0, 176.0, 220.0, 263.0, 182.0, 180.0, 182.0], "value": 193.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 89.0, 62.0, 58.0, 61.0, 75.0, 110.0, 82.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 93.0, 71.0, 90.0, 98.0, 130.0, 73.0, 68.0, 91.0], "value": 87.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 87.0, 90.0, 86.0, 90.0, 87.0, 88.0, 87.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 99.0, 98.0, 98.0, 99.0, 97.0, 98.0, 98.0, 97.0], "value": 98.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 82.0, 79.0, 82.0, 84.0, 87.0, 83.0, 96.0, 123.0], "value": 88.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 56.0, 56.0, 56.0, 55.0, 56.0, 56.0, 55.0, 55.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 68.0, 81.0, 74.0, 72.0, 71.0, 69.0, 70.0, 68.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 91.0, 87.0, 103.0, 82.0, 90.0, 87.0, 89.0, 92.0, 95.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 151.0, 145.0, 154.0, 135.0, 153.0, 152.0, 216.0, 148.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 232.0, 197.0, 174.0, 175.0, 177.0, 232.0, 202.0, 171.0, 170.0], "value": 190.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 66.0, 67.0, 67.0, 67.0, 66.0, 68.0, 66.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1512.0, "alertThreshold": 2.0}]} 11:14:21 INFO - Return code: 0 11:14:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:14:21 INFO - # TBPL SUCCESS # 11:14:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:14:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:14:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:14:21 INFO - 2016-10-31 11:14:21.914 screenresolution[1658:10068] starting screenresolution argv=screenresolution get 11:14:21 INFO - 2016-10-31 11:14:21.917 screenresolution[1658:10068] Display 0: 1600x1200x32@60 11:14:21 INFO - 2016-10-31 11:14:21.923 screenresolution[1659:10072] starting screenresolution argv=screenresolution list 11:14:21 INFO - Available Modes on Display 0 11:14:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:14:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:14:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:14:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:14:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:14:22 INFO - Intel Iris: 11:14:22 INFO - Chipset Model: Intel Iris 11:14:22 INFO - Type: GPU 11:14:22 INFO - Bus: Built-In 11:14:22 INFO - VRAM (Dynamic, Max): 1536 MB 11:14:22 INFO - Vendor: Intel (0x8086) 11:14:22 INFO - Device ID: 0x0a2e 11:14:22 INFO - Revision ID: 0x0009 11:14:22 INFO - Displays: 11:14:22 INFO - Display: 11:14:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:14:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:14:22 INFO - Display Serial Number: 1600x1200 60 11:14:22 INFO - Main Display: Yes 11:14:22 INFO - Mirror: Off 11:14:22 INFO - Online: Yes 11:14:22 INFO - Rotation: Supported 11:14:22 INFO - Return code: 0 11:14:22 INFO - Running post-action listener: _resource_record_post_action 11:14:22 INFO - [mozharness: 2016-10-31 18:14:22.165838Z] Finished run-tests step (success) 11:14:22 INFO - Running post-run listener: _resource_record_post_run 11:14:22 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 37680128; Write bytes: 709224960; Read time: 248; Write time: 1966 11:14:22 INFO - TinderboxPrint: CPU usage
53.6% 11:14:22 INFO - TinderboxPrint: I/O read bytes / time
37,680,128 / 248 11:14:22 INFO - TinderboxPrint: I/O write bytes / time
709,224,960 / 1,966 11:14:22 INFO - TinderboxPrint: CPU idle
2,254.8 (46.4%) 11:14:22 INFO - TinderboxPrint: CPU system
977.0 (20.1%) 11:14:22 INFO - TinderboxPrint: CPU user
1,629.9 (33.5%) 11:14:22 INFO - TinderboxPrint: Swap in / out
444,461,056 / 0 11:14:22 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 385692160; Write bytes: 380376576; Read time: 29285; Write time: 768 11:14:22 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36713472; Write bytes: 298742784; Read time: 235; Write time: 1126 11:14:22 INFO - Running post-run listener: _upload_blobber_files 11:14:22 INFO - Blob upload gear active. 11:14:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:14:22 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 11:14:22 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:14:22 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:14:23 INFO - (blobuploader) - INFO - Open directory for files ... 11:14:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:14:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:14:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:14:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:14:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:14:24 INFO - (blobuploader) - INFO - Done attempting. 11:14:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:14:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:14:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:14:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:14:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:14:25 INFO - (blobuploader) - INFO - Done attempting. 11:14:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:14:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:14:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:14:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:14:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:14:27 INFO - (blobuploader) - INFO - Done attempting. 11:14:27 INFO - (blobuploader) - INFO - Iteration through files over. 11:14:27 INFO - Return code: 0 11:14:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:14:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:14:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/213e39f62a7e0816c1dce6049c73ea8548003e3e88f485106022a109da4451aae114d76b8c3014e848eb80ca603c16ef9a09372356f4e5da1359db98bee351c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c71c164fb7e5e9a6c77ff53d4def2171f9d6f3782ee54a20f97a0a729d6a070c2fb53e997a4dea4c16ef9f7aa48c04ad36f5be7e6b8ecff93995b38c751200c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c9405885054fcb17d7e4890f90a66e36e4ceeeb34b6785ffaa35cc048b889370a6e235d760d94b313fc13a57b1089c926da9e9f0f19bad9346068c3b27630ce"} 11:14:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:14:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:14:27 INFO - Contents: 11:14:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/213e39f62a7e0816c1dce6049c73ea8548003e3e88f485106022a109da4451aae114d76b8c3014e848eb80ca603c16ef9a09372356f4e5da1359db98bee351c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c71c164fb7e5e9a6c77ff53d4def2171f9d6f3782ee54a20f97a0a729d6a070c2fb53e997a4dea4c16ef9f7aa48c04ad36f5be7e6b8ecff93995b38c751200c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c9405885054fcb17d7e4890f90a66e36e4ceeeb34b6785ffaa35cc048b889370a6e235d760d94b313fc13a57b1089c926da9e9f0f19bad9346068c3b27630ce"} 11:14:27 INFO - Running post-run listener: copy_logs_to_upload_dir 11:14:27 INFO - Copying logs to upload dir... 11:14:27 INFO - mkdir: /builds/slave/test/build/upload/logs 11:14:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.372502 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-31 11:14:27.959434) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 11:14:27.962522) ========= 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.Out2Tauc9c/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.2HDcM5n4ej/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/Firefox/sha512/213e39f62a7e0816c1dce6049c73ea8548003e3e88f485106022a109da4451aae114d76b8c3014e848eb80ca603c16ef9a09372356f4e5da1359db98bee351c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c71c164fb7e5e9a6c77ff53d4def2171f9d6f3782ee54a20f97a0a729d6a070c2fb53e997a4dea4c16ef9f7aa48c04ad36f5be7e6b8ecff93995b38c751200c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c9405885054fcb17d7e4890f90a66e36e4ceeeb34b6785ffaa35cc048b889370a6e235d760d94b313fc13a57b1089c926da9e9f0f19bad9346068c3b27630ce"} build_url:https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010991 build_url: 'https://queue.taskcluster.net/v1/task/U1jcSPuASzK5m4aewQ7aWA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/213e39f62a7e0816c1dce6049c73ea8548003e3e88f485106022a109da4451aae114d76b8c3014e848eb80ca603c16ef9a09372356f4e5da1359db98bee351c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c71c164fb7e5e9a6c77ff53d4def2171f9d6f3782ee54a20f97a0a729d6a070c2fb53e997a4dea4c16ef9f7aa48c04ad36f5be7e6b8ecff93995b38c751200c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c9405885054fcb17d7e4890f90a66e36e4ceeeb34b6785ffaa35cc048b889370a6e235d760d94b313fc13a57b1089c926da9e9f0f19bad9346068c3b27630ce"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 11:14:28.000013) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 11:14:28.000418) ========= 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.Out2Tauc9c/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.2HDcM5n4ej/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.005230 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 11:14:28.049432) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 11:14:28.049872) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 11:14:28.050275) ========= ========= Total master_lag: 0.18 =========