builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0200 starttime: 1479466849.21 results: success (0) buildid: 20161118013228 builduid: 7110c1e0783e4d79869099d8930342e2 revision: ed70a3e3c24248e83074ba5640b0251b4adcb801 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 03:00:49.210045) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 03:00:49.210526) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 03:00:49.210826) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 03:00:49.236240) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 03:00:49.236636) ========= 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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-18 03:00:49-- 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% 168M=0s 2016-11-18 03:00:49 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.076186 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 03:00:49.335526) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 03:00:49.335972) ========= 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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.035644 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 03:00:49.395934) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-18 03:00:49.396725) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ed70a3e3c24248e83074ba5640b0251b4adcb801 --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 ed70a3e3c24248e83074ba5640b0251b4adcb801 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1Bok6WMJQ7/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.oS1slgsJyv/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-18 03:00:49,503 truncating revision to first 12 chars 2016-11-18 03:00:49,503 Setting DEBUG logging. 2016-11-18 03:00:49,503 attempt 1/10 2016-11-18 03:00:49,503 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ed70a3e3c242?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-18 03:00:50,530 unpacking tar archive at: mozilla-aurora-ed70a3e3c242/testing/mozharness/ program finished with exit code 0 elapsedTime=1.300915 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-18 03:00:50.721107) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 03:00:50.721490) ========= script_repo_revision: ed70a3e3c24248e83074ba5640b0251b4adcb801 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 03:00:50.722020) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 03:00:50.722346) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 03:00:50.741119) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-18 03:00:50.741473) ========= /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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:00:50 INFO - MultiFileLogger online at 20161118 03:00:50 in /builds/slave/test 03:00:50 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 03:00:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:00:50 INFO - {'append_to_log': False, 03:00:50 INFO - 'base_work_dir': '/builds/slave/test', 03:00:50 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:00:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:00:50 INFO - 'branch': 'Mozilla-Aurora', 03:00:50 INFO - 'buildbot_json_path': 'buildprops.json', 03:00:50 INFO - 'config_files': ('talos/mac_config.py',), 03:00:50 INFO - 'default_actions': ('clobber', 03:00:50 INFO - 'read-buildbot-config', 03:00:50 INFO - 'download-and-extract', 03:00:50 INFO - 'populate-webroot', 03:00:50 INFO - 'create-virtualenv', 03:00:50 INFO - 'install', 03:00:50 INFO - 'run-tests'), 03:00:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:00:50 INFO - 'download_minidump_stackwalk': True, 03:00:50 INFO - 'download_symbols': 'ondemand', 03:00:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:00:50 INFO - 'tooltool.py': '/tools/tooltool.py', 03:00:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:00:50 INFO - '/tools/misc-python/virtualenv.py')}, 03:00:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:00:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:00:50 INFO - 'installer_path': 'installer.exe', 03:00:50 INFO - 'log_level': 'info', 03:00:50 INFO - 'log_name': 'talos', 03:00:50 INFO - 'log_to_console': True, 03:00:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:00:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:00:50 INFO - 'opt_config_files': (), 03:00:50 INFO - 'pip_index': False, 03:00:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:00:50 INFO - 'cmd': ('bash', 03:00:50 INFO - '-c', 03:00:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:00:50 INFO - 'enabled': True, 03:00:50 INFO - 'halt_on_failure': False, 03:00:50 INFO - 'name': 'check_screen_resolution'},), 03:00:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:00:50 INFO - 'cmd': ('bash', 03:00:50 INFO - '-c', 03:00:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:00:50 INFO - 'enabled': True, 03:00:50 INFO - 'halt_on_failure': False, 03:00:50 INFO - 'name': 'check_screen_resolution'},), 03:00:50 INFO - 'run_cmd_checks_enabled': True, 03:00:50 INFO - 'sps_profile': False, 03:00:50 INFO - 'sps_profile_interval': 0, 03:00:50 INFO - 'suite': 'dromaeojs', 03:00:50 INFO - 'system_bits': '32', 03:00:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:00:50 INFO - 'title': 't-yosemite-r7-0200', 03:00:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:00:50 INFO - 'use_talos_json': True, 03:00:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:00:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:00:50 INFO - 'work_dir': 'build'} 03:00:50 INFO - [mozharness: 2016-11-18 11:00:50.964307Z] Running clobber step. 03:00:50 INFO - Running pre-action listener: _resource_record_pre_action 03:00:50 INFO - Running main action method: clobber 03:00:50 INFO - rmtree: /builds/slave/test/build 03:00:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:00:53 INFO - Running post-action listener: _resource_record_post_action 03:00:53 INFO - [mozharness: 2016-11-18 11:00:53.313070Z] Finished clobber step (success) 03:00:53 INFO - [mozharness: 2016-11-18 11:00:53.313290Z] Running read-buildbot-config step. 03:00:53 INFO - Running pre-action listener: _resource_record_pre_action 03:00:53 INFO - Running main action method: read_buildbot_config 03:00:53 INFO - Using buildbot properties: 03:00:53 INFO - { 03:00:53 INFO - "project": "", 03:00:53 INFO - "product": "firefox", 03:00:53 INFO - "script_repo_revision": "production", 03:00:53 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:00:53 INFO - "repository": "", 03:00:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:00:53 INFO - "buildid": "20161118013228", 03:00:53 INFO - "pgo_build": "False", 03:00:53 INFO - "basedir": "/builds/slave/test", 03:00:53 INFO - "buildnumber": 23, 03:00:53 INFO - "slavename": "t-yosemite-r7-0200", 03:00:53 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:00:53 INFO - "platform": "yosemite_r7", 03:00:53 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:00:53 INFO - "branch": "mozilla-aurora", 03:00:53 INFO - "repo_path": "releases/mozilla-aurora", 03:00:53 INFO - "revision": "ed70a3e3c24248e83074ba5640b0251b4adcb801", 03:00:53 INFO - "stage_platform": "macosx64", 03:00:53 INFO - "builduid": "7110c1e0783e4d79869099d8930342e2", 03:00:53 INFO - "slavebuilddir": "test" 03:00:53 INFO - } 03:00:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:00:53 INFO - Running post-action listener: _resource_record_post_action 03:00:53 INFO - [mozharness: 2016-11-18 11:00:53.319013Z] Finished read-buildbot-config step (success) 03:00:53 INFO - [mozharness: 2016-11-18 11:00:53.319224Z] Running download-and-extract step. 03:00:53 INFO - Running pre-action listener: _resource_record_pre_action 03:00:53 INFO - Running main action method: download_and_extract 03:00:53 INFO - mkdir: /builds/slave/test/build/tests 03:00: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')]} 03:00:53 INFO - trying https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:00:53 INFO - Downloading https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/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 03:00:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/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 03:00:54 INFO - Downloaded 1199 bytes. 03:00:54 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:00:54 INFO - Using the following test package requirements: 03:00:54 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:00:54 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:54 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:00:54 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:54 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:00:54 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:54 INFO - u'jsshell-mac.zip'], 03:00:54 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:54 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:00:54 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:00:54 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:54 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:00:54 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:54 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:00:54 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:54 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:00:54 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:00:54 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:00:54 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:00:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:00:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:00:54 INFO - Fetch https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:00:54 INFO - Http code: 200 03:00:54 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 03:00:54 INFO - x-amz-cf-id: WhgJGWNdNJMkM3wrT41-Not5ATrEyCBUW7kDxJ7D5ds9SBG9Zg00Mg== 03:00:54 INFO - x-amz-version-id: x5ARPMBeY_RoW4AxjS0a8AJyGnxvOmaJ 03:00:55 INFO - Expected file size: 16274791 03:00:55 INFO - Obtained file size: 16274791 03:01:01 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 03:01:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:01:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:01:01 INFO - Fetch https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:01:02 INFO - Http code: 200 03:01:02 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 03:01:02 INFO - x-amz-cf-id: T6xJkQbibSF96NYoFPxynrbZqyDzAaDl3DRcV0PsmuNIxKfU0FSA9w== 03:01:02 INFO - x-amz-version-id: x5ARPMBeY_RoW4AxjS0a8AJyGnxvOmaJ 03:01:02 INFO - Expected file size: 16274791 03:01:02 INFO - Obtained file size: 16274791 03:01:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:01:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:01:09 INFO - Fetch https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:01:10 INFO - Http code: 200 03:01:10 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 03:01:10 INFO - x-amz-cf-id: Nm1VjvWnR9xno5Qgyg8RbgPl9li3879CJORujeOQR7iLTvkrMM7kdA== 03:01:10 INFO - x-amz-version-id: MMjvLON8ghd42v21sKVhT62h3PRLlAtl 03:01:11 INFO - Expected file size: 14409038 03:01:11 INFO - Obtained file size: 14409038 03:01:11 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')]} 03:01:11 INFO - trying https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:01:11 INFO - Downloading https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:01:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/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 03:01:14 INFO - Downloaded 114365093 bytes. 03:01:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:01:14 INFO - mkdir: /builds/slave/test/properties 03:01:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:01:14 INFO - Writing to file /builds/slave/test/properties/build_url 03:01:14 INFO - Contents: 03:01:14 INFO - build_url:https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:01:15 INFO - Running post-action listener: _resource_record_post_action 03:01:15 INFO - Running post-action listener: set_extra_try_arguments 03:01:15 INFO - [mozharness: 2016-11-18 11:01:15.006965Z] Finished download-and-extract step (success) 03:01:15 INFO - [mozharness: 2016-11-18 11:01:15.007093Z] Running populate-webroot step. 03:01:15 INFO - Running pre-action listener: _resource_record_pre_action 03:01:15 INFO - Running main action method: populate_webroot 03:01:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:01:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:01:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:01:15 INFO - '--tppagecycles', 03:01:15 INFO - '7'], 03:01:15 INFO - 'tests': ['tsvgm']}}, 03:01:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:01:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:01:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:01:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:01:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:01:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:01:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['damp', 'tps']}, 03:01:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'tests': ['damp', 'tps']}, 03:01:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['dromaeo_dom']}, 03:01:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:01:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['basic_compositor_video']}, 03:01:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:01:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['a11yr', 03:01:15 INFO - 'ts_paint', 03:01:15 INFO - 'tpaint', 03:01:15 INFO - 'sessionrestore', 03:01:15 INFO - 'sessionrestore_no_auto_restore', 03:01:15 INFO - 'tabpaint']}, 03:01:15 INFO - 'other-e10s': {'tests': ['a11yr', 03:01:15 INFO - 'ts_paint', 03:01:15 INFO - 'tpaint', 03:01:15 INFO - 'sessionrestore', 03:01:15 INFO - 'sessionrestore_no_auto_restore', 03:01:15 INFO - 'tabpaint']}, 03:01:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:01:15 INFO - 'ts_paint', 03:01:15 INFO - 'tpaint', 03:01:15 INFO - 'sessionrestore', 03:01:15 INFO - 'sessionrestore_no_auto_restore', 03:01:15 INFO - 'tabpaint']}, 03:01:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:01:15 INFO - 'ts_paint', 03:01:15 INFO - 'tpaint', 03:01:15 INFO - 'sessionrestore', 03:01:15 INFO - 'sessionrestore_no_auto_restore', 03:01:15 INFO - 'tabpaint']}, 03:01:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['a11yr', 03:01:15 INFO - 'ts_paint', 03:01:15 INFO - 'tpaint', 03:01:15 INFO - 'sessionrestore', 03:01:15 INFO - 'sessionrestore_no_auto_restore', 03:01:15 INFO - 'tabpaint']}, 03:01:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['a11yr', 03:01:15 INFO - 'ts_paint', 03:01:15 INFO - 'tpaint', 03:01:15 INFO - 'sessionrestore', 03:01:15 INFO - 'sessionrestore_no_auto_restore', 03:01:15 INFO - 'tabpaint']}, 03:01:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['tsvgx', 03:01:15 INFO - 'tsvgr_opacity', 03:01:15 INFO - 'tart', 03:01:15 INFO - 'tscrollx', 03:01:15 INFO - 'cart']}, 03:01:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:01:15 INFO - 'tsvgr_opacity', 03:01:15 INFO - 'tart', 03:01:15 INFO - 'tscrollx', 03:01:15 INFO - 'cart']}, 03:01:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'talos_options': ['--disable-e10s'], 03:01:15 INFO - 'tests': ['tp5o']}, 03:01:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'tests': ['tp5o']}, 03:01:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'talos_options': ['--disable-e10s', 03:01:15 INFO - '--xperf_path', 03:01:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:01:15 INFO - 'tests': ['tp5n']}, 03:01:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:15 INFO - 'talos_options': ['--xperf_path', 03:01:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:01:15 INFO - 'tests': ['tp5n']}}, 03:01:15 INFO - 'talos.zip': {'path': '', 03:01:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:01:15 INFO - Running post-action listener: _resource_record_post_action 03:01:15 INFO - [mozharness: 2016-11-18 11:01:15.026051Z] Finished populate-webroot step (success) 03:01:15 INFO - [mozharness: 2016-11-18 11:01:15.026170Z] Running create-virtualenv step. 03:01:15 INFO - Running pre-action listener: _resource_record_pre_action 03:01:15 INFO - Running main action method: create_virtualenv 03:01:15 INFO - Creating virtualenv /builds/slave/test/build/venv 03:01:15 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 03:01:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:01:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:01:15 INFO - Using real prefix '/tools/python27' 03:01:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:01:15 INFO - Installing distribute.............................................................................................................................................................................................done. 03:01:18 INFO - Installing pip.................done. 03:01:18 INFO - Return code: 0 03:01:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:01:18 INFO - Reading from file tmpfile_stdout 03:01:18 INFO - Output received: 03:01:18 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) 03:01:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:01:18 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')]} 03:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:18 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')]} 03:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:18 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 0x104614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe40cc00d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104428d30>, '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 0x10428edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104420b20>, '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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:01:18 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 03:01:18 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 03:01:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Bok6WMJQ7/Render', 03:01:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:19 INFO - 'HOME': '/Users/cltbld', 03:01:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:19 INFO - 'LOGNAME': 'cltbld', 03:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:19 INFO - 'MOZ_NO_REMOTE': '1', 03:01:19 INFO - 'NO_EM_RESTART': '1', 03:01:19 INFO - 'PAGER': '/bin/cat', 03:01:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:19 INFO - 'PWD': '/builds/slave/test', 03:01:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:19 INFO - 'SHELL': '/bin/bash', 03:01:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oS1slgsJyv/Listeners', 03:01:19 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:01:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:19 INFO - 'USER': 'cltbld', 03:01:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:19 INFO - 'XPC_FLAGS': '0x0', 03:01:19 INFO - 'XPC_SERVICE_NAME': '0', 03:01:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:19 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 03:01:19 INFO - Cleaning up... 03:01:19 INFO - Return code: 0 03:01:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:01:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe40cc00d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104428d30>, '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 0x10428edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104420b20>, '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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:01:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:01:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:01:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Bok6WMJQ7/Render', 03:01:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:19 INFO - 'HOME': '/Users/cltbld', 03:01:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:19 INFO - 'LOGNAME': 'cltbld', 03:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:19 INFO - 'MOZ_NO_REMOTE': '1', 03:01:19 INFO - 'NO_EM_RESTART': '1', 03:01:19 INFO - 'PAGER': '/bin/cat', 03:01:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:19 INFO - 'PWD': '/builds/slave/test', 03:01:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:19 INFO - 'SHELL': '/bin/bash', 03:01:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oS1slgsJyv/Listeners', 03:01:19 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:01:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:19 INFO - 'USER': 'cltbld', 03:01:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:19 INFO - 'XPC_FLAGS': '0x0', 03:01:19 INFO - 'XPC_SERVICE_NAME': '0', 03:01:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:19 INFO - Downloading/unpacking psutil>=3.1.1 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 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 03:01:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:01:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:22 INFO - Installing collected packages: psutil 03:01:22 INFO - Running setup.py install for psutil 03:01:22 INFO - building 'psutil._psutil_osx' extension 03:01:22 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 03:01:23 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 03:01:23 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 03:01:23 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 03:01:23 INFO - building 'psutil._psutil_posix' extension 03:01:23 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 03:01:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:01:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:01:23 INFO - ^ 03:01:23 INFO - 1 warning generated. 03:01:23 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 03:01:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:24 INFO - Successfully installed psutil 03:01:24 INFO - Cleaning up... 03:01:24 INFO - Return code: 0 03:01:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:24 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 0x104614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe40cc00d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104428d30>, '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 0x10428edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104420b20>, '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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:01:24 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 03:01:24 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 03:01:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Bok6WMJQ7/Render', 03:01:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:24 INFO - 'HOME': '/Users/cltbld', 03:01:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:24 INFO - 'LOGNAME': 'cltbld', 03:01:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:24 INFO - 'MOZ_NO_REMOTE': '1', 03:01:24 INFO - 'NO_EM_RESTART': '1', 03:01:24 INFO - 'PAGER': '/bin/cat', 03:01:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:24 INFO - 'PWD': '/builds/slave/test', 03:01:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:24 INFO - 'SHELL': '/bin/bash', 03:01:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oS1slgsJyv/Listeners', 03:01:24 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:01:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:24 INFO - 'USER': 'cltbld', 03:01:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:24 INFO - 'XPC_FLAGS': '0x0', 03:01:24 INFO - 'XPC_SERVICE_NAME': '0', 03:01:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:01:24 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 03:01:24 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 03:01:24 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 03:01:24 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 03:01:24 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 03:01:24 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 03:01:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:01:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:01:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:01:27 INFO - Installing collected packages: mozsystemmonitor 03:01:27 INFO - Running setup.py install for mozsystemmonitor 03:01:27 INFO - Successfully installed mozsystemmonitor 03:01:27 INFO - Cleaning up... 03:01:27 INFO - Return code: 0 03:01:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:01:27 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')]} 03:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:27 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')]} 03:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:27 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 0x104614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe40cc00d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104428d30>, '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 0x10428edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104420b20>, '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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:01:27 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 03:01:27 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 03:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Bok6WMJQ7/Render', 03:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:27 INFO - 'HOME': '/Users/cltbld', 03:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:27 INFO - 'LOGNAME': 'cltbld', 03:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:27 INFO - 'MOZ_NO_REMOTE': '1', 03:01:27 INFO - 'NO_EM_RESTART': '1', 03:01:27 INFO - 'PAGER': '/bin/cat', 03:01:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:27 INFO - 'PWD': '/builds/slave/test', 03:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:27 INFO - 'SHELL': '/bin/bash', 03:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oS1slgsJyv/Listeners', 03:01:27 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:27 INFO - 'USER': 'cltbld', 03:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:27 INFO - 'XPC_FLAGS': '0x0', 03:01:27 INFO - 'XPC_SERVICE_NAME': '0', 03:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:27 INFO - Downloading/unpacking jsonschema==2.5.1 03:01:27 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 03:01:27 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 03:01:27 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 03:01:27 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 03:01:27 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 03:01:27 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 03:01:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:01:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:01:30 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 03:01:30 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 03:01:30 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 03:01:30 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 03:01:30 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 03:01:30 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 03:01:30 INFO - Downloading functools32-3.2.3-2.tar.gz 03:01:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:01:30 INFO - warning: no files found matching '*.txt' 03:01:30 INFO - no previously-included directories found matching 'build' 03:01:30 INFO - no previously-included directories found matching 'dist' 03:01:30 INFO - no previously-included directories found matching '.git*' 03:01:30 INFO - Installing collected packages: jsonschema, functools32 03:01:30 INFO - Running setup.py install for functools32 03:01:30 INFO - warning: no files found matching '*.txt' 03:01:30 INFO - no previously-included directories found matching 'build' 03:01:30 INFO - no previously-included directories found matching 'dist' 03:01:30 INFO - no previously-included directories found matching '.git*' 03:01:30 INFO - Successfully installed jsonschema functools32 03:01:30 INFO - Cleaning up... 03:01:30 INFO - Return code: 0 03:01:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:01:30 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')]} 03:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:30 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')]} 03:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:30 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 0x104614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe40cc00d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104428d30>, '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 0x10428edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104420b20>, '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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:01:30 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 03:01:30 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 03:01:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Bok6WMJQ7/Render', 03:01:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:30 INFO - 'HOME': '/Users/cltbld', 03:01:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:30 INFO - 'LOGNAME': 'cltbld', 03:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:30 INFO - 'MOZ_NO_REMOTE': '1', 03:01:30 INFO - 'NO_EM_RESTART': '1', 03:01:30 INFO - 'PAGER': '/bin/cat', 03:01:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:30 INFO - 'PWD': '/builds/slave/test', 03:01:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:30 INFO - 'SHELL': '/bin/bash', 03:01:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oS1slgsJyv/Listeners', 03:01:30 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:01:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:30 INFO - 'USER': 'cltbld', 03:01:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:30 INFO - 'XPC_FLAGS': '0x0', 03:01:30 INFO - 'XPC_SERVICE_NAME': '0', 03:01:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:01:31 INFO - Cleaning up... 03:01:31 INFO - Return code: 0 03:01:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:01:31 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')]} 03:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:31 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')]} 03:01:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:31 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 0x104614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe40cc00d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104428d30>, '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 0x10428edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104420b20>, '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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:01:31 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 03:01:31 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 03:01:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Bok6WMJQ7/Render', 03:01:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:31 INFO - 'HOME': '/Users/cltbld', 03:01:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:31 INFO - 'LOGNAME': 'cltbld', 03:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:31 INFO - 'MOZ_NO_REMOTE': '1', 03:01:31 INFO - 'NO_EM_RESTART': '1', 03:01:31 INFO - 'PAGER': '/bin/cat', 03:01:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:31 INFO - 'PWD': '/builds/slave/test', 03:01:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:31 INFO - 'SHELL': '/bin/bash', 03:01:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oS1slgsJyv/Listeners', 03:01:31 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:01:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:31 INFO - 'USER': 'cltbld', 03:01:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:31 INFO - 'XPC_FLAGS': '0x0', 03:01:31 INFO - 'XPC_SERVICE_NAME': '0', 03:01:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:31 INFO - Downloading/unpacking blobuploader==1.2.4 03:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:33 INFO - Downloading blobuploader-1.2.4.tar.gz 03:01:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:01:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:01:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:01:34 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 03:01:34 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 03:01:34 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 03:01:34 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 03:01:34 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 03:01:34 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 03:01:34 INFO - Downloading docopt-0.6.1.tar.gz 03:01:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:01:35 INFO - Installing collected packages: blobuploader, requests, docopt 03:01:35 INFO - Running setup.py install for blobuploader 03:01:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:01:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:01:35 INFO - Running setup.py install for requests 03:01:35 INFO - Running setup.py install for docopt 03:01:35 INFO - Successfully installed blobuploader requests docopt 03:01:35 INFO - Cleaning up... 03:01:35 INFO - Return code: 0 03:01:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:35 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')]} 03:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:35 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')]} 03:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:35 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 0x104614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe40cc00d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104428d30>, '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 0x10428edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104420b20>, '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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:01:35 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 03:01:35 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 03:01:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Bok6WMJQ7/Render', 03:01:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:35 INFO - 'HOME': '/Users/cltbld', 03:01:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:35 INFO - 'LOGNAME': 'cltbld', 03:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:35 INFO - 'MOZ_NO_REMOTE': '1', 03:01:35 INFO - 'NO_EM_RESTART': '1', 03:01:35 INFO - 'PAGER': '/bin/cat', 03:01:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:35 INFO - 'PWD': '/builds/slave/test', 03:01:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:35 INFO - 'SHELL': '/bin/bash', 03:01:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oS1slgsJyv/Listeners', 03:01:35 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:01:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:35 INFO - 'USER': 'cltbld', 03:01:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:35 INFO - 'XPC_FLAGS': '0x0', 03:01:35 INFO - 'XPC_SERVICE_NAME': '0', 03:01:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:36 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-caM08J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:36 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-lu7SDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:36 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-rrWX8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:36 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-YtwlD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:36 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-aJd5A4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:36 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-t5urQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:36 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-mKfiMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:36 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-mlq1Gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-0MlMXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-UWI40P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-OQTr9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-pcMi0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-nZ9nv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-DYVoXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-nokman-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-saUew_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-EBxmry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:01:38 INFO - Running setup.py install for manifestparser 03:01:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:01:38 INFO - Running setup.py install for mozcrash 03:01:38 INFO - Running setup.py install for mozdebug 03:01:38 INFO - Running setup.py install for mozdevice 03:01:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:01:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:01:38 INFO - Running setup.py install for mozfile 03:01:38 INFO - Running setup.py install for mozhttpd 03:01:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:01:38 INFO - Running setup.py install for mozinfo 03:01:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:01:39 INFO - Running setup.py install for mozInstall 03:01:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:01:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:01:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:01:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:01:39 INFO - Running setup.py install for mozleak 03:01:39 INFO - Running setup.py install for mozlog 03:01:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:01:39 INFO - Running setup.py install for moznetwork 03:01:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:01:39 INFO - Running setup.py install for mozprocess 03:01:39 INFO - Running setup.py install for mozprofile 03:01:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:01:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:01:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:01:39 INFO - Running setup.py install for mozrunner 03:01:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:01:40 INFO - Running setup.py install for mozscreenshot 03:01:40 INFO - Running setup.py install for moztest 03:01:40 INFO - Running setup.py install for mozversion 03:01:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:01:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:01:40 INFO - Cleaning up... 03:01:40 INFO - Return code: 0 03:01:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:40 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')]} 03:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:40 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')]} 03:01:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:40 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 0x104614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe40cc00d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104428d30>, '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 0x10428edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104420b20>, '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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:01:40 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 03:01:40 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 03:01:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Bok6WMJQ7/Render', 03:01:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:40 INFO - 'HOME': '/Users/cltbld', 03:01:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:40 INFO - 'LOGNAME': 'cltbld', 03:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:40 INFO - 'MOZ_NO_REMOTE': '1', 03:01:40 INFO - 'NO_EM_RESTART': '1', 03:01:40 INFO - 'PAGER': '/bin/cat', 03:01:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:40 INFO - 'PWD': '/builds/slave/test', 03:01:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:40 INFO - 'SHELL': '/bin/bash', 03:01:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oS1slgsJyv/Listeners', 03:01:40 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:01:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:40 INFO - 'USER': 'cltbld', 03:01:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:40 INFO - 'XPC_FLAGS': '0x0', 03:01:40 INFO - 'XPC_SERVICE_NAME': '0', 03:01:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:40 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-ti4Bnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:40 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)) 03:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:40 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-bYwxkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:41 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)) 03:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:41 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-z6Lh5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:41 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)) 03:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:41 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-SXRoqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:41 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)) 03:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:41 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-BNOvT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:41 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)) 03:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:41 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-sSfwgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:41 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)) 03:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:41 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-HV1KEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:41 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)) 03:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:41 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-WzzU9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:41 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)) 03:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:41 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-YIlAuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:41 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)) 03:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:41 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-lBxJ82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:41 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)) 03:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:41 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-GBcwLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:42 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)) 03:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:42 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-trBuNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:42 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)) 03:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:42 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-mWjicW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:42 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)) 03:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:42 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-23RjsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:42 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)) 03:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:42 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-VH27Eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:42 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)) 03:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:42 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-YWNu_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:42 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-XrkWXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 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)) 03:01:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:01:42 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 03:01:42 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 03:01:42 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 03:01:42 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 03:01:42 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 03:01:42 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 03:01:45 INFO - Downloading blessings-1.6.tar.gz 03:01:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:01:45 INFO - Installing collected packages: blessings 03:01:45 INFO - Running setup.py install for blessings 03:01:45 INFO - Successfully installed blessings 03:01:45 INFO - Cleaning up... 03:01:45 INFO - Return code: 0 03:01:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:01:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:45 INFO - Reading from file tmpfile_stdout 03:01:45 INFO - Current package versions: 03:01:45 INFO - blessings == 1.6 03:01:45 INFO - blobuploader == 1.2.4 03:01:45 INFO - docopt == 0.6.1 03:01:45 INFO - functools32 == 3.2.3-2 03:01:45 INFO - jsonschema == 2.5.1 03:01:45 INFO - manifestparser == 1.1 03:01:45 INFO - mozInstall == 1.12 03:01:45 INFO - mozcrash == 1.0 03:01:45 INFO - mozdebug == 0.1 03:01:45 INFO - mozdevice == 0.48 03:01:45 INFO - mozfile == 1.2 03:01:45 INFO - mozhttpd == 0.7 03:01:45 INFO - mozinfo == 0.9 03:01:45 INFO - mozleak == 0.1 03:01:45 INFO - mozlog == 3.4 03:01:45 INFO - moznetwork == 0.27 03:01:45 INFO - mozprocess == 0.23 03:01:45 INFO - mozprofile == 0.28 03:01:45 INFO - mozrunner == 6.13 03:01:45 INFO - mozscreenshot == 0.1 03:01:45 INFO - mozsystemmonitor == 0.3 03:01:45 INFO - moztest == 0.7 03:01:45 INFO - mozversion == 1.4 03:01:45 INFO - psutil == 3.1.1 03:01:45 INFO - requests == 1.2.3 03:01:45 INFO - wsgiref == 0.1.2 03:01:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:45 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')]} 03:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:46 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')]} 03:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:46 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 0x104614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe40cc00d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104428d30>, '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 0x10428edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104420b20>, '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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:01:46 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 03:01:46 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 03:01:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Bok6WMJQ7/Render', 03:01:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:46 INFO - 'HOME': '/Users/cltbld', 03:01:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:46 INFO - 'LOGNAME': 'cltbld', 03:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:46 INFO - 'MOZ_NO_REMOTE': '1', 03:01:46 INFO - 'NO_EM_RESTART': '1', 03:01:46 INFO - 'PAGER': '/bin/cat', 03:01:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:46 INFO - 'PWD': '/builds/slave/test', 03:01:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:46 INFO - 'SHELL': '/bin/bash', 03:01:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oS1slgsJyv/Listeners', 03:01:46 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:01:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:46 INFO - 'USER': 'cltbld', 03:01:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:46 INFO - 'XPC_FLAGS': '0x0', 03:01:46 INFO - 'XPC_SERVICE_NAME': '0', 03:01:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:47 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)) 03:01:47 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)) 03:01:47 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)) 03:01:47 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)) 03:01:47 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)) 03:01:47 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)) 03:01:47 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)) 03:01:47 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)) 03:01:47 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)) 03:01:47 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)) 03:01:47 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)) 03:01:47 INFO - Cleaning up... 03:01:47 INFO - Return code: 0 03:01:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:47 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 0x104614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe40cc00d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104428d30>, '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 0x10428edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104420b20>, '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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:01:47 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 03:01:47 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 03:01:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Bok6WMJQ7/Render', 03:01:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:47 INFO - 'HOME': '/Users/cltbld', 03:01:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:47 INFO - 'LOGNAME': 'cltbld', 03:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:47 INFO - 'MOZ_NO_REMOTE': '1', 03:01:47 INFO - 'NO_EM_RESTART': '1', 03:01:47 INFO - 'PAGER': '/bin/cat', 03:01:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:47 INFO - 'PWD': '/builds/slave/test', 03:01:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:47 INFO - 'SHELL': '/bin/bash', 03:01:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oS1slgsJyv/Listeners', 03:01:47 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:01:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:47 INFO - 'USER': 'cltbld', 03:01:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:47 INFO - 'XPC_FLAGS': '0x0', 03:01:47 INFO - 'XPC_SERVICE_NAME': '0', 03:01:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:01:47 INFO - Cleaning up... 03:01:47 INFO - Return code: 0 03:01:47 INFO - Running post-action listener: _resource_record_post_action 03:01:47 INFO - Running post-action listener: _start_resource_monitoring 03:01:47 INFO - Starting resource monitoring. 03:01:47 INFO - [mozharness: 2016-11-18 11:01:47.826328Z] Finished create-virtualenv step (success) 03:01:47 INFO - [mozharness: 2016-11-18 11:01:47.827129Z] Running install step. 03:01:47 INFO - Running pre-action listener: _resource_record_pre_action 03:01:47 INFO - Running main action method: install 03:01:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:48 INFO - Reading from file tmpfile_stdout 03:01:48 INFO - Detecting whether we're running mozinstall >=1.0... 03:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:01:48 INFO - Reading from file tmpfile_stdout 03:01:48 INFO - Output received: 03:01:48 INFO - Usage: mozinstall [options] installer 03:01:48 INFO - Options: 03:01:48 INFO - -h, --help show this help message and exit 03:01:48 INFO - -d DEST, --destination=DEST 03:01:48 INFO - Directory to install application into. [default: 03:01:48 INFO - "/builds/slave/test"] 03:01:48 INFO - --app=APP Application being installed. [default: firefox] 03:01:48 INFO - mkdir: /builds/slave/test/build/application 03:01:48 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'] 03:01:48 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 03:02:19 INFO - Reading from file tmpfile_stdout 03:02:19 INFO - Output received: 03:02:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:02:19 INFO - Running post-action listener: _resource_record_post_action 03:02:19 INFO - [mozharness: 2016-11-18 11:02:19.994754Z] Finished install step (success) 03:02:19 INFO - [mozharness: 2016-11-18 11:02:19.994918Z] Running run-tests step. 03:02:19 INFO - Running pre-action listener: _resource_record_pre_action 03:02:19 INFO - Running main action method: run_tests 03:02:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:02:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:02:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:02:20 INFO - 2016-11-18 03:02:20.009 screenresolution[1627:7318] starting screenresolution argv=screenresolution get 03:02:20 INFO - 2016-11-18 03:02:20.012 screenresolution[1627:7318] Display 0: 1600x1200x32@60 03:02:20 INFO - 2016-11-18 03:02:20.018 screenresolution[1628:7320] starting screenresolution argv=screenresolution list 03:02:20 INFO - Available Modes on Display 0 03:02:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:02:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:02:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:02:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:02:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:02:20 INFO - Intel Iris: 03:02:20 INFO - Chipset Model: Intel Iris 03:02:20 INFO - Type: GPU 03:02:20 INFO - Bus: Built-In 03:02:20 INFO - VRAM (Dynamic, Max): 1536 MB 03:02:20 INFO - Vendor: Intel (0x8086) 03:02:20 INFO - Device ID: 0x0a2e 03:02:20 INFO - Revision ID: 0x0009 03:02:20 INFO - Displays: 03:02:20 INFO - Display: 03:02:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:02:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:02:20 INFO - Display Serial Number: 1600x1200 60 03:02:20 INFO - Main Display: Yes 03:02:20 INFO - Mirror: Off 03:02:20 INFO - Online: Yes 03:02:20 INFO - Rotation: Supported 03:02:20 INFO - Return code: 0 03:02:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:02:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:02:20 INFO - Python 2.7.3 03:02:20 INFO - Return code: 0 03:02:20 INFO - grabbing minidump binary from tooltool 03:02: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')]} 03:02:20 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 0x104428d30>, '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 0x10428edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104420b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:02:20 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 03:02:20 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 03:02:20 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 03:02:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:02:20 INFO - Return code: 0 03:02:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:02:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:02:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:02:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:02:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:02:20 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/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 03:02:20 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/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0200 --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 03:02:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1Bok6WMJQ7/Render', 03:02:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:20 INFO - 'HOME': '/Users/cltbld', 03:02:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:20 INFO - 'LOGNAME': 'cltbld', 03:02:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:02:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:20 INFO - 'MOZ_NO_REMOTE': '1', 03:02:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:02:20 INFO - 'NO_EM_RESTART': '1', 03:02:20 INFO - 'PAGER': '/bin/cat', 03:02:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:20 INFO - 'PWD': '/builds/slave/test', 03:02:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:02:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:20 INFO - 'SHELL': '/bin/bash', 03:02:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oS1slgsJyv/Listeners', 03:02:20 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:02:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:20 INFO - 'USER': 'cltbld', 03:02:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:20 INFO - 'XPC_FLAGS': '0x0', 03:02:20 INFO - 'XPC_SERVICE_NAME': '0', 03:02:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:20 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/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 03:02:20 INFO - mozversion application_buildid: 20161118013228 03:02:20 INFO - mozversion application_changeset: ed70a3e3c24248e83074ba5640b0251b4adcb801 03:02:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:02:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:02:20 INFO - mozversion application_name: Firefox 03:02:20 INFO - mozversion application_remotingname: firefox-dev 03:02:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:02:20 INFO - mozversion application_vendor: Mozilla 03:02:20 INFO - mozversion application_version: 52.0a2 03:02:20 INFO - mozversion platform_buildid: 20161118013228 03:02:20 INFO - mozversion platform_changeset: ed70a3e3c24248e83074ba5640b0251b4adcb801 03:02:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:02:20 INFO - mozversion platform_version: 52.0a2 03:02:20 INFO - using testdate: 1479466940 03:02:20 INFO - actual date: 1479466940 03:02:20 INFO - starting webserver on 'localhost:49241' 03:02:20 INFO - SUITE-START | Running 2 tests 03:02:20 INFO - TEST-START | dromaeo_css 03:02:20 INFO - operating with platform_type : mac_ 03:02:20 INFO - Initialising browser for dromaeo_css test... 03:02:20 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpJu__Dj/profile http://localhost:49241/getInfo.html) 03:02:22 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 03:02:22 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 03:02:22 INFO - PROCESS | 1638 | colorDepth:24 03:02:22 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 03:02:22 INFO - PROCESS | 1638 | __metrics 03:02:23 INFO - PROCESS | 1638 | 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" 03:02:23 INFO - PROCESS | 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:02:23 INFO - TEST-INFO | 1638: exit 0 03:02:23 INFO - Browser initialized. 03:02:23 INFO - Running cycle 1/1 for dromaeo_css test... 03:02:23 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpJu__Dj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:02:35 INFO - PROCESS | 1640 | RSS: Main: 213475328 03:02:35 INFO - PROCESS | 1640 | 03:03:26 INFO - PROCESS | 1640 | 1479467006567 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:05:39 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 03:05:41 INFO - PROCESS | 1640 | RSS: Main: 315752448 03:05:41 INFO - PROCESS | 1640 | 03:08:44 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 03:08:45 INFO - PROCESS | 1640 | RSS: Main: 348680192 03:08:45 INFO - PROCESS | 1640 | 03:11:47 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 03:11:48 INFO - PROCESS | 1640 | RSS: Main: 324276224 03:11:48 INFO - PROCESS | 1640 | 03:14:50 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 03:14:50 INFO - PROCESS | 1640 | RSS: Main: 336072704 03:14:50 INFO - PROCESS | 1640 | 03:17:54 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 03:17:54 INFO - PROCESS | 1640 | RSS: Main: 310018048 03:17:54 INFO - PROCESS | 1640 | 03:20:57 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 03:20:58 INFO - PROCESS | 1640 | RSS: Main: 523800576 03:20:58 INFO - PROCESS | 1640 | 03:20:58 INFO - PROCESS | 1640 | __start_tp_report 03:20:58 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 03:20:58 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 03:20:58 INFO - PROCESS | 1640 | |i|pagename|runs| 03:20:58 INFO - PROCESS | 1640 | |0;dojo.html;2963.244372684965;3304.355650648123;3322.9667703322975;3330.300636866258;3351.463765797472;5512.503874651281;5569.777208911639;5623.353314368844;5706.942930570698;5739.995500787361;5679.4036626154275;5728.398518155585;5730.7421166047525;5747.2528571285775;5800.042992906176;5200.8699782505455;5220.060389129955;5223.451537588556;5253.842384728445;5286.233496143057;5187.48125187482;5189.065968125736;5209.661372010826;5215.087359712054;5375.43557926418;5240.9213861792105;5277.393099793515;5283.55089817366;5296.25852380667;5303.310569625944;5289.100852855024;5301.946980530203;5309.256704061435;5312.787488500456;5332.066888294533;5038.992201559703;5150.690958542499;5213.713245771481;5245.186996015624;5250.317458730379;5585.636933599303;5625.803096891589;5650.802221922254;5693.629914055573;5698.8290351289525;6173.290071641729;6187.164732761064;6220.968895155541;6253.812385628438;6285.182926219608;6194.287656919467;6244.875102497925;6253.405926436982;6257.655828929407;6276.121343011982;5659.236003139524;5665.490105890449;5716.085426331767;5758.395368486249;5778.479936805707;6226.501879849601;6228.439440450381;6242.282137554149;6244.063390491463;6245.500359971192;6111.8472038198515;6137.570370074052;6139.539534534839;6144.4469997699525;6144.539159563051;6114.093258577931;6133.304060594526;6187.381261873777;6211.689415529206;6221.3156552779155;5482.451754824566;5913.881722365529;6201.10084037821;6211.65835879031;6216.316205217422;6163.106349579376;6169.0129579268;6201.441870231701;6215.34738852423;6215.378462153749;4411.558844115627;4772.260299653559;4858.78135483904;4899.436564795023;4940.975295123501;4372.300431930876;4407.360932664746;4407.405000324915;4416.470023597125;4460.598546130864;5828.6211396259105;5835.2414186156375;5847.9415205848245;5855.824325270249;5871.148683440877;6192.690365481708;6232.220972378453;6237.126802247689;6245.656488893154;6265.467435267962;5660.15097735343;5741.88516229673;5743.54051675865;5774.653520788766;5828.417158284137;5423.837284881381;5424.159255315432;5424.566034717213;5440.211169380417;5442.591805614595;6340.239171299474;6360.236771587439;6362.109304697254;6365.58623689468;6373.235211774617;5252.737363131904;6144.3717414884195;6217.160683307696;6260.060990851227;6274.278457977209;6207.037909124093;6337.8732425352155;6341.0805433212845;6346.397092276226;6357.586756860789;6189.319174890668;6221.4089661482085;6243.719032643451;6254.21822272216;6261.843453913689;6112.144299797943;6123.069106197319;6255.906161407492;6281.96859015702;6298.307186209605;6069.695015571673;6109.283207920518;6115.052166914135;6168.907466387918;6172.783952561639;6072.000279264083;6119.082137679384;6130.295016073209;6166.013437850102;6173.475254603409;5775.7400916957995;5937.287525496968;5963.552733545088;5994.430529099728;6001.219841420588;4648;4954.653174277766;6029.909551356821;6103.755849765959;6153.015517517176;5996.430339117776;6018.879622407615;6026.246719160105;6040.124181993674;6077.756889724538 03:20:58 INFO - PROCESS | 1640 | |1;ext.html;437.6214574393149;470.8493282149712;495.9826406075788;520.1132069820956;526.8409499101974;34889.47665785015;37113.44329835051;38702.419463708065;38859.61140388595;39485.80257098718;40037;40381;40383.798081009554;40560.79719601398;40581;8355.540445275508;9211.907880921206;9379.859302110439;9402.5768840402;9405.388649737779;8470.407071504997;11019.063379612742;11268.380239086848;11309.547618095266;11438.542458301657;11139;11202;11204.943975280112;11231;11299.209055366127;11089.946455086754;11145.387003714792;11148.888511114947;11153.442327883573;11158.888411115906;10959;11031.338119712844;11039.889601103967;11047.779044419067;11070.723231919266;37799.62200378016;38116.61883381158;38149.427758583646;38241.426378604345;38264.80867595672;3217.549543063973;3330.467125259971;3420.3330350581646;3649.4708267301357;3771.453139294787;29112.27219319534;29492.852535737184;29551.70448295511;29790.85104574485;29940.70059299401;3162.5414314924205;3196.5844440222854;3222.8388580570877;3245.3022600141085;3252.4633435483115;479973;481714;482492;483031;483213.5839320816;32517.34965300728;52538;52562.73718631459;52742.73628631832;52810.94378112416;33361;34153.146171345914;34214.82892585521;34253.486197707054;34294.65705342965;13894.791578126336;14018.018738688397;14024.579262621934;14043.50847720325;14063.015588908678;10771.676849694522;11057.281276716987;11723.589674361356;11784.410779460992;11881.702957425961;7570;7607.733729319447;7672.462927595015;7677.577733224726;7700.807479813049;3141.858616362269;3190.776645634783;3191.888283910052;3192.6009248843893;3227.322262324891;910.9089091090971;916.7524768312518;1050.4380156616173;1057.069778594847;1075.983195879886;28909.710902890703;29346.068626864242;29630.85184574063;29930;30122.09633710992;1025.4923812712755;1050.1965996013057;1051.6161601015726;1063.542676649033;1075.9677209683723;15022.549323520312;15090.17004064787;15219.847801522064;15424.845751542342;15525.45660901863;658202;660073;661893;662841;664973;53901;54192.18711719327;54237.1864422034;54286;54416;1207.6558180918396;1256.5476428485738;1333.833270841145;1358.2393859438746;1365.8019587159806;23938.874409109212;28236.43527129405;30084.097477075724;30751.77106403885;30827.845860770554;52742.47257527376;56003.159952599926;56485.435145648014;56706.716466417405;56882.57793555194;539827;632368;636067;636799;638917;25455.236342909742;26579.202623921312;26600.7339926606;26749.19752407431;27025;44195.337069943336;44238.336424954294;44414.3337849926;44676;44726.77636611796;36311.273774523834;36934.8153259232;37495.625043750304;37502.43746343862;37790.43314350342;36271.63728362683;37103.81448092742;37654;38429.423558646085;38567.614323857524;35992.10019749423;36326.09184770402;36743.448848267835;36828.81585592055;37174.69888553996;31648;33636.49545256774;33705;33721.15697107592;33877.83061084778;20872.58254834925;24361.39096522601;24609.876950615133;25068.874655626605;25146.11988580391 03:20:58 INFO - PROCESS | 1640 | |2;jquery.html;337.3809060374213;353.20000199548014;370.54423059636656;381.4564245949523;403.3405382200102;14854.851451485496;15018.624534386632;16012.519624411272;20126.798732012674;20189.697154542657;19752;19802;20276.986150692443;20494;20718.792812071875;4982.875428114299;5799.768009279624;5850.912236316459;5860.120981852735;5865.296164460271;4117.526484454281;4356.869293921188;4435.534268901767;5744.626599271055;5812.825615231529;4350.173467041268;6121.479674227674;6438.742450302006;6450.838729031789;6503.577267477623;6309.274433440144;6405.711742971531;6432.356764323577;6469.83825404364;6471.158749362553;5615.522680572156;5617.634853734535;5619.80330688424;5628.0713682242385;5628.690422026787;20316.57783955124;23599;24292.514149717084;25012.99948002078;25136.874315628487;1971.8225359717694;2055.8487247141647;2508.924732258035;2702.3514356554288;2986.4325778102093;14239.64400889986;15017;15500;15607.765883511756;15888.920555397264;1770.7339252434442;1828.9176987035614;1833.9908300458546;1847.4457662701136;1919.779225389084;112538.74922501504;115781;123744;124273.13590296153;124950;10355.94822025885;10448.73878153037;10508.001739834703;10515.894841051644;10820.188485863591;13407.396667150002;13438.260895650834;13440.395182216627;13445.327733613476;13461.730765384638;8246.340292776684;8294.709685160991;8328.125546817619;8328.125546817619;8569.571521423904;8409.621567029499;8424.663013479514;8530.87203691945;8569.442986205877;8621.61202745878;5400.1899715043055;6433.517486188554;6494.253160886464;6514.902276465857;6535.411812936859;1555.5955451582445;1650.257384177125;1792.856571474305;1834.2112891456534;1837.6508463391913;761.8115739446371;850.6172222499777;913;915.1809130827871;980.789130336981;20340.931813637333;21819.781802181777;21827.413699368015;21906.291139745634;22036.446082470586;814.9149308316183;926.8424367857481;928.9071092890657;949.2121539122512;999.0758548342753;10723.195760318007;11332.830007549894;11411.942940285246;11437.370944598128;11613.477393517313;623654;626990.8650456718;635032;646959;647125;66156.66921665361;75925.24074759292;76774;106157;107606;945.645382981382;962.100436092256;1005.1807776661997;1031.504877658713;1035.994820025895;15892.125933073792;17745.29018839232;18310.816891831444;18664.34674786376;18827.34104306343;50805.745971269906;51566.421678915154;52345;52890;53944.73027634837;70641;70890.93663594947;71542;72987.50087423975;74338.62830685813;5153.175491921274;5330.626856120034;7586.544807311579;7641.923580764121;7911.960440197762;12096.153269271066;12512.812307815208;12538.122331436713;12698.428570714155;12885.677858053623;10587.947060264649;10798.460076995962;11178.496967636313;11246.66260012201;11317.71705707364;7620.733274335372;10377.273590848568;10561.577536898745;10815.945920270347;10944.343339399344;10956.234038193412;11024.283421577571;11219;11289.322640641587;11369.191320907588;7762.262585054411;7828.84342313162;7849.665556855234;7948.523088614702;8008.239217274349;10542.367457952547;10582.523786429783;10834.295770774874;10843;11057.391843448688 03:20:58 INFO - PROCESS | 1640 | |3;mootools.html;1447.1606468248417;1457.4971634785998;1586.8968517046392;1745.2146534059661;1789.0428620687926;8159.510429374234;8609.354298427626;8615.181557752012;8617.224449799516;8624.482531048126;7573.659185336659;7789.727359542417;7800.687972481094;8173.97825271841;8442.662293508252;2290.061074959267;2336.334144768741;2338.1348900906687;2341.3327201747506;2346.7887890089887;2173.673948907661;2224.6663000549884;2337.2520793346134;2366.4320563064825;2368.5854975379325;2301.4821665125382;2304.539092181562;2326.9069237230574;2330.382448651109;2351.870647114408;2304.2050492580115;2328.895199716017;2333.183385814951;2335.684682567857;2336.100601268507;2328.010595496924;2365.4204719843747;2365.6096744037213;2367.0650093213085;2367.112332875172;8501.362397820189;8553.914460855436;8564.743057708216;8568.057513673491;8576.656933722643;5060.443351231362;5226.425093239741;5228.973855130738;5232.502912223333;5233.188855727369;3235.9191020224685;3354.899353019389;3580.6777390034763;3581.247937933037;3581.5164952731307;1057.196530636714;1058.0371861605902;1085.218642577351;1098.0885864732254;1102.85662863828;11034.227604067719;11144.777104457946;11184.328940263611;11188.776224475547;11190;4555.589996900295;4631.51369106239;4763.476017638057;4778.593819525309;4779.880502987453;9674.70975870725;9677.419354838732;9694;9694.272929530314;9712.271579631415;5239.030779305841;5243.213517972335;5245.816396426107;5253.816116435906;5260.421353651095;4047.6357127858632;4198.433211516467;4689.1559519286175;4779.689320194177;4792.400949881265;3145.968540314613;3784.6404591563755;3859.3824988001784;3880.301545721797;3886.689064874803;1243.8942689871462;1253.4296894912975;1254.8431446069242;1260.3887114749339;1267.5436842736608;790.8299715561067;797.0634504457239;907.5734404829719;918.008550765172;927.1238679447991;5677.858053548612;5703.62926409782;5823.417658234142;5855.2973643163095;6067;441.73937376947754;443.53428899655233;454.5227511113318;462.49818946443486;465.1371705486342;3903.9609603904164;3926.8821935341984;3950.150717595673;3955.3473676843;3971.1065010372436;10067.69796906094;10296.691099267035;10445.11216546602;10465.68602941913;10471;10391.37651740898;10408.947955260326;10414.323069000491;10423.791524169474;10434.165266778791;7871.685132594632;7931.841363172704;7940.16628254025;7955.1249362571225;7957.204279571996;5249.238860365149;5403.108487099585;5643.492085712305;5729.2265544151005;5811.4479124483105;9219.76950576241;9561.760955976157;9563.37838040553;9630.951845240728;9697.903020969983;9320.533973301443;9551.044895510393;9697.369670971364;9906.851397228904;9909.752256193653;6072.635641861502;7227.8554428912175;7286.48994570375;7344.375728063174;7366.6684999174095;7985.960070199611;8077.5961201940845;8082.19178082187;8085.151059138706;8134.877976830234;7030.824229394101;7184.6048467334795;7208.35124838767;7271.381932535794;7452.366548843409;7046;7128.928710712827;7142.928570714226;7178.461615378972;7255.637218138966;5630.352509461464;6743.831404214934;6813.4208592270215;6823.283555226828;6823.454123669995;4841.544924571196;4942.950570494249;4944.258361245842;4973.676711013773;4989.525995030466;4816.95183048179;4905.656604037683;4910.21436570147;5066.316047333563;5090.720010399464 03:20:58 INFO - PROCESS | 1640 | |4;prototype.html;293.31364606820034;298.1666242851231;308.74065784740816;312.560852002936;314.7198992896321;4775.188218002939;5199.246109314147;5275.393329767073;5290.365156181254;5304.223085266431;4950.752462376885;5139.511746384097;5195.32460780099;5198.076711616698;5208.895822083556;3385.984204738581;3898.200868821885;3994.181192855472;3995.480587523618;3997.220541994305;3284.983575082121;4005.5994400560003;4008.659263962552;4014.116894283253;4031.2743706132883;3847.403652433877;3900.278448487025;3928.876543148204;3962.4650672159323;4011.257917285297;3683.907902302437;3923.8430462781453;3933.744306620089;3954.3673012318036;3962.7622342659533;3416.333814906094;3433.8454769535433;3435.914102147441;3444.431668774649;3460.498227756986;4820.397643229223;5062.974685126587;5098.719570423626;5107.693538387709;5119.846404607868;3333.4333163362285;4197.769122698251;4466.397036400077;4524.298733696282;4533.546645335473;4529;5515.200295957064;5527.972360138214;5530.723463826833;5579.302587176603;3271.705546500826;3742.438634204864;3744.64425879541;3755.2301778135416;3787.394016957274;5342.759575819058;5366.624336296461;5455.536279416255;5659.5755318350875;5832.708364581797;3625.8034848499933;3663.2673465307043;3664.5052917856283;3668.248009158116;3670.137517683342;3650.3429382710838;3667.431548110047;3839.7504162229366;3851.5185601799776;3852.845886164578;3847.6921846252235;4179.185058913544;4387.561243875587;4451.599356057905;4471.882029492627;5079.644424890296;5466.207399926989;5535.667859928417;5546.944530554642;5590.91613625796;1081.6268387406333;1089.5206109312037;1226.05593692856;1231.7167051578008;1239.231676360662;720.3516834848679;732.3921145449265;750.0140980093639;760.5702777930452;801.5711594297023;4545.681802273875;4963.528464795839;4972.563443912529;4978.47725988766;4988;4740.051989602093;4973.8010479581135;5379.35447746273;5386.91919621206;5402.864928376822;4023;4242.554531774181;4909.116359055333;4934.481879402611;4981.683517164536;2395.748446413136;2545.630883521879;2817.9295517612222;2831.089014914924;2847.3092927184025;4562.931556026662;5067.18771082053;5461.15352120422;5567;5581.302337207849;4633.513481084437;4937.604991600663;4977.40271167455;5149.613778966593;5557.861053473695;4261.616454519108;5126.974365128225;5550.916736248879;5592.86017849557;5611.98984182851;4730.881727956828;4786.305985632133;4798.712077275374;5028.876321416404;5089.338386009943;4628.791704373379;4959;5069.377799104252;5121.001404726043;5334.839954801362;5248.370195576403;5416.8645783854145;5554.694491802989;5643.871225754783;5840.153177789111;3158.862809388664;3162.525621156753;3687.391580389313;3688.7602305850032;3782.867599634;5132.255822905732;5461.344638643388;5567.220589117608;5660.88678226425;5690.516306113861;5330.2804121443105;5471.179323101566;5518.220019286801;5518.862028449321;5536.252605898152;5124.30821839047;5134.5635620972635;5480.931218412371;5482.861683943345;5502.559795216293;5039;5315.335583052119;5361.892762144813;5421.484958928895;5425.620206585659;4589.957468926295;4849.418069831643;5546.084895992116;5574.609777315712;5590.86022849432;5415;5425.593080518898;5426.240326354235;5451.64564303319;5469.917951230815 03:20:58 INFO - PROCESS | 1640 | |5;yui.html;705.6154395854259;710.978670639881;748.6556184155288;748.9775306740798;771.9691212351506;13770.24263665498;14375.928120359396;15696.843031569695;16224.432144874932;16234.269457874394;14835;14957.177355245458;14958.177300248482;14961.850381496182;14967.401303947856;647;668.9030090636854;780.7072347869534;805.8630032894422;812.045846130794;649.9252585952605;668.7057694614379;808.3048578222724;812.305478815613;820.8358328334327;857.7212405968054;861.8491763941316;863.2187869977649;863.9179277968615;865.7143142762905;677.6814896998403;855.6919508976763;857.4683696108422;857.7684025313131;860.9397342186049;520.4119345140006;521.7730287325026;521.9399769026572;523.7041071794424;617.8239201827503;13958.162510249316;14465.85534144666;14514.056586321854;14519.637009074751;14531.200783956878;12552.81170782439;12671.176373535784;12736.299503527302;13028.869711302861;13081.345932703327;11816.940915295454;12285.38573071343;12315.199512031779;12441.440135193938;12744.61766147017;4253.553376895412;4373.846915357979;4434.4678638563255;4502.077074199781;4547.226971414834;96712;96721.51639241759;96954.98764053914;98563;98969;9920.702378928643;10176.694699159038;10218.956208758278;10299.485025748683;10330.638427654996;19493;19607.411777646408;19621;19630.705539416922;19642.508937276398;7024.367806897404;7027;7032.331928466788;7078.92921070793;7272.127344718562;4048.4534587830267;4196.433481479961;4236.639885609696;4252.681048921343;4274.380214868826;3281.507773833944;3390.0846771372085;3391.8134502602593;3392.084137282969;3396;673.1719984419192;676.6921050921819;707.7770502291762;708.5394493579215;714.7819914925998;604.4167378479788;653.4086651580327;654.6988385342796;663.2439019517753;690.433844247712;8338.499690018618;8769.42998705095;8839.425437346552;9087.81824363509;9322;633.9666343859571;637.4931929116271;641.4804008752925;643.6331291163991;670.5641333133502;3569.982150089233;4365.80353884076;4391.956080439219;4427.158839820424;4546.931796023062;71619;104480;105527.4723626377;107831.46084269529;123626.38186809009;85464.29071418536;86588;86605.56697216474;93934;95562.52218738862;79555.60222198968;83348;85712;86548;87772.56113719646;8368.288695460711;8719.694810681596;9525.285603579621;9559.139677429064;9600.471974041495;55322.44677553334;57241;57242.7137864308;58377;59009.40990590211;79172;79861;80888.59555702184;81294.18705812866;83571;11756.412179390825;12057.397130143283;12090.153689241668;12189.451474683838;12531.498740050296;12350.703176166733;12430.502779889064;12949.158304710163;12985.480580776662;13981.44074237019;11685.415729213675;12198.57304994321;12919.677008074497;13084.542041028519;13159;11260.88739112631;11295.322280663186;12084.335361555199;12097.818532722193;12568.245905245714;11978.621356706308;11979.640610781691;12451.501939922302;12608.432620532281;12816.487340506648;9990.850137248093;10568.788624227625;11133.498992545194;11286.201022146352;11524.827127592926;9594.597949405204;10037.146842518176;11074.950886035593;11177.105831533621;11208.775824483302 03:20:58 INFO - PROCESS | 1640 | __end_tp_report 03:20:58 INFO - PROCESS | 1640 | __start_cc_report 03:20:58 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1707 03:20:58 INFO - PROCESS | 1640 | __end_cc_report 03:20:58 INFO - PROCESS | 1640 | __startTimestamp1479468058195__endTimestamp 03:20:58 INFO - PROCESS | 1640 | 03:20:58 INFO - PROCESS | 1640 | ------- Summary: start ------- 03:20:58 INFO - PROCESS | 1640 | Number of tests: 6 03:20:58 INFO - PROCESS | 1640 | 03:20:58 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5722.92 Median:5963.55 stddev:660.09 (11.1%) stddev-sans-first:625.61 03:20:58 INFO - PROCESS | 1640 | Values: 2963.2 3304.4 3323.0 3330.3 3351.5 5512.5 5569.8 5623.4 5706.9 5740.0 5679.4 5728.4 5730.7 5747.3 5800.0 5200.9 5220.1 5223.5 5253.8 5286.2 5187.5 5189.1 5209.7 5215.1 5375.4 5240.9 5277.4 5283.6 5296.3 5303.3 5289.1 5301.9 5309.3 5312.8 5332.1 5039.0 5150.7 5213.7 5245.2 5250.3 5585.6 5625.8 5650.8 5693.6 5698.8 6173.3 6187.2 6221.0 6253.8 6285.2 6194.3 6244.9 6253.4 6257.7 6276.1 5659.2 5665.5 5716.1 5758.4 5778.5 6226.5 6228.4 6242.3 6244.1 6245.5 6111.8 6137.6 6139.5 6144.4 6144.5 6114.1 6133.3 6187.4 6211.7 6221.3 5482.5 5913.9 6201.1 6211.7 6216.3 6163.1 6169.0 6201.4 6215.3 6215.4 4411.6 4772.3 4858.8 4899.4 4941.0 4372.3 4407.4 4407.4 4416.5 4460.6 5828.6 5835.2 5847.9 5855.8 5871.1 6192.7 6232.2 6237.1 6245.7 6265.5 5660.2 5741.9 5743.5 5774.7 5828.4 5423.8 5424.2 5424.6 5440.2 5442.6 6340.2 6360.2 6362.1 6365.6 6373.2 5252.7 6144.4 6217.2 6260.1 6274.3 6207.0 6337.9 6341.1 6346.4 6357.6 6189.3 6221.4 6243.7 6254.2 6261.8 6112.1 6123.1 6255.9 6282.0 6298.3 6069.7 6109.3 6115.1 6168.9 6172.8 6072.0 6119.1 6130.3 6166.0 6173.5 5775.7 5937.3 5963.6 5994.4 6001.2 4648.0 4954.7 6029.9 6103.8 6153.0 5996.4 6018.9 6026.2 6040.1 6077.8 03:20:58 INFO - PROCESS | 1640 | 03:20:58 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:69788.74 Median:34015.49 stddev:158835.11 (466.9%) stddev-sans-first:159195.33 03:20:58 INFO - PROCESS | 1640 | Values: 437.6 470.8 496.0 520.1 526.8 34889.5 37113.4 38702.4 38859.6 39485.8 40037.0 40381.0 40383.8 40560.8 40581.0 8355.5 9211.9 9379.9 9402.6 9405.4 8470.4 11019.1 11268.4 11309.5 11438.5 11139.0 11202.0 11204.9 11231.0 11299.2 11089.9 11145.4 11148.9 11153.4 11158.9 10959.0 11031.3 11039.9 11047.8 11070.7 37799.6 38116.6 38149.4 38241.4 38264.8 3217.5 3330.5 3420.3 3649.5 3771.5 29112.3 29492.9 29551.7 29790.9 29940.7 3162.5 3196.6 3222.8 3245.3 3252.5 479973.0 481714.0 482492.0 483031.0 483213.6 32517.3 52538.0 52562.7 52742.7 52810.9 33361.0 34153.1 34214.8 34253.5 34294.7 13894.8 14018.0 14024.6 14043.5 14063.0 10771.7 11057.3 11723.6 11784.4 11881.7 7570.0 7607.7 7672.5 7677.6 7700.8 3141.9 3190.8 3191.9 3192.6 3227.3 910.9 916.8 1050.4 1057.1 1076.0 28909.7 29346.1 29630.9 29930.0 30122.1 1025.5 1050.2 1051.6 1063.5 1076.0 15022.5 15090.2 15219.8 15424.8 15525.5 658202.0 660073.0 661893.0 662841.0 664973.0 53901.0 54192.2 54237.2 54286.0 54416.0 1207.7 1256.5 1333.8 1358.2 1365.8 23938.9 28236.4 30084.1 30751.8 30827.8 52742.5 56003.2 56485.4 56706.7 56882.6 539827.0 632368.0 636067.0 636799.0 638917.0 25455.2 26579.2 26600.7 26749.2 27025.0 44195.3 44238.3 44414.3 44676.0 44726.8 36311.3 36934.8 37495.6 37502.4 37790.4 36271.6 37103.8 37654.0 38429.4 38567.6 35992.1 36326.1 36743.4 36828.8 37174.7 31648.0 33636.5 33705.0 33721.2 33877.8 20872.6 24361.4 24609.9 25068.9 25146.1 03:20:58 INFO - PROCESS | 1640 | 03:20:58 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:34703.79 Median:13822.90 stddev:105064.93 (760.1%) stddev-sans-first:105327.97 03:20:58 INFO - PROCESS | 1640 | Values: 337.4 353.2 370.5 381.5 403.3 14854.9 15018.6 16012.5 20126.8 20189.7 19752.0 19802.0 20277.0 20494.0 20718.8 4982.9 5799.8 5850.9 5860.1 5865.3 4117.5 4356.9 4435.5 5744.6 5812.8 4350.2 6121.5 6438.7 6450.8 6503.6 6309.3 6405.7 6432.4 6469.8 6471.2 5615.5 5617.6 5619.8 5628.1 5628.7 20316.6 23599.0 24292.5 25013.0 25136.9 1971.8 2055.8 2508.9 2702.4 2986.4 14239.6 15017.0 15500.0 15607.8 15888.9 1770.7 1828.9 1834.0 1847.4 1919.8 112538.7 115781.0 123744.0 124273.1 124950.0 10355.9 10448.7 10508.0 10515.9 10820.2 13407.4 13438.3 13440.4 13445.3 13461.7 8246.3 8294.7 8328.1 8328.1 8569.6 8409.6 8424.7 8530.9 8569.4 8621.6 5400.2 6433.5 6494.3 6514.9 6535.4 1555.6 1650.3 1792.9 1834.2 1837.7 761.8 850.6 913.0 915.2 980.8 20340.9 21819.8 21827.4 21906.3 22036.4 814.9 926.8 928.9 949.2 999.1 10723.2 11332.8 11411.9 11437.4 11613.5 623654.0 626990.9 635032.0 646959.0 647125.0 66156.7 75925.2 76774.0 106157.0 107606.0 945.6 962.1 1005.2 1031.5 1036.0 15892.1 17745.3 18310.8 18664.3 18827.3 50805.7 51566.4 52345.0 52890.0 53944.7 70641.0 70890.9 71542.0 72987.5 74338.6 5153.2 5330.6 7586.5 7641.9 7912.0 12096.2 12512.8 12538.1 12698.4 12885.7 10587.9 10798.5 11178.5 11246.7 11317.7 7620.7 10377.3 10561.6 10815.9 10944.3 10956.2 11024.3 11219.0 11289.3 11369.2 7762.3 7828.8 7849.7 7948.5 8008.2 10542.4 10582.5 10834.3 10843.0 11057.4 03:20:58 INFO - PROCESS | 1640 | 03:20:58 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5522.60 Median:4958.97 stddev:3083.89 (62.2%) stddev-sans-first:3077.34 03:20:58 INFO - PROCESS | 1640 | Values: 1447.2 1457.5 1586.9 1745.2 1789.0 8159.5 8609.4 8615.2 8617.2 8624.5 7573.7 7789.7 7800.7 8174.0 8442.7 2290.1 2336.3 2338.1 2341.3 2346.8 2173.7 2224.7 2337.3 2366.4 2368.6 2301.5 2304.5 2326.9 2330.4 2351.9 2304.2 2328.9 2333.2 2335.7 2336.1 2328.0 2365.4 2365.6 2367.1 2367.1 8501.4 8553.9 8564.7 8568.1 8576.7 5060.4 5226.4 5229.0 5232.5 5233.2 3235.9 3354.9 3580.7 3581.2 3581.5 1057.2 1058.0 1085.2 1098.1 1102.9 11034.2 11144.8 11184.3 11188.8 11190.0 4555.6 4631.5 4763.5 4778.6 4779.9 9674.7 9677.4 9694.0 9694.3 9712.3 5239.0 5243.2 5245.8 5253.8 5260.4 4047.6 4198.4 4689.2 4779.7 4792.4 3146.0 3784.6 3859.4 3880.3 3886.7 1243.9 1253.4 1254.8 1260.4 1267.5 790.8 797.1 907.6 918.0 927.1 5677.9 5703.6 5823.4 5855.3 6067.0 441.7 443.5 454.5 462.5 465.1 3904.0 3926.9 3950.2 3955.3 3971.1 10067.7 10296.7 10445.1 10465.7 10471.0 10391.4 10408.9 10414.3 10423.8 10434.2 7871.7 7931.8 7940.2 7955.1 7957.2 5249.2 5403.1 5643.5 5729.2 5811.4 9219.8 9561.8 9563.4 9631.0 9697.9 9320.5 9551.0 9697.4 9906.9 9909.8 6072.6 7227.9 7286.5 7344.4 7366.7 7986.0 8077.6 8082.2 8085.2 8134.9 7030.8 7184.6 7208.4 7271.4 7452.4 7046.0 7128.9 7142.9 7178.5 7255.6 5630.4 6743.8 6813.4 6823.3 6823.5 4841.5 4943.0 4944.3 4973.7 4989.5 4817.0 4905.7 4910.2 5066.3 5090.7 03:20:58 INFO - PROCESS | 1640 | 03:20:58 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4349.87 Median:4961.26 stddev:1347.59 (27.2%) stddev-sans-first:1316.53 03:20:58 INFO - PROCESS | 1640 | Values: 293.3 298.2 308.7 312.6 314.7 4775.2 5199.2 5275.4 5290.4 5304.2 4950.8 5139.5 5195.3 5198.1 5208.9 3386.0 3898.2 3994.2 3995.5 3997.2 3285.0 4005.6 4008.7 4014.1 4031.3 3847.4 3900.3 3928.9 3962.5 4011.3 3683.9 3923.8 3933.7 3954.4 3962.8 3416.3 3433.8 3435.9 3444.4 3460.5 4820.4 5063.0 5098.7 5107.7 5119.8 3333.4 4197.8 4466.4 4524.3 4533.5 4529.0 5515.2 5528.0 5530.7 5579.3 3271.7 3742.4 3744.6 3755.2 3787.4 5342.8 5366.6 5455.5 5659.6 5832.7 3625.8 3663.3 3664.5 3668.2 3670.1 3650.3 3667.4 3839.8 3851.5 3852.8 3847.7 4179.2 4387.6 4451.6 4471.9 5079.6 5466.2 5535.7 5546.9 5590.9 1081.6 1089.5 1226.1 1231.7 1239.2 720.4 732.4 750.0 760.6 801.6 4545.7 4963.5 4972.6 4978.5 4988.0 4740.1 4973.8 5379.4 5386.9 5402.9 4023.0 4242.6 4909.1 4934.5 4981.7 2395.7 2545.6 2817.9 2831.1 2847.3 4562.9 5067.2 5461.2 5567.0 5581.3 4633.5 4937.6 4977.4 5149.6 5557.9 4261.6 5127.0 5550.9 5592.9 5612.0 4730.9 4786.3 4798.7 5028.9 5089.3 4628.8 4959.0 5069.4 5121.0 5334.8 5248.4 5416.9 5554.7 5643.9 5840.2 3158.9 3162.5 3687.4 3688.8 3782.9 5132.3 5461.3 5567.2 5660.9 5690.5 5330.3 5471.2 5518.2 5518.9 5536.3 5124.3 5134.6 5480.9 5482.9 5502.6 5039.0 5315.3 5361.9 5421.5 5425.6 4590.0 4849.4 5546.1 5574.6 5590.9 5415.0 5425.6 5426.2 5451.6 5469.9 03:20:58 INFO - PROCESS | 1640 | 03:20:58 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20654.82 Median:2533.82 stddev:30273.20 (1194.8%) stddev-sans-first:30321.06 03:20:58 INFO - PROCESS | 1640 | Values: 705.6 711.0 748.7 749.0 772.0 13770.2 14375.9 15696.8 16224.4 16234.3 14835.0 14957.2 14958.2 14961.9 14967.4 647.0 668.9 780.7 805.9 812.0 649.9 668.7 808.3 812.3 820.8 857.7 861.8 863.2 863.9 865.7 677.7 855.7 857.5 857.8 860.9 520.4 521.8 521.9 523.7 617.8 13958.2 14465.9 14514.1 14519.6 14531.2 12552.8 12671.2 12736.3 13028.9 13081.3 11816.9 12285.4 12315.2 12441.4 12744.6 4253.6 4373.8 4434.5 4502.1 4547.2 96712.0 96721.5 96955.0 98563.0 98969.0 9920.7 10176.7 10219.0 10299.5 10330.6 19493.0 19607.4 19621.0 19630.7 19642.5 7024.4 7027.0 7032.3 7078.9 7272.1 4048.5 4196.4 4236.6 4252.7 4274.4 3281.5 3390.1 3391.8 3392.1 3396.0 673.2 676.7 707.8 708.5 714.8 604.4 653.4 654.7 663.2 690.4 8338.5 8769.4 8839.4 9087.8 9322.0 634.0 637.5 641.5 643.6 670.6 3570.0 4365.8 4392.0 4427.2 4546.9 71619.0 104480.0 105527.5 107831.5 123626.4 85464.3 86588.0 86605.6 93934.0 95562.5 79555.6 83348.0 85712.0 86548.0 87772.6 8368.3 8719.7 9525.3 9559.1 9600.5 55322.4 57241.0 57242.7 58377.0 59009.4 79172.0 79861.0 80888.6 81294.2 83571.0 11756.4 12057.4 12090.2 12189.5 12531.5 12350.7 12430.5 12949.2 12985.5 13981.4 11685.4 12198.6 12919.7 13084.5 13159.0 11260.9 11295.3 12084.3 12097.8 12568.2 11978.6 11979.6 12451.5 12608.4 12816.5 9990.9 10568.8 11133.5 11286.2 11524.8 9594.6 10037.1 11075.0 11177.1 11208.8 03:20:58 INFO - PROCESS | 1640 | -------- Summary: end -------- 03:20:58 INFO - PROCESS | 1640 | 03:20:58 INFO - TEST-INFO | 1640: exit 0 03:20:58 INFO - TEST-OK | dromaeo_css | took 1118224ms 03:20:58 INFO - TEST-START | kraken 03:20:58 INFO - operating with platform_type : mac_ 03:20:58 INFO - Initialising browser for kraken test... 03:20:58 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpk1IOZS/profile http://localhost:49241/getInfo.html) 03:21:00 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 03:21:01 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 03:21:01 INFO - PROCESS | 1650 | colorDepth:24 03:21:01 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 03:21:01 INFO - PROCESS | 1650 | __metrics 03:21:01 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" 03:21:01 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:21:02 INFO - TEST-INFO | 1650: exit 0 03:21:02 INFO - Browser initialized. 03:21:02 INFO - Running cycle 1/1 for kraken test... 03:21:02 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpk1IOZS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:21:14 INFO - PROCESS | 1651 | RSS: Main: 217272320 03:21:14 INFO - PROCESS | 1651 | 03:21:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 03:21:17 INFO - PROCESS | 1651 | RSS: Main: 267132928 03:21:17 INFO - PROCESS | 1651 | 03:21:20 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 03:21:20 INFO - PROCESS | 1651 | RSS: Main: 276856832 03:21:20 INFO - PROCESS | 1651 | 03:21:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 03:21:24 INFO - PROCESS | 1651 | RSS: Main: 376369152 03:21:24 INFO - PROCESS | 1651 | 03:21:26 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 03:21:27 INFO - PROCESS | 1651 | RSS: Main: 277995520 03:21:27 INFO - PROCESS | 1651 | 03:21:29 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:21:30 INFO - PROCESS | 1651 | RSS: Main: 279375872 03:21:30 INFO - PROCESS | 1651 | 03:21:32 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 03:21:33 INFO - PROCESS | 1651 | RSS: Main: 320425984 03:21:33 INFO - PROCESS | 1651 | 03:21:36 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 03:21:36 INFO - PROCESS | 1651 | RSS: Main: 319434752 03:21:36 INFO - PROCESS | 1651 | 03:21:39 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 03:21:40 INFO - PROCESS | 1651 | RSS: Main: 317026304 03:21:40 INFO - PROCESS | 1651 | 03:21:41 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:21:42 INFO - PROCESS | 1651 | RSS: Main: 295317504 03:21:42 INFO - PROCESS | 1651 | 03:21:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:21:44 INFO - PROCESS | 1651 | RSS: Main: 295464960 03:21:44 INFO - PROCESS | 1651 | 03:21:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:21:47 INFO - PROCESS | 1651 | RSS: Main: 298770432 03:21:47 INFO - PROCESS | 1651 | 03:21:50 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:21:51 INFO - PROCESS | 1651 | RSS: Main: 308047872 03:21:51 INFO - PROCESS | 1651 | 03:21:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:21:54 INFO - PROCESS | 1651 | RSS: Main: 307982336 03:21:54 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 03:21:56 INFO - PROCESS | 1651 | RSS: Main: 350932992 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | __start_tp_report 03:21:56 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 03:21:56 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 03:21:56 INFO - PROCESS | 1651 | |i|pagename|runs| 03:21:56 INFO - PROCESS | 1651 | |0;ai-astar;114;114;114;114;113;114;113;114;115;114 03:21:56 INFO - PROCESS | 1651 | |1;audio-beat-detection;148;140;139;143;115;139;141;138;140;112 03:21:56 INFO - PROCESS | 1651 | |2;audio-dft;184;186;180;185;189;182;187;235;183;181 03:21:56 INFO - PROCESS | 1651 | |3;audio-fft;80;58;59;87;51;59;83;59;82;58 03:21:56 INFO - PROCESS | 1651 | |4;audio-oscillator;83;70;95;71;71;70;88;91;56;93 03:21:56 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;86;85;86;84;88;83;87;83;86;84 03:21:56 INFO - PROCESS | 1651 | |6;imaging-darkroom;139;112;92;92;93;92;92;92;92;92 03:21:56 INFO - PROCESS | 1651 | |7;imaging-desaturate;81;79;79;82;83;88;119;85;83;81 03:21:56 INFO - PROCESS | 1651 | |8;json-parse-financial;55;55;55;55;57;56;55;56;57;56 03:21:56 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;71;67;65;75;73;73;70;70;72;71 03:21:56 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;85;84;84;84;84;86;92;84;82;85 03:21:56 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;139;146;144;146;145;146;151;153;211;180 03:21:56 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;167;165;168;164;167;167;166;167;166;164 03:21:56 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;66;68;69;84;85;88;77;65;65;65 03:21:56 INFO - PROCESS | 1651 | __end_tp_report 03:21:56 INFO - PROCESS | 1651 | __start_cc_report 03:21:56 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1987 03:21:56 INFO - PROCESS | 1651 | __end_cc_report 03:21:56 INFO - PROCESS | 1651 | __startTimestamp1479468116953__endTimestamp 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | ------- Summary: start ------- 03:21:56 INFO - PROCESS | 1651 | Number of tests: 14 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 03:21:56 INFO - PROCESS | 1651 | Values: 114.0 114.0 114.0 114.0 113.0 114.0 113.0 114.0 115.0 114.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:140.00 stddev:11.96 (8.5%) stddev-sans-first:11.79 03:21:56 INFO - PROCESS | 1651 | Values: 148.0 140.0 139.0 143.0 115.0 139.0 141.0 138.0 140.0 112.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:189.20 Median:185.50 stddev:16.33 (8.8%) stddev-sans-first:17.21 03:21:56 INFO - PROCESS | 1651 | Values: 184.0 186.0 180.0 185.0 189.0 182.0 187.0 235.0 183.0 181.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:67.60 Median:69.50 stddev:13.57 (19.5%) stddev-sans-first:13.63 03:21:56 INFO - PROCESS | 1651 | Values: 80.0 58.0 59.0 87.0 51.0 59.0 83.0 59.0 82.0 58.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:85.50 stddev:12.96 (15.2%) stddev-sans-first:13.66 03:21:56 INFO - PROCESS | 1651 | Values: 83.0 70.0 95.0 71.0 71.0 70.0 88.0 91.0 56.0 93.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:86.00 stddev:1.69 (2.0%) stddev-sans-first:1.76 03:21:56 INFO - PROCESS | 1651 | Values: 86.0 85.0 86.0 84.0 88.0 83.0 87.0 83.0 86.0 84.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:98.80 Median:92.00 stddev:15.45 (16.8%) stddev-sans-first:6.63 03:21:56 INFO - PROCESS | 1651 | Values: 139.0 112.0 92.0 92.0 93.0 92.0 92.0 92.0 92.0 92.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:82.50 stddev:11.91 (14.4%) stddev-sans-first:12.49 03:21:56 INFO - PROCESS | 1651 | Values: 81.0 79.0 79.0 82.0 83.0 88.0 119.0 85.0 83.0 81.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:0.82 (1.5%) stddev-sans-first:0.83 03:21:56 INFO - PROCESS | 1651 | Values: 55.0 55.0 55.0 55.0 57.0 56.0 55.0 56.0 57.0 56.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.50 stddev:2.95 (4.1%) stddev-sans-first:3.12 03:21:56 INFO - PROCESS | 1651 | Values: 71.0 67.0 65.0 75.0 73.0 73.0 70.0 70.0 72.0 71.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:84.50 stddev:2.67 (3.2%) stddev-sans-first:2.83 03:21:56 INFO - PROCESS | 1651 | Values: 85.0 84.0 84.0 84.0 84.0 86.0 92.0 84.0 82.0 85.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:156.10 Median:148.50 stddev:22.33 (15.0%) stddev-sans-first:22.81 03:21:56 INFO - PROCESS | 1651 | Values: 139.0 146.0 144.0 146.0 145.0 146.0 151.0 153.0 211.0 180.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:167.00 stddev:1.37 (0.8%) stddev-sans-first:1.41 03:21:56 INFO - PROCESS | 1651 | Values: 167.0 165.0 168.0 164.0 167.0 167.0 166.0 167.0 166.0 164.0 03:21:56 INFO - PROCESS | 1651 | 03:21:56 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:73.00 stddev:9.35 (12.8%) stddev-sans-first:9.55 03:21:56 INFO - PROCESS | 1651 | Values: 66.0 68.0 69.0 84.0 85.0 88.0 77.0 65.0 65.0 65.0 03:21:56 INFO - PROCESS | 1651 | -------- Summary: end -------- 03:21:56 INFO - PROCESS | 1651 | 03:21:57 INFO - TEST-INFO | 1651: exit 0 03:21:57 INFO - TEST-OK | kraken | took 59026ms 03:21:57 INFO - SUITE-END | took 1177s 03:21:58 INFO - Completed test suite (00:19:37) 03:21:58 INFO - javascript benchmark 03:21:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2963.244372684965, 3304.355650648123, 3322.9667703322975, 3330.300636866258, 3351.463765797472, 5512.503874651281, 5569.777208911639, 5623.353314368844, 5706.942930570698, 5739.995500787361, 5679.4036626154275, 5728.398518155585, 5730.7421166047525, 5747.2528571285775, 5800.042992906176, 5200.8699782505455, 5220.060389129955, 5223.451537588556, 5253.842384728445, 5286.233496143057, 5187.48125187482, 5189.065968125736, 5209.661372010826, 5215.087359712054, 5375.43557926418, 5240.9213861792105, 5277.393099793515, 5283.55089817366, 5296.25852380667, 5303.310569625944, 5289.100852855024, 5301.946980530203, 5309.256704061435, 5312.787488500456, 5332.066888294533, 5038.992201559703, 5150.690958542499, 5213.713245771481, 5245.186996015624, 5250.317458730379, 5585.636933599303, 5625.803096891589, 5650.802221922254, 5693.629914055573, 5698.8290351289525, 6173.290071641729, 6187.164732761064, 6220.968895155541, 6253.812385628438, 6285.182926219608, 6194.287656919467, 6244.875102497925, 6253.405926436982, 6257.655828929407, 6276.121343011982, 5659.236003139524, 5665.490105890449, 5716.085426331767, 5758.395368486249, 5778.479936805707, 6226.501879849601, 6228.439440450381, 6242.282137554149, 6244.063390491463, 6245.500359971192, 6111.8472038198515, 6137.570370074052, 6139.539534534839, 6144.4469997699525, 6144.539159563051, 6114.093258577931, 6133.304060594526, 6187.381261873777, 6211.689415529206, 6221.3156552779155, 5482.451754824566, 5913.881722365529, 6201.10084037821, 6211.65835879031, 6216.316205217422, 6163.106349579376, 6169.0129579268, 6201.441870231701, 6215.34738852423, 6215.378462153749, 4411.558844115627, 4772.260299653559, 4858.78135483904, 4899.436564795023, 4940.975295123501, 4372.300431930876, 4407.360932664746, 4407.405000324915, 4416.470023597125, 4460.598546130864, 5828.6211396259105, 5835.2414186156375, 5847.9415205848245, 5855.824325270249, 5871.148683440877, 6192.690365481708, 6232.220972378453, 6237.126802247689, 6245.656488893154, 6265.467435267962, 5660.15097735343, 5741.88516229673, 5743.54051675865, 5774.653520788766, 5828.417158284137, 5423.837284881381, 5424.159255315432, 5424.566034717213, 5440.211169380417, 5442.591805614595, 6340.239171299474, 6360.236771587439, 6362.109304697254, 6365.58623689468, 6373.235211774617, 5252.737363131904, 6144.3717414884195, 6217.160683307696, 6260.060990851227, 6274.278457977209, 6207.037909124093, 6337.8732425352155, 6341.0805433212845, 6346.397092276226, 6357.586756860789, 6189.319174890668, 6221.4089661482085, 6243.719032643451, 6254.21822272216, 6261.843453913689, 6112.144299797943, 6123.069106197319, 6255.906161407492, 6281.96859015702, 6298.307186209605, 6069.695015571673, 6109.283207920518, 6115.052166914135, 6168.907466387918, 6172.783952561639, 6072.000279264083, 6119.082137679384, 6130.295016073209, 6166.013437850102, 6173.475254603409, 5775.7400916957995, 5937.287525496968, 5963.552733545088, 5994.430529099728, 6001.219841420588, 4648.0, 4954.653174277766, 6029.909551356821, 6103.755849765959, 6153.015517517176, 5996.430339117776, 6018.879622407615, 6026.246719160105, 6040.124181993674, 6077.756889724538], "value": 5679.494729338535, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.6214574393149, 470.8493282149712, 495.9826406075788, 520.1132069820956, 526.8409499101974, 34889.47665785015, 37113.44329835051, 38702.419463708065, 38859.61140388595, 39485.80257098718, 40037.0, 40381.0, 40383.798081009554, 40560.79719601398, 40581.0, 8355.540445275508, 9211.907880921206, 9379.859302110439, 9402.5768840402, 9405.388649737779, 8470.407071504997, 11019.063379612742, 11268.380239086848, 11309.547618095266, 11438.542458301657, 11139.0, 11202.0, 11204.943975280112, 11231.0, 11299.209055366127, 11089.946455086754, 11145.387003714792, 11148.888511114947, 11153.442327883573, 11158.888411115906, 10959.0, 11031.338119712844, 11039.889601103967, 11047.779044419067, 11070.723231919266, 37799.62200378016, 38116.61883381158, 38149.427758583646, 38241.426378604345, 38264.80867595672, 3217.549543063973, 3330.467125259971, 3420.3330350581646, 3649.4708267301357, 3771.453139294787, 29112.27219319534, 29492.852535737184, 29551.70448295511, 29790.85104574485, 29940.70059299401, 3162.5414314924205, 3196.5844440222854, 3222.8388580570877, 3245.3022600141085, 3252.4633435483115, 479973.0, 481714.0, 482492.0, 483031.0, 483213.5839320816, 32517.34965300728, 52538.0, 52562.73718631459, 52742.73628631832, 52810.94378112416, 33361.0, 34153.146171345914, 34214.82892585521, 34253.486197707054, 34294.65705342965, 13894.791578126336, 14018.018738688397, 14024.579262621934, 14043.50847720325, 14063.015588908678, 10771.676849694522, 11057.281276716987, 11723.589674361356, 11784.410779460992, 11881.702957425961, 7570.0, 7607.733729319447, 7672.462927595015, 7677.577733224726, 7700.807479813049, 3141.858616362269, 3190.776645634783, 3191.888283910052, 3192.6009248843893, 3227.322262324891, 910.9089091090971, 916.7524768312518, 1050.4380156616173, 1057.069778594847, 1075.983195879886, 28909.710902890703, 29346.068626864242, 29630.85184574063, 29930.0, 30122.09633710992, 1025.4923812712755, 1050.1965996013057, 1051.6161601015726, 1063.542676649033, 1075.9677209683723, 15022.549323520312, 15090.17004064787, 15219.847801522064, 15424.845751542342, 15525.45660901863, 658202.0, 660073.0, 661893.0, 662841.0, 664973.0, 53901.0, 54192.18711719327, 54237.1864422034, 54286.0, 54416.0, 1207.6558180918396, 1256.5476428485738, 1333.833270841145, 1358.2393859438746, 1365.8019587159806, 23938.874409109212, 28236.43527129405, 30084.097477075724, 30751.77106403885, 30827.845860770554, 52742.47257527376, 56003.159952599926, 56485.435145648014, 56706.716466417405, 56882.57793555194, 539827.0, 632368.0, 636067.0, 636799.0, 638917.0, 25455.236342909742, 26579.202623921312, 26600.7339926606, 26749.19752407431, 27025.0, 44195.337069943336, 44238.336424954294, 44414.3337849926, 44676.0, 44726.77636611796, 36311.273774523834, 36934.8153259232, 37495.625043750304, 37502.43746343862, 37790.43314350342, 36271.63728362683, 37103.81448092742, 37654.0, 38429.423558646085, 38567.614323857524, 35992.10019749423, 36326.09184770402, 36743.448848267835, 36828.81585592055, 37174.69888553996, 31648.0, 33636.49545256774, 33705.0, 33721.15697107592, 33877.83061084778, 20872.58254834925, 24361.39096522601, 24609.876950615133, 25068.874655626605, 25146.11988580391], "value": 18512.321363521256, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.3809060374213, 353.20000199548014, 370.54423059636656, 381.4564245949523, 403.3405382200102, 14854.851451485496, 15018.624534386632, 16012.519624411272, 20126.798732012674, 20189.697154542657, 19752.0, 19802.0, 20276.986150692443, 20494.0, 20718.792812071875, 4982.875428114299, 5799.768009279624, 5850.912236316459, 5860.120981852735, 5865.296164460271, 4117.526484454281, 4356.869293921188, 4435.534268901767, 5744.626599271055, 5812.825615231529, 4350.173467041268, 6121.479674227674, 6438.742450302006, 6450.838729031789, 6503.577267477623, 6309.274433440144, 6405.711742971531, 6432.356764323577, 6469.83825404364, 6471.158749362553, 5615.522680572156, 5617.634853734535, 5619.80330688424, 5628.0713682242385, 5628.690422026787, 20316.57783955124, 23599.0, 24292.514149717084, 25012.99948002078, 25136.874315628487, 1971.8225359717694, 2055.8487247141647, 2508.924732258035, 2702.3514356554288, 2986.4325778102093, 14239.64400889986, 15017.0, 15500.0, 15607.765883511756, 15888.920555397264, 1770.7339252434442, 1828.9176987035614, 1833.9908300458546, 1847.4457662701136, 1919.779225389084, 112538.74922501504, 115781.0, 123744.0, 124273.13590296153, 124950.0, 10355.94822025885, 10448.73878153037, 10508.001739834703, 10515.894841051644, 10820.188485863591, 13407.396667150002, 13438.260895650834, 13440.395182216627, 13445.327733613476, 13461.730765384638, 8246.340292776684, 8294.709685160991, 8328.125546817619, 8328.125546817619, 8569.571521423904, 8409.621567029499, 8424.663013479514, 8530.87203691945, 8569.442986205877, 8621.61202745878, 5400.1899715043055, 6433.517486188554, 6494.253160886464, 6514.902276465857, 6535.411812936859, 1555.5955451582445, 1650.257384177125, 1792.856571474305, 1834.2112891456534, 1837.6508463391913, 761.8115739446371, 850.6172222499777, 913.0, 915.1809130827871, 980.789130336981, 20340.931813637333, 21819.781802181777, 21827.413699368015, 21906.291139745634, 22036.446082470586, 814.9149308316183, 926.8424367857481, 928.9071092890657, 949.2121539122512, 999.0758548342753, 10723.195760318007, 11332.830007549894, 11411.942940285246, 11437.370944598128, 11613.477393517313, 623654.0, 626990.8650456718, 635032.0, 646959.0, 647125.0, 66156.66921665361, 75925.24074759292, 76774.0, 106157.0, 107606.0, 945.645382981382, 962.100436092256, 1005.1807776661997, 1031.504877658713, 1035.994820025895, 15892.125933073792, 17745.29018839232, 18310.816891831444, 18664.34674786376, 18827.34104306343, 50805.745971269906, 51566.421678915154, 52345.0, 52890.0, 53944.73027634837, 70641.0, 70890.93663594947, 71542.0, 72987.50087423975, 74338.62830685813, 5153.175491921274, 5330.626856120034, 7586.544807311579, 7641.923580764121, 7911.960440197762, 12096.153269271066, 12512.812307815208, 12538.122331436713, 12698.428570714155, 12885.677858053623, 10587.947060264649, 10798.460076995962, 11178.496967636313, 11246.66260012201, 11317.71705707364, 7620.733274335372, 10377.273590848568, 10561.577536898745, 10815.945920270347, 10944.343339399344, 10956.234038193412, 11024.283421577571, 11219.0, 11289.322640641587, 11369.191320907588, 7762.262585054411, 7828.84342313162, 7849.665556855234, 7948.523088614702, 8008.239217274349, 10542.367457952547, 10582.523786429783, 10834.295770774874, 10843.0, 11057.391843448688], "value": 9401.232729858923, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1447.1606468248417, 1457.4971634785998, 1586.8968517046392, 1745.2146534059661, 1789.0428620687926, 8159.510429374234, 8609.354298427626, 8615.181557752012, 8617.224449799516, 8624.482531048126, 7573.659185336659, 7789.727359542417, 7800.687972481094, 8173.97825271841, 8442.662293508252, 2290.061074959267, 2336.334144768741, 2338.1348900906687, 2341.3327201747506, 2346.7887890089887, 2173.673948907661, 2224.6663000549884, 2337.2520793346134, 2366.4320563064825, 2368.5854975379325, 2301.4821665125382, 2304.539092181562, 2326.9069237230574, 2330.382448651109, 2351.870647114408, 2304.2050492580115, 2328.895199716017, 2333.183385814951, 2335.684682567857, 2336.100601268507, 2328.010595496924, 2365.4204719843747, 2365.6096744037213, 2367.0650093213085, 2367.112332875172, 8501.362397820189, 8553.914460855436, 8564.743057708216, 8568.057513673491, 8576.656933722643, 5060.443351231362, 5226.425093239741, 5228.973855130738, 5232.502912223333, 5233.188855727369, 3235.9191020224685, 3354.899353019389, 3580.6777390034763, 3581.247937933037, 3581.5164952731307, 1057.196530636714, 1058.0371861605902, 1085.218642577351, 1098.0885864732254, 1102.85662863828, 11034.227604067719, 11144.777104457946, 11184.328940263611, 11188.776224475547, 11190.0, 4555.589996900295, 4631.51369106239, 4763.476017638057, 4778.593819525309, 4779.880502987453, 9674.70975870725, 9677.419354838732, 9694.0, 9694.272929530314, 9712.271579631415, 5239.030779305841, 5243.213517972335, 5245.816396426107, 5253.816116435906, 5260.421353651095, 4047.6357127858632, 4198.433211516467, 4689.1559519286175, 4779.689320194177, 4792.400949881265, 3145.968540314613, 3784.6404591563755, 3859.3824988001784, 3880.301545721797, 3886.689064874803, 1243.8942689871462, 1253.4296894912975, 1254.8431446069242, 1260.3887114749339, 1267.5436842736608, 790.8299715561067, 797.0634504457239, 907.5734404829719, 918.008550765172, 927.1238679447991, 5677.858053548612, 5703.62926409782, 5823.417658234142, 5855.2973643163095, 6067.0, 441.73937376947754, 443.53428899655233, 454.5227511113318, 462.49818946443486, 465.1371705486342, 3903.9609603904164, 3926.8821935341984, 3950.150717595673, 3955.3473676843, 3971.1065010372436, 10067.69796906094, 10296.691099267035, 10445.11216546602, 10465.68602941913, 10471.0, 10391.37651740898, 10408.947955260326, 10414.323069000491, 10423.791524169474, 10434.165266778791, 7871.685132594632, 7931.841363172704, 7940.16628254025, 7955.1249362571225, 7957.204279571996, 5249.238860365149, 5403.108487099585, 5643.492085712305, 5729.2265544151005, 5811.4479124483105, 9219.76950576241, 9561.760955976157, 9563.37838040553, 9630.951845240728, 9697.903020969983, 9320.533973301443, 9551.044895510393, 9697.369670971364, 9906.851397228904, 9909.752256193653, 6072.635641861502, 7227.8554428912175, 7286.48994570375, 7344.375728063174, 7366.6684999174095, 7985.960070199611, 8077.5961201940845, 8082.19178082187, 8085.151059138706, 8134.877976830234, 7030.824229394101, 7184.6048467334795, 7208.35124838767, 7271.381932535794, 7452.366548843409, 7046.0, 7128.928710712827, 7142.928570714226, 7178.461615378972, 7255.637218138966, 5630.352509461464, 6743.831404214934, 6813.4208592270215, 6823.283555226828, 6823.454123669995, 4841.544924571196, 4942.950570494249, 4944.258361245842, 4973.676711013773, 4989.525995030466, 4816.95183048179, 4905.656604037683, 4910.21436570147, 5066.316047333563, 5090.720010399464], "value": 4386.2977976539405, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.31364606820034, 298.1666242851231, 308.74065784740816, 312.560852002936, 314.7198992896321, 4775.188218002939, 5199.246109314147, 5275.393329767073, 5290.365156181254, 5304.223085266431, 4950.752462376885, 5139.511746384097, 5195.32460780099, 5198.076711616698, 5208.895822083556, 3385.984204738581, 3898.200868821885, 3994.181192855472, 3995.480587523618, 3997.220541994305, 3284.983575082121, 4005.5994400560003, 4008.659263962552, 4014.116894283253, 4031.2743706132883, 3847.403652433877, 3900.278448487025, 3928.876543148204, 3962.4650672159323, 4011.257917285297, 3683.907902302437, 3923.8430462781453, 3933.744306620089, 3954.3673012318036, 3962.7622342659533, 3416.333814906094, 3433.8454769535433, 3435.914102147441, 3444.431668774649, 3460.498227756986, 4820.397643229223, 5062.974685126587, 5098.719570423626, 5107.693538387709, 5119.846404607868, 3333.4333163362285, 4197.769122698251, 4466.397036400077, 4524.298733696282, 4533.546645335473, 4529.0, 5515.200295957064, 5527.972360138214, 5530.723463826833, 5579.302587176603, 3271.705546500826, 3742.438634204864, 3744.64425879541, 3755.2301778135416, 3787.394016957274, 5342.759575819058, 5366.624336296461, 5455.536279416255, 5659.5755318350875, 5832.708364581797, 3625.8034848499933, 3663.2673465307043, 3664.5052917856283, 3668.248009158116, 3670.137517683342, 3650.3429382710838, 3667.431548110047, 3839.7504162229366, 3851.5185601799776, 3852.845886164578, 3847.6921846252235, 4179.185058913544, 4387.561243875587, 4451.599356057905, 4471.882029492627, 5079.644424890296, 5466.207399926989, 5535.667859928417, 5546.944530554642, 5590.91613625796, 1081.6268387406333, 1089.5206109312037, 1226.05593692856, 1231.7167051578008, 1239.231676360662, 720.3516834848679, 732.3921145449265, 750.0140980093639, 760.5702777930452, 801.5711594297023, 4545.681802273875, 4963.528464795839, 4972.563443912529, 4978.47725988766, 4988.0, 4740.051989602093, 4973.8010479581135, 5379.35447746273, 5386.91919621206, 5402.864928376822, 4023.0, 4242.554531774181, 4909.116359055333, 4934.481879402611, 4981.683517164536, 2395.748446413136, 2545.630883521879, 2817.9295517612222, 2831.089014914924, 2847.3092927184025, 4562.931556026662, 5067.18771082053, 5461.15352120422, 5567.0, 5581.302337207849, 4633.513481084437, 4937.604991600663, 4977.40271167455, 5149.613778966593, 5557.861053473695, 4261.616454519108, 5126.974365128225, 5550.916736248879, 5592.86017849557, 5611.98984182851, 4730.881727956828, 4786.305985632133, 4798.712077275374, 5028.876321416404, 5089.338386009943, 4628.791704373379, 4959.0, 5069.377799104252, 5121.001404726043, 5334.839954801362, 5248.370195576403, 5416.8645783854145, 5554.694491802989, 5643.871225754783, 5840.153177789111, 3158.862809388664, 3162.525621156753, 3687.391580389313, 3688.7602305850032, 3782.867599634, 5132.255822905732, 5461.344638643388, 5567.220589117608, 5660.88678226425, 5690.516306113861, 5330.2804121443105, 5471.179323101566, 5518.220019286801, 5518.862028449321, 5536.252605898152, 5124.30821839047, 5134.5635620972635, 5480.931218412371, 5482.861683943345, 5502.559795216293, 5039.0, 5315.335583052119, 5361.892762144813, 5421.484958928895, 5425.620206585659, 4589.957468926295, 4849.418069831643, 5546.084895992116, 5574.609777315712, 5590.86022849432, 5415.0, 5425.593080518898, 5426.240326354235, 5451.64564303319, 5469.917951230815], "value": 3910.4748039424076, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.6154395854259, 710.978670639881, 748.6556184155288, 748.9775306740798, 771.9691212351506, 13770.24263665498, 14375.928120359396, 15696.843031569695, 16224.432144874932, 16234.269457874394, 14835.0, 14957.177355245458, 14958.177300248482, 14961.850381496182, 14967.401303947856, 647.0, 668.9030090636854, 780.7072347869534, 805.8630032894422, 812.045846130794, 649.9252585952605, 668.7057694614379, 808.3048578222724, 812.305478815613, 820.8358328334327, 857.7212405968054, 861.8491763941316, 863.2187869977649, 863.9179277968615, 865.7143142762905, 677.6814896998403, 855.6919508976763, 857.4683696108422, 857.7684025313131, 860.9397342186049, 520.4119345140006, 521.7730287325026, 521.9399769026572, 523.7041071794424, 617.8239201827503, 13958.162510249316, 14465.85534144666, 14514.056586321854, 14519.637009074751, 14531.200783956878, 12552.81170782439, 12671.176373535784, 12736.299503527302, 13028.869711302861, 13081.345932703327, 11816.940915295454, 12285.38573071343, 12315.199512031779, 12441.440135193938, 12744.61766147017, 4253.553376895412, 4373.846915357979, 4434.4678638563255, 4502.077074199781, 4547.226971414834, 96712.0, 96721.51639241759, 96954.98764053914, 98563.0, 98969.0, 9920.702378928643, 10176.694699159038, 10218.956208758278, 10299.485025748683, 10330.638427654996, 19493.0, 19607.411777646408, 19621.0, 19630.705539416922, 19642.508937276398, 7024.367806897404, 7027.0, 7032.331928466788, 7078.92921070793, 7272.127344718562, 4048.4534587830267, 4196.433481479961, 4236.639885609696, 4252.681048921343, 4274.380214868826, 3281.507773833944, 3390.0846771372085, 3391.8134502602593, 3392.084137282969, 3396.0, 673.1719984419192, 676.6921050921819, 707.7770502291762, 708.5394493579215, 714.7819914925998, 604.4167378479788, 653.4086651580327, 654.6988385342796, 663.2439019517753, 690.433844247712, 8338.499690018618, 8769.42998705095, 8839.425437346552, 9087.81824363509, 9322.0, 633.9666343859571, 637.4931929116271, 641.4804008752925, 643.6331291163991, 670.5641333133502, 3569.982150089233, 4365.80353884076, 4391.956080439219, 4427.158839820424, 4546.931796023062, 71619.0, 104480.0, 105527.4723626377, 107831.46084269529, 123626.38186809009, 85464.29071418536, 86588.0, 86605.56697216474, 93934.0, 95562.52218738862, 79555.60222198968, 83348.0, 85712.0, 86548.0, 87772.56113719646, 8368.288695460711, 8719.694810681596, 9525.285603579621, 9559.139677429064, 9600.471974041495, 55322.44677553334, 57241.0, 57242.7137864308, 58377.0, 59009.40990590211, 79172.0, 79861.0, 80888.59555702184, 81294.18705812866, 83571.0, 11756.412179390825, 12057.397130143283, 12090.153689241668, 12189.451474683838, 12531.498740050296, 12350.703176166733, 12430.502779889064, 12949.158304710163, 12985.480580776662, 13981.44074237019, 11685.415729213675, 12198.57304994321, 12919.677008074497, 13084.542041028519, 13159.0, 11260.88739112631, 11295.322280663186, 12084.335361555199, 12097.818532722193, 12568.245905245714, 11978.621356706308, 11979.640610781691, 12451.501939922302, 12608.432620532281, 12816.487340506648, 9990.850137248093, 10568.788624227625, 11133.498992545194, 11286.201022146352, 11524.827127592926, 9594.597949405204, 10037.146842518176, 11074.950886035593, 11177.105831533621, 11208.775824483302], "value": 7237.818739131797, "unit": "score"}], "name": "dromaeo_css", "value": 7049.465771562985, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 113.0, 114.0, 115.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 140.0, 139.0, 143.0, 115.0, 139.0, 141.0, 138.0, 140.0, 112.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 180.0, 185.0, 189.0, 182.0, 187.0, 235.0, 183.0, 181.0], "value": 189.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 59.0, 87.0, 51.0, 59.0, 83.0, 59.0, 82.0, 58.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 95.0, 71.0, 71.0, 70.0, 88.0, 91.0, 56.0, 93.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 84.0, 88.0, 83.0, 87.0, 83.0, 86.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 112.0, 92.0, 92.0, 93.0, 92.0, 92.0, 92.0, 92.0, 92.0], "value": 98.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 79.0, 82.0, 83.0, 88.0, 119.0, 85.0, 83.0, 81.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 57.0, 56.0, 55.0, 56.0, 57.0, 56.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 65.0, 75.0, 73.0, 73.0, 70.0, 70.0, 72.0, 71.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 84.0, 84.0, 86.0, 92.0, 84.0, 82.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 146.0, 144.0, 146.0, 145.0, 146.0, 151.0, 153.0, 211.0, 180.0], "value": 156.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 168.0, 164.0, 167.0, 167.0, 166.0, 167.0, 166.0, 164.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 69.0, 84.0, 85.0, 88.0, 77.0, 65.0, 65.0, 65.0], "value": 73.2, "unit": "score"}], "name": "kraken", "value": 1461.8, "alertThreshold": 2.0}]} 03:21:58 INFO - Return code: 0 03:21:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:21:58 ERROR - # TBPL SUCCESS # 03:21:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:21:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:21:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:21:58 INFO - 2016-11-18 03:21:58.263 screenresolution[1655:9818] starting screenresolution argv=screenresolution get 03:21:58 INFO - 2016-11-18 03:21:58.267 screenresolution[1655:9818] Display 0: 1600x1200x32@60 03:21:58 INFO - 2016-11-18 03:21:58.273 screenresolution[1656:9821] starting screenresolution argv=screenresolution list 03:21:58 INFO - Available Modes on Display 0 03:21:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:21:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:21:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:21:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:21:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:21:58 INFO - Intel Iris: 03:21:58 INFO - Chipset Model: Intel Iris 03:21:58 INFO - Type: GPU 03:21:58 INFO - Bus: Built-In 03:21:58 INFO - VRAM (Dynamic, Max): 1536 MB 03:21:58 INFO - Vendor: Intel (0x8086) 03:21:58 INFO - Device ID: 0x0a2e 03:21:58 INFO - Revision ID: 0x0009 03:21:58 INFO - Displays: 03:21:58 INFO - Display: 03:21:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:21:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:21:58 INFO - Display Serial Number: 1600x1200 60 03:21:58 INFO - Main Display: Yes 03:21:58 INFO - Mirror: Off 03:21:58 INFO - Online: Yes 03:21:58 INFO - Rotation: Supported 03:21:58 INFO - Return code: 0 03:21:58 INFO - Running post-action listener: _resource_record_post_action 03:21:58 INFO - [mozharness: 2016-11-18 11:21:58.515575Z] Finished run-tests step (success) 03:21:58 INFO - Running post-run listener: _resource_record_post_run 03:21:58 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 37614592; Write bytes: 656445952; Read time: 391; Write time: 2251 03:21:58 INFO - TinderboxPrint: CPU usage
53.5% 03:21:58 INFO - TinderboxPrint: I/O read bytes / time
37,614,592 / 391 03:21:58 INFO - TinderboxPrint: I/O write bytes / time
656,445,952 / 2,251 03:21:58 INFO - TinderboxPrint: CPU idle
2,253.1 (46.5%) 03:21:58 INFO - TinderboxPrint: CPU system
966.5 (20.0%) 03:21:58 INFO - TinderboxPrint: CPU user
1,622.1 (33.5%) 03:21:58 INFO - TinderboxPrint: Swap in / out
390,762,496 / 0 03:21:58 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 352657920; Write bytes: 348809216; Read time: 27847; Write time: 643 03:21:58 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 34456576; Write bytes: 295343104; Read time: 363; Write time: 1565 03:21:58 INFO - Running post-run listener: _upload_blobber_files 03:21:58 INFO - Blob upload gear active. 03:21:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:21:58 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 03:21:58 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'] 03:21:58 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 03:21:59 INFO - (blobuploader) - INFO - Open directory for files ... 03:21:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:21:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:22:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:22:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:22:00 INFO - (blobuploader) - INFO - Done attempting. 03:22:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:22:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:22:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:22:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:22:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:22:01 INFO - (blobuploader) - INFO - Done attempting. 03:22:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:22:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:22:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:22:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:22:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:22:03 INFO - (blobuploader) - INFO - Done attempting. 03:22:03 INFO - (blobuploader) - INFO - Iteration through files over. 03:22:03 INFO - Return code: 0 03:22:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:22:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:22:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/570c23352192a34461a647933cf90fbda8a017b52eb2b29d6a3e19fceb970639e88a07b23285543424336d6b9ddb51dbe7bc3c4b81992e5b7419b2cb52b0c411", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1eae48d3777c6afe3cefdae25e1b906f913d4c52aba67e9c6c4297ffaecc97dee96de2352a348684a735ee053b28be272bff170ac15bc6211f5b57fceccf5fa8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7bacea2924cbe8878a46e2b64a0ecaf5e4e323e01151088c11a015758935f366a8369df514ef7defec36072e5a318e97d119d7ca95086bc1689e8e4d89b7ef3c"} 03:22:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:22:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:22:03 INFO - Contents: 03:22:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/570c23352192a34461a647933cf90fbda8a017b52eb2b29d6a3e19fceb970639e88a07b23285543424336d6b9ddb51dbe7bc3c4b81992e5b7419b2cb52b0c411", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1eae48d3777c6afe3cefdae25e1b906f913d4c52aba67e9c6c4297ffaecc97dee96de2352a348684a735ee053b28be272bff170ac15bc6211f5b57fceccf5fa8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7bacea2924cbe8878a46e2b64a0ecaf5e4e323e01151088c11a015758935f366a8369df514ef7defec36072e5a318e97d119d7ca95086bc1689e8e4d89b7ef3c"} 03:22:03 INFO - Running post-run listener: copy_logs_to_upload_dir 03:22:03 INFO - Copying logs to upload dir... 03:22:03 INFO - mkdir: /builds/slave/test/build/upload/logs 03:22:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.253281 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-18 03:22:04.032094) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 03:22:04.035275) ========= 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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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/570c23352192a34461a647933cf90fbda8a017b52eb2b29d6a3e19fceb970639e88a07b23285543424336d6b9ddb51dbe7bc3c4b81992e5b7419b2cb52b0c411", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1eae48d3777c6afe3cefdae25e1b906f913d4c52aba67e9c6c4297ffaecc97dee96de2352a348684a735ee053b28be272bff170ac15bc6211f5b57fceccf5fa8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7bacea2924cbe8878a46e2b64a0ecaf5e4e323e01151088c11a015758935f366a8369df514ef7defec36072e5a318e97d119d7ca95086bc1689e8e4d89b7ef3c"} build_url:https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011042 build_url: 'https://queue.taskcluster.net/v1/task/KuJmiSoXQOu7yUB9ycnw6Q/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/570c23352192a34461a647933cf90fbda8a017b52eb2b29d6a3e19fceb970639e88a07b23285543424336d6b9ddb51dbe7bc3c4b81992e5b7419b2cb52b0c411", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1eae48d3777c6afe3cefdae25e1b906f913d4c52aba67e9c6c4297ffaecc97dee96de2352a348684a735ee053b28be272bff170ac15bc6211f5b57fceccf5fa8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7bacea2924cbe8878a46e2b64a0ecaf5e4e323e01151088c11a015758935f366a8369df514ef7defec36072e5a318e97d119d7ca95086bc1689e8e4d89b7ef3c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 03:22:04.067906) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 03:22:04.068293) ========= 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.1Bok6WMJQ7/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.oS1slgsJyv/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.004755 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 03:22:04.116604) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 03:22:04.116918) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 03:22:04.117239) ========= ========= Total master_lag: 0.17 =========