builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0093 starttime: 1483957345.35 results: success (0) buildid: 20170108234108 builduid: 61c8c2b722174db491f223ed4bcb537d revision: fed81ec38bdf9a6ae80bdbedf5845d0fe84c1c86 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 02:22:25.349828) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 02:22:25.350299) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 02:22:25.350574) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 02:22:25.377753) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2017-01-09 02:22:25.378183) ========= 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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 --2017-01-09 02:22:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2017-01-09 02:22:36 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=10.718140 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2017-01-09 02:22:36.120285) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 02:22:36.120645) ========= 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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.028054 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 02:22:36.171412) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-01-09 02:22:36.171758) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fed81ec38bdf9a6ae80bdbedf5845d0fe84c1c86 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fed81ec38bdf9a6ae80bdbedf5845d0fe84c1c86 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 2017-01-09 02:22:36,528 truncating revision to first 12 chars 2017-01-09 02:22:36,528 Setting DEBUG logging. 2017-01-09 02:22:36,528 attempt 1/10 2017-01-09 02:22:36,528 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fed81ec38bdf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 02:22:41,025 unpacking tar archive at: mozilla-aurora-fed81ec38bdf/testing/mozharness/ program finished with exit code 0 elapsedTime=4.776224 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-01-09 02:22:40.971743) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 02:22:40.975203) ========= script_repo_revision: fed81ec38bdf9a6ae80bdbedf5845d0fe84c1c86 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 02:22:40.975790) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 02:22:40.976081) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 02:22:40.996530) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 29 mins, 58 secs) (at 2017-01-09 02:22:40.996916) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 02:22:41 INFO - MultiFileLogger online at 20170109 02:22:41 in /builds/slave/test 02:22:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:22:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:22:41 INFO - {'append_to_log': False, 02:22:41 INFO - 'base_work_dir': '/builds/slave/test', 02:22:41 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:22:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:22:41 INFO - 'branch': 'Mozilla-Aurora', 02:22:41 INFO - 'buildbot_json_path': 'buildprops.json', 02:22:41 INFO - 'config_files': ('talos/mac_config.py',), 02:22:41 INFO - 'default_actions': ('clobber', 02:22:41 INFO - 'read-buildbot-config', 02:22:41 INFO - 'download-and-extract', 02:22:41 INFO - 'populate-webroot', 02:22:41 INFO - 'create-virtualenv', 02:22:41 INFO - 'install', 02:22:41 INFO - 'run-tests'), 02:22:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:22:41 INFO - 'download_minidump_stackwalk': True, 02:22:41 INFO - 'download_symbols': 'ondemand', 02:22:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:22:41 INFO - 'tooltool.py': '/tools/tooltool.py', 02:22:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:22:41 INFO - '/tools/misc-python/virtualenv.py')}, 02:22:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:22:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:22:41 INFO - 'installer_path': 'installer.exe', 02:22:41 INFO - 'log_level': 'info', 02:22:41 INFO - 'log_name': 'talos', 02:22:41 INFO - 'log_to_console': True, 02:22:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:22:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:22:41 INFO - 'opt_config_files': (), 02:22:41 INFO - 'pip_index': False, 02:22:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:22:41 INFO - 'cmd': ('bash', 02:22:41 INFO - '-c', 02:22:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:22:41 INFO - 'enabled': True, 02:22:41 INFO - 'halt_on_failure': False, 02:22:41 INFO - 'name': 'check_screen_resolution'},), 02:22:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:22:41 INFO - 'cmd': ('bash', 02:22:41 INFO - '-c', 02:22:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:22:41 INFO - 'enabled': True, 02:22:41 INFO - 'halt_on_failure': False, 02:22:41 INFO - 'name': 'check_screen_resolution'},), 02:22:41 INFO - 'run_cmd_checks_enabled': True, 02:22:41 INFO - 'sps_profile': False, 02:22:41 INFO - 'sps_profile_interval': 0, 02:22:41 INFO - 'suite': 'dromaeojs', 02:22:41 INFO - 'system_bits': '32', 02:22:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:22:41 INFO - 'title': 't-yosemite-r7-0093', 02:22:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:22:41 INFO - 'use_talos_json': True, 02:22:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:22:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:22:41 INFO - 'work_dir': 'build'} 02:22:41 INFO - [mozharness: 2017-01-09 10:22:41.466147Z] Running clobber step. 02:22:41 INFO - Running pre-action listener: _resource_record_pre_action 02:22:41 INFO - Running main action method: clobber 02:22:41 INFO - rmtree: /builds/slave/test/build 02:22:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:22:43 INFO - Running post-action listener: _resource_record_post_action 02:22:43 INFO - [mozharness: 2017-01-09 10:22:43.881085Z] Finished clobber step (success) 02:22:43 INFO - [mozharness: 2017-01-09 10:22:43.881209Z] Running read-buildbot-config step. 02:22:43 INFO - Running pre-action listener: _resource_record_pre_action 02:22:43 INFO - Running main action method: read_buildbot_config 02:22:43 INFO - Using buildbot properties: 02:22:43 INFO - { 02:22:43 INFO - "project": "", 02:22:43 INFO - "product": "firefox", 02:22:43 INFO - "script_repo_revision": "production", 02:22:43 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:22:43 INFO - "repository": "", 02:22:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:22:43 INFO - "buildid": "20170108234108", 02:22:43 INFO - "pgo_build": "False", 02:22:43 INFO - "basedir": "/builds/slave/test", 02:22:43 INFO - "buildnumber": 55, 02:22:43 INFO - "slavename": "t-yosemite-r7-0093", 02:22:43 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:22:43 INFO - "platform": "yosemite_r7", 02:22:43 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:22:43 INFO - "branch": "mozilla-aurora", 02:22:43 INFO - "repo_path": "releases/mozilla-aurora", 02:22:43 INFO - "revision": "fed81ec38bdf9a6ae80bdbedf5845d0fe84c1c86", 02:22:43 INFO - "stage_platform": "macosx64", 02:22:43 INFO - "builduid": "61c8c2b722174db491f223ed4bcb537d", 02:22:43 INFO - "slavebuilddir": "test" 02:22:43 INFO - } 02:22:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:22:43 INFO - Running post-action listener: _resource_record_post_action 02:22:43 INFO - [mozharness: 2017-01-09 10:22:43.884366Z] Finished read-buildbot-config step (success) 02:22:43 INFO - [mozharness: 2017-01-09 10:22:43.884509Z] Running download-and-extract step. 02:22:43 INFO - Running pre-action listener: _resource_record_pre_action 02:22:43 INFO - Running main action method: download_and_extract 02:22:43 INFO - mkdir: /builds/slave/test/build/tests 02:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:43 INFO - trying https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:22:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:22:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:22:47 INFO - Downloaded 1199 bytes. 02:22:47 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:22:47 INFO - Using the following test package requirements: 02:22:47 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:22:47 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:22:47 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:22:47 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:22:47 INFO - u'jsshell-mac.zip'], 02:22:47 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:22:47 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:22:47 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:22:47 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:22:47 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:22:47 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:22:47 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:22:47 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:22:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:22:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:22:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:22:49 INFO - Http code: 200 02:22:49 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 02:22:49 INFO - x-amz-cf-id: r86ySRTcOeDJ1rZ2rf-lxTHVHPihdbKDs1ByE_PNXbuFWFNG1-pUYA== 02:22:49 INFO - x-amz-version-id: ..QJpBJvoMS2gJB8PzVp_yTknCVzZpgs 02:22:49 INFO - Expected file size: 16270960 02:22:49 INFO - Obtained file size: 16270960 02:22:56 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:22:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:22:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:22:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:23:27 INFO - retry: attempt #1 caught exception: '' 02:23:27 INFO - retry: Failed, sleeping 60 seconds before retrying 02:24:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #2 02:24:27 INFO - Fetch https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:25:02 INFO - retry: attempt #2 caught exception: 02:25:02 INFO - retry: Failed, sleeping 120 seconds before retrying 02:27:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #3 02:27:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:27:35 INFO - retry: attempt #3 caught exception: '' 02:27:35 INFO - retry: Failed, sleeping 240 seconds before retrying 02:31:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #4 02:31:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:31:37 INFO - Http code: 200 02:31:37 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 02:31:37 INFO - x-amz-cf-id: GM0dYhmUPomydbUkXagfjldxbMQvzjST00-KT-f6cUjEV0Ilsjmudg== 02:31:37 INFO - x-amz-version-id: ..QJpBJvoMS2gJB8PzVp_yTknCVzZpgs 02:31:37 INFO - Expected file size: 16270960 02:31:37 INFO - Obtained file size: 16270960 02:31:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:31:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:31:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:31:44 INFO - Http code: 200 02:31:44 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 02:31:44 INFO - x-amz-cf-id: X7WQ1sA_Ec-bxnJRPsvd8cTtMmbUaA1SG-BHWLjyb8uUshb9dW7-KQ== 02:31:44 INFO - x-amz-version-id: HzIUwkurC25uciVA5KuJUMLMrxs.c_3N 02:31:44 INFO - Expected file size: 14409038 02:31:44 INFO - Obtained file size: 14409038 02:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:44 INFO - trying https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:31:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:31:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:31:47 INFO - Downloaded 109878872 bytes. 02:31:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:31:47 INFO - mkdir: /builds/slave/test/properties 02:31:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:31:47 INFO - Writing to file /builds/slave/test/properties/build_url 02:31:47 INFO - Contents: 02:31:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:31:48 INFO - Running post-action listener: _resource_record_post_action 02:31:48 INFO - Running post-action listener: set_extra_try_arguments 02:31:48 INFO - [mozharness: 2017-01-09 10:31:48.561705Z] Finished download-and-extract step (success) 02:31:48 INFO - [mozharness: 2017-01-09 10:31:48.561842Z] Running populate-webroot step. 02:31:48 INFO - Running pre-action listener: _resource_record_pre_action 02:31:48 INFO - Running main action method: populate_webroot 02:31:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:31:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:31:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:31:48 INFO - '--tppagecycles', 02:31:48 INFO - '7'], 02:31:48 INFO - 'tests': ['tsvgm']}}, 02:31:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:31:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:31:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:31:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:31:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:31:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:31:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:48 INFO - 'talos_options': ['--disable-e10s'], 02:31:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:31:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:31:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:48 INFO - 'talos_options': ['--disable-e10s'], 02:31:48 INFO - 'tests': ['damp', 'tps']}, 02:31:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:48 INFO - 'tests': ['damp', 'tps']}, 02:31:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:31:48 INFO - 'tests': ['dromaeo_dom']}, 02:31:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:31:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:31:48 INFO - 'tests': ['basic_compositor_video']}, 02:31:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:31:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:31:48 INFO - 'tests': ['a11yr', 02:31:48 INFO - 'ts_paint', 02:31:48 INFO - 'tpaint', 02:31:48 INFO - 'sessionrestore', 02:31:48 INFO - 'sessionrestore_no_auto_restore', 02:31:48 INFO - 'tabpaint']}, 02:31:48 INFO - 'other-e10s': {'tests': ['a11yr', 02:31:48 INFO - 'ts_paint', 02:31:48 INFO - 'tpaint', 02:31:48 INFO - 'sessionrestore', 02:31:48 INFO - 'sessionrestore_no_auto_restore', 02:31:48 INFO - 'tabpaint']}, 02:31:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:31:48 INFO - 'ts_paint', 02:31:48 INFO - 'tpaint', 02:31:48 INFO - 'sessionrestore', 02:31:48 INFO - 'sessionrestore_no_auto_restore', 02:31:48 INFO - 'tabpaint']}, 02:31:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:31:48 INFO - 'ts_paint', 02:31:48 INFO - 'tpaint', 02:31:48 INFO - 'sessionrestore', 02:31:48 INFO - 'sessionrestore_no_auto_restore', 02:31:48 INFO - 'tabpaint']}, 02:31:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:31:48 INFO - 'tests': ['a11yr', 02:31:48 INFO - 'ts_paint', 02:31:48 INFO - 'tpaint', 02:31:48 INFO - 'sessionrestore', 02:31:48 INFO - 'sessionrestore_no_auto_restore', 02:31:48 INFO - 'tabpaint']}, 02:31:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:31:48 INFO - 'tests': ['a11yr', 02:31:48 INFO - 'ts_paint', 02:31:48 INFO - 'tpaint', 02:31:48 INFO - 'sessionrestore', 02:31:48 INFO - 'sessionrestore_no_auto_restore', 02:31:48 INFO - 'tabpaint']}, 02:31:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:31:48 INFO - 'tests': ['tsvgx', 02:31:48 INFO - 'tsvgr_opacity', 02:31:48 INFO - 'tart', 02:31:48 INFO - 'tscrollx', 02:31:48 INFO - 'cart']}, 02:31:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:31:48 INFO - 'tsvgr_opacity', 02:31:48 INFO - 'tart', 02:31:48 INFO - 'tscrollx', 02:31:48 INFO - 'cart']}, 02:31:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:48 INFO - 'talos_options': ['--disable-e10s'], 02:31:48 INFO - 'tests': ['tp5o']}, 02:31:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:48 INFO - 'tests': ['tp5o']}, 02:31:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:48 INFO - 'talos_options': ['--disable-e10s', 02:31:48 INFO - '--xperf_path', 02:31:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:31:48 INFO - 'tests': ['tp5n']}, 02:31:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:31:48 INFO - 'talos_options': ['--xperf_path', 02:31:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:31:48 INFO - 'tests': ['tp5n']}}, 02:31:48 INFO - 'talos.zip': {'path': '', 02:31:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:31:48 INFO - Running post-action listener: _resource_record_post_action 02:31:48 INFO - [mozharness: 2017-01-09 10:31:48.580880Z] Finished populate-webroot step (success) 02:31:48 INFO - [mozharness: 2017-01-09 10:31:48.581012Z] Running create-virtualenv step. 02:31:48 INFO - Running pre-action listener: _resource_record_pre_action 02:31:48 INFO - Running main action method: create_virtualenv 02:31:48 INFO - Creating virtualenv /builds/slave/test/build/venv 02:31:48 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 02:31:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:31:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:31:48 INFO - Using real prefix '/tools/python27' 02:31:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:31:49 INFO - Installing distribute.............................................................................................................................................................................................done. 02:31:52 INFO - Installing pip.................done. 02:31:52 INFO - Return code: 0 02:31:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:31:52 INFO - Reading from file tmpfile_stdout 02:31:52 INFO - Output received: 02:31:52 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) 02:31:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:31:52 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')]} 02:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:52 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')]} 02:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:52 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 0x107e26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85004c4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ad30>, '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 0x107aa0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c32b20>, '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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 02:31:52 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 02:31:52 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 02:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o3iqvqtO4B/Render', 02:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:52 INFO - 'HOME': '/Users/cltbld', 02:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:52 INFO - 'LOGNAME': 'cltbld', 02:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:52 INFO - 'MOZ_NO_REMOTE': '1', 02:31:52 INFO - 'NO_EM_RESTART': '1', 02:31:52 INFO - 'PAGER': '/bin/cat', 02:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:52 INFO - 'PWD': '/builds/slave/test', 02:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:52 INFO - 'SHELL': '/bin/bash', 02:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOFO0KKMoQ/Listeners', 02:31:52 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 02:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:52 INFO - 'USER': 'cltbld', 02:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:52 INFO - 'XPC_FLAGS': '0x0', 02:31:52 INFO - 'XPC_SERVICE_NAME': '0', 02:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:52 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 02:31:52 INFO - Cleaning up... 02:31:52 INFO - Return code: 0 02:31:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:31:52 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')]} 02:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:52 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')]} 02:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:52 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 0x107e26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85004c4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ad30>, '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 0x107aa0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c32b20>, '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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 02:31:52 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 02:31:52 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 02:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o3iqvqtO4B/Render', 02:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:52 INFO - 'HOME': '/Users/cltbld', 02:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:52 INFO - 'LOGNAME': 'cltbld', 02:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:52 INFO - 'MOZ_NO_REMOTE': '1', 02:31:52 INFO - 'NO_EM_RESTART': '1', 02:31:52 INFO - 'PAGER': '/bin/cat', 02:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:52 INFO - 'PWD': '/builds/slave/test', 02:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:52 INFO - 'SHELL': '/bin/bash', 02:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOFO0KKMoQ/Listeners', 02:31:52 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 02:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:52 INFO - 'USER': 'cltbld', 02:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:52 INFO - 'XPC_FLAGS': '0x0', 02:31:52 INFO - 'XPC_SERVICE_NAME': '0', 02:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:53 INFO - Downloading/unpacking psutil>=3.1.1 02:31:53 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 02:31:53 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 02:31:53 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 02:31:53 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 02:31:53 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 02:31:53 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 02:31:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:31:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:57 INFO - Installing collected packages: psutil 02:31:57 INFO - Running setup.py install for psutil 02:31:57 INFO - building 'psutil._psutil_osx' extension 02:31:57 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 02:31:57 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 02:31:57 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 02:31:58 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 02:31:58 INFO - building 'psutil._psutil_posix' extension 02:31:58 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 02:31:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:31:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:31:58 INFO - ^ 02:31:58 INFO - 1 warning generated. 02:31:58 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 02:31:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:58 INFO - Successfully installed psutil 02:31:58 INFO - Cleaning up... 02:31:58 INFO - Return code: 0 02:31:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:58 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 0x107e26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85004c4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ad30>, '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 0x107aa0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c32b20>, '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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 02:31:58 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 02:31:58 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 02:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o3iqvqtO4B/Render', 02:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:58 INFO - 'HOME': '/Users/cltbld', 02:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:58 INFO - 'LOGNAME': 'cltbld', 02:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:58 INFO - 'MOZ_NO_REMOTE': '1', 02:31:58 INFO - 'NO_EM_RESTART': '1', 02:31:58 INFO - 'PAGER': '/bin/cat', 02:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:58 INFO - 'PWD': '/builds/slave/test', 02:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:58 INFO - 'SHELL': '/bin/bash', 02:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOFO0KKMoQ/Listeners', 02:31:58 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 02:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:58 INFO - 'USER': 'cltbld', 02:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:58 INFO - 'XPC_FLAGS': '0x0', 02:31:58 INFO - 'XPC_SERVICE_NAME': '0', 02:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:31:58 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 02:31:58 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 02:31:58 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 02:31:58 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 02:31:58 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 02:31:58 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 02:32:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:32:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:32:01 INFO - Installing collected packages: mozsystemmonitor 02:32:01 INFO - Running setup.py install for mozsystemmonitor 02:32:01 INFO - Successfully installed mozsystemmonitor 02:32:01 INFO - Cleaning up... 02:32:01 INFO - Return code: 0 02:32:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:32:01 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')]} 02:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:01 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')]} 02:32:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:01 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 0x107e26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85004c4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ad30>, '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 0x107aa0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c32b20>, '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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 02:32:01 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 02:32:01 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 02:32:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o3iqvqtO4B/Render', 02:32:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:01 INFO - 'HOME': '/Users/cltbld', 02:32:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:01 INFO - 'LOGNAME': 'cltbld', 02:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:01 INFO - 'MOZ_NO_REMOTE': '1', 02:32:01 INFO - 'NO_EM_RESTART': '1', 02:32:01 INFO - 'PAGER': '/bin/cat', 02:32:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:01 INFO - 'PWD': '/builds/slave/test', 02:32:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:01 INFO - 'SHELL': '/bin/bash', 02:32:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOFO0KKMoQ/Listeners', 02:32:01 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 02:32:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:01 INFO - 'USER': 'cltbld', 02:32:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:01 INFO - 'XPC_FLAGS': '0x0', 02:32:01 INFO - 'XPC_SERVICE_NAME': '0', 02:32:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:01 INFO - Downloading/unpacking jsonschema==2.5.1 02:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:32:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:32:04 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 02:32:04 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 02:32:04 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 02:32:04 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 02:32:04 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 02:32:04 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 02:32:05 INFO - Downloading functools32-3.2.3-2.tar.gz 02:32:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:32:05 INFO - warning: no files found matching '*.txt' 02:32:05 INFO - no previously-included directories found matching 'build' 02:32:05 INFO - no previously-included directories found matching 'dist' 02:32:05 INFO - no previously-included directories found matching '.git*' 02:32:05 INFO - Installing collected packages: jsonschema, functools32 02:32:05 INFO - Running setup.py install for functools32 02:32:05 INFO - warning: no files found matching '*.txt' 02:32:05 INFO - no previously-included directories found matching 'build' 02:32:05 INFO - no previously-included directories found matching 'dist' 02:32:05 INFO - no previously-included directories found matching '.git*' 02:32:05 INFO - Successfully installed jsonschema functools32 02:32:05 INFO - Cleaning up... 02:32:05 INFO - Return code: 0 02:32:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107e26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85004c4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ad30>, '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 0x107aa0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c32b20>, '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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 02:32:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o3iqvqtO4B/Render', 02:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:05 INFO - 'HOME': '/Users/cltbld', 02:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:05 INFO - 'LOGNAME': 'cltbld', 02:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:05 INFO - 'MOZ_NO_REMOTE': '1', 02:32:05 INFO - 'NO_EM_RESTART': '1', 02:32:05 INFO - 'PAGER': '/bin/cat', 02:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:05 INFO - 'PWD': '/builds/slave/test', 02:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:05 INFO - 'SHELL': '/bin/bash', 02:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOFO0KKMoQ/Listeners', 02:32:05 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 02:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:05 INFO - 'USER': 'cltbld', 02:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:05 INFO - 'XPC_FLAGS': '0x0', 02:32:05 INFO - 'XPC_SERVICE_NAME': '0', 02:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:32:05 INFO - Cleaning up... 02:32:05 INFO - Return code: 0 02:32:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107e26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85004c4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ad30>, '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 0x107aa0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c32b20>, '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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 02:32:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o3iqvqtO4B/Render', 02:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:05 INFO - 'HOME': '/Users/cltbld', 02:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:05 INFO - 'LOGNAME': 'cltbld', 02:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:05 INFO - 'MOZ_NO_REMOTE': '1', 02:32:05 INFO - 'NO_EM_RESTART': '1', 02:32:05 INFO - 'PAGER': '/bin/cat', 02:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:05 INFO - 'PWD': '/builds/slave/test', 02:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:05 INFO - 'SHELL': '/bin/bash', 02:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOFO0KKMoQ/Listeners', 02:32:05 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 02:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:05 INFO - 'USER': 'cltbld', 02:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:05 INFO - 'XPC_FLAGS': '0x0', 02:32:05 INFO - 'XPC_SERVICE_NAME': '0', 02:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:05 INFO - Downloading/unpacking blobuploader==1.2.4 02:32:05 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 02:32:05 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 02:32:05 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 02:32:05 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 02:32:05 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 02:32:05 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 02:32:08 INFO - Downloading blobuploader-1.2.4.tar.gz 02:32:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:32:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:32:08 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 02:32:08 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 02:32:08 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 02:32:08 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 02:32:08 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 02:32:08 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 02:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:32:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:32:09 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 02:32:09 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 02:32:09 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 02:32:09 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 02:32:09 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 02:32:09 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 02:32:09 INFO - Downloading docopt-0.6.1.tar.gz 02:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:32:09 INFO - Installing collected packages: blobuploader, requests, docopt 02:32:09 INFO - Running setup.py install for blobuploader 02:32:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:32:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:32:10 INFO - Running setup.py install for requests 02:32:10 INFO - Running setup.py install for docopt 02:32:10 INFO - Successfully installed blobuploader requests docopt 02:32:10 INFO - Cleaning up... 02:32:10 INFO - Return code: 0 02:32:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:10 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 0x107e26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85004c4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ad30>, '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 0x107aa0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c32b20>, '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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 02:32:10 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 02:32:10 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 02:32:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o3iqvqtO4B/Render', 02:32:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:10 INFO - 'HOME': '/Users/cltbld', 02:32:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:10 INFO - 'LOGNAME': 'cltbld', 02:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:10 INFO - 'MOZ_NO_REMOTE': '1', 02:32:10 INFO - 'NO_EM_RESTART': '1', 02:32:10 INFO - 'PAGER': '/bin/cat', 02:32:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:10 INFO - 'PWD': '/builds/slave/test', 02:32:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:10 INFO - 'SHELL': '/bin/bash', 02:32:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOFO0KKMoQ/Listeners', 02:32:10 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 02:32:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:10 INFO - 'USER': 'cltbld', 02:32:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:10 INFO - 'XPC_FLAGS': '0x0', 02:32:10 INFO - 'XPC_SERVICE_NAME': '0', 02:32:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:32:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-mzNQp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:32:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-DnDnGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:32:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-bfRFDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:32:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-MVjI2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:32:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-HfBEwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:32:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-9GIBJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:32:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-kgkJpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:32:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-HsA24c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:32:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-jaaSHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:32:11 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ezjVCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:32:12 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-fHhfRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:32:12 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-TWvlHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:32:12 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ao_BMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:32:12 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-I8Webs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:12 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-7cmqBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:32:12 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-VaQciI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:32:12 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-TbRcNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:32:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:32:12 INFO - Running setup.py install for manifestparser 02:32:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:32:12 INFO - Running setup.py install for mozcrash 02:32:13 INFO - Running setup.py install for mozdebug 02:32:13 INFO - Running setup.py install for mozdevice 02:32:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:32:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:32:13 INFO - Running setup.py install for mozfile 02:32:13 INFO - Running setup.py install for mozhttpd 02:32:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:32:13 INFO - Running setup.py install for mozinfo 02:32:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:32:13 INFO - Running setup.py install for mozInstall 02:32:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:32:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:32:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:32:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:32:13 INFO - Running setup.py install for mozleak 02:32:14 INFO - Running setup.py install for mozlog 02:32:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:32:14 INFO - Running setup.py install for moznetwork 02:32:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:32:14 INFO - Running setup.py install for mozprocess 02:32:14 INFO - Running setup.py install for mozprofile 02:32:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:32:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:32:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:32:14 INFO - Running setup.py install for mozrunner 02:32:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:32:14 INFO - Running setup.py install for mozscreenshot 02:32:15 INFO - Running setup.py install for moztest 02:32:15 INFO - Running setup.py install for mozversion 02:32:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:32:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:32:15 INFO - Cleaning up... 02:32:15 INFO - Return code: 0 02:32:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:15 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')]} 02:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:15 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')]} 02:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:15 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 0x107e26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85004c4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ad30>, '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 0x107aa0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c32b20>, '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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 02:32:15 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 02:32:15 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 02:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o3iqvqtO4B/Render', 02:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:15 INFO - 'HOME': '/Users/cltbld', 02:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:15 INFO - 'LOGNAME': 'cltbld', 02:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:15 INFO - 'MOZ_NO_REMOTE': '1', 02:32:15 INFO - 'NO_EM_RESTART': '1', 02:32:15 INFO - 'PAGER': '/bin/cat', 02:32:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:15 INFO - 'PWD': '/builds/slave/test', 02:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:15 INFO - 'SHELL': '/bin/bash', 02:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOFO0KKMoQ/Listeners', 02:32:15 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 02:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:15 INFO - 'USER': 'cltbld', 02:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:15 INFO - 'XPC_FLAGS': '0x0', 02:32:15 INFO - 'XPC_SERVICE_NAME': '0', 02:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:32:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-fxajPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:32:15 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)) 02:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:32:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-W4ajHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:32:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ibXRJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:32:15 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)) 02:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:32:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Dvz06Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:32:16 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)) 02:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:32:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-uQ2bTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:32:16 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)) 02:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:32:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-fHHz1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:32:16 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)) 02:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:32:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-q3dcOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:32:16 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)) 02:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:32:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-8qj_VO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:32:16 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)) 02:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:32:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-bT4xYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:32:16 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)) 02:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:32:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-oOpR9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:32:16 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)) 02:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:32:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-9Plooe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:32:16 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)) 02:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:32:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-oFk2UP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:32:16 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)) 02:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:32:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-xQYaA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:32:17 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)) 02:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:32:17 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Df029R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:17 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-PK0DBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:17 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)) 02:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:32:17 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-tYq_E1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 02:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:32:17 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-4Xbs3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:32:17 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)) 02:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:32:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:32:17 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)) 02:32:17 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)) 02:32:17 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)) 02:32:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:20 INFO - Downloading blessings-1.6.tar.gz 02:32:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:32:20 INFO - Installing collected packages: blessings 02:32:20 INFO - Running setup.py install for blessings 02:32:20 INFO - Successfully installed blessings 02:32:20 INFO - Cleaning up... 02:32:20 INFO - Return code: 0 02:32:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:32:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:32:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:32:20 INFO - Reading from file tmpfile_stdout 02:32:20 INFO - Current package versions: 02:32:20 INFO - blessings == 1.6 02:32:20 INFO - blobuploader == 1.2.4 02:32:20 INFO - docopt == 0.6.1 02:32:20 INFO - functools32 == 3.2.3-2 02:32:20 INFO - jsonschema == 2.5.1 02:32:20 INFO - manifestparser == 1.1 02:32:20 INFO - mozInstall == 1.12 02:32:20 INFO - mozcrash == 1.0 02:32:20 INFO - mozdebug == 0.1 02:32:20 INFO - mozdevice == 0.48 02:32:20 INFO - mozfile == 1.2 02:32:20 INFO - mozhttpd == 0.7 02:32:20 INFO - mozinfo == 0.9 02:32:20 INFO - mozleak == 0.1 02:32:20 INFO - mozlog == 3.4 02:32:20 INFO - moznetwork == 0.27 02:32:20 INFO - mozprocess == 0.23 02:32:20 INFO - mozprofile == 0.28 02:32:20 INFO - mozrunner == 6.13 02:32:20 INFO - mozscreenshot == 0.1 02:32:20 INFO - mozsystemmonitor == 0.3 02:32:20 INFO - moztest == 0.8 02:32:20 INFO - mozversion == 1.4 02:32:20 INFO - psutil == 3.1.1 02:32:20 INFO - requests == 1.2.3 02:32:20 INFO - wsgiref == 0.1.2 02:32:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:20 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 0x107e26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85004c4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ad30>, '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 0x107aa0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c32b20>, '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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 02:32:20 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 02:32:20 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 02:32:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o3iqvqtO4B/Render', 02:32:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:20 INFO - 'HOME': '/Users/cltbld', 02:32:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:20 INFO - 'LOGNAME': 'cltbld', 02:32:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:20 INFO - 'MOZ_NO_REMOTE': '1', 02:32:20 INFO - 'NO_EM_RESTART': '1', 02:32:20 INFO - 'PAGER': '/bin/cat', 02:32:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:20 INFO - 'PWD': '/builds/slave/test', 02:32:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:20 INFO - 'SHELL': '/bin/bash', 02:32:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOFO0KKMoQ/Listeners', 02:32:20 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 02:32:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:20 INFO - 'USER': 'cltbld', 02:32:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:20 INFO - 'XPC_FLAGS': '0x0', 02:32:20 INFO - 'XPC_SERVICE_NAME': '0', 02:32:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:21 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)) 02:32:21 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)) 02:32:21 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)) 02:32:21 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)) 02:32:21 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)) 02:32:21 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)) 02:32:21 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)) 02:32:21 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)) 02:32:21 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)) 02:32:21 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)) 02:32:21 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)) 02:32:21 INFO - Cleaning up... 02:32:21 INFO - Return code: 0 02:32:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02: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')]} 02:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02: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')]} 02:32:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85004c4650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ad30>, '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 0x107aa0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c32b20>, '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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 02: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', 'jsonschema'] in /builds/slave/test/build 02: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 jsonschema 02:32:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o3iqvqtO4B/Render', 02:32:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:21 INFO - 'HOME': '/Users/cltbld', 02:32:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:21 INFO - 'LOGNAME': 'cltbld', 02:32:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:21 INFO - 'MOZ_NO_REMOTE': '1', 02:32:21 INFO - 'NO_EM_RESTART': '1', 02:32:21 INFO - 'PAGER': '/bin/cat', 02:32:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:21 INFO - 'PWD': '/builds/slave/test', 02:32:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:21 INFO - 'SHELL': '/bin/bash', 02:32:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOFO0KKMoQ/Listeners', 02:32:21 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 02:32:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:21 INFO - 'USER': 'cltbld', 02:32:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:21 INFO - 'XPC_FLAGS': '0x0', 02:32:21 INFO - 'XPC_SERVICE_NAME': '0', 02:32:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:32:21 INFO - Cleaning up... 02:32:21 INFO - Return code: 0 02:32:21 INFO - Running post-action listener: _resource_record_post_action 02:32:21 INFO - Running post-action listener: _start_resource_monitoring 02:32:21 INFO - Starting resource monitoring. 02:32:21 INFO - [mozharness: 2017-01-09 10:32:21.364752Z] Finished create-virtualenv step (success) 02:32:21 INFO - [mozharness: 2017-01-09 10:32:21.365741Z] Running install step. 02:32:21 INFO - Running pre-action listener: _resource_record_pre_action 02:32:21 INFO - Running main action method: install 02:32:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:32:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:32:21 INFO - Reading from file tmpfile_stdout 02:32:21 INFO - Detecting whether we're running mozinstall >=1.0... 02:32:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:32:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:32:21 INFO - Reading from file tmpfile_stdout 02:32:21 INFO - Output received: 02:32:21 INFO - Usage: mozinstall [options] installer 02:32:21 INFO - Options: 02:32:21 INFO - -h, --help show this help message and exit 02:32:21 INFO - -d DEST, --destination=DEST 02:32:21 INFO - Directory to install application into. [default: 02:32:21 INFO - "/builds/slave/test"] 02:32:21 INFO - --app=APP Application being installed. [default: firefox] 02:32:21 INFO - mkdir: /builds/slave/test/build/application 02:32:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:32:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:32:52 INFO - Reading from file tmpfile_stdout 02:32:52 INFO - Output received: 02:32:52 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:32:52 INFO - Running post-action listener: _resource_record_post_action 02:32:52 INFO - [mozharness: 2017-01-09 10:32:52.962944Z] Finished install step (success) 02:32:52 INFO - [mozharness: 2017-01-09 10:32:52.963116Z] Running run-tests step. 02:32:52 INFO - Running pre-action listener: _resource_record_pre_action 02:32:52 INFO - Running main action method: run_tests 02:32:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:32:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:32:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:32:52 INFO - 2017-01-09 02:32:52.977 screenresolution[1630:8229] starting screenresolution argv=screenresolution get 02:32:52 INFO - 2017-01-09 02:32:52.980 screenresolution[1630:8229] Display 0: 1600x1200x32@60 02:32:52 INFO - 2017-01-09 02:32:52.986 screenresolution[1631:8231] starting screenresolution argv=screenresolution list 02:32:52 INFO - Available Modes on Display 0 02:32:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:32:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:32:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:32:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:32:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:32:53 INFO - Intel Iris: 02:32:53 INFO - Chipset Model: Intel Iris 02:32:53 INFO - Type: GPU 02:32:53 INFO - Bus: Built-In 02:32:53 INFO - VRAM (Dynamic, Max): 1536 MB 02:32:53 INFO - Vendor: Intel (0x8086) 02:32:53 INFO - Device ID: 0x0a2e 02:32:53 INFO - Revision ID: 0x0009 02:32:53 INFO - Displays: 02:32:53 INFO - Display: 02:32:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:32:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:32:53 INFO - Display Serial Number: 1600x1200 60 02:32:53 INFO - Main Display: Yes 02:32:53 INFO - Mirror: Off 02:32:53 INFO - Online: Yes 02:32:53 INFO - Rotation: Supported 02:32:53 INFO - Return code: 0 02:32:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:32:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:32:53 INFO - Python 2.7.3 02:32:53 INFO - Return code: 0 02:32:53 INFO - grabbing minidump binary from tooltool 02:32:53 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')]} 02:32:53 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 0x107c3ad30>, '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 0x107aa0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c32b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:32:53 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 02:32:53 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 02:32:53 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 02:32:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:32:53 INFO - Return code: 0 02:32:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:32:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:32:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:32:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:32:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:32:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--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 02:32:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0093 --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 02:32:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o3iqvqtO4B/Render', 02:32:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:53 INFO - 'HOME': '/Users/cltbld', 02:32:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:53 INFO - 'LOGNAME': 'cltbld', 02:32:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:32:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:32:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:53 INFO - 'MOZ_NO_REMOTE': '1', 02:32:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:32:53 INFO - 'NO_EM_RESTART': '1', 02:32:53 INFO - 'PAGER': '/bin/cat', 02:32:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:53 INFO - 'PWD': '/builds/slave/test', 02:32:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:32:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:53 INFO - 'SHELL': '/bin/bash', 02:32:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOFO0KKMoQ/Listeners', 02:32:53 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 02:32:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:53 INFO - 'USER': 'cltbld', 02:32:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:53 INFO - 'XPC_FLAGS': '0x0', 02:32:53 INFO - 'XPC_SERVICE_NAME': '0', 02:32:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--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 02:32:53 INFO - mozversion application_buildid: 20170108234108 02:32:53 INFO - mozversion application_changeset: fed81ec38bdf9a6ae80bdbedf5845d0fe84c1c86 02:32:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:32:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:32:53 INFO - mozversion application_name: Firefox 02:32:53 INFO - mozversion application_remotingname: firefox-dev 02:32:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:32:53 INFO - mozversion application_vendor: Mozilla 02:32:53 INFO - mozversion application_version: 52.0a2 02:32:53 INFO - mozversion platform_buildid: 20170108234108 02:32:53 INFO - mozversion platform_changeset: fed81ec38bdf9a6ae80bdbedf5845d0fe84c1c86 02:32:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:32:53 INFO - mozversion platform_version: 52.0a2 02:32:53 INFO - using testdate: 1483957973 02:32:53 INFO - actual date: 1483957973 02:32:53 INFO - starting webserver on 'localhost:49245' 02:32:53 INFO - SUITE-START | Running 2 tests 02:32:53 INFO - TEST-START | dromaeo_css 02:32:53 INFO - operating with platform_type : mac_ 02:32:53 INFO - Initialising browser for dromaeo_css test... 02:32:53 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmp5bJtqT/profile http://localhost:49245/getInfo.html) 02:32:56 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 02:32:56 INFO - PROCESS | 1641 | colorDepth:24 02:32:56 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 02:32:56 INFO - PROCESS | 1641 | __metrics 02:32:56 INFO - PROCESS | 1641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:32:56 INFO - PROCESS | 1641 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:32:57 INFO - TEST-INFO | 1641: exit 0 02:32:57 INFO - Browser initialized. 02:32:57 INFO - Running cycle 1/1 for dromaeo_css test... 02:32:57 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmp5bJtqT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:33:09 INFO - PROCESS | 1644 | RSS: Main: 236449792 02:33:09 INFO - PROCESS | 1644 | 02:36:14 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 02:36:15 INFO - PROCESS | 1644 | RSS: Main: 366260224 02:36:15 INFO - PROCESS | 1644 | 02:39:18 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 02:39:19 INFO - PROCESS | 1644 | RSS: Main: 359452672 02:39:19 INFO - PROCESS | 1644 | 02:42:21 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 02:42:22 INFO - PROCESS | 1644 | RSS: Main: 332713984 02:42:22 INFO - PROCESS | 1644 | 02:45:24 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 02:45:25 INFO - PROCESS | 1644 | RSS: Main: 348127232 02:45:25 INFO - PROCESS | 1644 | 02:48:29 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 02:48:29 INFO - PROCESS | 1644 | RSS: Main: 321122304 02:48:29 INFO - PROCESS | 1644 | 02:51:32 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 02:51:32 INFO - PROCESS | 1644 | RSS: Main: 384274432 02:51:32 INFO - PROCESS | 1644 | 02:51:32 INFO - PROCESS | 1644 | __start_tp_report 02:51:32 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 02:51:32 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 02:51:32 INFO - PROCESS | 1644 | |i|pagename|runs| 02:51:32 INFO - PROCESS | 1644 | |0;dojo.html;3282.737381009519;3289.0954987378477;3323.4017876782186;3327.7005069543734;3356.6405605729688;5632.26780518532;5632.662040277581;5681.829545113643;5698.173764804106;5725.7995970141055;5605.103183490643;5612.186232996223;5641.548676105912;5713.342965558962;5741.081426971686;5118.6416950813455;5118.897622047575;5119.820806271781;5185.170372740344;5194.2208668699805;5117.1556693145585;5135.024345374367;5165.741712914358;5242.947570524303;5298.788048478075;5198.40218374888;5207.401148867891;5217.2695822584865;5217.37391513019;5238.266642670029;5210.869728256786;5221.347331583552;5222.686638801665;5227.712475813829;5229.1633338666015;5059.468755780626;5175.120229560984;5185.092608793446;5185.896282074337;5222.321098257202;5722.713864306768;5722.799702010409;5728.140778883159;5736.627119237278;5739.569532285053;5259.737013149327;6003.0695242237525;6096.254562767837;6098.420650038239;6114.082887566856;5076;6182.289036760785;6183.350748171467;6189.845253868644;6193.1020002099895;5696.943030569682;5701.543876489913;5709.628874123168;5733.197352370671;5753.33836608787;5294.808668049735;6166.013437849922;6199.132121502993;6232.065190221503;6262.749490020439;5005.649604527721;5005.724685142352;6028.969855150696;6120.969395152996;6135.202423684892;6217.098520714471;6249.6562689052535;6259.812205633838;6274.027525733519;6274.9686251569365;5181.885894532618;6008.729607167688;6070.787522436782;6202.534809889186;6211.627302361872;6119.472088496418;6129.662868542184;6176;6178.042403427476;6210.813675589739;4895.265710143436;4912.263160525949;4919.655624106278;4927.260910863328;4943.975280123648;4377.671674624416;4404.361367601679;4407.801648925806;4416.66874984371;4438.090191510732;4674.672772905864;5542.0855558832345;5624.959382514248;5640.407757185519;5691.800786972436;6175.413335733098;6238.064290356483;6240.937590624036;6252.499800015988;6255.78104766331;5688.601797874192;5694.743736531866;5698.9741846467205;5708.20085188074;5730.255066841284;5208.427073021964;5307.681539107743;5349.117395629678;5362.490563396471;5362.624616276823;6183.721732521957;6188.4739797117745;6211.472024877755;6249.625022498664;6263.4362907338555;6244.46922011616;6254.687265636609;6268.717907694074;6270.435660790551;6276.529260305404;6222.906656400248;6268.498520118471;6274.592151510137;6281.120643109877;6292;4743;4866.7079975201605;6061.1514387984835;6099.115628233792;6122.6726173278175;6224.346443623535;6229.034499652561;6235.158253635701;6235.594686345373;6238.282597501346;6051.425114614105;6057.697115144137;6062.090686396899;6070.301915279622;6083.330833608211;5884.352721200579;5947.702614869153;5968.671723055274;5975.850603734942;6051.031834906394;5890.028145355968;5896.086106653475;5900.911486327623;5920.644761314335;5962.672053037125;5882.323532793613;5947.494462970696;6028.216331876828;6048.848778780566;6059.636421814529;5803.071508558611;5807.477327040587;5858.9121163181735;5864.384239654945;5963.4334738199805 02:51:32 INFO - PROCESS | 1644 | |1;ext.html;451.595821739543;474.53732610704566;484.9466558678547;497.1275411652549;521.4967556308162;29355;31013.534796978063;36456.27087458249;38140.42789358162;38923;40426.595734042654;40776.59223407765;40817;40821;41013.179736405255;9130.817383652306;9155.862662060075;9251.953740231289;9301.72094837156;9324.8601270981;8381.748547543553;8574.056853746082;9134.086591340845;10927.453627318642;11214.70098629783;11052.502637381296;11073.944630276837;11087.057600104;11099.445027748621;11113.277636953571;11137.888621113807;11205.943970280177;11214.831777523344;11241.381724005176;11268.436578171058;10968.17738669603;11035.613753518588;11043.447827608668;11044.779104417947;11061.336319820755;37930.43104353437;38109.0472738181;38139.42790858139;38217.80891095527;38321;3246.2371342734436;3285.474324108155;3302.2734998300334;3315.1049216711594;3335.1495368681076;28017;28298.43403131926;28349.14952551427;28460.430791384264;29371.41257174866;3088.7839339577595;3146.732527735145;3195.984020079885;3254.853531591084;3272.100172452571;403524;451192.744036281;456892;456913;463833;32787;55212.44787552153;55575.444245557024;55756.32731018076;55830;33489;34584.96245112651;34655;34736;34758;13969.65075873091;14064.718705626035;14076.929615351857;14117.43530258778;14135.363908624136;10852;11794.99742521878;11885.405729713653;11894.345810980307;11928.045756339472;7299.781006569811;7390.150132734804;7395.001674773836;7399.519031263058;7408.03695519579;2926.97073029267;3184.347208822186;3249.52881832133;3254.225814800825;3309.4373956427467;935.8876934767735;937.4422218779815;976.3214565876648;983.7098056073476;1003.207466101786;21310.680339794915;28838.279043024093;29148.7085129146;29158.125256242347;29357.853210734236;965.9372140810825;1065.4779158212575;1074.1567869222622;1076.047697787448;1083.34999000599;12138.514459421702;14758.96687231905;14764.335604897806;14936;15087.396504139931;565033;569388;570546;571813;578062;45076.77461612737;47116.8220794476;47945.76027119842;53811;54719.72640136774;1208.7642909632536;1345.4819894340749;1377.3457607636292;1380.3029470117929;1383.190833362474;29076.418471630874;29624.259393514472;29948.550771738006;30422.54366184553;31354.372912542076;43032;47729.761351193025;52357.7382113087;54093;56867.86264274774;587116;588937;669677;696901;701655;38992.6100738989;39001.804990974866;39385.606143938196;39520.81437556878;39951.00122496961;42565.57434425616;43035;43487.34768978531;43566.346504803085;44100.89747756332;36011.27977440489;36142;36240;36409.63590364062;36763;35705.07990641259;36016.459753104245;36177.63822361848;36627;37552.43671344878;35017.824910875286;35710.64289357177;35779.821100894325;35835.28329433344;36176.63823361733;31077.844610776803;32413;32818;32825.83587082049;32921.506177407835;23822.761772382746;24180;24273;24653.38366540851;24747.62878556787 02:51:32 INFO - PROCESS | 1644 | |2;jquery.html;347.8747650845696;350.3046452791211;387.3337858882721;389.4236529935696;407.7961019490255;14765.335559899804;15020;15473.92263038686;20153.798462015377;20566.280180193695;19497.51256218595;19941.700874486858;20418.89790551045;20635.896820515878;20841.27055553056;5792.218050563172;5806.1000544915605;6030.427109424598;6053;6057.061155520902;5251.947480525184;5935.910961335583;5955.1960485334575;5969.22400087988;5977.133315669225;5855.884778947251;5918.112283157518;5922.437368449991;5932.436418540232;5942.702864856761;5636.887262254752;5798.623089499169;5847.06446968483;5870.853228669274;5875.794347197871;4911.238757992517;5058.4688607696025;5127.230915362688;5146.433892271847;5148;19702.70445943312;25134.868930898152;25412.237632871045;25947.740522594722;26202.475950481075;1953.4237399967044;2245.4723140062074;2255.785700358463;2491.9003239870385;3007.2782532192336;14905.254737263202;15573.221338933121;15624.218789060502;16216.594585135348;16269.674606507804;1784.9910750446295;1834.5597056706429;1847.556586419263;1894.2517705506248;1897.193692680619;118130.22804658023;119418;121147;123059;124435;8801.25189358899;10856;11222.494987725571;11244.606438774603;11431.828522572168;12205.633830985085;13618.706222908808;14140.929295353457;14146.43414263418;14147.363368648435;8326.45878017939;8572.699955501528;8596.957015214884;8695.347848911357;8705.347098967603;8541.113793224193;8543.572821359032;8550.871736923951;8713.695020674246;8753.693620723245;6616.8345791355605;6716.932830671631;6742.528023038439;6772.255051944282;6795.898061529081;1842.1710230396106;1869.616728570667;1874.137896567567;1874.8406385457388;1876.9061546922599;840.3823189955378;881.9802651927015;997.3816233935007;1001.6944831826363;1004.352192835617;20930.686039709417;22321.553568928855;22436.775632243796;22491;22537.4365640857;921.8847644044495;942.2791564453199;944.7826999790087;945.2532499325456;1007.9970429422772;9872.506374681381;11061.834072488919;11656.941715291368;11681.88318116825;11837;494326;499136;601679;625716;627660;102969;108532.66725924861;110882;122359.38820305842;128975;432.4831825968001;953.909378609031;1006.9093781559549;1010.3129871687177;1011.261778901406;9300.302477314093;10794.56821727122;11140;11157.776844463227;11656.708582285512;52759.20861187162;53871;53952.46047539474;55302.72348638231;55418.44581554133;73416.63291683508;75819.86270205841;77436.22563774516;78830;78911.60544197472;8551;11118.777624447628;11991.460384282555;12005.177488917694;12694.555690550786;11999.340036297739;12909.54816581415;13492.865071349554;13641.795373069213;14064.929675351967;11133.721656958642;11190.104791616814;11567.01680357179;11568.363739994382;11605.941970290094;11643.301401915913;11676.824847627462;11866;11958.880411195776;12095.879041209828;11045.944770276097;11757;12150.756984860076;12166.330851803235;12180.390980450764;12569.560065397667;12772.936135319263;12840.871591283967;12852.164609300366;12856.521500027617;11638.70903227426;11780.76438471243;11881.346525940811;11906.40467976615;11929.463174157358 02:51:32 INFO - PROCESS | 1644 | |3;mootools.html;1302.7003789128505;1459.4381163252144;1491.6345495353637;1599.856102886436;1645.5227983884681;7405.555666660004;7804.102528209258;7936.920630793683;8358.498490090598;8372.372072094588;8230.588470576477;8297.128801475848;8298.25315721585;8310.584470776452;8317.708880189195;2313.618252988255;2342.625179971205;2344.191254017361;2346.038124369009;2346.3547524430746;2319.698439202901;2352.529494101178;2370.8814559272055;2370.9051637934463;2371.513839662865;2297.9425514362106;2316.200910685819;2335.1710142899283;2338.333574931145;2339.508703172336;2278.897449614771;2295.1966811615966;2319.431739223884;2321.814254859607;2324.8605083695033;2316.409315624505;2335.061898624138;2337.0768546424238;2342.672025916373;2346.988265058681;6792.864142717168;8316.126806685272;8316.875246871301;8317.542535160565;8337.916620833774;5003.17447621142;5201.635885488017;5226.55574276187;5228.346456692913;5240.9213861792105;3317.8506967186536;3562.2697347043795;3569.821508924543;3624.3838547447;3637.8726744563814;1022.4121130350004;1062.9309094908883;1070.1866581398115;1102.85662863828;1112.260346869339;10817.755958064845;10844.240903136784;10929.508172132193;10933.671989840397;10961.780764384668;3561.8067947237964;3945.7829819359636;4038.9498730330324;4354.586314300136;4440.08978159483;8569.614367353484;8578.4424012439;8580.785480363042;8581.442206256574;8585.141485851489;4978.626603004789;5041.747912604355;5063.822766203165;5078.238264260391;5090.338256026693;4073.2668119738137;4279.657627389802;4435.578620031092;4459.888502787457;4758.452777930513;3674.338619048597;3689.90775230616;3742.345089609362;3751.9437208441896;3758.830852611639;1159.113278342069;1161.1001473857827;1162.2445410483085;1171.0924033873816;1173.2549830857297;803.2048272210469;900.2527901841587;901.5266984833015;902.3457992955055;933.332667142996;5122.385313762373;5335.9466405336225;5591.41290164535;5723.227364305765;5784.045632470596;428.25911173669726;452.2741000693839;458.7178884985776;460.4037771086451;461.9284010978304;3450.8957133716967;3482.233908540117;3617.3850445423814;3713.7771733696063;3743.7005039596766;9784;10133.391996480235;10258.640947566799;10264.230182736324;10265.024822641837;10103.393796372242;10187.592496300065;10221.897781022244;10228.897711022943;10233.181345492343;6921.688524016331;7588.658510367047;7699.26856948589;7866.449348545661;7885.802854928558;5372.946270537244;5415.268938693226;5489.066858634122;5622.9156562652415;5742.023855944416;9039.774005649911;9232.861507077265;9257.398269112486;9329.813403731752;9434.386764860263;9458.385204961656;9470.621175152917;9501.192398646193;9537.713868583953;9592.9040709592;7069.929300707133;7071.681774320065;7141.071660684078;7199;7208.71165153388;6546.607203567801;7499;7752.26353496417;7765.883511747441;7916.208379162038;6853.348931851466;7011.123609548807;7029.015937768614;7117.501774875709;7121.9643901780155;6264.2796078451565;6979.685914133977;7001.509894307554;7062.4703147263135;7196.0645116134565;6533.542652014314;6539.411452969255;6686.197656281277;6709.060731497691;6729.461643068445;4161.313383291723;4895.020995800783;4983.950160498349;5016.648834581544;5096.796128154832;4086.938695919623;4885.633577481632;5000.824971125993;5005.399352077774;5232.581393488588 02:51:32 INFO - PROCESS | 1644 | |4;prototype.html;257.7628581704832;277.6432284514399;299.3967156180297;310.81040565255205;317.5157884226553;4296.377025331325;4864.708117512942;5086.465921078285;5107.642465027445;5121.692698438096;4611.861644150681;4913.754312284389;4945.145570411096;5005.224190250517;5014.598832093424;3115.8130512169228;3388.102152929476;3503.4219353806593;3785.2429514097153;3896.7656844818875;3079.6612372638992;3907.7264591478606;3931.606839316074;3935.311320518912;3938.72428929975;2842.9715702842914;3721.162738383869;3765.6799172070405;3806.9619303806885;3808.8666896658624;3875.9418608720894;3886.1644746379525;3888.1446081861945;3891.280113179071;3895.357266051098;2848.501512235371;3281.6381201801223;3410.4202285611505;3428.7257019438384;3432.9141771455666;4631.564215093332;4885.607601833496;4913.803447862081;4935.210366341404;4947.554720075174;3629.8548058077645;3784.9621503784883;4280.229558679436;4342.1532801103795;4364.5635436456105;4524.909501809978;5255.316808814868;5265.078611243017;5276.683398996034;5283.973580132113;3521.943416974923;3646.3436581415335;3665.505156803824;3672.155404257009;3691.7231207659265;4757.857264282077;5155.61332900034;5248.816291429782;5418.130744893006;5541.445855414467;3113.5018397056815;3622.2936527377437;3623.1123374772824;3730.813459327023;3759.285735710206;3371.836716332861;3789.204267103884;3800.771953682788;3802.2585595809105;3817.682899399703;4158.376243563429;4324.827006919751;4370.929122365041;4373.693841431069;4428.7121337112985;4016.457778199964;4935.950640493621;5288.576913846883;5369.973150134224;5412.269343638558;1202.1945296651265;1212.1878341511208;1219.5731493977037;1219.9146059775908;1236.394166858233;809.5911564659809;825.9793505162298;848.8599381102173;850.2857599616228;853.825989264761;3758.605346438639;3766.4200022983796;4185.309423945015;4617.007343421181;4691.569071433246;4436.977815110969;4601.619514145743;5158.509941555546;5160.716160611202;5185.533302002837;3974.940375894364;4262.360645903139;4604.608608268335;4611.287913229882;4678.625709943196;2286.5372474747264;2528.292078218102;2680.9731902681115;2704.7971402144526;2712.924612671308;4593.931091033637;5117.462666419972;5317.707526086025;5360.678359298455;5373.731313434313;4700.835470758576;4867.5132486751045;5070.340855688737;5288.2596436498925;5360.303160589098;5275.287836142148;5347.518723314843;5364.678119312775;5366.082759585446;5398.406175320712;4546.7953942073345;4835.1296766580945;4858.416989961227;5138.07514647367;5161.716105614077;4538.659600529907;4813.614910807197;5071;5102.193793413533;5111.386633603991;4962.851114466572;5103.285540024325;5630.718464076698;5719.599628025998;5757.157709532909;3654.579723331851;3656.6526180012856;3702.315071711742;3713.795741234258;3746.5129533160516;5436.945630543644;5460.262864513399;5522.502974732293;5524.06090964545;5549.334079910436;3678.356287649704;4501.312007997001;5370.516653501204;5387.488188622231;5455.208994695668;4390.538993405647;5325.4940780625775;5371.4897084776885;5378.031954248273;5388.245645609696;5015.749212539431;5199.428062913152;5248.711320877388;5282.8943421132135;5308.389535203501;4483.484399294123;5088.5674717647935;5383.031054410244;5388.7036213007095;5417.6478528895495;4137.172565486854;4443.289073748185;5046.501981519172;5184.844454666366;5203.687778733288 02:51:32 INFO - PROCESS | 1644 | |5;yui.html;635.828326351885;713;752.7064444866502;756.2324240895489;778.0896351269015;14136.646583835396;14763.96652234344;14838;14846.480373186927;14850.034747741403;12980.610581682564;13052.477900883954;13089.607311780637;13096.41066152023;13247.80128298074;650.4958657040808;675.034700378461;679.9082123913258;801.5070731500115;812.3420029775871;822.6462621072936;826.499967519651;838.8741688746676;839.6011894350196;840.9117042710488;882.861109169171;888.1518150166576;890.3544929925785;893.8569828827389;895.2703546609525;688.4973969002606;713.7287830624382;882.8322618702458;886.8891388576428;887.4719541872607;628.4375483941892;650.4763665249473;653.0139489371084;655.9081728558006;656.4682607088274;13644.658883527893;13688.726225475535;13692.52076177329;13694.65763355924;13703.862961370358;12707.301098439582;12820.934743476584;13054.151480153854;13072.542461013818;13082.672933176651;11574.189806713533;11993.880061199363;12019;12193.39033048353;12426.689332766662;4268.338407546835;4314.935275970863;4429.512753597101;4492.775361231926;4541.296099104611;82725;83452.58273708653;84141.73787393194;84683;86350;10263.281570290008;10436.008579184965;10467.790644187073;10491.68524944253;10595.84106238407;19570.80429195718;20103.698444523343;20195.091220895094;20287.985600719905;20354.28759993393;6366.840828979312;7395.112586489656;7553.206913274137;7729.922700773032;7730.265624765639;4148.896277593084;4227.408162857202;4272.9359059614135;4309.07354918694;4417.712848664826;3285.917852053718;3312.9171770705443;3383.678550537695;3389.508521264403;3414.2488652496413;564.8192578374881;566.4109326300684;566.4873289672853;567.7133047810912;579.2064871126584;555.1395337227282;565.5673409841474;610.7715190717815;616.5745635511482;622.0793226025507;6713.858644030526;8661.263792577576;8760.56197190138;8779.29765618749;8840.469571825583;442.41822004065193;596.3350863786933;606.6147996022557;626.7305058824658;641.415264497321;4114.238865809834;4284.3145096784365;4324.632406245504;4361.6510679145595;4379.994958705915;76370.61814691003;87400;113431.4328428369;122686;128961;105404.47297763462;107067.46466267618;108178.99999999843;108246;108974.91025089647;61959.69020154961;62050.75898482005;93829.5308523453;94031;94763.0523694768;7112.431005519443;8243.422960392954;8327.625256863335;8361.540115293474;8434.873476897728;57627;58403.12395313989;60221.39778602158;60324.39675603188;62917.685411572646;79757;84103;85532.1446785549;89687;89850;11053.226274160732;11056.723581910517;11072.723181920517;11076.889231107585;11425.257358271685;10459.424731639529;11254.896127720955;11748.412579370826;11782.116341274268;11862.644120676394;10091.344062635904;10798.082163015917;11315.20793544444;11364.886351136383;11684.357360345262;10805.675829725122;10878.836817447904;11291.43542822872;11591.76816463649;11966.22219555726;11060.281081729661;11196.94401527987;11221;11397.430128493708;11680.240784348991;9443.386179898285;9718.271129665161;9826.852597210904;9878.25913056538;9917.900820991697;9418.905810942077;9583.185429238592;9606.471644059644;9612.711618651452;9805.656802011896 02:51:32 INFO - PROCESS | 1644 | __end_tp_report 02:51:32 INFO - PROCESS | 1644 | __start_cc_report 02:51:32 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1478 02:51:32 INFO - PROCESS | 1644 | __end_cc_report 02:51:32 INFO - PROCESS | 1644 | __startTimestamp1483959092964__endTimestamp 02:51:32 INFO - PROCESS | 1644 | 02:51:32 INFO - PROCESS | 1644 | ------- Summary: start ------- 02:51:32 INFO - PROCESS | 1644 | Number of tests: 6 02:51:32 INFO - PROCESS | 1644 | 02:51:32 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5646.78 Median:5753.34 stddev:641.88 (11.2%) stddev-sans-first:616.47 02:51:32 INFO - PROCESS | 1644 | Values: 3282.7 3289.1 3323.4 3327.7 3356.6 5632.3 5632.7 5681.8 5698.2 5725.8 5605.1 5612.2 5641.5 5713.3 5741.1 5118.6 5118.9 5119.8 5185.2 5194.2 5117.2 5135.0 5165.7 5242.9 5298.8 5198.4 5207.4 5217.3 5217.4 5238.3 5210.9 5221.3 5222.7 5227.7 5229.2 5059.5 5175.1 5185.1 5185.9 5222.3 5722.7 5722.8 5728.1 5736.6 5739.6 5259.7 6003.1 6096.3 6098.4 6114.1 5076.0 6182.3 6183.4 6189.8 6193.1 5696.9 5701.5 5709.6 5733.2 5753.3 5294.8 6166.0 6199.1 6232.1 6262.7 5005.6 5005.7 6029.0 6121.0 6135.2 6217.1 6249.7 6259.8 6274.0 6275.0 5181.9 6008.7 6070.8 6202.5 6211.6 6119.5 6129.7 6176.0 6178.0 6210.8 4895.3 4912.3 4919.7 4927.3 4944.0 4377.7 4404.4 4407.8 4416.7 4438.1 4674.7 5542.1 5625.0 5640.4 5691.8 6175.4 6238.1 6240.9 6252.5 6255.8 5688.6 5694.7 5699.0 5708.2 5730.3 5208.4 5307.7 5349.1 5362.5 5362.6 6183.7 6188.5 6211.5 6249.6 6263.4 6244.5 6254.7 6268.7 6270.4 6276.5 6222.9 6268.5 6274.6 6281.1 6292.0 4743.0 4866.7 6061.2 6099.1 6122.7 6224.3 6229.0 6235.2 6235.6 6238.3 6051.4 6057.7 6062.1 6070.3 6083.3 5884.4 5947.7 5968.7 5975.9 6051.0 5890.0 5896.1 5900.9 5920.6 5962.7 5882.3 5947.5 6028.2 6048.8 6059.6 5803.1 5807.5 5858.9 5864.4 5963.4 02:51:32 INFO - PROCESS | 1644 | 02:51:32 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:67034.74 Median:35361.45 stddev:150755.88 (426.3%) stddev-sans-first:151095.90 02:51:32 INFO - PROCESS | 1644 | Values: 451.6 474.5 484.9 497.1 521.5 29355.0 31013.5 36456.3 38140.4 38923.0 40426.6 40776.6 40817.0 40821.0 41013.2 9130.8 9155.9 9252.0 9301.7 9324.9 8381.7 8574.1 9134.1 10927.5 11214.7 11052.5 11073.9 11087.1 11099.4 11113.3 11137.9 11205.9 11214.8 11241.4 11268.4 10968.2 11035.6 11043.4 11044.8 11061.3 37930.4 38109.0 38139.4 38217.8 38321.0 3246.2 3285.5 3302.3 3315.1 3335.1 28017.0 28298.4 28349.1 28460.4 29371.4 3088.8 3146.7 3196.0 3254.9 3272.1 403524.0 451192.7 456892.0 456913.0 463833.0 32787.0 55212.4 55575.4 55756.3 55830.0 33489.0 34585.0 34655.0 34736.0 34758.0 13969.7 14064.7 14076.9 14117.4 14135.4 10852.0 11795.0 11885.4 11894.3 11928.0 7299.8 7390.2 7395.0 7399.5 7408.0 2927.0 3184.3 3249.5 3254.2 3309.4 935.9 937.4 976.3 983.7 1003.2 21310.7 28838.3 29148.7 29158.1 29357.9 965.9 1065.5 1074.2 1076.0 1083.3 12138.5 14759.0 14764.3 14936.0 15087.4 565033.0 569388.0 570546.0 571813.0 578062.0 45076.8 47116.8 47945.8 53811.0 54719.7 1208.8 1345.5 1377.3 1380.3 1383.2 29076.4 29624.3 29948.6 30422.5 31354.4 43032.0 47729.8 52357.7 54093.0 56867.9 587116.0 588937.0 669677.0 696901.0 701655.0 38992.6 39001.8 39385.6 39520.8 39951.0 42565.6 43035.0 43487.3 43566.3 44100.9 36011.3 36142.0 36240.0 36409.6 36763.0 35705.1 36016.5 36177.6 36627.0 37552.4 35017.8 35710.6 35779.8 35835.3 36176.6 31077.8 32413.0 32818.0 32825.8 32921.5 23822.8 24180.0 24273.0 24653.4 24747.6 02:51:32 INFO - PROCESS | 1644 | 02:51:32 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:34139.13 Median:20492.59 stddev:95580.66 (466.4%) stddev-sans-first:95815.11 02:51:32 INFO - PROCESS | 1644 | Values: 347.9 350.3 387.3 389.4 407.8 14765.3 15020.0 15473.9 20153.8 20566.3 19497.5 19941.7 20418.9 20635.9 20841.3 5792.2 5806.1 6030.4 6053.0 6057.1 5251.9 5935.9 5955.2 5969.2 5977.1 5855.9 5918.1 5922.4 5932.4 5942.7 5636.9 5798.6 5847.1 5870.9 5875.8 4911.2 5058.5 5127.2 5146.4 5148.0 19702.7 25134.9 25412.2 25947.7 26202.5 1953.4 2245.5 2255.8 2491.9 3007.3 14905.3 15573.2 15624.2 16216.6 16269.7 1785.0 1834.6 1847.6 1894.3 1897.2 118130.2 119418.0 121147.0 123059.0 124435.0 8801.3 10856.0 11222.5 11244.6 11431.8 12205.6 13618.7 14140.9 14146.4 14147.4 8326.5 8572.7 8597.0 8695.3 8705.3 8541.1 8543.6 8550.9 8713.7 8753.7 6616.8 6716.9 6742.5 6772.3 6795.9 1842.2 1869.6 1874.1 1874.8 1876.9 840.4 882.0 997.4 1001.7 1004.4 20930.7 22321.6 22436.8 22491.0 22537.4 921.9 942.3 944.8 945.3 1008.0 9872.5 11061.8 11656.9 11681.9 11837.0 494326.0 499136.0 601679.0 625716.0 627660.0 102969.0 108532.7 110882.0 122359.4 128975.0 432.5 953.9 1006.9 1010.3 1011.3 9300.3 10794.6 11140.0 11157.8 11656.7 52759.2 53871.0 53952.5 55302.7 55418.4 73416.6 75819.9 77436.2 78830.0 78911.6 8551.0 11118.8 11991.5 12005.2 12694.6 11999.3 12909.5 13492.9 13641.8 14064.9 11133.7 11190.1 11567.0 11568.4 11605.9 11643.3 11676.8 11866.0 11958.9 12095.9 11045.9 11757.0 12150.8 12166.3 12180.4 12569.6 12772.9 12840.9 12852.2 12856.5 11638.7 11780.8 11881.3 11906.4 11929.5 02:51:32 INFO - PROCESS | 1644 | 02:51:32 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5352.35 Median:5002.00 stddev:2973.14 (59.4%) stddev-sans-first:2965.90 02:51:32 INFO - PROCESS | 1644 | Values: 1302.7 1459.4 1491.6 1599.9 1645.5 7405.6 7804.1 7936.9 8358.5 8372.4 8230.6 8297.1 8298.3 8310.6 8317.7 2313.6 2342.6 2344.2 2346.0 2346.4 2319.7 2352.5 2370.9 2370.9 2371.5 2297.9 2316.2 2335.2 2338.3 2339.5 2278.9 2295.2 2319.4 2321.8 2324.9 2316.4 2335.1 2337.1 2342.7 2347.0 6792.9 8316.1 8316.9 8317.5 8337.9 5003.2 5201.6 5226.6 5228.3 5240.9 3317.9 3562.3 3569.8 3624.4 3637.9 1022.4 1062.9 1070.2 1102.9 1112.3 10817.8 10844.2 10929.5 10933.7 10961.8 3561.8 3945.8 4038.9 4354.6 4440.1 8569.6 8578.4 8580.8 8581.4 8585.1 4978.6 5041.7 5063.8 5078.2 5090.3 4073.3 4279.7 4435.6 4459.9 4758.5 3674.3 3689.9 3742.3 3751.9 3758.8 1159.1 1161.1 1162.2 1171.1 1173.3 803.2 900.3 901.5 902.3 933.3 5122.4 5335.9 5591.4 5723.2 5784.0 428.3 452.3 458.7 460.4 461.9 3450.9 3482.2 3617.4 3713.8 3743.7 9784.0 10133.4 10258.6 10264.2 10265.0 10103.4 10187.6 10221.9 10228.9 10233.2 6921.7 7588.7 7699.3 7866.4 7885.8 5372.9 5415.3 5489.1 5622.9 5742.0 9039.8 9232.9 9257.4 9329.8 9434.4 9458.4 9470.6 9501.2 9537.7 9592.9 7069.9 7071.7 7141.1 7199.0 7208.7 6546.6 7499.0 7752.3 7765.9 7916.2 6853.3 7011.1 7029.0 7117.5 7122.0 6264.3 6979.7 7001.5 7062.5 7196.1 6533.5 6539.4 6686.2 6709.1 6729.5 4161.3 4895.0 4984.0 5016.6 5096.8 4086.9 4885.6 5000.8 5005.4 5232.6 02:51:32 INFO - PROCESS | 1644 | 02:51:32 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4197.42 Median:4611.57 stddev:1289.27 (28.0%) stddev-sans-first:1258.52 02:51:32 INFO - PROCESS | 1644 | Values: 257.8 277.6 299.4 310.8 317.5 4296.4 4864.7 5086.5 5107.6 5121.7 4611.9 4913.8 4945.1 5005.2 5014.6 3115.8 3388.1 3503.4 3785.2 3896.8 3079.7 3907.7 3931.6 3935.3 3938.7 2843.0 3721.2 3765.7 3807.0 3808.9 3875.9 3886.2 3888.1 3891.3 3895.4 2848.5 3281.6 3410.4 3428.7 3432.9 4631.6 4885.6 4913.8 4935.2 4947.6 3629.9 3785.0 4280.2 4342.2 4364.6 4524.9 5255.3 5265.1 5276.7 5284.0 3521.9 3646.3 3665.5 3672.2 3691.7 4757.9 5155.6 5248.8 5418.1 5541.4 3113.5 3622.3 3623.1 3730.8 3759.3 3371.8 3789.2 3800.8 3802.3 3817.7 4158.4 4324.8 4370.9 4373.7 4428.7 4016.5 4936.0 5288.6 5370.0 5412.3 1202.2 1212.2 1219.6 1219.9 1236.4 809.6 826.0 848.9 850.3 853.8 3758.6 3766.4 4185.3 4617.0 4691.6 4437.0 4601.6 5158.5 5160.7 5185.5 3974.9 4262.4 4604.6 4611.3 4678.6 2286.5 2528.3 2681.0 2704.8 2712.9 4593.9 5117.5 5317.7 5360.7 5373.7 4700.8 4867.5 5070.3 5288.3 5360.3 5275.3 5347.5 5364.7 5366.1 5398.4 4546.8 4835.1 4858.4 5138.1 5161.7 4538.7 4813.6 5071.0 5102.2 5111.4 4962.9 5103.3 5630.7 5719.6 5757.2 3654.6 3656.7 3702.3 3713.8 3746.5 5436.9 5460.3 5522.5 5524.1 5549.3 3678.4 4501.3 5370.5 5387.5 5455.2 4390.5 5325.5 5371.5 5378.0 5388.2 5015.7 5199.4 5248.7 5282.9 5308.4 4483.5 5088.6 5383.0 5388.7 5417.6 4137.2 4443.3 5046.5 5184.8 5203.7 02:51:32 INFO - PROCESS | 1644 | 02:51:32 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:20659.48 Median:565.19 stddev:31368.85 (5550.1%) stddev-sans-first:31420.82 02:51:32 INFO - PROCESS | 1644 | Values: 635.8 713.0 752.7 756.2 778.1 14136.6 14764.0 14838.0 14846.5 14850.0 12980.6 13052.5 13089.6 13096.4 13247.8 650.5 675.0 679.9 801.5 812.3 822.6 826.5 838.9 839.6 840.9 882.9 888.2 890.4 893.9 895.3 688.5 713.7 882.8 886.9 887.5 628.4 650.5 653.0 655.9 656.5 13644.7 13688.7 13692.5 13694.7 13703.9 12707.3 12820.9 13054.2 13072.5 13082.7 11574.2 11993.9 12019.0 12193.4 12426.7 4268.3 4314.9 4429.5 4492.8 4541.3 82725.0 83452.6 84141.7 84683.0 86350.0 10263.3 10436.0 10467.8 10491.7 10595.8 19570.8 20103.7 20195.1 20288.0 20354.3 6366.8 7395.1 7553.2 7729.9 7730.3 4148.9 4227.4 4272.9 4309.1 4417.7 3285.9 3312.9 3383.7 3389.5 3414.2 564.8 566.4 566.5 567.7 579.2 555.1 565.6 610.8 616.6 622.1 6713.9 8661.3 8760.6 8779.3 8840.5 442.4 596.3 606.6 626.7 641.4 4114.2 4284.3 4324.6 4361.7 4380.0 76370.6 87400.0 113431.4 122686.0 128961.0 105404.5 107067.5 108179.0 108246.0 108974.9 61959.7 62050.8 93829.5 94031.0 94763.1 7112.4 8243.4 8327.6 8361.5 8434.9 57627.0 58403.1 60221.4 60324.4 62917.7 79757.0 84103.0 85532.1 89687.0 89850.0 11053.2 11056.7 11072.7 11076.9 11425.3 10459.4 11254.9 11748.4 11782.1 11862.6 10091.3 10798.1 11315.2 11364.9 11684.4 10805.7 10878.8 11291.4 11591.8 11966.2 11060.3 11196.9 11221.0 11397.4 11680.2 9443.4 9718.3 9826.9 9878.3 9917.9 9418.9 9583.2 9606.5 9612.7 9805.7 02:51:32 INFO - PROCESS | 1644 | -------- Summary: end -------- 02:51:32 INFO - PROCESS | 1644 | 02:51:33 INFO - TEST-INFO | 1644: exit 0 02:51:33 INFO - TEST-OK | dromaeo_css | took 1120057ms 02:51:33 INFO - TEST-START | kraken 02:51:33 INFO - operating with platform_type : mac_ 02:51:33 INFO - Initialising browser for kraken test... 02:51:33 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpxcUrkO/profile http://localhost:49245/getInfo.html) 02:51:36 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 02:51:36 INFO - PROCESS | 1650 | colorDepth:24 02:51:36 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 02:51:36 INFO - PROCESS | 1650 | __metrics 02:51:36 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:51:36 INFO - PROCESS | 1650 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:51:37 INFO - TEST-INFO | 1650: exit 0 02:51:37 INFO - Browser initialized. 02:51:37 INFO - Running cycle 1/1 for kraken test... 02:51:37 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpxcUrkO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:51:49 INFO - PROCESS | 1651 | RSS: Main: 242814976 02:51:49 INFO - PROCESS | 1651 | 02:51:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 02:51:53 INFO - PROCESS | 1651 | RSS: Main: 289808384 02:51:53 INFO - PROCESS | 1651 | 02:51:55 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 02:51:56 INFO - PROCESS | 1651 | RSS: Main: 301887488 02:51:56 INFO - PROCESS | 1651 | 02:51:59 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 02:52:00 INFO - PROCESS | 1651 | RSS: Main: 396328960 02:52:00 INFO - PROCESS | 1651 | 02:52:02 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 02:52:02 INFO - PROCESS | 1651 | RSS: Main: 297639936 02:52:02 INFO - PROCESS | 1651 | 02:52:04 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:52:05 INFO - PROCESS | 1651 | RSS: Main: 298692608 02:52:05 INFO - PROCESS | 1651 | 02:52:08 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 02:52:08 INFO - PROCESS | 1651 | RSS: Main: 338563072 02:52:08 INFO - PROCESS | 1651 | 02:52:11 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 02:52:12 INFO - PROCESS | 1651 | RSS: Main: 340819968 02:52:12 INFO - PROCESS | 1651 | 02:52:14 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 02:52:15 INFO - PROCESS | 1651 | RSS: Main: 337240064 02:52:15 INFO - PROCESS | 1651 | 02:52:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:52:17 INFO - PROCESS | 1651 | RSS: Main: 311853056 02:52:17 INFO - PROCESS | 1651 | 02:52:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:52:20 INFO - PROCESS | 1651 | RSS: Main: 316071936 02:52:20 INFO - PROCESS | 1651 | 02:52:22 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:52:23 INFO - PROCESS | 1651 | RSS: Main: 317353984 02:52:23 INFO - PROCESS | 1651 | 02:52:26 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:52:26 INFO - PROCESS | 1651 | RSS: Main: 336519168 02:52:26 INFO - PROCESS | 1651 | 02:52:29 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:52:30 INFO - PROCESS | 1651 | RSS: Main: 329469952 02:52:30 INFO - PROCESS | 1651 | 02:52:31 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 02:52:32 INFO - PROCESS | 1651 | RSS: Main: 373796864 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | __start_tp_report 02:52:32 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 02:52:32 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 02:52:32 INFO - PROCESS | 1651 | |i|pagename|runs| 02:52:32 INFO - PROCESS | 1651 | |0;ai-astar;115;113;112;114;112;114;111;111;112;112 02:52:32 INFO - PROCESS | 1651 | |1;audio-beat-detection;174;144;143;144;111;110;110;114;176;139 02:52:32 INFO - PROCESS | 1651 | |2;audio-dft;184;183;185;187;184;184;182;182;180;183 02:52:32 INFO - PROCESS | 1651 | |3;audio-fft;82;57;58;88;60;58;59;58;82;59 02:52:32 INFO - PROCESS | 1651 | |4;audio-oscillator;90;72;96;71;69;71;91;69;71;93 02:52:32 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;86;84;86;84;86;84;86;-124;87;84 02:52:32 INFO - PROCESS | 1651 | |6;imaging-darkroom;93;92;93;93;92;93;94;93;93;93 02:52:32 INFO - PROCESS | 1651 | |7;imaging-desaturate;87;126;121;122;81;81;85;85;84;83 02:52:32 INFO - PROCESS | 1651 | |8;json-parse-financial;53;55;53;52;56;57;59;59;57;57 02:52:32 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;69;74;75;73;74;70;69;70;71;72 02:52:32 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;74;81;101;103;103;101;84;81;86;81 02:52:32 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;140;139;138;139;138;143;142;143;198;151 02:52:32 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;161;168;162;168;164;165;164;164;164;163 02:52:32 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;69;67;70;68;66;67;68;67;66;66 02:52:32 INFO - PROCESS | 1651 | __end_tp_report 02:52:32 INFO - PROCESS | 1651 | __start_cc_report 02:52:32 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1981 02:52:32 INFO - PROCESS | 1651 | __end_cc_report 02:52:32 INFO - PROCESS | 1651 | __startTimestamp1483959152154__endTimestamp 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | ------- Summary: start ------- 02:52:32 INFO - PROCESS | 1651 | Number of tests: 14 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:112.60 Median:112.50 stddev:1.35 (1.2%) stddev-sans-first:1.12 02:52:32 INFO - PROCESS | 1651 | Values: 115.0 113.0 112.0 114.0 112.0 114.0 111.0 111.0 112.0 112.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:136.50 Median:143.50 stddev:25.14 (17.5%) stddev-sans-first:22.71 02:52:32 INFO - PROCESS | 1651 | Values: 174.0 144.0 143.0 144.0 111.0 110.0 110.0 114.0 176.0 139.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.90 (1.0%) stddev-sans-first:2.00 02:52:32 INFO - PROCESS | 1651 | Values: 184.0 183.0 185.0 187.0 184.0 184.0 182.0 182.0 180.0 183.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:66.10 Median:59.50 stddev:12.49 (21.0%) stddev-sans-first:11.84 02:52:32 INFO - PROCESS | 1651 | Values: 82.0 57.0 58.0 88.0 60.0 58.0 59.0 58.0 82.0 59.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:79.30 Median:81.00 stddev:11.50 (14.2%) stddev-sans-first:11.53 02:52:32 INFO - PROCESS | 1651 | Values: 90.0 72.0 96.0 71.0 69.0 71.0 91.0 69.0 71.0 93.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:64.30 Median:86.00 stddev:66.17 (76.9%) stddev-sans-first:69.72 02:52:32 INFO - PROCESS | 1651 | Values: 86.0 84.0 86.0 84.0 86.0 84.0 86.0 -124.0 87.0 84.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 02:52:32 INFO - PROCESS | 1651 | Values: 93.0 92.0 93.0 93.0 92.0 93.0 94.0 93.0 93.0 93.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:83.50 stddev:19.10 (22.9%) stddev-sans-first:20.01 02:52:32 INFO - PROCESS | 1651 | Values: 87.0 126.0 121.0 122.0 81.0 81.0 85.0 85.0 84.0 83.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:57.00 stddev:2.49 (4.4%) stddev-sans-first:2.42 02:52:32 INFO - PROCESS | 1651 | Values: 53.0 55.0 53.0 52.0 56.0 57.0 59.0 59.0 57.0 57.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:72.50 stddev:2.21 (3.1%) stddev-sans-first:2.12 02:52:32 INFO - PROCESS | 1651 | Values: 69.0 74.0 75.0 73.0 74.0 70.0 69.0 70.0 71.0 72.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:81.00 stddev:11.20 (13.8%) stddev-sans-first:10.38 02:52:32 INFO - PROCESS | 1651 | Values: 74.0 81.0 101.0 103.0 103.0 101.0 84.0 81.0 86.0 81.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:142.50 stddev:18.30 (12.8%) stddev-sans-first:19.23 02:52:32 INFO - PROCESS | 1651 | Values: 140.0 139.0 138.0 139.0 138.0 143.0 142.0 143.0 198.0 151.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.30 Median:164.00 stddev:2.26 (1.4%) stddev-sans-first:2.06 02:52:32 INFO - PROCESS | 1651 | Values: 161.0 168.0 162.0 168.0 164.0 165.0 164.0 164.0 164.0 163.0 02:52:32 INFO - PROCESS | 1651 | 02:52:32 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.35 (2.0%) stddev-sans-first:1.30 02:52:32 INFO - PROCESS | 1651 | Values: 69.0 67.0 70.0 68.0 66.0 67.0 68.0 67.0 66.0 66.0 02:52:32 INFO - PROCESS | 1651 | -------- Summary: end -------- 02:52:32 INFO - PROCESS | 1651 | 02:52:33 INFO - TEST-INFO | 1651: exit 0 02:52:33 INFO - TEST-OK | kraken | took 59784ms 02:52:33 INFO - SUITE-END | took 1179s 02:52:33 INFO - Completed test suite (00:19:40) 02:52:33 INFO - javascript benchmark 02:52:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3282.737381009519, 3289.0954987378477, 3323.4017876782186, 3327.7005069543734, 3356.6405605729688, 5632.26780518532, 5632.662040277581, 5681.829545113643, 5698.173764804106, 5725.7995970141055, 5605.103183490643, 5612.186232996223, 5641.548676105912, 5713.342965558962, 5741.081426971686, 5118.6416950813455, 5118.897622047575, 5119.820806271781, 5185.170372740344, 5194.2208668699805, 5117.1556693145585, 5135.024345374367, 5165.741712914358, 5242.947570524303, 5298.788048478075, 5198.40218374888, 5207.401148867891, 5217.2695822584865, 5217.37391513019, 5238.266642670029, 5210.869728256786, 5221.347331583552, 5222.686638801665, 5227.712475813829, 5229.1633338666015, 5059.468755780626, 5175.120229560984, 5185.092608793446, 5185.896282074337, 5222.321098257202, 5722.713864306768, 5722.799702010409, 5728.140778883159, 5736.627119237278, 5739.569532285053, 5259.737013149327, 6003.0695242237525, 6096.254562767837, 6098.420650038239, 6114.082887566856, 5076.0, 6182.289036760785, 6183.350748171467, 6189.845253868644, 6193.1020002099895, 5696.943030569682, 5701.543876489913, 5709.628874123168, 5733.197352370671, 5753.33836608787, 5294.808668049735, 6166.013437849922, 6199.132121502993, 6232.065190221503, 6262.749490020439, 5005.649604527721, 5005.724685142352, 6028.969855150696, 6120.969395152996, 6135.202423684892, 6217.098520714471, 6249.6562689052535, 6259.812205633838, 6274.027525733519, 6274.9686251569365, 5181.885894532618, 6008.729607167688, 6070.787522436782, 6202.534809889186, 6211.627302361872, 6119.472088496418, 6129.662868542184, 6176.0, 6178.042403427476, 6210.813675589739, 4895.265710143436, 4912.263160525949, 4919.655624106278, 4927.260910863328, 4943.975280123648, 4377.671674624416, 4404.361367601679, 4407.801648925806, 4416.66874984371, 4438.090191510732, 4674.672772905864, 5542.0855558832345, 5624.959382514248, 5640.407757185519, 5691.800786972436, 6175.413335733098, 6238.064290356483, 6240.937590624036, 6252.499800015988, 6255.78104766331, 5688.601797874192, 5694.743736531866, 5698.9741846467205, 5708.20085188074, 5730.255066841284, 5208.427073021964, 5307.681539107743, 5349.117395629678, 5362.490563396471, 5362.624616276823, 6183.721732521957, 6188.4739797117745, 6211.472024877755, 6249.625022498664, 6263.4362907338555, 6244.46922011616, 6254.687265636609, 6268.717907694074, 6270.435660790551, 6276.529260305404, 6222.906656400248, 6268.498520118471, 6274.592151510137, 6281.120643109877, 6292.0, 4743.0, 4866.7079975201605, 6061.1514387984835, 6099.115628233792, 6122.6726173278175, 6224.346443623535, 6229.034499652561, 6235.158253635701, 6235.594686345373, 6238.282597501346, 6051.425114614105, 6057.697115144137, 6062.090686396899, 6070.301915279622, 6083.330833608211, 5884.352721200579, 5947.702614869153, 5968.671723055274, 5975.850603734942, 6051.031834906394, 5890.028145355968, 5896.086106653475, 5900.911486327623, 5920.644761314335, 5962.672053037125, 5882.323532793613, 5947.494462970696, 6028.216331876828, 6048.848778780566, 6059.636421814529, 5803.071508558611, 5807.477327040587, 5858.9121163181735, 5864.384239654945, 5963.4334738199805], "value": 5608.169847976112, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.595821739543, 474.53732610704566, 484.9466558678547, 497.1275411652549, 521.4967556308162, 29355.0, 31013.534796978063, 36456.27087458249, 38140.42789358162, 38923.0, 40426.595734042654, 40776.59223407765, 40817.0, 40821.0, 41013.179736405255, 9130.817383652306, 9155.862662060075, 9251.953740231289, 9301.72094837156, 9324.8601270981, 8381.748547543553, 8574.056853746082, 9134.086591340845, 10927.453627318642, 11214.70098629783, 11052.502637381296, 11073.944630276837, 11087.057600104, 11099.445027748621, 11113.277636953571, 11137.888621113807, 11205.943970280177, 11214.831777523344, 11241.381724005176, 11268.436578171058, 10968.17738669603, 11035.613753518588, 11043.447827608668, 11044.779104417947, 11061.336319820755, 37930.43104353437, 38109.0472738181, 38139.42790858139, 38217.80891095527, 38321.0, 3246.2371342734436, 3285.474324108155, 3302.2734998300334, 3315.1049216711594, 3335.1495368681076, 28017.0, 28298.43403131926, 28349.14952551427, 28460.430791384264, 29371.41257174866, 3088.7839339577595, 3146.732527735145, 3195.984020079885, 3254.853531591084, 3272.100172452571, 403524.0, 451192.744036281, 456892.0, 456913.0, 463833.0, 32787.0, 55212.44787552153, 55575.444245557024, 55756.32731018076, 55830.0, 33489.0, 34584.96245112651, 34655.0, 34736.0, 34758.0, 13969.65075873091, 14064.718705626035, 14076.929615351857, 14117.43530258778, 14135.363908624136, 10852.0, 11794.99742521878, 11885.405729713653, 11894.345810980307, 11928.045756339472, 7299.781006569811, 7390.150132734804, 7395.001674773836, 7399.519031263058, 7408.03695519579, 2926.97073029267, 3184.347208822186, 3249.52881832133, 3254.225814800825, 3309.4373956427467, 935.8876934767735, 937.4422218779815, 976.3214565876648, 983.7098056073476, 1003.207466101786, 21310.680339794915, 28838.279043024093, 29148.7085129146, 29158.125256242347, 29357.853210734236, 965.9372140810825, 1065.4779158212575, 1074.1567869222622, 1076.047697787448, 1083.34999000599, 12138.514459421702, 14758.96687231905, 14764.335604897806, 14936.0, 15087.396504139931, 565033.0, 569388.0, 570546.0, 571813.0, 578062.0, 45076.77461612737, 47116.8220794476, 47945.76027119842, 53811.0, 54719.72640136774, 1208.7642909632536, 1345.4819894340749, 1377.3457607636292, 1380.3029470117929, 1383.190833362474, 29076.418471630874, 29624.259393514472, 29948.550771738006, 30422.54366184553, 31354.372912542076, 43032.0, 47729.761351193025, 52357.7382113087, 54093.0, 56867.86264274774, 587116.0, 588937.0, 669677.0, 696901.0, 701655.0, 38992.6100738989, 39001.804990974866, 39385.606143938196, 39520.81437556878, 39951.00122496961, 42565.57434425616, 43035.0, 43487.34768978531, 43566.346504803085, 44100.89747756332, 36011.27977440489, 36142.0, 36240.0, 36409.63590364062, 36763.0, 35705.07990641259, 36016.459753104245, 36177.63822361848, 36627.0, 37552.43671344878, 35017.824910875286, 35710.64289357177, 35779.821100894325, 35835.28329433344, 36176.63823361733, 31077.844610776803, 32413.0, 32818.0, 32825.83587082049, 32921.506177407835, 23822.761772382746, 24180.0, 24273.0, 24653.38366540851, 24747.62878556787], "value": 18303.73776172485, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.8747650845696, 350.3046452791211, 387.3337858882721, 389.4236529935696, 407.7961019490255, 14765.335559899804, 15020.0, 15473.92263038686, 20153.798462015377, 20566.280180193695, 19497.51256218595, 19941.700874486858, 20418.89790551045, 20635.896820515878, 20841.27055553056, 5792.218050563172, 5806.1000544915605, 6030.427109424598, 6053.0, 6057.061155520902, 5251.947480525184, 5935.910961335583, 5955.1960485334575, 5969.22400087988, 5977.133315669225, 5855.884778947251, 5918.112283157518, 5922.437368449991, 5932.436418540232, 5942.702864856761, 5636.887262254752, 5798.623089499169, 5847.06446968483, 5870.853228669274, 5875.794347197871, 4911.238757992517, 5058.4688607696025, 5127.230915362688, 5146.433892271847, 5148.0, 19702.70445943312, 25134.868930898152, 25412.237632871045, 25947.740522594722, 26202.475950481075, 1953.4237399967044, 2245.4723140062074, 2255.785700358463, 2491.9003239870385, 3007.2782532192336, 14905.254737263202, 15573.221338933121, 15624.218789060502, 16216.594585135348, 16269.674606507804, 1784.9910750446295, 1834.5597056706429, 1847.556586419263, 1894.2517705506248, 1897.193692680619, 118130.22804658023, 119418.0, 121147.0, 123059.0, 124435.0, 8801.25189358899, 10856.0, 11222.494987725571, 11244.606438774603, 11431.828522572168, 12205.633830985085, 13618.706222908808, 14140.929295353457, 14146.43414263418, 14147.363368648435, 8326.45878017939, 8572.699955501528, 8596.957015214884, 8695.347848911357, 8705.347098967603, 8541.113793224193, 8543.572821359032, 8550.871736923951, 8713.695020674246, 8753.693620723245, 6616.8345791355605, 6716.932830671631, 6742.528023038439, 6772.255051944282, 6795.898061529081, 1842.1710230396106, 1869.616728570667, 1874.137896567567, 1874.8406385457388, 1876.9061546922599, 840.3823189955378, 881.9802651927015, 997.3816233935007, 1001.6944831826363, 1004.352192835617, 20930.686039709417, 22321.553568928855, 22436.775632243796, 22491.0, 22537.4365640857, 921.8847644044495, 942.2791564453199, 944.7826999790087, 945.2532499325456, 1007.9970429422772, 9872.506374681381, 11061.834072488919, 11656.941715291368, 11681.88318116825, 11837.0, 494326.0, 499136.0, 601679.0, 625716.0, 627660.0, 102969.0, 108532.66725924861, 110882.0, 122359.38820305842, 128975.0, 432.4831825968001, 953.909378609031, 1006.9093781559549, 1010.3129871687177, 1011.261778901406, 9300.302477314093, 10794.56821727122, 11140.0, 11157.776844463227, 11656.708582285512, 52759.20861187162, 53871.0, 53952.46047539474, 55302.72348638231, 55418.44581554133, 73416.63291683508, 75819.86270205841, 77436.22563774516, 78830.0, 78911.60544197472, 8551.0, 11118.777624447628, 11991.460384282555, 12005.177488917694, 12694.555690550786, 11999.340036297739, 12909.54816581415, 13492.865071349554, 13641.795373069213, 14064.929675351967, 11133.721656958642, 11190.104791616814, 11567.01680357179, 11568.363739994382, 11605.941970290094, 11643.301401915913, 11676.824847627462, 11866.0, 11958.880411195776, 12095.879041209828, 11045.944770276097, 11757.0, 12150.756984860076, 12166.330851803235, 12180.390980450764, 12569.560065397667, 12772.936135319263, 12840.871591283967, 12852.164609300366, 12856.521500027617, 11638.70903227426, 11780.76438471243, 11881.346525940811, 11906.40467976615, 11929.463174157358], "value": 9792.670461242968, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.7003789128505, 1459.4381163252144, 1491.6345495353637, 1599.856102886436, 1645.5227983884681, 7405.555666660004, 7804.102528209258, 7936.920630793683, 8358.498490090598, 8372.372072094588, 8230.588470576477, 8297.128801475848, 8298.25315721585, 8310.584470776452, 8317.708880189195, 2313.618252988255, 2342.625179971205, 2344.191254017361, 2346.038124369009, 2346.3547524430746, 2319.698439202901, 2352.529494101178, 2370.8814559272055, 2370.9051637934463, 2371.513839662865, 2297.9425514362106, 2316.200910685819, 2335.1710142899283, 2338.333574931145, 2339.508703172336, 2278.897449614771, 2295.1966811615966, 2319.431739223884, 2321.814254859607, 2324.8605083695033, 2316.409315624505, 2335.061898624138, 2337.0768546424238, 2342.672025916373, 2346.988265058681, 6792.864142717168, 8316.126806685272, 8316.875246871301, 8317.542535160565, 8337.916620833774, 5003.17447621142, 5201.635885488017, 5226.55574276187, 5228.346456692913, 5240.9213861792105, 3317.8506967186536, 3562.2697347043795, 3569.821508924543, 3624.3838547447, 3637.8726744563814, 1022.4121130350004, 1062.9309094908883, 1070.1866581398115, 1102.85662863828, 1112.260346869339, 10817.755958064845, 10844.240903136784, 10929.508172132193, 10933.671989840397, 10961.780764384668, 3561.8067947237964, 3945.7829819359636, 4038.9498730330324, 4354.586314300136, 4440.08978159483, 8569.614367353484, 8578.4424012439, 8580.785480363042, 8581.442206256574, 8585.141485851489, 4978.626603004789, 5041.747912604355, 5063.822766203165, 5078.238264260391, 5090.338256026693, 4073.2668119738137, 4279.657627389802, 4435.578620031092, 4459.888502787457, 4758.452777930513, 3674.338619048597, 3689.90775230616, 3742.345089609362, 3751.9437208441896, 3758.830852611639, 1159.113278342069, 1161.1001473857827, 1162.2445410483085, 1171.0924033873816, 1173.2549830857297, 803.2048272210469, 900.2527901841587, 901.5266984833015, 902.3457992955055, 933.332667142996, 5122.385313762373, 5335.9466405336225, 5591.41290164535, 5723.227364305765, 5784.045632470596, 428.25911173669726, 452.2741000693839, 458.7178884985776, 460.4037771086451, 461.9284010978304, 3450.8957133716967, 3482.233908540117, 3617.3850445423814, 3713.7771733696063, 3743.7005039596766, 9784.0, 10133.391996480235, 10258.640947566799, 10264.230182736324, 10265.024822641837, 10103.393796372242, 10187.592496300065, 10221.897781022244, 10228.897711022943, 10233.181345492343, 6921.688524016331, 7588.658510367047, 7699.26856948589, 7866.449348545661, 7885.802854928558, 5372.946270537244, 5415.268938693226, 5489.066858634122, 5622.9156562652415, 5742.023855944416, 9039.774005649911, 9232.861507077265, 9257.398269112486, 9329.813403731752, 9434.386764860263, 9458.385204961656, 9470.621175152917, 9501.192398646193, 9537.713868583953, 9592.9040709592, 7069.929300707133, 7071.681774320065, 7141.071660684078, 7199.0, 7208.71165153388, 6546.607203567801, 7499.0, 7752.26353496417, 7765.883511747441, 7916.208379162038, 6853.348931851466, 7011.123609548807, 7029.015937768614, 7117.501774875709, 7121.9643901780155, 6264.2796078451565, 6979.685914133977, 7001.509894307554, 7062.4703147263135, 7196.0645116134565, 6533.542652014314, 6539.411452969255, 6686.197656281277, 6709.060731497691, 6729.461643068445, 4161.313383291723, 4895.020995800783, 4983.950160498349, 5016.648834581544, 5096.796128154832, 4086.938695919623, 4885.633577481632, 5000.824971125993, 5005.399352077774, 5232.581393488588], "value": 4268.504949325166, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.7628581704832, 277.6432284514399, 299.3967156180297, 310.81040565255205, 317.5157884226553, 4296.377025331325, 4864.708117512942, 5086.465921078285, 5107.642465027445, 5121.692698438096, 4611.861644150681, 4913.754312284389, 4945.145570411096, 5005.224190250517, 5014.598832093424, 3115.8130512169228, 3388.102152929476, 3503.4219353806593, 3785.2429514097153, 3896.7656844818875, 3079.6612372638992, 3907.7264591478606, 3931.606839316074, 3935.311320518912, 3938.72428929975, 2842.9715702842914, 3721.162738383869, 3765.6799172070405, 3806.9619303806885, 3808.8666896658624, 3875.9418608720894, 3886.1644746379525, 3888.1446081861945, 3891.280113179071, 3895.357266051098, 2848.501512235371, 3281.6381201801223, 3410.4202285611505, 3428.7257019438384, 3432.9141771455666, 4631.564215093332, 4885.607601833496, 4913.803447862081, 4935.210366341404, 4947.554720075174, 3629.8548058077645, 3784.9621503784883, 4280.229558679436, 4342.1532801103795, 4364.5635436456105, 4524.909501809978, 5255.316808814868, 5265.078611243017, 5276.683398996034, 5283.973580132113, 3521.943416974923, 3646.3436581415335, 3665.505156803824, 3672.155404257009, 3691.7231207659265, 4757.857264282077, 5155.61332900034, 5248.816291429782, 5418.130744893006, 5541.445855414467, 3113.5018397056815, 3622.2936527377437, 3623.1123374772824, 3730.813459327023, 3759.285735710206, 3371.836716332861, 3789.204267103884, 3800.771953682788, 3802.2585595809105, 3817.682899399703, 4158.376243563429, 4324.827006919751, 4370.929122365041, 4373.693841431069, 4428.7121337112985, 4016.457778199964, 4935.950640493621, 5288.576913846883, 5369.973150134224, 5412.269343638558, 1202.1945296651265, 1212.1878341511208, 1219.5731493977037, 1219.9146059775908, 1236.394166858233, 809.5911564659809, 825.9793505162298, 848.8599381102173, 850.2857599616228, 853.825989264761, 3758.605346438639, 3766.4200022983796, 4185.309423945015, 4617.007343421181, 4691.569071433246, 4436.977815110969, 4601.619514145743, 5158.509941555546, 5160.716160611202, 5185.533302002837, 3974.940375894364, 4262.360645903139, 4604.608608268335, 4611.287913229882, 4678.625709943196, 2286.5372474747264, 2528.292078218102, 2680.9731902681115, 2704.7971402144526, 2712.924612671308, 4593.931091033637, 5117.462666419972, 5317.707526086025, 5360.678359298455, 5373.731313434313, 4700.835470758576, 4867.5132486751045, 5070.340855688737, 5288.2596436498925, 5360.303160589098, 5275.287836142148, 5347.518723314843, 5364.678119312775, 5366.082759585446, 5398.406175320712, 4546.7953942073345, 4835.1296766580945, 4858.416989961227, 5138.07514647367, 5161.716105614077, 4538.659600529907, 4813.614910807197, 5071.0, 5102.193793413533, 5111.386633603991, 4962.851114466572, 5103.285540024325, 5630.718464076698, 5719.599628025998, 5757.157709532909, 3654.579723331851, 3656.6526180012856, 3702.315071711742, 3713.795741234258, 3746.5129533160516, 5436.945630543644, 5460.262864513399, 5522.502974732293, 5524.06090964545, 5549.334079910436, 3678.356287649704, 4501.312007997001, 5370.516653501204, 5387.488188622231, 5455.208994695668, 4390.538993405647, 5325.4940780625775, 5371.4897084776885, 5378.031954248273, 5388.245645609696, 5015.749212539431, 5199.428062913152, 5248.711320877388, 5282.8943421132135, 5308.389535203501, 4483.484399294123, 5088.5674717647935, 5383.031054410244, 5388.7036213007095, 5417.6478528895495, 4137.172565486854, 4443.289073748185, 5046.501981519172, 5184.844454666366, 5203.687778733288], "value": 3792.53033326936, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.828326351885, 713.0, 752.7064444866502, 756.2324240895489, 778.0896351269015, 14136.646583835396, 14763.96652234344, 14838.0, 14846.480373186927, 14850.034747741403, 12980.610581682564, 13052.477900883954, 13089.607311780637, 13096.41066152023, 13247.80128298074, 650.4958657040808, 675.034700378461, 679.9082123913258, 801.5070731500115, 812.3420029775871, 822.6462621072936, 826.499967519651, 838.8741688746676, 839.6011894350196, 840.9117042710488, 882.861109169171, 888.1518150166576, 890.3544929925785, 893.8569828827389, 895.2703546609525, 688.4973969002606, 713.7287830624382, 882.8322618702458, 886.8891388576428, 887.4719541872607, 628.4375483941892, 650.4763665249473, 653.0139489371084, 655.9081728558006, 656.4682607088274, 13644.658883527893, 13688.726225475535, 13692.52076177329, 13694.65763355924, 13703.862961370358, 12707.301098439582, 12820.934743476584, 13054.151480153854, 13072.542461013818, 13082.672933176651, 11574.189806713533, 11993.880061199363, 12019.0, 12193.39033048353, 12426.689332766662, 4268.338407546835, 4314.935275970863, 4429.512753597101, 4492.775361231926, 4541.296099104611, 82725.0, 83452.58273708653, 84141.73787393194, 84683.0, 86350.0, 10263.281570290008, 10436.008579184965, 10467.790644187073, 10491.68524944253, 10595.84106238407, 19570.80429195718, 20103.698444523343, 20195.091220895094, 20287.985600719905, 20354.28759993393, 6366.840828979312, 7395.112586489656, 7553.206913274137, 7729.922700773032, 7730.265624765639, 4148.896277593084, 4227.408162857202, 4272.9359059614135, 4309.07354918694, 4417.712848664826, 3285.917852053718, 3312.9171770705443, 3383.678550537695, 3389.508521264403, 3414.2488652496413, 564.8192578374881, 566.4109326300684, 566.4873289672853, 567.7133047810912, 579.2064871126584, 555.1395337227282, 565.5673409841474, 610.7715190717815, 616.5745635511482, 622.0793226025507, 6713.858644030526, 8661.263792577576, 8760.56197190138, 8779.29765618749, 8840.469571825583, 442.41822004065193, 596.3350863786933, 606.6147996022557, 626.7305058824658, 641.415264497321, 4114.238865809834, 4284.3145096784365, 4324.632406245504, 4361.6510679145595, 4379.994958705915, 76370.61814691003, 87400.0, 113431.4328428369, 122686.0, 128961.0, 105404.47297763462, 107067.46466267618, 108178.99999999843, 108246.0, 108974.91025089647, 61959.69020154961, 62050.75898482005, 93829.5308523453, 94031.0, 94763.0523694768, 7112.431005519443, 8243.422960392954, 8327.625256863335, 8361.540115293474, 8434.873476897728, 57627.0, 58403.12395313989, 60221.39778602158, 60324.39675603188, 62917.685411572646, 79757.0, 84103.0, 85532.1446785549, 89687.0, 89850.0, 11053.226274160732, 11056.723581910517, 11072.723181920517, 11076.889231107585, 11425.257358271685, 10459.424731639529, 11254.896127720955, 11748.412579370826, 11782.116341274268, 11862.644120676394, 10091.344062635904, 10798.082163015917, 11315.20793544444, 11364.886351136383, 11684.357360345262, 10805.675829725122, 10878.836817447904, 11291.43542822872, 11591.76816463649, 11966.22219555726, 11060.281081729661, 11196.94401527987, 11221.0, 11397.430128493708, 11680.240784348991, 9443.386179898285, 9718.271129665161, 9826.852597210904, 9878.25913056538, 9917.900820991697, 9418.905810942077, 9583.185429238592, 9606.471644059644, 9612.711618651452, 9805.656802011896], "value": 7037.83026401584, "unit": "score"}], "name": "dromaeo_css", "value": 6968.819438744322, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 112.0, 114.0, 112.0, 114.0, 111.0, 111.0, 112.0, 112.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 144.0, 143.0, 144.0, 111.0, 110.0, 110.0, 114.0, 176.0, 139.0], "value": 136.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 185.0, 187.0, 184.0, 184.0, 182.0, 182.0, 180.0, 183.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 58.0, 88.0, 60.0, 58.0, 59.0, 58.0, 82.0, 59.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 96.0, 71.0, 69.0, 71.0, 91.0, 69.0, 71.0, 93.0], "value": 79.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 86.0, 84.0, 86.0, -124.0, 87.0, 84.0], "value": 64.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 93.0, 92.0, 93.0, 94.0, 93.0, 93.0, 93.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 126.0, 121.0, 122.0, 81.0, 81.0, 85.0, 85.0, 84.0, 83.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 53.0, 52.0, 56.0, 57.0, 59.0, 59.0, 57.0, 57.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 75.0, 73.0, 74.0, 70.0, 69.0, 70.0, 71.0, 72.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 81.0, 101.0, 103.0, 103.0, 101.0, 84.0, 81.0, 86.0, 81.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 138.0, 139.0, 138.0, 143.0, 142.0, 143.0, 198.0, 151.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 168.0, 162.0, 168.0, 164.0, 165.0, 164.0, 164.0, 164.0, 163.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 70.0, 68.0, 66.0, 67.0, 68.0, 67.0, 66.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1426.3999999999999, "alertThreshold": 2.0}]} 02:52:33 INFO - Return code: 0 02:52:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:52:33 INFO - # TBPL SUCCESS # 02:52:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:52:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:52:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:52:33 INFO - 2017-01-09 02:52:33.971 screenresolution[1655:10783] starting screenresolution argv=screenresolution get 02:52:33 INFO - 2017-01-09 02:52:33.974 screenresolution[1655:10783] Display 0: 1600x1200x32@60 02:52:33 INFO - 2017-01-09 02:52:33.979 screenresolution[1656:10786] starting screenresolution argv=screenresolution list 02:52:33 INFO - Available Modes on Display 0 02:52:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:52:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:52:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:52:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:52:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:52:34 INFO - Intel Iris: 02:52:34 INFO - Chipset Model: Intel Iris 02:52:34 INFO - Type: GPU 02:52:34 INFO - Bus: Built-In 02:52:34 INFO - VRAM (Dynamic, Max): 1536 MB 02:52:34 INFO - Vendor: Intel (0x8086) 02:52:34 INFO - Device ID: 0x0a2e 02:52:34 INFO - Revision ID: 0x0009 02:52:34 INFO - Displays: 02:52:34 INFO - Display: 02:52:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:52:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:52:34 INFO - Display Serial Number: 1600x1200 60 02:52:34 INFO - Main Display: Yes 02:52:34 INFO - Mirror: Off 02:52:34 INFO - Online: Yes 02:52:34 INFO - Rotation: Supported 02:52:34 INFO - Return code: 0 02:52:34 INFO - Running post-action listener: _resource_record_post_action 02:52:34 INFO - [mozharness: 2017-01-09 10:52:34.215238Z] Finished run-tests step (success) 02:52:34 INFO - Running post-run listener: _resource_record_post_run 02:52:34 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 33635328; Write bytes: 660581376; Read time: 315; Write time: 1928 02:52:34 INFO - TinderboxPrint: CPU usage
53.6% 02:52:34 INFO - TinderboxPrint: I/O read bytes / time
33,635,328 / 315 02:52:34 INFO - TinderboxPrint: I/O write bytes / time
660,581,376 / 1,928 02:52:34 INFO - TinderboxPrint: CPU idle
2,248.9 (46.4%) 02:52:34 INFO - TinderboxPrint: CPU system
975.3 (20.1%) 02:52:34 INFO - TinderboxPrint: CPU user
1,625.6 (33.5%) 02:52:34 INFO - TinderboxPrint: Swap in / out
379,052,032 / 0 02:52:34 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344230400; Write bytes: 336627712; Read time: 27031; Write time: 613 02:52:34 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 31833088; Write bytes: 308532224; Read time: 298; Write time: 1264 02:52:34 INFO - Running post-run listener: _upload_blobber_files 02:52:34 INFO - Blob upload gear active. 02:52:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:52:34 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 02:52:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:52:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:52:35 INFO - (blobuploader) - INFO - Open directory for files ... 02:52:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:52:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:52:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:36 INFO - (blobuploader) - INFO - Done attempting. 02:52:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:52:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:52:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:37 INFO - (blobuploader) - INFO - Done attempting. 02:52:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:52:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:52:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:39 INFO - (blobuploader) - INFO - Done attempting. 02:52:39 INFO - (blobuploader) - INFO - Iteration through files over. 02:52:39 INFO - Return code: 0 02:52:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:52:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:52:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a6ecd8b4f2c9467bdb3697c6f3bffb06fc39012a06dc2fdcd65c85986431378935597abf56e8e64cbd68638a8a6d5161803df36804921f0595f3583fbef817c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82ecc868ceb55e468e70e97acb2a8437ed92c4959c3845ba173cf9d28d9e9f2335a127ca6385dfe08bab860d55a987152bc11136d2ff7bbc3becfc8025647125", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/927b6edb610fbc616e84724a956d8aa943737ae5122de783d3d27166d1a0626924ee2e2c731f9bd4315dfb725bbf027f47df1d9b3d8b8e14f99eb0c874e0dd2d"} 02:52:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:52:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:52:39 INFO - Contents: 02:52:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a6ecd8b4f2c9467bdb3697c6f3bffb06fc39012a06dc2fdcd65c85986431378935597abf56e8e64cbd68638a8a6d5161803df36804921f0595f3583fbef817c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82ecc868ceb55e468e70e97acb2a8437ed92c4959c3845ba173cf9d28d9e9f2335a127ca6385dfe08bab860d55a987152bc11136d2ff7bbc3becfc8025647125", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/927b6edb610fbc616e84724a956d8aa943737ae5122de783d3d27166d1a0626924ee2e2c731f9bd4315dfb725bbf027f47df1d9b3d8b8e14f99eb0c874e0dd2d"} 02:52:39 INFO - Running post-run listener: copy_logs_to_upload_dir 02:52:39 INFO - Copying logs to upload dir... 02:52:39 INFO - mkdir: /builds/slave/test/build/upload/logs 02:52:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1798.361336 ========= master_lag: 0.33 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 29 mins, 58 secs) (at 2017-01-09 02:52:39.688001) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 02:52:39.695554) ========= 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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a6ecd8b4f2c9467bdb3697c6f3bffb06fc39012a06dc2fdcd65c85986431378935597abf56e8e64cbd68638a8a6d5161803df36804921f0595f3583fbef817c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82ecc868ceb55e468e70e97acb2a8437ed92c4959c3845ba173cf9d28d9e9f2335a127ca6385dfe08bab860d55a987152bc11136d2ff7bbc3becfc8025647125", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/927b6edb610fbc616e84724a956d8aa943737ae5122de783d3d27166d1a0626924ee2e2c731f9bd4315dfb725bbf027f47df1d9b3d8b8e14f99eb0c874e0dd2d"} build_url:https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011026 build_url: 'https://queue.taskcluster.net/v1/task/Wr3_7NZYRnqm0nrurA6LGQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a6ecd8b4f2c9467bdb3697c6f3bffb06fc39012a06dc2fdcd65c85986431378935597abf56e8e64cbd68638a8a6d5161803df36804921f0595f3583fbef817c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82ecc868ceb55e468e70e97acb2a8437ed92c4959c3845ba173cf9d28d9e9f2335a127ca6385dfe08bab860d55a987152bc11136d2ff7bbc3becfc8025647125", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/927b6edb610fbc616e84724a956d8aa943737ae5122de783d3d27166d1a0626924ee2e2c731f9bd4315dfb725bbf027f47df1d9b3d8b8e14f99eb0c874e0dd2d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 02:52:39.733560) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 02:52:39.733987) ========= 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.o3iqvqtO4B/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.YOFO0KKMoQ/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.004669 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 02:52:39.782008) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 02:52:39.785492) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 02:52:39.785894) ========= ========= Total master_lag: 0.47 =========