builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0264 starttime: 1477830713.65 results: success (0) buildid: 20161030030204 builduid: ad75f2f9f6854d1b9de0e8158fcf7f67 revision: 969c3295d3aa77931cca26eddb047d9d74bd9858 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-30 05:31:53.647012) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-30 05:31:53.647445) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-30 05:31:53.647722) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-30 05:31:53.671959) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 05:31:53.672260) ========= 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.dF7mrevgjK/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.jhPBYTl84u/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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-30 05:31:53-- 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% 7.89M=0.001s 2016-10-30 05:31:53 (7.89 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.154809 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 05:31:53.850153) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 05:31:53.850460) ========= 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.dF7mrevgjK/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.jhPBYTl84u/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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.019803 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 05:31:53.910396) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-30 05:31:53.910742) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 969c3295d3aa77931cca26eddb047d9d74bd9858 --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 969c3295d3aa77931cca26eddb047d9d74bd9858 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dF7mrevgjK/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.jhPBYTl84u/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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-30 05:31:53,994 truncating revision to first 12 chars 2016-10-30 05:31:53,994 Setting DEBUG logging. 2016-10-30 05:31:53,994 attempt 1/10 2016-10-30 05:31:53,994 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/969c3295d3aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-30 05:31:55,043 unpacking tar archive at: mozilla-central-969c3295d3aa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.307718 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-30 05:31:55.241145) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-30 05:31:55.241516) ========= script_repo_revision: 969c3295d3aa77931cca26eddb047d9d74bd9858 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-30 05:31:55.241930) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-30 05:31:55.242298) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-30 05:31:55.260902) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-30 05:31:55.261293) ========= /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.dF7mrevgjK/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.jhPBYTl84u/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 05:31:55 INFO - MultiFileLogger online at 20161030 05:31:55 in /builds/slave/test 05:31:55 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 05:31:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:31:55 INFO - {'append_to_log': False, 05:31:55 INFO - 'base_work_dir': '/builds/slave/test', 05:31:55 INFO - 'blob_upload_branch': 'Firefox', 05:31:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:31:55 INFO - 'branch': 'Firefox', 05:31:55 INFO - 'buildbot_json_path': 'buildprops.json', 05:31:55 INFO - 'config_files': ('talos/mac_config.py',), 05:31:55 INFO - 'default_actions': ('clobber', 05:31:55 INFO - 'read-buildbot-config', 05:31:55 INFO - 'download-and-extract', 05:31:55 INFO - 'populate-webroot', 05:31:55 INFO - 'create-virtualenv', 05:31:55 INFO - 'install', 05:31:55 INFO - 'run-tests'), 05:31:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:31:55 INFO - 'download_minidump_stackwalk': True, 05:31:55 INFO - 'download_symbols': 'ondemand', 05:31:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:31:55 INFO - 'tooltool.py': '/tools/tooltool.py', 05:31:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:31:55 INFO - '/tools/misc-python/virtualenv.py')}, 05:31:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:31:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:31:55 INFO - 'installer_path': 'installer.exe', 05:31:55 INFO - 'log_level': 'info', 05:31:55 INFO - 'log_name': 'talos', 05:31:55 INFO - 'log_to_console': True, 05:31:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:31:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:31:55 INFO - 'opt_config_files': (), 05:31:55 INFO - 'pip_index': False, 05:31:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:31:55 INFO - 'cmd': ('bash', 05:31:55 INFO - '-c', 05:31:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:31:55 INFO - 'enabled': True, 05:31:55 INFO - 'halt_on_failure': False, 05:31:55 INFO - 'name': 'check_screen_resolution'},), 05:31:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:31:55 INFO - 'cmd': ('bash', 05:31:55 INFO - '-c', 05:31:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:31:55 INFO - 'enabled': True, 05:31:55 INFO - 'halt_on_failure': False, 05:31:55 INFO - 'name': 'check_screen_resolution'},), 05:31:55 INFO - 'run_cmd_checks_enabled': True, 05:31:55 INFO - 'sps_profile': False, 05:31:55 INFO - 'sps_profile_interval': 0, 05:31:55 INFO - 'suite': 'dromaeojs', 05:31:55 INFO - 'system_bits': '32', 05:31:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:31:55 INFO - 'title': 't-yosemite-r7-0264', 05:31:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:31:55 INFO - 'use_talos_json': True, 05:31:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:31:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:31:55 INFO - 'work_dir': 'build'} 05:31:55 INFO - [mozharness: 2016-10-30 12:31:55.468695Z] Running clobber step. 05:31:55 INFO - Running pre-action listener: _resource_record_pre_action 05:31:55 INFO - Running main action method: clobber 05:31:55 INFO - rmtree: /builds/slave/test/build 05:31:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:31:57 INFO - Running post-action listener: _resource_record_post_action 05:31:57 INFO - [mozharness: 2016-10-30 12:31:57.151495Z] Finished clobber step (success) 05:31:57 INFO - [mozharness: 2016-10-30 12:31:57.151628Z] Running read-buildbot-config step. 05:31:57 INFO - Running pre-action listener: _resource_record_pre_action 05:31:57 INFO - Running main action method: read_buildbot_config 05:31:57 INFO - Using buildbot properties: 05:31:57 INFO - { 05:31:57 INFO - "project": "", 05:31:57 INFO - "product": "firefox", 05:31:57 INFO - "script_repo_revision": "production", 05:31:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:31:57 INFO - "repository": "", 05:31:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:31:57 INFO - "buildid": "20161030030204", 05:31:57 INFO - "stage_platform": "macosx64", 05:31:57 INFO - "basedir": "/builds/slave/test", 05:31:57 INFO - "nightly_build": "True", 05:31:57 INFO - "buildnumber": 16, 05:31:57 INFO - "slavename": "t-yosemite-r7-0264", 05:31:57 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:31:57 INFO - "platform": "yosemite_r7", 05:31:57 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:31:57 INFO - "branch": "mozilla-central", 05:31:57 INFO - "repo_path": "mozilla-central", 05:31:57 INFO - "revision": "969c3295d3aa77931cca26eddb047d9d74bd9858", 05:31:57 INFO - "pgo_build": "False", 05:31:57 INFO - "builduid": "ad75f2f9f6854d1b9de0e8158fcf7f67", 05:31:57 INFO - "slavebuilddir": "test" 05:31:57 INFO - } 05:31:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:31:57 INFO - Running post-action listener: _resource_record_post_action 05:31:57 INFO - [mozharness: 2016-10-30 12:31:57.155014Z] Finished read-buildbot-config step (success) 05:31:57 INFO - [mozharness: 2016-10-30 12:31:57.155127Z] Running download-and-extract step. 05:31:57 INFO - Running pre-action listener: _resource_record_pre_action 05:31:57 INFO - Running main action method: download_and_extract 05:31:57 INFO - mkdir: /builds/slave/test/build/tests 05:31:57 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')]} 05:31:57 INFO - trying https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:31:57 INFO - Downloading https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/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 05:31:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/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 05:31:58 INFO - Downloaded 1199 bytes. 05:31:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:31:58 INFO - Using the following test package requirements: 05:31:58 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:31:58 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:31:58 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:31:58 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'jsshell-mac.zip'], 05:31:58 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:31:58 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:31:58 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:31:58 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:31:58 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:31:58 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:31:58 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:31:58 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:31:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:31:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:31:58 INFO - Fetch https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:31:58 INFO - Http code: 200 05:31:58 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 05:31:58 INFO - x-amz-cf-id: PKgJZHk328eUEbRt_h6jlpzbCxcvaLd5zKaaU_aUZXIIfYP6mRuCLA== 05:31:58 INFO - x-amz-version-id: Q._ezRc3jRAUrX1eK9tZpX1HsaMBt6C0 05:31:58 INFO - Expected file size: 16391782 05:31:58 INFO - Obtained file size: 16391782 05:32:05 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 05:32:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:32:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:32:05 INFO - Fetch https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:32:06 INFO - Http code: 200 05:32:06 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 05:32:06 INFO - x-amz-cf-id: UQmXQOqM3SJ2C8iBq4tC2SKnLFEimJOhU97WHI0wlH5fQPJf4qHAKg== 05:32:06 INFO - x-amz-version-id: Q._ezRc3jRAUrX1eK9tZpX1HsaMBt6C0 05:32:06 INFO - Expected file size: 16391782 05:32:06 INFO - Obtained file size: 16391782 05:32:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:32:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:32:12 INFO - Fetch https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:32:13 INFO - Http code: 200 05:32:13 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 05:32:13 INFO - x-amz-cf-id: bUclXo0s1mbMfqOegz_RHUUv-_U-Qs94p1cFifjl4WdDtkJf2wTl4g== 05:32:13 INFO - x-amz-version-id: g9C_TCMV_FZwAoK8WtXR2bHg5_NMloIT 05:32:13 INFO - Expected file size: 14409038 05:32:13 INFO - Obtained file size: 14409038 05:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:13 INFO - trying https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:32:13 INFO - Downloading https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:32:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/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 05:32:16 INFO - Downloaded 112185659 bytes. 05:32:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:32:16 INFO - mkdir: /builds/slave/test/properties 05:32:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:32:16 INFO - Writing to file /builds/slave/test/properties/build_url 05:32:16 INFO - Contents: 05:32:16 INFO - build_url:https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:32:17 INFO - Running post-action listener: _resource_record_post_action 05:32:17 INFO - Running post-action listener: set_extra_try_arguments 05:32:17 INFO - [mozharness: 2016-10-30 12:32:17.375049Z] Finished download-and-extract step (success) 05:32:17 INFO - [mozharness: 2016-10-30 12:32:17.375187Z] Running populate-webroot step. 05:32:17 INFO - Running pre-action listener: _resource_record_pre_action 05:32:17 INFO - Running main action method: populate_webroot 05:32:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:32:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:32:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:32:17 INFO - '--tppagecycles', 05:32:17 INFO - '7'], 05:32:17 INFO - 'tests': ['tsvgm']}}, 05:32:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:32:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:32:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:32:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:32:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:32:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:32:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:17 INFO - 'talos_options': ['--disable-e10s'], 05:32:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:17 INFO - 'talos_options': ['--disable-e10s'], 05:32:17 INFO - 'tests': ['damp', 'tps']}, 05:32:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:17 INFO - 'tests': ['damp', 'tps']}, 05:32:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:32:17 INFO - 'tests': ['dromaeo_dom']}, 05:32:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:32:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:32:17 INFO - 'tests': ['basic_compositor_video']}, 05:32:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:32:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:32:17 INFO - 'tests': ['a11yr', 05:32:17 INFO - 'ts_paint', 05:32:17 INFO - 'tpaint', 05:32:17 INFO - 'sessionrestore', 05:32:17 INFO - 'sessionrestore_no_auto_restore', 05:32:17 INFO - 'tabpaint']}, 05:32:17 INFO - 'other-e10s': {'tests': ['a11yr', 05:32:17 INFO - 'ts_paint', 05:32:17 INFO - 'tpaint', 05:32:17 INFO - 'sessionrestore', 05:32:17 INFO - 'sessionrestore_no_auto_restore', 05:32:17 INFO - 'tabpaint']}, 05:32:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:32:17 INFO - 'ts_paint', 05:32:17 INFO - 'tpaint', 05:32:17 INFO - 'sessionrestore', 05:32:17 INFO - 'sessionrestore_no_auto_restore', 05:32:17 INFO - 'tabpaint']}, 05:32:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:32:17 INFO - 'ts_paint', 05:32:17 INFO - 'tpaint', 05:32:17 INFO - 'sessionrestore', 05:32:17 INFO - 'sessionrestore_no_auto_restore', 05:32:17 INFO - 'tabpaint']}, 05:32:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:32:17 INFO - 'tests': ['a11yr', 05:32:17 INFO - 'ts_paint', 05:32:17 INFO - 'tpaint', 05:32:17 INFO - 'sessionrestore', 05:32:17 INFO - 'sessionrestore_no_auto_restore', 05:32:17 INFO - 'tabpaint']}, 05:32:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:32:17 INFO - 'tests': ['a11yr', 05:32:17 INFO - 'ts_paint', 05:32:17 INFO - 'tpaint', 05:32:17 INFO - 'sessionrestore', 05:32:17 INFO - 'sessionrestore_no_auto_restore', 05:32:17 INFO - 'tabpaint']}, 05:32:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:32:17 INFO - 'tests': ['tsvgx', 05:32:17 INFO - 'tsvgr_opacity', 05:32:17 INFO - 'tart', 05:32:17 INFO - 'tscrollx', 05:32:17 INFO - 'cart']}, 05:32:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:32:17 INFO - 'tsvgr_opacity', 05:32:17 INFO - 'tart', 05:32:17 INFO - 'tscrollx', 05:32:17 INFO - 'cart']}, 05:32:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:17 INFO - 'talos_options': ['--disable-e10s'], 05:32:17 INFO - 'tests': ['tp5o']}, 05:32:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:17 INFO - 'tests': ['tp5o']}, 05:32:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:17 INFO - 'talos_options': ['--disable-e10s', 05:32:17 INFO - '--xperf_path', 05:32:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:17 INFO - 'tests': ['tp5n']}, 05:32:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:17 INFO - 'talos_options': ['--xperf_path', 05:32:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:17 INFO - 'tests': ['tp5n']}}, 05:32:17 INFO - 'talos.zip': {'path': '', 05:32:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:32:17 INFO - Running post-action listener: _resource_record_post_action 05:32:17 INFO - [mozharness: 2016-10-30 12:32:17.393930Z] Finished populate-webroot step (success) 05:32:17 INFO - [mozharness: 2016-10-30 12:32:17.394026Z] Running create-virtualenv step. 05:32:17 INFO - Running pre-action listener: _resource_record_pre_action 05:32:17 INFO - Running main action method: create_virtualenv 05:32:17 INFO - Creating virtualenv /builds/slave/test/build/venv 05:32:17 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 05:32:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:32:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:32:17 INFO - Using real prefix '/tools/python27' 05:32:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:32:18 INFO - Installing distribute.............................................................................................................................................................................................done. 05:32:21 INFO - Installing pip.................done. 05:32:21 INFO - Return code: 0 05:32:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:32:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:32:21 INFO - Reading from file tmpfile_stdout 05:32:21 INFO - Output received: 05:32:21 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) 05:32:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:32:21 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')]} 05:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:21 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')]} 05:32:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:21 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 0x10a34e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28844edc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a162d30>, '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 0x109f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15ab20>, '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.dF7mrevgjK/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.jhPBYTl84u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 05:32:21 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 05:32:21 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 05:32:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dF7mrevgjK/Render', 05:32:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:21 INFO - 'HOME': '/Users/cltbld', 05:32:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:21 INFO - 'LOGNAME': 'cltbld', 05:32:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:21 INFO - 'MOZ_NO_REMOTE': '1', 05:32:21 INFO - 'NO_EM_RESTART': '1', 05:32:21 INFO - 'PAGER': '/bin/cat', 05:32:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:21 INFO - 'PWD': '/builds/slave/test', 05:32:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:21 INFO - 'SHELL': '/bin/bash', 05:32:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jhPBYTl84u/Listeners', 05:32:21 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 05:32:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:21 INFO - 'USER': 'cltbld', 05:32:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:21 INFO - 'XPC_FLAGS': '0x0', 05:32:21 INFO - 'XPC_SERVICE_NAME': '0', 05:32:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:21 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 05:32:21 INFO - Cleaning up... 05:32:21 INFO - Return code: 0 05:32:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:32:21 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')]} 05:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:21 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')]} 05:32:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:21 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 0x10a34e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28844edc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a162d30>, '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 0x109f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15ab20>, '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.dF7mrevgjK/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.jhPBYTl84u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 05:32:21 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 05:32:21 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 05:32:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dF7mrevgjK/Render', 05:32:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:21 INFO - 'HOME': '/Users/cltbld', 05:32:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:21 INFO - 'LOGNAME': 'cltbld', 05:32:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:21 INFO - 'MOZ_NO_REMOTE': '1', 05:32:21 INFO - 'NO_EM_RESTART': '1', 05:32:21 INFO - 'PAGER': '/bin/cat', 05:32:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:21 INFO - 'PWD': '/builds/slave/test', 05:32:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:21 INFO - 'SHELL': '/bin/bash', 05:32:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jhPBYTl84u/Listeners', 05:32:21 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 05:32:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:21 INFO - 'USER': 'cltbld', 05:32:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:21 INFO - 'XPC_FLAGS': '0x0', 05:32:21 INFO - 'XPC_SERVICE_NAME': '0', 05:32:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:21 INFO - Downloading/unpacking psutil>=3.1.1 05:32:21 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 05:32:21 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 05:32:21 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 05:32:21 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 05:32:21 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 05:32:21 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 05:32:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:32:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:25 INFO - Installing collected packages: psutil 05:32:25 INFO - Running setup.py install for psutil 05:32:25 INFO - building 'psutil._psutil_osx' extension 05:32:25 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 05:32:26 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 05:32:26 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 05:32:26 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 05:32:26 INFO - building 'psutil._psutil_posix' extension 05:32:26 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 05:32:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:32:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:32:26 INFO - ^ 05:32:26 INFO - 1 warning generated. 05:32:26 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 05:32:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:26 INFO - Successfully installed psutil 05:32:26 INFO - Cleaning up... 05:32:26 INFO - Return code: 0 05:32:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:26 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 0x10a34e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28844edc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a162d30>, '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 0x109f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15ab20>, '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.dF7mrevgjK/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.jhPBYTl84u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 05:32:26 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 05:32:26 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 05:32:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dF7mrevgjK/Render', 05:32:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:26 INFO - 'HOME': '/Users/cltbld', 05:32:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:26 INFO - 'LOGNAME': 'cltbld', 05:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:26 INFO - 'MOZ_NO_REMOTE': '1', 05:32:26 INFO - 'NO_EM_RESTART': '1', 05:32:26 INFO - 'PAGER': '/bin/cat', 05:32:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:26 INFO - 'PWD': '/builds/slave/test', 05:32:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:26 INFO - 'SHELL': '/bin/bash', 05:32:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jhPBYTl84u/Listeners', 05:32:26 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 05:32:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:26 INFO - 'USER': 'cltbld', 05:32:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:26 INFO - 'XPC_FLAGS': '0x0', 05:32:26 INFO - 'XPC_SERVICE_NAME': '0', 05:32:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:32:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:32:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:32:29 INFO - Installing collected packages: mozsystemmonitor 05:32:29 INFO - Running setup.py install for mozsystemmonitor 05:32:29 INFO - Successfully installed mozsystemmonitor 05:32:29 INFO - Cleaning up... 05:32:29 INFO - Return code: 0 05:32:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:32:29 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')]} 05:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:29 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')]} 05:32:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:29 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 0x10a34e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28844edc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a162d30>, '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 0x109f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15ab20>, '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.dF7mrevgjK/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.jhPBYTl84u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 05:32:29 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 05:32:29 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 05:32:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dF7mrevgjK/Render', 05:32:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:29 INFO - 'HOME': '/Users/cltbld', 05:32:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:29 INFO - 'LOGNAME': 'cltbld', 05:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:29 INFO - 'MOZ_NO_REMOTE': '1', 05:32:29 INFO - 'NO_EM_RESTART': '1', 05:32:29 INFO - 'PAGER': '/bin/cat', 05:32:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:29 INFO - 'PWD': '/builds/slave/test', 05:32:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:29 INFO - 'SHELL': '/bin/bash', 05:32:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jhPBYTl84u/Listeners', 05:32:29 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 05:32:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:29 INFO - 'USER': 'cltbld', 05:32:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:29 INFO - 'XPC_FLAGS': '0x0', 05:32:29 INFO - 'XPC_SERVICE_NAME': '0', 05:32:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:29 INFO - Downloading/unpacking jsonschema==2.5.1 05:32:29 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 05:32:29 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 05:32:29 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 05:32:29 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 05:32:29 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 05:32:29 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 05:32:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:32:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:32 INFO - Downloading functools32-3.2.3-2.tar.gz 05:32:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:32:32 INFO - warning: no files found matching '*.txt' 05:32:32 INFO - no previously-included directories found matching 'build' 05:32:32 INFO - no previously-included directories found matching 'dist' 05:32:32 INFO - no previously-included directories found matching '.git*' 05:32:32 INFO - Installing collected packages: jsonschema, functools32 05:32:32 INFO - Running setup.py install for functools32 05:32:33 INFO - warning: no files found matching '*.txt' 05:32:33 INFO - no previously-included directories found matching 'build' 05:32:33 INFO - no previously-included directories found matching 'dist' 05:32:33 INFO - no previously-included directories found matching '.git*' 05:32:33 INFO - Successfully installed jsonschema functools32 05:32:33 INFO - Cleaning up... 05:32:33 INFO - Return code: 0 05:32:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:33 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 0x10a34e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28844edc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a162d30>, '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 0x109f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15ab20>, '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.dF7mrevgjK/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.jhPBYTl84u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 05:32:33 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 05:32:33 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 05:32:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dF7mrevgjK/Render', 05:32:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:33 INFO - 'HOME': '/Users/cltbld', 05:32:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:33 INFO - 'LOGNAME': 'cltbld', 05:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:33 INFO - 'MOZ_NO_REMOTE': '1', 05:32:33 INFO - 'NO_EM_RESTART': '1', 05:32:33 INFO - 'PAGER': '/bin/cat', 05:32:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:33 INFO - 'PWD': '/builds/slave/test', 05:32:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:33 INFO - 'SHELL': '/bin/bash', 05:32:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jhPBYTl84u/Listeners', 05:32:33 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 05:32:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:33 INFO - 'USER': 'cltbld', 05:32:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:33 INFO - 'XPC_FLAGS': '0x0', 05:32:33 INFO - 'XPC_SERVICE_NAME': '0', 05:32:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:32:33 INFO - Cleaning up... 05:32:33 INFO - Return code: 0 05:32:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:33 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 0x10a34e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28844edc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a162d30>, '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 0x109f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15ab20>, '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.dF7mrevgjK/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.jhPBYTl84u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 05:32:33 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 05:32:33 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 05:32:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dF7mrevgjK/Render', 05:32:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:33 INFO - 'HOME': '/Users/cltbld', 05:32:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:33 INFO - 'LOGNAME': 'cltbld', 05:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:33 INFO - 'MOZ_NO_REMOTE': '1', 05:32:33 INFO - 'NO_EM_RESTART': '1', 05:32:33 INFO - 'PAGER': '/bin/cat', 05:32:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:33 INFO - 'PWD': '/builds/slave/test', 05:32:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:33 INFO - 'SHELL': '/bin/bash', 05:32:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jhPBYTl84u/Listeners', 05:32:33 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 05:32:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:33 INFO - 'USER': 'cltbld', 05:32:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:33 INFO - 'XPC_FLAGS': '0x0', 05:32:33 INFO - 'XPC_SERVICE_NAME': '0', 05:32:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:33 INFO - Downloading/unpacking blobuploader==1.2.4 05:32:33 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 05:32:33 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 05:32:33 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 05:32:33 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 05:32:33 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 05:32:33 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 05:32:36 INFO - Downloading blobuploader-1.2.4.tar.gz 05:32:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:32:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:32:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:32:37 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 05:32:37 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 05:32:37 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 05:32:37 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 05:32:37 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 05:32:37 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 05:32:37 INFO - Downloading docopt-0.6.1.tar.gz 05:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:32:37 INFO - Installing collected packages: blobuploader, requests, docopt 05:32:37 INFO - Running setup.py install for blobuploader 05:32:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:32:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:32:37 INFO - Running setup.py install for requests 05:32:38 INFO - Running setup.py install for docopt 05:32:38 INFO - Successfully installed blobuploader requests docopt 05:32:38 INFO - Cleaning up... 05:32:38 INFO - Return code: 0 05:32:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:38 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')]} 05:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:38 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')]} 05:32:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:38 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 0x10a34e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28844edc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a162d30>, '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 0x109f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15ab20>, '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.dF7mrevgjK/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.jhPBYTl84u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 05:32:38 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 05:32:38 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 05:32:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dF7mrevgjK/Render', 05:32:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:38 INFO - 'HOME': '/Users/cltbld', 05:32:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:38 INFO - 'LOGNAME': 'cltbld', 05:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:38 INFO - 'MOZ_NO_REMOTE': '1', 05:32:38 INFO - 'NO_EM_RESTART': '1', 05:32:38 INFO - 'PAGER': '/bin/cat', 05:32:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:38 INFO - 'PWD': '/builds/slave/test', 05:32:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:38 INFO - 'SHELL': '/bin/bash', 05:32:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jhPBYTl84u/Listeners', 05:32:38 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 05:32:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:38 INFO - 'USER': 'cltbld', 05:32:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:38 INFO - 'XPC_FLAGS': '0x0', 05:32:38 INFO - 'XPC_SERVICE_NAME': '0', 05:32:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:32:38 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-PoHrRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:38 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-tSXWeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:38 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-ub9p3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:38 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-g3JeRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:39 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-zyDVEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:39 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-BXJ6B7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:39 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-a2Y5bB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:39 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-qFzTBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:39 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-gXSWQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:39 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-tSbSK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:39 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-yiiC62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:39 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-6vjvCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:39 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-HSJCJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:39 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-3cZHZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:40 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-RWftuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:40 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-0fhkkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:40 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-sWZrqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:32:40 INFO - Running setup.py install for manifestparser 05:32:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:32:40 INFO - Running setup.py install for mozcrash 05:32:40 INFO - Running setup.py install for mozdebug 05:32:40 INFO - Running setup.py install for mozdevice 05:32:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:32:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:32:40 INFO - Running setup.py install for mozfile 05:32:41 INFO - Running setup.py install for mozhttpd 05:32:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:32:41 INFO - Running setup.py install for mozinfo 05:32:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:32:41 INFO - Running setup.py install for mozInstall 05:32:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:32:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:32:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:32:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:32:41 INFO - Running setup.py install for mozleak 05:32:41 INFO - Running setup.py install for mozlog 05:32:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:32:41 INFO - Running setup.py install for moznetwork 05:32:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:32:42 INFO - Running setup.py install for mozprocess 05:32:42 INFO - Running setup.py install for mozprofile 05:32:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:32:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:32:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:32:42 INFO - Running setup.py install for mozrunner 05:32:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:32:42 INFO - Running setup.py install for mozscreenshot 05:32:42 INFO - Running setup.py install for moztest 05:32:42 INFO - Running setup.py install for mozversion 05:32:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:32:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:32:42 INFO - Cleaning up... 05:32:42 INFO - Return code: 0 05:32:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a34e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28844edc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a162d30>, '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 0x109f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15ab20>, '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.dF7mrevgjK/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.jhPBYTl84u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 05:32:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:32:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dF7mrevgjK/Render', 05:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:42 INFO - 'HOME': '/Users/cltbld', 05:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:42 INFO - 'LOGNAME': 'cltbld', 05:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:42 INFO - 'MOZ_NO_REMOTE': '1', 05:32:42 INFO - 'NO_EM_RESTART': '1', 05:32:42 INFO - 'PAGER': '/bin/cat', 05:32:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:42 INFO - 'PWD': '/builds/slave/test', 05:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:42 INFO - 'SHELL': '/bin/bash', 05:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jhPBYTl84u/Listeners', 05:32:42 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 05:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:42 INFO - 'USER': 'cltbld', 05:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:42 INFO - 'XPC_FLAGS': '0x0', 05:32:42 INFO - 'XPC_SERVICE_NAME': '0', 05:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:32:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-AGS_lX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:43 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)) 05:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-pFtYKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:43 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)) 05:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-dNExJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:43 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)) 05:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-UmfIjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-1gaXO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-dsBMeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-MRoZPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:43 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-Qtjo1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:44 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)) 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:44 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-u_4YhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:44 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)) 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:44 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-zpSxaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:44 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)) 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:44 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-rbhyuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:44 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)) 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:44 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-HUKQev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:44 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)) 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:44 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-z0hrMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:44 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-SXTBtl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:44 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-KozPhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:44 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-lfZVkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:44 INFO - Running setup.py (path:/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/pip-CSN8za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:45 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)) 05:32:45 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)) 05:32:45 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)) 05:32:45 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)) 05:32:45 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)) 05:32:45 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)) 05:32:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:32:45 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 05:32:45 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 05:32:45 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 05:32:45 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 05:32:45 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 05:32:45 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 05:32:47 INFO - Downloading blessings-1.6.tar.gz 05:32:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:32:47 INFO - Installing collected packages: blessings 05:32:47 INFO - Running setup.py install for blessings 05:32:47 INFO - Successfully installed blessings 05:32:47 INFO - Cleaning up... 05:32:47 INFO - Return code: 0 05:32:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:32:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:48 INFO - Reading from file tmpfile_stdout 05:32:48 INFO - Current package versions: 05:32:48 INFO - blessings == 1.6 05:32:48 INFO - blobuploader == 1.2.4 05:32:48 INFO - docopt == 0.6.1 05:32:48 INFO - functools32 == 3.2.3-2 05:32:48 INFO - jsonschema == 2.5.1 05:32:48 INFO - manifestparser == 1.1 05:32:48 INFO - mozInstall == 1.12 05:32:48 INFO - mozcrash == 0.17 05:32:48 INFO - mozdebug == 0.1 05:32:48 INFO - mozdevice == 0.48 05:32:48 INFO - mozfile == 1.2 05:32:48 INFO - mozhttpd == 0.7 05:32:48 INFO - mozinfo == 0.9 05:32:48 INFO - mozleak == 0.1 05:32:48 INFO - mozlog == 3.4 05:32:48 INFO - moznetwork == 0.27 05:32:48 INFO - mozprocess == 0.23 05:32:48 INFO - mozprofile == 0.28 05:32:48 INFO - mozrunner == 6.12 05:32:48 INFO - mozscreenshot == 0.1 05:32:48 INFO - mozsystemmonitor == 0.3 05:32:48 INFO - moztest == 0.7 05:32:48 INFO - mozversion == 1.4 05:32:48 INFO - psutil == 3.1.1 05:32:48 INFO - requests == 1.2.3 05:32:48 INFO - wsgiref == 0.1.2 05:32:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a34e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28844edc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a162d30>, '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 0x109f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15ab20>, '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.dF7mrevgjK/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.jhPBYTl84u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 05:32:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:32:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:32:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dF7mrevgjK/Render', 05:32:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:48 INFO - 'HOME': '/Users/cltbld', 05:32:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:48 INFO - 'LOGNAME': 'cltbld', 05:32:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:48 INFO - 'MOZ_NO_REMOTE': '1', 05:32:48 INFO - 'NO_EM_RESTART': '1', 05:32:48 INFO - 'PAGER': '/bin/cat', 05:32:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:48 INFO - 'PWD': '/builds/slave/test', 05:32:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:48 INFO - 'SHELL': '/bin/bash', 05:32:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jhPBYTl84u/Listeners', 05:32:48 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 05:32:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:48 INFO - 'USER': 'cltbld', 05:32:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:48 INFO - 'XPC_FLAGS': '0x0', 05:32:48 INFO - 'XPC_SERVICE_NAME': '0', 05:32:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:32:48 INFO - Cleaning up... 05:32:48 INFO - Return code: 0 05:32:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a34e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28844edc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a162d30>, '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 0x109f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15ab20>, '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.dF7mrevgjK/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.jhPBYTl84u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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 05:32:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:32:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:32:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dF7mrevgjK/Render', 05:32:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:48 INFO - 'HOME': '/Users/cltbld', 05:32:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:48 INFO - 'LOGNAME': 'cltbld', 05:32:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:48 INFO - 'MOZ_NO_REMOTE': '1', 05:32:48 INFO - 'NO_EM_RESTART': '1', 05:32:48 INFO - 'PAGER': '/bin/cat', 05:32:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:48 INFO - 'PWD': '/builds/slave/test', 05:32:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:48 INFO - 'SHELL': '/bin/bash', 05:32:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jhPBYTl84u/Listeners', 05:32:48 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 05:32:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:48 INFO - 'USER': 'cltbld', 05:32:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:48 INFO - 'XPC_FLAGS': '0x0', 05:32:48 INFO - 'XPC_SERVICE_NAME': '0', 05:32:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:32:48 INFO - Cleaning up... 05:32:48 INFO - Return code: 0 05:32:48 INFO - Running post-action listener: _resource_record_post_action 05:32:48 INFO - Running post-action listener: _start_resource_monitoring 05:32:48 INFO - Starting resource monitoring. 05:32:48 INFO - [mozharness: 2016-10-30 12:32:48.810482Z] Finished create-virtualenv step (success) 05:32:48 INFO - [mozharness: 2016-10-30 12:32:48.811543Z] Running install step. 05:32:48 INFO - Running pre-action listener: _resource_record_pre_action 05:32:48 INFO - Running main action method: install 05:32:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:49 INFO - Reading from file tmpfile_stdout 05:32:49 INFO - Detecting whether we're running mozinstall >=1.0... 05:32:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:32:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:32:49 INFO - Reading from file tmpfile_stdout 05:32:49 INFO - Output received: 05:32:49 INFO - Usage: mozinstall [options] installer 05:32:49 INFO - Options: 05:32:49 INFO - -h, --help show this help message and exit 05:32:49 INFO - -d DEST, --destination=DEST 05:32:49 INFO - Directory to install application into. [default: 05:32:49 INFO - "/builds/slave/test"] 05:32:49 INFO - --app=APP Application being installed. [default: firefox] 05:32:49 INFO - mkdir: /builds/slave/test/build/application 05:32:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:32:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:33:23 INFO - Reading from file tmpfile_stdout 05:33:23 INFO - Output received: 05:33:23 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:33:23 INFO - Running post-action listener: _resource_record_post_action 05:33:23 INFO - [mozharness: 2016-10-30 12:33:23.575537Z] Finished install step (success) 05:33:23 INFO - [mozharness: 2016-10-30 12:33:23.575672Z] Running run-tests step. 05:33:23 INFO - Running pre-action listener: _resource_record_pre_action 05:33:23 INFO - Running main action method: run_tests 05:33:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:33:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:33:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:33:23 INFO - 2016-10-30 05:33:23.590 screenresolution[2043:28760] starting screenresolution argv=screenresolution get 05:33:23 INFO - 2016-10-30 05:33:23.592 screenresolution[2043:28760] Display 0: 1600x1200x32@60 05:33:23 INFO - 2016-10-30 05:33:23.598 screenresolution[2044:28763] starting screenresolution argv=screenresolution list 05:33:23 INFO - Available Modes on Display 0 05:33:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:33:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:33:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:33:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:33:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:33:23 INFO - Intel Iris: 05:33:23 INFO - Chipset Model: Intel Iris 05:33:23 INFO - Type: GPU 05:33:23 INFO - Bus: Built-In 05:33:23 INFO - VRAM (Dynamic, Max): 1536 MB 05:33:23 INFO - Vendor: Intel (0x8086) 05:33:23 INFO - Device ID: 0x0a2e 05:33:23 INFO - Revision ID: 0x0009 05:33:23 INFO - Displays: 05:33:23 INFO - Display: 05:33:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:33:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:33:23 INFO - Display Serial Number: 1600x1200 60 05:33:23 INFO - Main Display: Yes 05:33:23 INFO - Mirror: Off 05:33:23 INFO - Online: Yes 05:33:23 INFO - Rotation: Supported 05:33:23 INFO - Return code: 0 05:33:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:33:23 INFO - Python 2.7.3 05:33:23 INFO - Return code: 0 05:33:23 INFO - grabbing minidump binary from tooltool 05:33:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:23 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 0x10a162d30>, '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 0x109f88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a15ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:33:23 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 05:33:23 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 05:33:23 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 05:33:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:33:23 INFO - Return code: 0 05:33:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:33:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:33:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:33:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:33:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:33:23 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0264', '--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 05:33:23 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0264 --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 05:33:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dF7mrevgjK/Render', 05:33:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:23 INFO - 'HOME': '/Users/cltbld', 05:33:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:23 INFO - 'LOGNAME': 'cltbld', 05:33:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:33:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:23 INFO - 'MOZ_NO_REMOTE': '1', 05:33:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:33:23 INFO - 'NO_EM_RESTART': '1', 05:33:23 INFO - 'PAGER': '/bin/cat', 05:33:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:23 INFO - 'PWD': '/builds/slave/test', 05:33:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:33:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:23 INFO - 'SHELL': '/bin/bash', 05:33:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jhPBYTl84u/Listeners', 05:33:23 INFO - 'TMPDIR': '/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/', 05:33:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:23 INFO - 'USER': 'cltbld', 05:33:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:23 INFO - 'XPC_FLAGS': '0x0', 05:33:23 INFO - 'XPC_SERVICE_NAME': '0', 05:33:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:23 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0264', '--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 05:33:24 INFO - mozversion application_buildid: 20161030030204 05:33:24 INFO - mozversion application_changeset: 969c3295d3aa77931cca26eddb047d9d74bd9858 05:33:24 INFO - mozversion application_display_name: Nightly 05:33:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:33:24 INFO - mozversion application_name: Firefox 05:33:24 INFO - mozversion application_remotingname: firefox 05:33:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:33:24 INFO - mozversion application_vendor: Mozilla 05:33:24 INFO - mozversion application_version: 52.0a1 05:33:24 INFO - mozversion platform_buildid: 20161030030204 05:33:24 INFO - mozversion platform_changeset: 969c3295d3aa77931cca26eddb047d9d74bd9858 05:33:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:33:24 INFO - mozversion platform_version: 52.0a1 05:33:24 INFO - using testdate: 1477830804 05:33:24 INFO - actual date: 1477830804 05:33:24 INFO - starting webserver on 'localhost:50915' 05:33:24 INFO - SUITE-START | Running 2 tests 05:33:24 INFO - TEST-START | dromaeo_css 05:33:24 INFO - operating with platform_type : mac_ 05:33:24 INFO - Initialising browser for dromaeo_css test... 05:33:24 INFO - TEST-INFO | started process 2054 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpqTPTBy/profile http://localhost:50915/getInfo.html) 05:33:26 INFO - PROCESS | 2054 | __metrics Screen width/height:1600/1200 05:33:26 INFO - PROCESS | 2054 | colorDepth:24 05:33:26 INFO - PROCESS | 2054 | Browser inner width/height: 1024/689 05:33:26 INFO - PROCESS | 2054 | __metrics 05:33:26 INFO - PROCESS | 2054 | 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" 05:33:26 INFO - PROCESS | 2054 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 205: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:33:27 INFO - TEST-INFO | 2054: exit 0 05:33:27 INFO - Browser initialized. 05:33:27 INFO - Running cycle 1/1 for dromaeo_css test... 05:33:27 INFO - TEST-INFO | started process 2057 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpqTPTBy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:39 INFO - PROCESS | 2057 | RSS: Main: 233820160 05:33:39 INFO - PROCESS | 2057 | 05:34:31 INFO - PROCESS | 2057 | 1477830871074 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] 05:36:44 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:50915/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50915/tests/dromaeo/cssquery-ext.html) 05:36:46 INFO - PROCESS | 2057 | RSS: Main: 379084800 05:36:46 INFO - PROCESS | 2057 | 05:39:49 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:50915/tests/dromaeo/cssquery-ext.html (next: http://localhost:50915/tests/dromaeo/cssquery-jquery.html) 05:39:49 INFO - PROCESS | 2057 | RSS: Main: 372105216 05:39:49 INFO - PROCESS | 2057 | 05:42:52 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:50915/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50915/tests/dromaeo/cssquery-mootools.html) 05:42:52 INFO - PROCESS | 2057 | RSS: Main: 353353728 05:42:52 INFO - PROCESS | 2057 | 05:45:55 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:50915/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50915/tests/dromaeo/cssquery-prototype.html) 05:45:55 INFO - PROCESS | 2057 | RSS: Main: 340684800 05:45:55 INFO - PROCESS | 2057 | 05:48:59 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:50915/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50915/tests/dromaeo/cssquery-yui.html) 05:48:59 INFO - PROCESS | 2057 | RSS: Main: 337244160 05:48:59 INFO - PROCESS | 2057 | 05:52:02 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:50915/tests/dromaeo/cssquery-yui.html (next: http://localhost:50915/tests/dromaeo/cssquery-dojo.html) 05:52:03 INFO - PROCESS | 2057 | RSS: Main: 467988480 05:52:03 INFO - PROCESS | 2057 | 05:52:03 INFO - PROCESS | 2057 | __start_tp_report 05:52:03 INFO - PROCESS | 2057 | _x_x_mozilla_page_load 05:52:03 INFO - PROCESS | 2057 | _x_x_mozilla_page_load_details 05:52:03 INFO - PROCESS | 2057 | |i|pagename|runs| 05:52:03 INFO - PROCESS | 2057 | |0;dojo.html;2761.640986671733;3231.628362738286;3266.738660907127;3276.852541635626;3334.3664703706277;5427.62006659534;5476.397596264413;5512.476314750093;5542.889142217154;5598.720063996794;5445.83662490125;5534.75093620787;5556;5569.749361278742;5618.578606604511;4964.925526117093;5008.5993120550265;5021.246812978046;5063.8480845574695;5104.8979020419565;5069.543741063304;5191.714455704935;5196.194589838562;5209.765560549766;5243.292155558989;5160.8967820643375;5171.5862730981435;5183.170692689189;5219.347581552306;5259.921101183485;5001.299818025479;5030.673006254582;5038.974805125988;5085.313482679865;5101.0308041472;4980.701157930535;5021.472745361721;5049.015441988777;5065.8733531662;5068.467810879842;5466.671999680032;5521.834344969658;5523.033469142884;5572.080606699889;5685.715714214314;6115.997869406565;6116.457510225599;6121.63549213247;6130.860895427079;6132.416697016086;4984.27727979441;5366.838994830161;6136.403366381158;6137.322976246954;6152.692365381713;5491.945080549223;5526.585506087019;5573.968815769095;5665.943340566583;5677.744501497443;6213.751449942042;6217.875642487215;6217.974034284292;6219.937800622027;6229.470495007885;5825.0679891217205;5911.054231322968;5914.349421563625;5914.556408269311;5918.881622367615;5052.520010598987;6100.542459315569;6106.45041946218;6109.84725381869;6112.266528016667;5997.880042399215;6034.547408944259;6037.396260373927;6037.54718396122;6041.486473649701;5987.850303742354;6024.186734790835;6039.939600604026;6040.154378387029;6043.848903777441;4753.120672675566;4796.9760151199025;4802.27965805132;4808.35087263215;4838.056578967138;4189.07840275139;4248.596383343577;4253.468316460442;4260.510041345285;4282.186384586918;5439.401665816757;5586.860328491739;5609.467100625434;5610.831675049755;5662.745176467069;5952.940470595326;6054.727537260834;6061.242344706911;6081.6351018939;6112.847178820565;5575.498205161555;5600.047991841397;5612.07400778875;5614.073677843192;5647.0964645656495;5279.736013199402;5304.787808487617;5305.840824775263;5306.708131052752;5351.384590772111;6096.587161059724;6203.751849926133;6206.503479721521;6207.565470417027;6252.843678907881;6122.39815511198;6142.416470435302;6180.845478862884;6188.412100850412;6190.50475961931;6243.157173781663;6259.436650701458;6264.718087685974;6281.811545653638;6282.842928926813;5977.970110149421;6023.879522409439;6048.969755151372;6050.818475445568;6076.665783381956;5991.460768530853;6055.5458340623745;6071.392860713893;6073.848153796191;6132.877342453036;5330.546903513245;5330.8933821324135;5958.76164953397;5986.132010858489;5991.700414979146;5020.49795020495;5913.319968203712;5988.8802223956145;5995.310539288038;6012.6392416455155;5670.688112153871;5727.656340619409;5766.394528574439;5766.596338256281;5837.591368604157;5949.3455719871645;6001.7599296027665;6011.218541589566;6026.186464827192;6043.184170136975;5828.737706803289;5830.300363956352;5831.8833623326445;5834.854128646818;5897.174395584536 05:52:03 INFO - PROCESS | 2057 | |1;ext.html;468.88512314482944;510.39773067780015;522.681164489661;522.9045150409893;525.9184826351915;30572.847135764317;41053.7683869484;42120.73637790867;42123.368149477705;43754.78122609382;43512.78243608785;43846.34230486545;43991.78004109975;44003;44003.559964400345;8354.164583541658;9557.28320375971;9757.073078057609;9771.51142442875;9772.951135244313;9524.190443812284;9628.18160456362;9628.229741620653;9635.759106022335;9636.662716804913;9593.136617704406;9646.324757266993;9651.613935442609;9661.565229564687;9663.5168241588;9563.09150630689;9627.374220675634;9633.325667203299;9658.082482164184;9669.661561845302;9436.433813971114;9448.102430269113;9549.236061115094;9572.138507534286;9575.425474471554;40508;40965;40977.59022409767;40988.79505602453;41052.38421423681;2974.5686875402944;3387.5088112223834;3417.8803741869165;3426.880059197941;4095.4675892133832;25242;30432.54351184734;30727.692723072705;31525.52711709326;32154;3202.343519578481;3202.871885124592;3206.58314419124;3213.775035747499;3329.5338652588657;474022;476928.84606730926;478702;480353;486678;59234;60728.69635651882;60813;61177;61243.38756612466;34871.65128348684;35814.3883525242;65080.67459662671;65261;65401.34598654048;15481.148536830582;15964.36142554308;15981.68036639284;15988.600284992968;15993.920030399773;12331.198472099464;12424.627261182179;12437.689057773629;12446.004319654407;12449.37753112359;19472.415827525198;19503.512412189808;19511.82929024263;19530.414087577396;19542.89599184159;6433.131527243856;6765;6952.991816186624;7700.730474433368;7920.4059695523065;894.2086253665423;908.6229214875752;999.6751055906859;1058.793535260632;1064.4252103863982;28895.42209155805;29103.41793164167;30925.69074309273;31101.844490777403;31295.37409251802;884.827458645558;1013.2653825976256;1050.5430137890041;1065.0840277361463;1087.7335052911928;15487.148206848731;15677.529674109795;15971.680566388608;16118.435854745028;16327.34690612386;488767;655915;656258;657183.1422528666;657875;59021.704891476125;59346.703266484255;59545.106823397684;59689.80620387655;59782;1232.11903489004;1281.3785314122642;1365.0581099041628;1380.3374380297514;1404.6207523968676;29600.851995740744;31425.21436964094;31500.527492087178;31829.5225571612;32004.51993220057;50332.74833625808;51176.99999999851;51201;51285.48714512807;51436.22845657393;470285;522556;523734;524036;524972;26874.32814179661;27187.1843844685;27574.5863812047;27715;28093;41367.37948930708;47428.05143897172;47712.761436192595;48046;49157.75421122871;33129.66870331266;39494.80252598719;39535.2092958145;40100.598994009684;41024.79487602543;29731.851340743156;39359.80320098381;39981.80009099936;40869.38695919623;40887.59112408838;37698.81150594229;38973.220535589695;39278.80360598179;39375.606243937196;39428.80285598554;33248;34107.14732131716;35068;35142;35243.82378088093;22467.213647522258;22901;25781.6281820312;26008.21975340743;26126 05:52:03 INFO - PROCESS | 2057 | |2;jquery.html;319.7298282950906;320.2415756270196;349.6835363995585;369.7596562234549;398.5496389080339;15169.393224271029;15705.214739263034;17236.741448878278;20766.48083797906;20795;19675.704864427044;20343;21282.57434851302;21302.041408136633;21339.893300533477;4039.6970227233073;5827.096799995997;6124.938750612504;6132.846678833021;6144.877102457948;4190.036291652922;6352.301246862841;6564.573302735331;6694.062831203611;6753.831154221135;6405.8398540036405;6608.900866487006;6678.232003319607;6682.465402767792;6702.162229721285;6450.612963222222;6675.899861502081;6706.497012724041;6710.698018589126;6723.025161351626;5733.397993210736;5821.068629019379;5828.766849326022;5833.358330583633;5835.35811060783;25861.482770344675;26478.073267435546;26616.201513954613;26746.598801017804;26815.597766033527;1932.8163824436656;2293.3234695764622;2409.4096946248105;2427;2475.059477398595;15066.698666026728;15328.846711532853;15795.368185272575;16361.263743131587;16503.927244729053;1820.4265656318216;1856.3409989453685;1863.252194911085;1871.4105056907167;1919.8176173263519;102335.97664023339;105877;106261.46869265681;107670;119659.4017029918;9178.724638260863;9662.95168524167;9702.51487425626;9801.754956126153;9923.503824808731;12287.69280767988;12365.567205147776;12380.319082450551;12389.504419823286;12390.6282811514;7821.6871325147495;7941.9205807942335;7970.9202907970175;8059.919400805917;8076.949996500535;6959.4432445404245;8000.279939812968;8296.460730052528;8335.624896879655;8451;3884.96115038846;3885.630865067814;3900.6099390060767;3903.4730311407598;3936.5866584008268;1540.9829512521906;1872.9063546822604;1904.8761830480973;1928.4985903664867;1934.7678278606656;366.09391755405693;400.7775684495075;416.9061961058798;419.4547088784629;682.6006786030129;9401.858972115424;11629.18595698293;11673.299602023906;11727.706807329885;12035.157538972364;422.1788621131869;427.90158263599545;431.439129132127;437.5755517148361;852.1861622150831;10259.897401026043;11194.992450679478;11228.877112288868;11236;11316.547338106548;590943;592845;594231;606763;608863.9556802188;124158;131712;139429.3028534871;140307.29846350703;144113;1008.455434065611;1012.5392946209457;1031.6750223679667;1035.2701345551368;1037.3205550364523;18237.179326930054;18803.341883034027;18843.528911777314;18877.528061798566;18985.14566844468;39070.60929390784;52478.737606311726;53536.732316338166;53728;55110;74809.25190748023;75268.62365688136;76458;76514.23485765293;79897.60051199707;7758;7872.527648341118;8068.47554908929;8096;8404.537750423786;9368.95315523418;12646.177998430072;12835.101542892282;13172;13374;9530.285228607743;9976.401415915068;11166.330020198815;11326.263792853439;11345.773084538429;11010;11170.888291116984;11218;11542.3651699154;11691.240069395462;9158.633654654006;10431.11335536488;10492.422916739653;10829.350238985686;10858.511366988663;7621.847563048819;7719.228077192236;7942;8034.919650803417;8120.187981201832;10360.430176340073;10371.377717336984;10375.844362334721;10464.790704185722;10651.520681569193 05:52:03 INFO - PROCESS | 2057 | |3;mootools.html;1371.095077249016;1560.586444592183;1569.7096037233111;1789.8568114550837;1929.7105434184862;7502;7882.251186137315;8079.636416361271;8426.536540490271;8448.619812108454;8296.419250652456;8382.329413646909;8391.70629027984;8391.790205244857;8397.622107005183;2292.4154340643154;2330.1611419888827;2335.9182428615004;2336.030547322859;2338.029717667166;2304.43541332373;2350.753170917055;2361.799247063993;2363.3737059679197;2363.7163540375177;2272.2388000019932;2289.461976435545;2304.677345171677;2305.7348404933396;2315.0392587076344;2272.4318920269934;2282.566312400647;2306.7001289832383;2310.4223944013997;2316.8262380321457;2302.689136966505;2329.0450915124716;2336.918207862717;2343.7773411525877;2346.507233480971;8301.750947471586;8324.209200126039;8410.411271210956;8422.915770842274;8441;5055.721935293557;5232.189010703347;5238.476152384769;5274.26160337553;5276.894462110775;3439.6904278615043;3484.1463841359027;3507.2108775525558;3581.803000834953;3592.371335016388;1057.1225882517492;1088.9981217280122;1460.7881857130656;1774.8313910178513;1855.4711907106546;10772.40751758653;10916.508757105948;10923.781524369468;10928.398938058404;11048.889511104866;4418.469783625921;4575.702579332399;4736.644751643572;4739.4075740532435;4747;8515.148485151436;8519.403641745143;8645.783855403664;8649.567521623894;8703.912960870437;5125.308083408692;5125.666831655931;5130.102232109366;5131.461196574381;5139.074966506065;3997.5003124609425;4143.627073563393;4427.180971520279;4766.523347665206;4831.106245344622;3555.715542756573;3588.515550400715;3660.40772264066;3673.8060130457707;3676.632336766302;1105.2318638546294;1108.728361551425;1132.0943245403644;1140.3044143072718;1145.639123676056;893.4862454088795;902.4946030223097;906.9229115525122;943.2689665509148;967.274544091931;5648.435156484401;5693.402192769782;5938.49522790559;6005.498625343664;6175.783847565313;832.9583520823935;855.9215388610271;928.4893308680333;958.4009993753904;995.1938929467154;3316.585426821647;3711.4247291669835;3890.9610903891166;3944.783036932939;4126.257273690764;9985.45080020606;10036.146927511098;10142.036506531867;10200.693979180636;10223.59105635781;10144.949275253577;10152.492375381202;10156.746081348025;10162.593496260066;10179.949100254451;7449.851002980018;7841.490303130278;7851.254130857559;7950.920490795018;7955.602219889098;5406.297181366397;5447;5555.611107222618;5660.754633980518;5798.710064496674;8694.826103477768;9026.954865225895;9428.292878034037;9456.621735130519;9464.763380915532;8107.4324797263625;8508.74473765788;9401.717948461557;9452.669156579486;9613.711588652352;6442.967785161044;7195.604241766753;7248.746293879689;7271.563706177646;7381.4094872409005;6898.965505172442;7796.025496812898;7808.453408261367;7913.485623434458;7923.960380198062;7090.964545177241;7116.786496404909;7135.750248741269;7231.4937954342695;7288.672009759467;6821.556598821061;6969.61693518239;6997.440204783707;7093.467989900675;7094.361507464353;6538.313477084837;6585.341465853377;6676.265610782907;6686.598804071771;6690.632015239208;4814.9518504814505;4852.078105160008;4879.878003049952;4931.95323940575;5016;4871.926921096116;4899.706017638953;4937.185364414831;4993.80024799004;5022.121128802518 05:52:03 INFO - PROCESS | 2057 | |4;prototype.html;286.81213804957747;293.87216560796054;305.50813190762875;308.90269565087;310.51249538224965;4271.103068355641;5162.251473536345;5174.5162322085425;5182.533571978521;5228.633995620308;4301.591348821865;4863.246196839486;5026.572741316983;5031.471695471978;5106.744662766865;3500.9824950875272;3644.7266455015847;3947.940780888289;3969.0672691917375;4006.6394024537785;3440.55272814535;3947.8945895149404;3953.98023009886;3958.5051868516434;3961.425073702088;3024.3346463777934;3867.5165604299464;3909.687225022006;3937.606239376054;3958.2281455116263;3862.8261728222014;3917;3924.95988563031;3934.822932968009;3944.9940265232467;3095.4582947984013;3387.4749413840896;3389.3732357423196;3396.915077123067;3407.4718418645148;4298.001429335342;4969.856932905452;4989.276554899519;4997.850064498035;5022.974885125551;3862.594427585091;4185.500586869128;4403.6568846501805;4457.353683715843;4470.709403888769;5039.773210205512;5058.848234552969;5114.565261952738;5423.755930983115;5438.265834112383;3320.850561724704;3711.4989476420606;3713.740038197327;3729.0049823344652;3729.3287208302495;4687.578068010337;4848.878778030542;5348.9302139571855;5390.811321603725;5669.433056694339;3522.5244591979754;3731.570869350005;3733.2720119576966;3749.9062523437133;3766.60450652683;3437;3801.885943421702;3807.9528129764535;3815.2560250751394;3834.69322454203;4456.598906098467;4472.530384309665;4472.920092157097;4478.843240486567;4517.073999830027;4877.414710234723;5073.315102461121;5403.891922161613;5424.755885985141;5488;1213.7997230456876;1300.2783455182464;1303.094349427139;1305.6605282626394;1317.8616245294106;758.0751483190498;799.6961154761153;816.6570040582969;819.6516480495766;839.3117643532245;4856.368672072608;5032.597392208614;5040.958411843209;5068.391792984866;5071.39143302806;5524.640898341595;5525.364583072998;5527.53015993637;5565.107863326489;5628.887422251614;4617.561331673485;4855.465898751135;4928.704277743347;5007.273945277914;5056.924146137811;2566.9871650642;2660.406104048537;2670.5326567850548;2881.294082949649;2934.1050979451047;4699.647526435531;4786.123240175469;5686.232358631614;5704.402767225183;5715.599908006483;5224.52979231871;5591.776328946797;5640.700190388587;5696.829095127153;5703.686297253691;5724.055530837365;5736.397678243808;5757.006356694579;5758.884822303614;5886.558508111823;4120.896977575585;4456.5766252205985;4570.286142696489;5503.064479038653;5550.4449555044175;4260.744355338689;5153.098207813543;5342.813001544927;5467.808626698205;5504.642168277428;5338.172583249602;5478.479544443271;5570;5712;5718.79984200551;3266.44470440021;3494.3186078714407;3697.2790305891167;3705.5923848377197;3717.925641487101;4769.35613692161;4811.374521312207;5397.218917757053;5466.200990311695;5492.642978206404;4942.406911170682;5032.917922646502;5312.442193569619;5342.732863356894;5373.4357892422295;4418.955810441855;5041.6722913010535;5278.524932755917;5348.3363895731545;5350.892982140414;4689.437267527918;5006.949930500648;5348.946510535001;5442.455754424526;5465.508104270635;4713.340132381538;5395.514403703685;5398.920215956903;5454.372747134131;5484.506394424521;5264.368045905781;5359.812406565751;5364.049772624962;5383.461653834742;5384.596155288447 05:52:03 INFO - PROCESS | 2057 | |5;yui.html;727.8325985023445;734.1447213995696;743.1156923261318;743.724512461129;756.4931495897748;14738.189399583018;15243.92378038111;15263.923680381597;15265.465708700198;15466.226688665578;12414;12420.254784712924;12422.440990155443;12427.440765165567;12433.067519935996;689.1076056506836;716.3087620446269;722.5447967780291;877.368294827723;900.5722281916103;866.1511718515858;890.5680744838749;890.5725251879102;910.5947853205327;912.5619702542783;918.9724308270762;934.1685899549407;951.4291425144927;955.8900726416482;957.9329446938717;726.5640615630632;948.9762755931088;949.8242825077383;951.8001219743862;952.8951815300311;557.328419254797;675.790504943469;702.217028013766;704.309776419107;704.5103652961194;14676.339564719614;14725.484608038667;14738.631534211623;14766.926165369105;14774.335154917948;13030.609081727564;13173.143745656598;13285.829443786488;13293.867061329456;13549.458021679122;11749.765004699944;12048.096392770578;12272.570460033945;12392.938035309857;12526.997840172764;4345.8261669533495;4395.7142785718825;4423.358613001129;4451.198784218839;4586.954130458719;64560;79175;82455;82732.17267827365;82885;10340.327878687862;10405.011523905217;10596.205284603686;10613.204009699304;10664.680059598224;17247.74128388075;20368.69446958297;20569.765814051203;20613.690794638092;20974;6725.562838415585;7674.846503069907;7734.690612375442;7737.806554836062;7740.342070923922;4110.879130187815;4233.216854881856;4269.765162916069;4272.145570885836;4313.223619748413;3306.5205545195813;3399;3402.4556071028514;3405.0636075079055;3410.0622328859763;624.4286477872787;625.5527297981964;638.450932198309;642.9839254018686;651.371426573359;543.6819460615584;584.360125662399;604.6130476495047;629.3391938464594;636.5130675033603;8579.871301930476;8976.730698078938;9206.861897071549;9240.630374785067;9273.907260927304;524.6642149024627;559.9832005039856;578.23672751967;629.0972454527767;635.6472157952288;4297.505786834491;4558.886027849264;4560.224761790503;4578.587927086578;4623.953760462419;108131;108952.4552377233;109375;109536;110489.34265986017;65197;84741;85272;93226.53386733023;96337.51831240939;81483;84571.57714211389;86473;86546.56726716326;86768.56615717008;9693.854592180982;9851.605935762489;9877.703668889944;9879.555420006262;9906.306558540833;52868.73565632147;54405.72797135989;55422.168667470825;57340;58660;53288;68283.65858170677;76374.61812690902;77114.22885771295;78530.6073469629;12048;12681.112322137362;12705.872941270838;12873.034522411044;12971.935140324238;13980.300984950509;14107.083039602392;14153.504627337994;14167.574972750834;14248.857511424752;12864.742705146033;12950.611481655566;12961.805572916226;13092.214467132002;13290.003249756113;12790.924310952223;12844.293563854078;12858.485660573473;13048.54330098442;13122.803157952447;12678.239305641691;12961.675958101123;13098.476060957455;13252.801207981694;13311.66720831987;10841.891581084405;10939.835902461311;11104;11296.943515282372;11319.717007074889;7766.5728384939375;8740.388172827841;11003.97390717559;11182.66452006441;11372.374519401512 05:52:03 INFO - PROCESS | 2057 | __end_tp_report 05:52:03 INFO - PROCESS | 2057 | __start_cc_report 05:52:03 INFO - PROCESS | 2057 | _x_x_mozilla_cycle_collect,1334 05:52:03 INFO - PROCESS | 2057 | __end_cc_report 05:52:03 INFO - PROCESS | 2057 | __startTimestamp1477831923072__endTimestamp 05:52:03 INFO - PROCESS | 2057 | 05:52:03 INFO - PROCESS | 2057 | ------- Summary: start ------- 05:52:03 INFO - PROCESS | 2057 | Number of tests: 6 05:52:03 INFO - PROCESS | 2057 | 05:52:03 INFO - PROCESS | 2057 | [#0] dojo.html Cycles:165 Average:5583.62 Median:5727.66 stddev:647.00 (11.3%) stddev-sans-first:609.94 05:52:03 INFO - PROCESS | 2057 | Values: 2761.6 3231.6 3266.7 3276.9 3334.4 5427.6 5476.4 5512.5 5542.9 5598.7 5445.8 5534.8 5556.0 5569.7 5618.6 4964.9 5008.6 5021.2 5063.8 5104.9 5069.5 5191.7 5196.2 5209.8 5243.3 5160.9 5171.6 5183.2 5219.3 5259.9 5001.3 5030.7 5039.0 5085.3 5101.0 4980.7 5021.5 5049.0 5065.9 5068.5 5466.7 5521.8 5523.0 5572.1 5685.7 6116.0 6116.5 6121.6 6130.9 6132.4 4984.3 5366.8 6136.4 6137.3 6152.7 5491.9 5526.6 5574.0 5665.9 5677.7 6213.8 6217.9 6218.0 6219.9 6229.5 5825.1 5911.1 5914.3 5914.6 5918.9 5052.5 6100.5 6106.5 6109.8 6112.3 5997.9 6034.5 6037.4 6037.5 6041.5 5987.9 6024.2 6039.9 6040.2 6043.8 4753.1 4797.0 4802.3 4808.4 4838.1 4189.1 4248.6 4253.5 4260.5 4282.2 5439.4 5586.9 5609.5 5610.8 5662.7 5952.9 6054.7 6061.2 6081.6 6112.8 5575.5 5600.0 5612.1 5614.1 5647.1 5279.7 5304.8 5305.8 5306.7 5351.4 6096.6 6203.8 6206.5 6207.6 6252.8 6122.4 6142.4 6180.8 6188.4 6190.5 6243.2 6259.4 6264.7 6281.8 6282.8 5978.0 6023.9 6049.0 6050.8 6076.7 5991.5 6055.5 6071.4 6073.8 6132.9 5330.5 5330.9 5958.8 5986.1 5991.7 5020.5 5913.3 5988.9 5995.3 6012.6 5670.7 5727.7 5766.4 5766.6 5837.6 5949.3 6001.8 6011.2 6026.2 6043.2 5828.7 5830.3 5831.9 5834.9 5897.2 05:52:03 INFO - PROCESS | 2057 | 05:52:03 INFO - PROCESS | 2057 | [#1] ext.html Cycles:180 Average:67635.53 Median:40983.19 stddev:145073.97 (354.0%) stddev-sans-first:145393.29 05:52:03 INFO - PROCESS | 2057 | Values: 468.9 510.4 522.7 522.9 525.9 30572.8 41053.8 42120.7 42123.4 43754.8 43512.8 43846.3 43991.8 44003.0 44003.6 8354.2 9557.3 9757.1 9771.5 9773.0 9524.2 9628.2 9628.2 9635.8 9636.7 9593.1 9646.3 9651.6 9661.6 9663.5 9563.1 9627.4 9633.3 9658.1 9669.7 9436.4 9448.1 9549.2 9572.1 9575.4 40508.0 40965.0 40977.6 40988.8 41052.4 2974.6 3387.5 3417.9 3426.9 4095.5 25242.0 30432.5 30727.7 31525.5 32154.0 3202.3 3202.9 3206.6 3213.8 3329.5 474022.0 476928.8 478702.0 480353.0 486678.0 59234.0 60728.7 60813.0 61177.0 61243.4 34871.7 35814.4 65080.7 65261.0 65401.3 15481.1 15964.4 15981.7 15988.6 15993.9 12331.2 12424.6 12437.7 12446.0 12449.4 19472.4 19503.5 19511.8 19530.4 19542.9 6433.1 6765.0 6953.0 7700.7 7920.4 894.2 908.6 999.7 1058.8 1064.4 28895.4 29103.4 30925.7 31101.8 31295.4 884.8 1013.3 1050.5 1065.1 1087.7 15487.1 15677.5 15971.7 16118.4 16327.3 488767.0 655915.0 656258.0 657183.1 657875.0 59021.7 59346.7 59545.1 59689.8 59782.0 1232.1 1281.4 1365.1 1380.3 1404.6 29600.9 31425.2 31500.5 31829.5 32004.5 50332.7 51177.0 51201.0 51285.5 51436.2 470285.0 522556.0 523734.0 524036.0 524972.0 26874.3 27187.2 27574.6 27715.0 28093.0 41367.4 47428.1 47712.8 48046.0 49157.8 33129.7 39494.8 39535.2 40100.6 41024.8 29731.9 39359.8 39981.8 40869.4 40887.6 37698.8 38973.2 39278.8 39375.6 39428.8 33248.0 34107.1 35068.0 35142.0 35243.8 22467.2 22901.0 25781.6 26008.2 26126.0 05:52:03 INFO - PROCESS | 2057 | 05:52:03 INFO - PROCESS | 2057 | [#2] jquery.html Cycles:180 Average:34573.37 Median:319.99 stddev:99992.23 (31249.0%) stddev-sans-first:100239.66 05:52:03 INFO - PROCESS | 2057 | Values: 319.7 320.2 349.7 369.8 398.5 15169.4 15705.2 17236.7 20766.5 20795.0 19675.7 20343.0 21282.6 21302.0 21339.9 4039.7 5827.1 6124.9 6132.8 6144.9 4190.0 6352.3 6564.6 6694.1 6753.8 6405.8 6608.9 6678.2 6682.5 6702.2 6450.6 6675.9 6706.5 6710.7 6723.0 5733.4 5821.1 5828.8 5833.4 5835.4 25861.5 26478.1 26616.2 26746.6 26815.6 1932.8 2293.3 2409.4 2427.0 2475.1 15066.7 15328.8 15795.4 16361.3 16503.9 1820.4 1856.3 1863.3 1871.4 1919.8 102336.0 105877.0 106261.5 107670.0 119659.4 9178.7 9663.0 9702.5 9801.8 9923.5 12287.7 12365.6 12380.3 12389.5 12390.6 7821.7 7941.9 7970.9 8059.9 8076.9 6959.4 8000.3 8296.5 8335.6 8451.0 3885.0 3885.6 3900.6 3903.5 3936.6 1541.0 1872.9 1904.9 1928.5 1934.8 366.1 400.8 416.9 419.5 682.6 9401.9 11629.2 11673.3 11727.7 12035.2 422.2 427.9 431.4 437.6 852.2 10259.9 11195.0 11228.9 11236.0 11316.5 590943.0 592845.0 594231.0 606763.0 608864.0 124158.0 131712.0 139429.3 140307.3 144113.0 1008.5 1012.5 1031.7 1035.3 1037.3 18237.2 18803.3 18843.5 18877.5 18985.1 39070.6 52478.7 53536.7 53728.0 55110.0 74809.3 75268.6 76458.0 76514.2 79897.6 7758.0 7872.5 8068.5 8096.0 8404.5 9369.0 12646.2 12835.1 13172.0 13374.0 9530.3 9976.4 11166.3 11326.3 11345.8 11010.0 11170.9 11218.0 11542.4 11691.2 9158.6 10431.1 10492.4 10829.4 10858.5 7621.8 7719.2 7942.0 8034.9 8120.2 10360.4 10371.4 10375.8 10464.8 10651.5 05:52:03 INFO - PROCESS | 2057 | 05:52:03 INFO - PROCESS | 2057 | [#3] mootools.html Cycles:180 Average:5432.83 Median:5125.49 stddev:2931.50 (57.2%) stddev-sans-first:2923.83 05:52:03 INFO - PROCESS | 2057 | Values: 1371.1 1560.6 1569.7 1789.9 1929.7 7502.0 7882.3 8079.6 8426.5 8448.6 8296.4 8382.3 8391.7 8391.8 8397.6 2292.4 2330.2 2335.9 2336.0 2338.0 2304.4 2350.8 2361.8 2363.4 2363.7 2272.2 2289.5 2304.7 2305.7 2315.0 2272.4 2282.6 2306.7 2310.4 2316.8 2302.7 2329.0 2336.9 2343.8 2346.5 8301.8 8324.2 8410.4 8422.9 8441.0 5055.7 5232.2 5238.5 5274.3 5276.9 3439.7 3484.1 3507.2 3581.8 3592.4 1057.1 1089.0 1460.8 1774.8 1855.5 10772.4 10916.5 10923.8 10928.4 11048.9 4418.5 4575.7 4736.6 4739.4 4747.0 8515.1 8519.4 8645.8 8649.6 8703.9 5125.3 5125.7 5130.1 5131.5 5139.1 3997.5 4143.6 4427.2 4766.5 4831.1 3555.7 3588.5 3660.4 3673.8 3676.6 1105.2 1108.7 1132.1 1140.3 1145.6 893.5 902.5 906.9 943.3 967.3 5648.4 5693.4 5938.5 6005.5 6175.8 833.0 855.9 928.5 958.4 995.2 3316.6 3711.4 3891.0 3944.8 4126.3 9985.5 10036.1 10142.0 10200.7 10223.6 10144.9 10152.5 10156.7 10162.6 10179.9 7449.9 7841.5 7851.3 7950.9 7955.6 5406.3 5447.0 5555.6 5660.8 5798.7 8694.8 9027.0 9428.3 9456.6 9464.8 8107.4 8508.7 9401.7 9452.7 9613.7 6443.0 7195.6 7248.7 7271.6 7381.4 6899.0 7796.0 7808.5 7913.5 7924.0 7091.0 7116.8 7135.8 7231.5 7288.7 6821.6 6969.6 6997.4 7093.5 7094.4 6538.3 6585.3 6676.3 6686.6 6690.6 4815.0 4852.1 4879.9 4932.0 5016.0 4871.9 4899.7 4937.2 4993.8 5022.1 05:52:03 INFO - PROCESS | 2057 | 05:52:03 INFO - PROCESS | 2057 | [#4] prototype.html Cycles:180 Average:4349.25 Median:4903.06 stddev:1336.21 (27.3%) stddev-sans-first:1304.71 05:52:03 INFO - PROCESS | 2057 | Values: 286.8 293.9 305.5 308.9 310.5 4271.1 5162.3 5174.5 5182.5 5228.6 4301.6 4863.2 5026.6 5031.5 5106.7 3501.0 3644.7 3947.9 3969.1 4006.6 3440.6 3947.9 3954.0 3958.5 3961.4 3024.3 3867.5 3909.7 3937.6 3958.2 3862.8 3917.0 3925.0 3934.8 3945.0 3095.5 3387.5 3389.4 3396.9 3407.5 4298.0 4969.9 4989.3 4997.9 5023.0 3862.6 4185.5 4403.7 4457.4 4470.7 5039.8 5058.8 5114.6 5423.8 5438.3 3320.9 3711.5 3713.7 3729.0 3729.3 4687.6 4848.9 5348.9 5390.8 5669.4 3522.5 3731.6 3733.3 3749.9 3766.6 3437.0 3801.9 3808.0 3815.3 3834.7 4456.6 4472.5 4472.9 4478.8 4517.1 4877.4 5073.3 5403.9 5424.8 5488.0 1213.8 1300.3 1303.1 1305.7 1317.9 758.1 799.7 816.7 819.7 839.3 4856.4 5032.6 5041.0 5068.4 5071.4 5524.6 5525.4 5527.5 5565.1 5628.9 4617.6 4855.5 4928.7 5007.3 5056.9 2567.0 2660.4 2670.5 2881.3 2934.1 4699.6 4786.1 5686.2 5704.4 5715.6 5224.5 5591.8 5640.7 5696.8 5703.7 5724.1 5736.4 5757.0 5758.9 5886.6 4120.9 4456.6 4570.3 5503.1 5550.4 4260.7 5153.1 5342.8 5467.8 5504.6 5338.2 5478.5 5570.0 5712.0 5718.8 3266.4 3494.3 3697.3 3705.6 3717.9 4769.4 4811.4 5397.2 5466.2 5492.6 4942.4 5032.9 5312.4 5342.7 5373.4 4419.0 5041.7 5278.5 5348.3 5350.9 4689.4 5006.9 5348.9 5442.5 5465.5 4713.3 5395.5 5398.9 5454.4 5484.5 5264.4 5359.8 5364.0 5383.5 5384.6 05:52:03 INFO - PROCESS | 2057 | 05:52:03 INFO - PROCESS | 2057 | [#5] yui.html Cycles:180 Average:19978.61 Median:4582.77 stddev:28658.57 (625.4%) stddev-sans-first:28702.51 05:52:03 INFO - PROCESS | 2057 | Values: 727.8 734.1 743.1 743.7 756.5 14738.2 15243.9 15263.9 15265.5 15466.2 12414.0 12420.3 12422.4 12427.4 12433.1 689.1 716.3 722.5 877.4 900.6 866.2 890.6 890.6 910.6 912.6 919.0 934.2 951.4 955.9 957.9 726.6 949.0 949.8 951.8 952.9 557.3 675.8 702.2 704.3 704.5 14676.3 14725.5 14738.6 14766.9 14774.3 13030.6 13173.1 13285.8 13293.9 13549.5 11749.8 12048.1 12272.6 12392.9 12527.0 4345.8 4395.7 4423.4 4451.2 4587.0 64560.0 79175.0 82455.0 82732.2 82885.0 10340.3 10405.0 10596.2 10613.2 10664.7 17247.7 20368.7 20569.8 20613.7 20974.0 6725.6 7674.8 7734.7 7737.8 7740.3 4110.9 4233.2 4269.8 4272.1 4313.2 3306.5 3399.0 3402.5 3405.1 3410.1 624.4 625.6 638.5 643.0 651.4 543.7 584.4 604.6 629.3 636.5 8579.9 8976.7 9206.9 9240.6 9273.9 524.7 560.0 578.2 629.1 635.6 4297.5 4558.9 4560.2 4578.6 4624.0 108131.0 108952.5 109375.0 109536.0 110489.3 65197.0 84741.0 85272.0 93226.5 96337.5 81483.0 84571.6 86473.0 86546.6 86768.6 9693.9 9851.6 9877.7 9879.6 9906.3 52868.7 54405.7 55422.2 57340.0 58660.0 53288.0 68283.7 76374.6 77114.2 78530.6 12048.0 12681.1 12705.9 12873.0 12971.9 13980.3 14107.1 14153.5 14167.6 14248.9 12864.7 12950.6 12961.8 13092.2 13290.0 12790.9 12844.3 12858.5 13048.5 13122.8 12678.2 12961.7 13098.5 13252.8 13311.7 10841.9 10939.8 11104.0 11296.9 11319.7 7766.6 8740.4 11004.0 11182.7 11372.4 05:52:03 INFO - PROCESS | 2057 | -------- Summary: end -------- 05:52:03 INFO - PROCESS | 2057 | 05:52:03 INFO - TEST-INFO | 2057: exit 0 05:52:03 INFO - TEST-OK | dromaeo_css | took 1119767ms 05:52:03 INFO - TEST-START | kraken 05:52:03 INFO - operating with platform_type : mac_ 05:52:03 INFO - Initialising browser for kraken test... 05:52:03 INFO - TEST-INFO | started process 2061 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpyJa_EZ/profile http://localhost:50915/getInfo.html) 05:52:06 INFO - PROCESS | 2061 | __metrics Screen width/height:1600/1200 05:52:06 INFO - PROCESS | 2061 | colorDepth:24 05:52:06 INFO - PROCESS | 2061 | Browser inner width/height: 1024/689 05:52:06 INFO - PROCESS | 2061 | __metrics 05:52:06 INFO - PROCESS | 2061 | 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" 05:52:06 INFO - PROCESS | 2061 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 205: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:52:07 INFO - TEST-INFO | 2061: exit 0 05:52:07 INFO - Browser initialized. 05:52:07 INFO - Running cycle 1/1 for kraken test... 05:52:07 INFO - TEST-INFO | started process 2063 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/T/tmpyJa_EZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:19 INFO - PROCESS | 2063 | RSS: Main: 241512448 05:52:19 INFO - PROCESS | 2063 | 05:52:21 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50915/tests/kraken/driver.html?testName=audio-beat-detection) 05:52:22 INFO - PROCESS | 2063 | RSS: Main: 291282944 05:52:22 INFO - PROCESS | 2063 | 05:52:25 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50915/tests/kraken/driver.html?testName=audio-dft) 05:52:26 INFO - PROCESS | 2063 | RSS: Main: 293658624 05:52:26 INFO - PROCESS | 2063 | 05:52:29 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50915/tests/kraken/driver.html?testName=audio-fft) 05:52:30 INFO - PROCESS | 2063 | RSS: Main: 384323584 05:52:30 INFO - PROCESS | 2063 | 05:52:32 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50915/tests/kraken/driver.html?testName=audio-oscillator) 05:52:32 INFO - PROCESS | 2063 | RSS: Main: 289169408 05:52:32 INFO - PROCESS | 2063 | 05:52:34 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50915/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:52:35 INFO - PROCESS | 2063 | RSS: Main: 290582528 05:52:35 INFO - PROCESS | 2063 | 05:52:38 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50915/tests/kraken/driver.html?testName=imaging-darkroom) 05:52:39 INFO - PROCESS | 2063 | RSS: Main: 329564160 05:52:39 INFO - PROCESS | 2063 | 05:52:41 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50915/tests/kraken/driver.html?testName=imaging-desaturate) 05:52:42 INFO - PROCESS | 2063 | RSS: Main: 330072064 05:52:42 INFO - PROCESS | 2063 | 05:52:45 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50915/tests/kraken/driver.html?testName=json-parse-financial) 05:52:45 INFO - PROCESS | 2063 | RSS: Main: 328024064 05:52:45 INFO - PROCESS | 2063 | 05:52:47 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50915/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:52:48 INFO - PROCESS | 2063 | RSS: Main: 302985216 05:52:48 INFO - PROCESS | 2063 | 05:52:50 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:52:50 INFO - PROCESS | 2063 | RSS: Main: 308170752 05:52:50 INFO - PROCESS | 2063 | 05:52:52 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:52:53 INFO - PROCESS | 2063 | RSS: Main: 309805056 05:52:53 INFO - PROCESS | 2063 | 05:52:56 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:52:57 INFO - PROCESS | 2063 | RSS: Main: 319172608 05:52:57 INFO - PROCESS | 2063 | 05:53:00 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:53:01 INFO - PROCESS | 2063 | RSS: Main: 319246336 05:53:01 INFO - PROCESS | 2063 | 05:53:02 INFO - PROCESS | 2063 | Cycle 1(1): loaded http://localhost:50915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50915/tests/kraken/driver.html?testName=ai-astar) 05:53:03 INFO - PROCESS | 2063 | RSS: Main: 362504192 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | __start_tp_report 05:53:03 INFO - PROCESS | 2063 | _x_x_mozilla_page_load 05:53:03 INFO - PROCESS | 2063 | _x_x_mozilla_page_load_details 05:53:03 INFO - PROCESS | 2063 | |i|pagename|runs| 05:53:03 INFO - PROCESS | 2063 | |0;ai-astar;113;114;113;114;115;113;114;114;114;113 05:53:03 INFO - PROCESS | 2063 | |1;audio-beat-detection;148;132;113;144;132;143;105;131;178;110 05:53:03 INFO - PROCESS | 2063 | |2;audio-dft;183;184;183;184;186;181;180;181;181;181 05:53:03 INFO - PROCESS | 2063 | |3;audio-fft;80;58;58;90;79;82;82;59;84;58 05:53:03 INFO - PROCESS | 2063 | |4;audio-oscillator;83;71;96;69;67;69;94;69;99;124 05:53:03 INFO - PROCESS | 2063 | |5;imaging-gaussian-blur;89;87;88;86;89;88;134;131;89;86 05:53:03 INFO - PROCESS | 2063 | |6;imaging-darkroom;93;94;101;93;88;130;95;94;93;92 05:53:03 INFO - PROCESS | 2063 | |7;imaging-desaturate;81;84;81;79;83;83;85;83;81;80 05:53:03 INFO - PROCESS | 2063 | |8;json-parse-financial;55;54;53;54;55;53;70;81;85;85 05:53:03 INFO - PROCESS | 2063 | |9;json-stringify-tinderbox;65;74;67;74;76;71;70;70;72;72 05:53:03 INFO - PROCESS | 2063 | |10;stanford-crypto-aes;95;87;112;92;87;95;92;86;87;88 05:53:03 INFO - PROCESS | 2063 | |11;stanford-crypto-ccm;151;159;163;165;159;139;149;148;184;151 05:53:03 INFO - PROCESS | 2063 | |12;stanford-crypto-pbkdf2;173;170;174;175;173;171;171;174;171;174 05:53:03 INFO - PROCESS | 2063 | |13;stanford-crypto-sha256-iterative;66;68;69;78;87;88;88;75;65;68 05:53:03 INFO - PROCESS | 2063 | __end_tp_report 05:53:03 INFO - PROCESS | 2063 | __start_cc_report 05:53:03 INFO - PROCESS | 2063 | _x_x_mozilla_cycle_collect,2006 05:53:03 INFO - PROCESS | 2063 | __end_cc_report 05:53:03 INFO - PROCESS | 2063 | __startTimestamp1477831983098__endTimestamp 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | ------- Summary: start ------- 05:53:03 INFO - PROCESS | 2063 | Number of tests: 14 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 05:53:03 INFO - PROCESS | 2063 | Values: 113.0 114.0 113.0 114.0 115.0 113.0 114.0 114.0 114.0 113.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#1] audio-beat-detection Cycles:10 Average:133.60 Median:137.50 stddev:21.57 (15.7%) stddev-sans-first:22.24 05:53:03 INFO - PROCESS | 2063 | Values: 148.0 132.0 113.0 144.0 132.0 143.0 105.0 131.0 178.0 110.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#2] audio-dft Cycles:10 Average:182.40 Median:183.00 stddev:1.90 (1.0%) stddev-sans-first:2.00 05:53:03 INFO - PROCESS | 2063 | Values: 183.0 184.0 183.0 184.0 186.0 181.0 180.0 181.0 181.0 181.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#3] audio-fft Cycles:10 Average:73.00 Median:81.00 stddev:13.03 (16.1%) stddev-sans-first:13.57 05:53:03 INFO - PROCESS | 2063 | Values: 80.0 58.0 58.0 90.0 79.0 82.0 82.0 59.0 84.0 58.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:77.00 stddev:18.86 (24.5%) stddev-sans-first:20.00 05:53:03 INFO - PROCESS | 2063 | Values: 83.0 71.0 96.0 69.0 67.0 69.0 94.0 69.0 99.0 124.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#5] imaging-gaussian-blur Cycles:10 Average:96.70 Median:88.00 stddev:18.92 (21.5%) stddev-sans-first:19.86 05:53:03 INFO - PROCESS | 2063 | Values: 89.0 87.0 88.0 86.0 89.0 88.0 134.0 131.0 89.0 86.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#6] imaging-darkroom Cycles:10 Average:97.30 Median:93.00 stddev:11.93 (12.8%) stddev-sans-first:12.55 05:53:03 INFO - PROCESS | 2063 | Values: 93.0 94.0 101.0 93.0 88.0 130.0 95.0 94.0 93.0 92.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#7] imaging-desaturate Cycles:10 Average:82.00 Median:83.00 stddev:1.89 (2.3%) stddev-sans-first:1.96 05:53:03 INFO - PROCESS | 2063 | Values: 81.0 84.0 81.0 79.0 83.0 83.0 85.0 83.0 81.0 80.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:62.50 stddev:14.18 (22.7%) stddev-sans-first:14.61 05:53:03 INFO - PROCESS | 2063 | Values: 55.0 54.0 53.0 54.0 55.0 53.0 70.0 81.0 85.0 85.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:72.00 stddev:3.31 (4.6%) stddev-sans-first:2.68 05:53:03 INFO - PROCESS | 2063 | Values: 65.0 74.0 67.0 74.0 76.0 71.0 70.0 70.0 72.0 72.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#10] stanford-crypto-aes Cycles:10 Average:92.10 Median:90.00 stddev:7.78 (8.6%) stddev-sans-first:8.18 05:53:03 INFO - PROCESS | 2063 | Values: 95.0 87.0 112.0 92.0 87.0 95.0 92.0 86.0 87.0 88.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#11] stanford-crypto-ccm Cycles:10 Average:156.80 Median:159.00 stddev:12.37 (7.8%) stddev-sans-first:12.94 05:53:03 INFO - PROCESS | 2063 | Values: 151.0 159.0 163.0 165.0 159.0 139.0 149.0 148.0 184.0 151.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:173.50 stddev:1.71 (1.0%) stddev-sans-first:1.81 05:53:03 INFO - PROCESS | 2063 | Values: 173.0 170.0 174.0 175.0 173.0 171.0 171.0 174.0 171.0 174.0 05:53:03 INFO - PROCESS | 2063 | 05:53:03 INFO - PROCESS | 2063 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:76.50 stddev:9.46 (12.4%) stddev-sans-first:9.43 05:53:03 INFO - PROCESS | 2063 | Values: 66.0 68.0 69.0 78.0 87.0 88.0 88.0 75.0 65.0 68.0 05:53:03 INFO - PROCESS | 2063 | -------- Summary: end -------- 05:53:03 INFO - PROCESS | 2063 | 05:53:04 INFO - TEST-INFO | 2063: exit 0 05:53:04 INFO - TEST-OK | kraken | took 60229ms 05:53:04 INFO - SUITE-END | took 1179s 05:53:04 INFO - Completed test suite (00:19:40) 05:53:04 INFO - javascript benchmark 05:53:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2761.640986671733, 3231.628362738286, 3266.738660907127, 3276.852541635626, 3334.3664703706277, 5427.62006659534, 5476.397596264413, 5512.476314750093, 5542.889142217154, 5598.720063996794, 5445.83662490125, 5534.75093620787, 5556.0, 5569.749361278742, 5618.578606604511, 4964.925526117093, 5008.5993120550265, 5021.246812978046, 5063.8480845574695, 5104.8979020419565, 5069.543741063304, 5191.714455704935, 5196.194589838562, 5209.765560549766, 5243.292155558989, 5160.8967820643375, 5171.5862730981435, 5183.170692689189, 5219.347581552306, 5259.921101183485, 5001.299818025479, 5030.673006254582, 5038.974805125988, 5085.313482679865, 5101.0308041472, 4980.701157930535, 5021.472745361721, 5049.015441988777, 5065.8733531662, 5068.467810879842, 5466.671999680032, 5521.834344969658, 5523.033469142884, 5572.080606699889, 5685.715714214314, 6115.997869406565, 6116.457510225599, 6121.63549213247, 6130.860895427079, 6132.416697016086, 4984.27727979441, 5366.838994830161, 6136.403366381158, 6137.322976246954, 6152.692365381713, 5491.945080549223, 5526.585506087019, 5573.968815769095, 5665.943340566583, 5677.744501497443, 6213.751449942042, 6217.875642487215, 6217.974034284292, 6219.937800622027, 6229.470495007885, 5825.0679891217205, 5911.054231322968, 5914.349421563625, 5914.556408269311, 5918.881622367615, 5052.520010598987, 6100.542459315569, 6106.45041946218, 6109.84725381869, 6112.266528016667, 5997.880042399215, 6034.547408944259, 6037.396260373927, 6037.54718396122, 6041.486473649701, 5987.850303742354, 6024.186734790835, 6039.939600604026, 6040.154378387029, 6043.848903777441, 4753.120672675566, 4796.9760151199025, 4802.27965805132, 4808.35087263215, 4838.056578967138, 4189.07840275139, 4248.596383343577, 4253.468316460442, 4260.510041345285, 4282.186384586918, 5439.401665816757, 5586.860328491739, 5609.467100625434, 5610.831675049755, 5662.745176467069, 5952.940470595326, 6054.727537260834, 6061.242344706911, 6081.6351018939, 6112.847178820565, 5575.498205161555, 5600.047991841397, 5612.07400778875, 5614.073677843192, 5647.0964645656495, 5279.736013199402, 5304.787808487617, 5305.840824775263, 5306.708131052752, 5351.384590772111, 6096.587161059724, 6203.751849926133, 6206.503479721521, 6207.565470417027, 6252.843678907881, 6122.39815511198, 6142.416470435302, 6180.845478862884, 6188.412100850412, 6190.50475961931, 6243.157173781663, 6259.436650701458, 6264.718087685974, 6281.811545653638, 6282.842928926813, 5977.970110149421, 6023.879522409439, 6048.969755151372, 6050.818475445568, 6076.665783381956, 5991.460768530853, 6055.5458340623745, 6071.392860713893, 6073.848153796191, 6132.877342453036, 5330.546903513245, 5330.8933821324135, 5958.76164953397, 5986.132010858489, 5991.700414979146, 5020.49795020495, 5913.319968203712, 5988.8802223956145, 5995.310539288038, 6012.6392416455155, 5670.688112153871, 5727.656340619409, 5766.394528574439, 5766.596338256281, 5837.591368604157, 5949.3455719871645, 6001.7599296027665, 6011.218541589566, 6026.186464827192, 6043.184170136975, 5828.737706803289, 5830.300363956352, 5831.8833623326445, 5834.854128646818, 5897.174395584536], "value": 5541.018343796814, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.88512314482944, 510.39773067780015, 522.681164489661, 522.9045150409893, 525.9184826351915, 30572.847135764317, 41053.7683869484, 42120.73637790867, 42123.368149477705, 43754.78122609382, 43512.78243608785, 43846.34230486545, 43991.78004109975, 44003.0, 44003.559964400345, 8354.164583541658, 9557.28320375971, 9757.073078057609, 9771.51142442875, 9772.951135244313, 9524.190443812284, 9628.18160456362, 9628.229741620653, 9635.759106022335, 9636.662716804913, 9593.136617704406, 9646.324757266993, 9651.613935442609, 9661.565229564687, 9663.5168241588, 9563.09150630689, 9627.374220675634, 9633.325667203299, 9658.082482164184, 9669.661561845302, 9436.433813971114, 9448.102430269113, 9549.236061115094, 9572.138507534286, 9575.425474471554, 40508.0, 40965.0, 40977.59022409767, 40988.79505602453, 41052.38421423681, 2974.5686875402944, 3387.5088112223834, 3417.8803741869165, 3426.880059197941, 4095.4675892133832, 25242.0, 30432.54351184734, 30727.692723072705, 31525.52711709326, 32154.0, 3202.343519578481, 3202.871885124592, 3206.58314419124, 3213.775035747499, 3329.5338652588657, 474022.0, 476928.84606730926, 478702.0, 480353.0, 486678.0, 59234.0, 60728.69635651882, 60813.0, 61177.0, 61243.38756612466, 34871.65128348684, 35814.3883525242, 65080.67459662671, 65261.0, 65401.34598654048, 15481.148536830582, 15964.36142554308, 15981.68036639284, 15988.600284992968, 15993.920030399773, 12331.198472099464, 12424.627261182179, 12437.689057773629, 12446.004319654407, 12449.37753112359, 19472.415827525198, 19503.512412189808, 19511.82929024263, 19530.414087577396, 19542.89599184159, 6433.131527243856, 6765.0, 6952.991816186624, 7700.730474433368, 7920.4059695523065, 894.2086253665423, 908.6229214875752, 999.6751055906859, 1058.793535260632, 1064.4252103863982, 28895.42209155805, 29103.41793164167, 30925.69074309273, 31101.844490777403, 31295.37409251802, 884.827458645558, 1013.2653825976256, 1050.5430137890041, 1065.0840277361463, 1087.7335052911928, 15487.148206848731, 15677.529674109795, 15971.680566388608, 16118.435854745028, 16327.34690612386, 488767.0, 655915.0, 656258.0, 657183.1422528666, 657875.0, 59021.704891476125, 59346.703266484255, 59545.106823397684, 59689.80620387655, 59782.0, 1232.11903489004, 1281.3785314122642, 1365.0581099041628, 1380.3374380297514, 1404.6207523968676, 29600.851995740744, 31425.21436964094, 31500.527492087178, 31829.5225571612, 32004.51993220057, 50332.74833625808, 51176.99999999851, 51201.0, 51285.48714512807, 51436.22845657393, 470285.0, 522556.0, 523734.0, 524036.0, 524972.0, 26874.32814179661, 27187.1843844685, 27574.5863812047, 27715.0, 28093.0, 41367.37948930708, 47428.05143897172, 47712.761436192595, 48046.0, 49157.75421122871, 33129.66870331266, 39494.80252598719, 39535.2092958145, 40100.598994009684, 41024.79487602543, 29731.851340743156, 39359.80320098381, 39981.80009099936, 40869.38695919623, 40887.59112408838, 37698.81150594229, 38973.220535589695, 39278.80360598179, 39375.606243937196, 39428.80285598554, 33248.0, 34107.14732131716, 35068.0, 35142.0, 35243.82378088093, 22467.213647522258, 22901.0, 25781.6281820312, 26008.21975340743, 26126.0], "value": 19845.717049681534, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.7298282950906, 320.2415756270196, 349.6835363995585, 369.7596562234549, 398.5496389080339, 15169.393224271029, 15705.214739263034, 17236.741448878278, 20766.48083797906, 20795.0, 19675.704864427044, 20343.0, 21282.57434851302, 21302.041408136633, 21339.893300533477, 4039.6970227233073, 5827.096799995997, 6124.938750612504, 6132.846678833021, 6144.877102457948, 4190.036291652922, 6352.301246862841, 6564.573302735331, 6694.062831203611, 6753.831154221135, 6405.8398540036405, 6608.900866487006, 6678.232003319607, 6682.465402767792, 6702.162229721285, 6450.612963222222, 6675.899861502081, 6706.497012724041, 6710.698018589126, 6723.025161351626, 5733.397993210736, 5821.068629019379, 5828.766849326022, 5833.358330583633, 5835.35811060783, 25861.482770344675, 26478.073267435546, 26616.201513954613, 26746.598801017804, 26815.597766033527, 1932.8163824436656, 2293.3234695764622, 2409.4096946248105, 2427.0, 2475.059477398595, 15066.698666026728, 15328.846711532853, 15795.368185272575, 16361.263743131587, 16503.927244729053, 1820.4265656318216, 1856.3409989453685, 1863.252194911085, 1871.4105056907167, 1919.8176173263519, 102335.97664023339, 105877.0, 106261.46869265681, 107670.0, 119659.4017029918, 9178.724638260863, 9662.95168524167, 9702.51487425626, 9801.754956126153, 9923.503824808731, 12287.69280767988, 12365.567205147776, 12380.319082450551, 12389.504419823286, 12390.6282811514, 7821.6871325147495, 7941.9205807942335, 7970.9202907970175, 8059.919400805917, 8076.949996500535, 6959.4432445404245, 8000.279939812968, 8296.460730052528, 8335.624896879655, 8451.0, 3884.96115038846, 3885.630865067814, 3900.6099390060767, 3903.4730311407598, 3936.5866584008268, 1540.9829512521906, 1872.9063546822604, 1904.8761830480973, 1928.4985903664867, 1934.7678278606656, 366.09391755405693, 400.7775684495075, 416.9061961058798, 419.4547088784629, 682.6006786030129, 9401.858972115424, 11629.18595698293, 11673.299602023906, 11727.706807329885, 12035.157538972364, 422.1788621131869, 427.90158263599545, 431.439129132127, 437.5755517148361, 852.1861622150831, 10259.897401026043, 11194.992450679478, 11228.877112288868, 11236.0, 11316.547338106548, 590943.0, 592845.0, 594231.0, 606763.0, 608863.9556802188, 124158.0, 131712.0, 139429.3028534871, 140307.29846350703, 144113.0, 1008.455434065611, 1012.5392946209457, 1031.6750223679667, 1035.2701345551368, 1037.3205550364523, 18237.179326930054, 18803.341883034027, 18843.528911777314, 18877.528061798566, 18985.14566844468, 39070.60929390784, 52478.737606311726, 53536.732316338166, 53728.0, 55110.0, 74809.25190748023, 75268.62365688136, 76458.0, 76514.23485765293, 79897.60051199707, 7758.0, 7872.527648341118, 8068.47554908929, 8096.0, 8404.537750423786, 9368.95315523418, 12646.177998430072, 12835.101542892282, 13172.0, 13374.0, 9530.285228607743, 9976.401415915068, 11166.330020198815, 11326.263792853439, 11345.773084538429, 11010.0, 11170.888291116984, 11218.0, 11542.3651699154, 11691.240069395462, 9158.633654654006, 10431.11335536488, 10492.422916739653, 10829.350238985686, 10858.511366988663, 7621.847563048819, 7719.228077192236, 7942.0, 8034.919650803417, 8120.187981201832, 10360.430176340073, 10371.377717336984, 10375.844362334721, 10464.790704185722, 10651.520681569193], "value": 9001.381212433613, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.095077249016, 1560.586444592183, 1569.7096037233111, 1789.8568114550837, 1929.7105434184862, 7502.0, 7882.251186137315, 8079.636416361271, 8426.536540490271, 8448.619812108454, 8296.419250652456, 8382.329413646909, 8391.70629027984, 8391.790205244857, 8397.622107005183, 2292.4154340643154, 2330.1611419888827, 2335.9182428615004, 2336.030547322859, 2338.029717667166, 2304.43541332373, 2350.753170917055, 2361.799247063993, 2363.3737059679197, 2363.7163540375177, 2272.2388000019932, 2289.461976435545, 2304.677345171677, 2305.7348404933396, 2315.0392587076344, 2272.4318920269934, 2282.566312400647, 2306.7001289832383, 2310.4223944013997, 2316.8262380321457, 2302.689136966505, 2329.0450915124716, 2336.918207862717, 2343.7773411525877, 2346.507233480971, 8301.750947471586, 8324.209200126039, 8410.411271210956, 8422.915770842274, 8441.0, 5055.721935293557, 5232.189010703347, 5238.476152384769, 5274.26160337553, 5276.894462110775, 3439.6904278615043, 3484.1463841359027, 3507.2108775525558, 3581.803000834953, 3592.371335016388, 1057.1225882517492, 1088.9981217280122, 1460.7881857130656, 1774.8313910178513, 1855.4711907106546, 10772.40751758653, 10916.508757105948, 10923.781524369468, 10928.398938058404, 11048.889511104866, 4418.469783625921, 4575.702579332399, 4736.644751643572, 4739.4075740532435, 4747.0, 8515.148485151436, 8519.403641745143, 8645.783855403664, 8649.567521623894, 8703.912960870437, 5125.308083408692, 5125.666831655931, 5130.102232109366, 5131.461196574381, 5139.074966506065, 3997.5003124609425, 4143.627073563393, 4427.180971520279, 4766.523347665206, 4831.106245344622, 3555.715542756573, 3588.515550400715, 3660.40772264066, 3673.8060130457707, 3676.632336766302, 1105.2318638546294, 1108.728361551425, 1132.0943245403644, 1140.3044143072718, 1145.639123676056, 893.4862454088795, 902.4946030223097, 906.9229115525122, 943.2689665509148, 967.274544091931, 5648.435156484401, 5693.402192769782, 5938.49522790559, 6005.498625343664, 6175.783847565313, 832.9583520823935, 855.9215388610271, 928.4893308680333, 958.4009993753904, 995.1938929467154, 3316.585426821647, 3711.4247291669835, 3890.9610903891166, 3944.783036932939, 4126.257273690764, 9985.45080020606, 10036.146927511098, 10142.036506531867, 10200.693979180636, 10223.59105635781, 10144.949275253577, 10152.492375381202, 10156.746081348025, 10162.593496260066, 10179.949100254451, 7449.851002980018, 7841.490303130278, 7851.254130857559, 7950.920490795018, 7955.602219889098, 5406.297181366397, 5447.0, 5555.611107222618, 5660.754633980518, 5798.710064496674, 8694.826103477768, 9026.954865225895, 9428.292878034037, 9456.621735130519, 9464.763380915532, 8107.4324797263625, 8508.74473765788, 9401.717948461557, 9452.669156579486, 9613.711588652352, 6442.967785161044, 7195.604241766753, 7248.746293879689, 7271.563706177646, 7381.4094872409005, 6898.965505172442, 7796.025496812898, 7808.453408261367, 7913.485623434458, 7923.960380198062, 7090.964545177241, 7116.786496404909, 7135.750248741269, 7231.4937954342695, 7288.672009759467, 6821.556598821061, 6969.61693518239, 6997.440204783707, 7093.467989900675, 7094.361507464353, 6538.313477084837, 6585.341465853377, 6676.265610782907, 6686.598804071771, 6690.632015239208, 4814.9518504814505, 4852.078105160008, 4879.878003049952, 4931.95323940575, 5016.0, 4871.926921096116, 4899.706017638953, 4937.185364414831, 4993.80024799004, 5022.121128802518], "value": 4444.02297980098, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.81213804957747, 293.87216560796054, 305.50813190762875, 308.90269565087, 310.51249538224965, 4271.103068355641, 5162.251473536345, 5174.5162322085425, 5182.533571978521, 5228.633995620308, 4301.591348821865, 4863.246196839486, 5026.572741316983, 5031.471695471978, 5106.744662766865, 3500.9824950875272, 3644.7266455015847, 3947.940780888289, 3969.0672691917375, 4006.6394024537785, 3440.55272814535, 3947.8945895149404, 3953.98023009886, 3958.5051868516434, 3961.425073702088, 3024.3346463777934, 3867.5165604299464, 3909.687225022006, 3937.606239376054, 3958.2281455116263, 3862.8261728222014, 3917.0, 3924.95988563031, 3934.822932968009, 3944.9940265232467, 3095.4582947984013, 3387.4749413840896, 3389.3732357423196, 3396.915077123067, 3407.4718418645148, 4298.001429335342, 4969.856932905452, 4989.276554899519, 4997.850064498035, 5022.974885125551, 3862.594427585091, 4185.500586869128, 4403.6568846501805, 4457.353683715843, 4470.709403888769, 5039.773210205512, 5058.848234552969, 5114.565261952738, 5423.755930983115, 5438.265834112383, 3320.850561724704, 3711.4989476420606, 3713.740038197327, 3729.0049823344652, 3729.3287208302495, 4687.578068010337, 4848.878778030542, 5348.9302139571855, 5390.811321603725, 5669.433056694339, 3522.5244591979754, 3731.570869350005, 3733.2720119576966, 3749.9062523437133, 3766.60450652683, 3437.0, 3801.885943421702, 3807.9528129764535, 3815.2560250751394, 3834.69322454203, 4456.598906098467, 4472.530384309665, 4472.920092157097, 4478.843240486567, 4517.073999830027, 4877.414710234723, 5073.315102461121, 5403.891922161613, 5424.755885985141, 5488.0, 1213.7997230456876, 1300.2783455182464, 1303.094349427139, 1305.6605282626394, 1317.8616245294106, 758.0751483190498, 799.6961154761153, 816.6570040582969, 819.6516480495766, 839.3117643532245, 4856.368672072608, 5032.597392208614, 5040.958411843209, 5068.391792984866, 5071.39143302806, 5524.640898341595, 5525.364583072998, 5527.53015993637, 5565.107863326489, 5628.887422251614, 4617.561331673485, 4855.465898751135, 4928.704277743347, 5007.273945277914, 5056.924146137811, 2566.9871650642, 2660.406104048537, 2670.5326567850548, 2881.294082949649, 2934.1050979451047, 4699.647526435531, 4786.123240175469, 5686.232358631614, 5704.402767225183, 5715.599908006483, 5224.52979231871, 5591.776328946797, 5640.700190388587, 5696.829095127153, 5703.686297253691, 5724.055530837365, 5736.397678243808, 5757.006356694579, 5758.884822303614, 5886.558508111823, 4120.896977575585, 4456.5766252205985, 4570.286142696489, 5503.064479038653, 5550.4449555044175, 4260.744355338689, 5153.098207813543, 5342.813001544927, 5467.808626698205, 5504.642168277428, 5338.172583249602, 5478.479544443271, 5570.0, 5712.0, 5718.79984200551, 3266.44470440021, 3494.3186078714407, 3697.2790305891167, 3705.5923848377197, 3717.925641487101, 4769.35613692161, 4811.374521312207, 5397.218917757053, 5466.200990311695, 5492.642978206404, 4942.406911170682, 5032.917922646502, 5312.442193569619, 5342.732863356894, 5373.4357892422295, 4418.955810441855, 5041.6722913010535, 5278.524932755917, 5348.3363895731545, 5350.892982140414, 4689.437267527918, 5006.949930500648, 5348.946510535001, 5442.455754424526, 5465.508104270635, 4713.340132381538, 5395.514403703685, 5398.920215956903, 5454.372747134131, 5484.506394424521, 5264.368045905781, 5359.812406565751, 5364.049772624962, 5383.461653834742, 5384.596155288447], "value": 3922.533022611309, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.8325985023445, 734.1447213995696, 743.1156923261318, 743.724512461129, 756.4931495897748, 14738.189399583018, 15243.92378038111, 15263.923680381597, 15265.465708700198, 15466.226688665578, 12414.0, 12420.254784712924, 12422.440990155443, 12427.440765165567, 12433.067519935996, 689.1076056506836, 716.3087620446269, 722.5447967780291, 877.368294827723, 900.5722281916103, 866.1511718515858, 890.5680744838749, 890.5725251879102, 910.5947853205327, 912.5619702542783, 918.9724308270762, 934.1685899549407, 951.4291425144927, 955.8900726416482, 957.9329446938717, 726.5640615630632, 948.9762755931088, 949.8242825077383, 951.8001219743862, 952.8951815300311, 557.328419254797, 675.790504943469, 702.217028013766, 704.309776419107, 704.5103652961194, 14676.339564719614, 14725.484608038667, 14738.631534211623, 14766.926165369105, 14774.335154917948, 13030.609081727564, 13173.143745656598, 13285.829443786488, 13293.867061329456, 13549.458021679122, 11749.765004699944, 12048.096392770578, 12272.570460033945, 12392.938035309857, 12526.997840172764, 4345.8261669533495, 4395.7142785718825, 4423.358613001129, 4451.198784218839, 4586.954130458719, 64560.0, 79175.0, 82455.0, 82732.17267827365, 82885.0, 10340.327878687862, 10405.011523905217, 10596.205284603686, 10613.204009699304, 10664.680059598224, 17247.74128388075, 20368.69446958297, 20569.765814051203, 20613.690794638092, 20974.0, 6725.562838415585, 7674.846503069907, 7734.690612375442, 7737.806554836062, 7740.342070923922, 4110.879130187815, 4233.216854881856, 4269.765162916069, 4272.145570885836, 4313.223619748413, 3306.5205545195813, 3399.0, 3402.4556071028514, 3405.0636075079055, 3410.0622328859763, 624.4286477872787, 625.5527297981964, 638.450932198309, 642.9839254018686, 651.371426573359, 543.6819460615584, 584.360125662399, 604.6130476495047, 629.3391938464594, 636.5130675033603, 8579.871301930476, 8976.730698078938, 9206.861897071549, 9240.630374785067, 9273.907260927304, 524.6642149024627, 559.9832005039856, 578.23672751967, 629.0972454527767, 635.6472157952288, 4297.505786834491, 4558.886027849264, 4560.224761790503, 4578.587927086578, 4623.953760462419, 108131.0, 108952.4552377233, 109375.0, 109536.0, 110489.34265986017, 65197.0, 84741.0, 85272.0, 93226.53386733023, 96337.51831240939, 81483.0, 84571.57714211389, 86473.0, 86546.56726716326, 86768.56615717008, 9693.854592180982, 9851.605935762489, 9877.703668889944, 9879.555420006262, 9906.306558540833, 52868.73565632147, 54405.72797135989, 55422.168667470825, 57340.0, 58660.0, 53288.0, 68283.65858170677, 76374.61812690902, 77114.22885771295, 78530.6073469629, 12048.0, 12681.112322137362, 12705.872941270838, 12873.034522411044, 12971.935140324238, 13980.300984950509, 14107.083039602392, 14153.504627337994, 14167.574972750834, 14248.857511424752, 12864.742705146033, 12950.611481655566, 12961.805572916226, 13092.214467132002, 13290.003249756113, 12790.924310952223, 12844.293563854078, 12858.485660573473, 13048.54330098442, 13122.803157952447, 12678.239305641691, 12961.675958101123, 13098.476060957455, 13252.801207981694, 13311.66720831987, 10841.891581084405, 10939.835902461311, 11104.0, 11296.943515282372, 11319.717007074889, 7766.5728384939375, 8740.388172827841, 11003.97390717559, 11182.66452006441, 11372.374519401512], "value": 7316.824180115029, "unit": "score"}], "name": "dromaeo_css", "value": 7082.934794994084, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 114.0, 115.0, 113.0, 114.0, 114.0, 114.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 132.0, 113.0, 144.0, 132.0, 143.0, 105.0, 131.0, 178.0, 110.0], "value": 133.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 183.0, 184.0, 186.0, 181.0, 180.0, 181.0, 181.0, 181.0], "value": 182.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 58.0, 90.0, 79.0, 82.0, 82.0, 59.0, 84.0, 58.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 96.0, 69.0, 67.0, 69.0, 94.0, 69.0, 99.0, 124.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 86.0, 89.0, 88.0, 134.0, 131.0, 89.0, 86.0], "value": 96.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 101.0, 93.0, 88.0, 130.0, 95.0, 94.0, 93.0, 92.0], "value": 97.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 81.0, 79.0, 83.0, 83.0, 85.0, 83.0, 81.0, 80.0], "value": 82.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 54.0, 55.0, 53.0, 70.0, 81.0, 85.0, 85.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 67.0, 74.0, 76.0, 71.0, 70.0, 70.0, 72.0, 72.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 87.0, 112.0, 92.0, 87.0, 95.0, 92.0, 86.0, 87.0, 88.0], "value": 92.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 159.0, 163.0, 165.0, 159.0, 139.0, 149.0, 148.0, 184.0, 151.0], "value": 156.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 170.0, 174.0, 175.0, 173.0, 171.0, 171.0, 174.0, 171.0, 174.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 69.0, 78.0, 87.0, 88.0, 88.0, 75.0, 65.0, 68.0], "value": 75.2, "unit": "score"}], "name": "kraken", "value": 1495.1, "alertThreshold": 2.0}]} 05:53:04 INFO - Return code: 0 05:53:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:53:04 INFO - # TBPL SUCCESS # 05:53:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:53:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:53:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:53:04 INFO - 2016-10-30 05:53:04.393 screenresolution[2067:30902] starting screenresolution argv=screenresolution get 05:53:04 INFO - 2016-10-30 05:53:04.398 screenresolution[2067:30902] Display 0: 1600x1200x32@60 05:53:04 INFO - 2016-10-30 05:53:04.404 screenresolution[2068:30905] starting screenresolution argv=screenresolution list 05:53:04 INFO - Available Modes on Display 0 05:53:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:53:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:53:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:53:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:53:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:53:04 INFO - Intel Iris: 05:53:04 INFO - Chipset Model: Intel Iris 05:53:04 INFO - Type: GPU 05:53:04 INFO - Bus: Built-In 05:53:04 INFO - VRAM (Dynamic, Max): 1536 MB 05:53:04 INFO - Vendor: Intel (0x8086) 05:53:04 INFO - Device ID: 0x0a2e 05:53:04 INFO - Revision ID: 0x0009 05:53:04 INFO - Displays: 05:53:04 INFO - Display: 05:53:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:53:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:53:04 INFO - Display Serial Number: 1600x1200 60 05:53:04 INFO - Main Display: Yes 05:53:04 INFO - Mirror: Off 05:53:04 INFO - Online: Yes 05:53:04 INFO - Rotation: Supported 05:53:04 INFO - Return code: 0 05:53:04 INFO - Running post-action listener: _resource_record_post_action 05:53:04 INFO - [mozharness: 2016-10-30 12:53:04.652501Z] Finished run-tests step (success) 05:53:04 INFO - Running post-run listener: _resource_record_post_run 05:53:04 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 753664; Write bytes: 709976064; Read time: 7; Write time: 1949 05:53:04 INFO - TinderboxPrint: CPU usage
53.4% 05:53:04 INFO - TinderboxPrint: I/O read bytes / time
753,664 / 7 05:53:04 INFO - TinderboxPrint: I/O write bytes / time
709,976,064 / 1,949 05:53:04 INFO - TinderboxPrint: CPU idle
2,263.2 (46.6%) 05:53:04 INFO - TinderboxPrint: CPU system
971.2 (20.0%) 05:53:04 INFO - TinderboxPrint: CPU user
1,627.4 (33.5%) 05:53:04 INFO - TinderboxPrint: Swap in / out
407,425,024 / 0 05:53:04 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391256576; Write bytes: 385891328; Read time: 29359; Write time: 792 05:53:04 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 753664; Write bytes: 299246592; Read time: 7; Write time: 1098 05:53:05 INFO - Running post-run listener: _upload_blobber_files 05:53:05 INFO - Blob upload gear active. 05:53:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:53:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:53:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:53:05 INFO - (blobuploader) - INFO - Open directory for files ... 05:53:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:53:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:53:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:06 INFO - (blobuploader) - INFO - Done attempting. 05:53:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:53:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:53:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:07 INFO - (blobuploader) - INFO - Done attempting. 05:53:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:53:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:53:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:53:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:09 INFO - (blobuploader) - INFO - Done attempting. 05:53:09 INFO - (blobuploader) - INFO - Iteration through files over. 05:53:09 INFO - Return code: 0 05:53:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:53:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:53:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfefabf05de1842a20d7fc67002f01f4a7a916a4b8e313ec8ce427ea5b41c5d0d2c3fc1aeaf48351e68844f45562f4421e0b3df96422369501f84ffd79d4d6ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c45236a1e998f80d5b89b4b443a810eb993a4f727a9c2b3e43183a61a287ff558deb601b08fa9272cb6428f65d8f4ff1fbef491a33878df3a36c9741137816", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6aa4e6f2ed8162210ff094587891acc71a3f9aa39011b7c13c291024c27900fb52b6fb1c2d101590b6a46db8418969221c7684652f9fe6cb64bb9aea38a9bc8"} 05:53:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:53:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:53:09 INFO - Contents: 05:53:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfefabf05de1842a20d7fc67002f01f4a7a916a4b8e313ec8ce427ea5b41c5d0d2c3fc1aeaf48351e68844f45562f4421e0b3df96422369501f84ffd79d4d6ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c45236a1e998f80d5b89b4b443a810eb993a4f727a9c2b3e43183a61a287ff558deb601b08fa9272cb6428f65d8f4ff1fbef491a33878df3a36c9741137816", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6aa4e6f2ed8162210ff094587891acc71a3f9aa39011b7c13c291024c27900fb52b6fb1c2d101590b6a46db8418969221c7684652f9fe6cb64bb9aea38a9bc8"} 05:53:09 INFO - Running post-run listener: copy_logs_to_upload_dir 05:53:09 INFO - Copying logs to upload dir... 05:53:09 INFO - mkdir: /builds/slave/test/build/upload/logs 05:53:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.427850 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-30 05:53:09.732354) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-30 05:53:09.736373) ========= 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.dF7mrevgjK/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.jhPBYTl84u/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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/cfefabf05de1842a20d7fc67002f01f4a7a916a4b8e313ec8ce427ea5b41c5d0d2c3fc1aeaf48351e68844f45562f4421e0b3df96422369501f84ffd79d4d6ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c45236a1e998f80d5b89b4b443a810eb993a4f727a9c2b3e43183a61a287ff558deb601b08fa9272cb6428f65d8f4ff1fbef491a33878df3a36c9741137816", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6aa4e6f2ed8162210ff094587891acc71a3f9aa39011b7c13c291024c27900fb52b6fb1c2d101590b6a46db8418969221c7684652f9fe6cb64bb9aea38a9bc8"} build_url:https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011397 build_url: 'https://queue.taskcluster.net/v1/task/POFsch2yRKi-Y9msGELByw/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/cfefabf05de1842a20d7fc67002f01f4a7a916a4b8e313ec8ce427ea5b41c5d0d2c3fc1aeaf48351e68844f45562f4421e0b3df96422369501f84ffd79d4d6ae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c45236a1e998f80d5b89b4b443a810eb993a4f727a9c2b3e43183a61a287ff558deb601b08fa9272cb6428f65d8f4ff1fbef491a33878df3a36c9741137816", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6aa4e6f2ed8162210ff094587891acc71a3f9aa39011b7c13c291024c27900fb52b6fb1c2d101590b6a46db8418969221c7684652f9fe6cb64bb9aea38a9bc8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-30 05:53:09.770293) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 05:53:09.770637) ========= 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.dF7mrevgjK/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.jhPBYTl84u/Listeners TMPDIR=/var/folders/zr/ytw5mt592jdfk1rbvw03yt0h00000w/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.005162 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-30 05:53:09.819909) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-30 05:53:09.820229) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-30 05:53:09.820569) ========= ========= Total master_lag: 0.20 =========