builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0191 starttime: 1488936573.67 results: success (0) buildid: 20170307162823 builduid: 31c6b45ef88b4098b62ad13cca7e1048 revision: dd92d0734a266dab534af530c8ae35670aedd282 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-07 17:29:33.670723) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-07 17:29:33.671161) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-07 17:29:33.671443) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-07 17:29:33.696600) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 17:29:33.696988) ========= 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.dphizwOYh6/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.X1JCCIk7fe/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-07 17:29:33-- 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% 6.88M=0.002s 2017-03-07 17:29:33 (6.88 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088691 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 17:29:33.809840) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 17:29:33.810176) ========= 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.dphizwOYh6/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.X1JCCIk7fe/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.024734 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 17:29:33.860120) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-07 17:29:33.860512) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dd92d0734a266dab534af530c8ae35670aedd282 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev dd92d0734a266dab534af530c8ae35670aedd282 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dphizwOYh6/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.X1JCCIk7fe/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-07 17:29:33,946 truncating revision to first 12 chars 2017-03-07 17:29:33,946 Setting DEBUG logging. 2017-03-07 17:29:33,946 attempt 1/10 2017-03-07 17:29:33,946 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dd92d0734a26?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-07 17:29:35,998 unpacking tar archive at: mozilla-central-dd92d0734a26/testing/mozharness/ program finished with exit code 0 elapsedTime=2.313754 ========= master_lag: 0.74 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-07 17:29:36.919069) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-07 17:29:36.919472) ========= script_repo_revision: dd92d0734a266dab534af530c8ae35670aedd282 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-07 17:29:36.919885) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-07 17:29:36.920160) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-07 17:29:36.939261) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-07 17:29:36.939547) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dphizwOYh6/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.X1JCCIk7fe/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:29:37 INFO - MultiFileLogger online at 20170307 17:29:37 in /builds/slave/test 17:29:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:29:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:29:37 INFO - {'append_to_log': False, 17:29:37 INFO - 'base_work_dir': '/builds/slave/test', 17:29:37 INFO - 'blob_upload_branch': 'Firefox', 17:29:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:29:37 INFO - 'branch': 'Firefox', 17:29:37 INFO - 'buildbot_json_path': 'buildprops.json', 17:29:37 INFO - 'config_files': ('talos/mac_config.py',), 17:29:37 INFO - 'default_actions': ('clobber', 17:29:37 INFO - 'read-buildbot-config', 17:29:37 INFO - 'download-and-extract', 17:29:37 INFO - 'populate-webroot', 17:29:37 INFO - 'create-virtualenv', 17:29:37 INFO - 'install', 17:29:37 INFO - 'run-tests'), 17:29:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:29:37 INFO - 'download_minidump_stackwalk': True, 17:29:37 INFO - 'download_symbols': 'ondemand', 17:29:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:29:37 INFO - 'tooltool.py': '/tools/tooltool.py', 17:29:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:29:37 INFO - '/tools/misc-python/virtualenv.py')}, 17:29:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:29:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:29:37 INFO - 'gecko_profile': False, 17:29:37 INFO - 'gecko_profile_interval': 0, 17:29:37 INFO - 'installer_path': 'installer.exe', 17:29:37 INFO - 'log_level': 'info', 17:29:37 INFO - 'log_name': 'talos', 17:29:37 INFO - 'log_to_console': True, 17:29:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:29:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:29:37 INFO - 'opt_config_files': (), 17:29:37 INFO - 'pip_index': False, 17:29:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:29:37 INFO - 'cmd': ('bash', 17:29:37 INFO - '-c', 17:29:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:29:37 INFO - 'enabled': True, 17:29:37 INFO - 'halt_on_failure': False, 17:29:37 INFO - 'name': 'check_screen_resolution'},), 17:29:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:29:37 INFO - 'cmd': ('bash', 17:29:37 INFO - '-c', 17:29:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:29:37 INFO - 'enabled': True, 17:29:37 INFO - 'halt_on_failure': False, 17:29:37 INFO - 'name': 'check_screen_resolution'},), 17:29:37 INFO - 'run_cmd_checks_enabled': True, 17:29:37 INFO - 'suite': 'dromaeojs-e10s', 17:29:37 INFO - 'system_bits': '32', 17:29:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:29:37 INFO - 'title': 't-yosemite-r7-0191', 17:29:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:29:37 INFO - 'use_talos_json': True, 17:29:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:29:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:29:37 INFO - 'work_dir': 'build'} 17:29:37 INFO - [mozharness: 2017-03-08 01:29:37.154772Z] Running clobber step. 17:29:37 INFO - Running pre-action listener: _resource_record_pre_action 17:29:37 INFO - Running main action method: clobber 17:29:37 INFO - rmtree: /builds/slave/test/build 17:29:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:29:39 INFO - Running post-action listener: _resource_record_post_action 17:29:39 INFO - [mozharness: 2017-03-08 01:29:39.003245Z] Finished clobber step (success) 17:29:39 INFO - [mozharness: 2017-03-08 01:29:39.003426Z] Running read-buildbot-config step. 17:29:39 INFO - Running pre-action listener: _resource_record_pre_action 17:29:39 INFO - Running main action method: read_buildbot_config 17:29:39 INFO - Using buildbot properties: 17:29:39 INFO - { 17:29:39 INFO - "project": "", 17:29:39 INFO - "product": "firefox", 17:29:39 INFO - "script_repo_revision": "production", 17:29:39 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:29:39 INFO - "repository": "", 17:29:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:29:39 INFO - "buildid": "20170307162823", 17:29:39 INFO - "pgo_build": "False", 17:29:39 INFO - "basedir": "/builds/slave/test", 17:29:39 INFO - "buildnumber": 103, 17:29:39 INFO - "slavename": "t-yosemite-r7-0191", 17:29:39 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 17:29:39 INFO - "platform": "yosemite_r7", 17:29:39 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:29:39 INFO - "branch": "mozilla-central", 17:29:39 INFO - "repo_path": "mozilla-central", 17:29:39 INFO - "revision": "dd92d0734a266dab534af530c8ae35670aedd282", 17:29:39 INFO - "stage_platform": "macosx64", 17:29:39 INFO - "builduid": "31c6b45ef88b4098b62ad13cca7e1048", 17:29:39 INFO - "slavebuilddir": "test" 17:29:39 INFO - } 17:29:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 17:29:39 INFO - Running post-action listener: _resource_record_post_action 17:29:39 INFO - [mozharness: 2017-03-08 01:29:39.006756Z] Finished read-buildbot-config step (success) 17:29:39 INFO - [mozharness: 2017-03-08 01:29:39.006864Z] Running download-and-extract step. 17:29:39 INFO - Running pre-action listener: _resource_record_pre_action 17:29:39 INFO - Running main action method: download_and_extract 17:29:39 INFO - mkdir: /builds/slave/test/build/tests 17:29:39 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')]} 17:29:39 INFO - trying https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:29:39 INFO - Downloading https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:29:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 17:29:40 INFO - Downloaded 1199 bytes. 17:29:40 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:29:40 INFO - Using the following test package requirements: 17:29:40 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:29:40 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:29:40 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:29:40 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:29:40 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:29:40 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:29:40 INFO - u'jsshell-mac.zip'], 17:29:40 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:29:40 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:29:40 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:29:40 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:29:40 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:29:40 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:29:40 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:29:40 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:29:40 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:29:40 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:29:40 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:29:40 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:29:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:29:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:29:40 INFO - Fetch https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:29:41 INFO - Expected file size: 51787693 17:29:41 INFO - Obtained file size: 51787693 17:29:57 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:29:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:29:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:29:57 INFO - Fetch https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:29:59 INFO - Expected file size: 51787693 17:29:59 INFO - Obtained file size: 51787693 17:30:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:30:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:30:14 INFO - Fetch https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:30:15 INFO - Expected file size: 14404424 17:30:15 INFO - Obtained file size: 14404424 17:30:16 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')]} 17:30:16 INFO - trying https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:30:16 INFO - Downloading https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:30:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:30:18 INFO - Downloaded 74914980 bytes. 17:30:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:30:18 INFO - mkdir: /builds/slave/test/properties 17:30:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:30:18 INFO - Writing to file /builds/slave/test/properties/build_url 17:30:18 INFO - Contents: 17:30:18 INFO - build_url:https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:30:19 INFO - Running post-action listener: _resource_record_post_action 17:30:19 INFO - Running post-action listener: set_extra_try_arguments 17:30:19 INFO - [mozharness: 2017-03-08 01:30:19.343239Z] Finished download-and-extract step (success) 17:30:19 INFO - [mozharness: 2017-03-08 01:30:19.343342Z] Running populate-webroot step. 17:30:19 INFO - Running pre-action listener: _resource_record_pre_action 17:30:19 INFO - Running main action method: populate_webroot 17:30:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:30:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:30:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:30:19 INFO - '--tppagecycles', 17:30:19 INFO - '7'], 17:30:19 INFO - 'tests': ['tsvgm']}}, 17:30:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:30:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:30:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:30:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:30:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:30:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:30:19 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:30:19 INFO - 'talos_options': ['--disable-e10s'], 17:30:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:30:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:30:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:30:19 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:30:19 INFO - 'talos_options': ['--disable-e10s'], 17:30:19 INFO - 'tests': ['damp', 'tps']}, 17:30:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:30:19 INFO - 'tests': ['damp', 'tps']}, 17:30:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:30:19 INFO - 'tests': ['dromaeo_dom']}, 17:30:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:30:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:30:19 INFO - 'tests': ['basic_compositor_video']}, 17:30:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:30:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:30:19 INFO - 'tests': ['a11yr', 17:30:19 INFO - 'ts_paint', 17:30:19 INFO - 'tpaint', 17:30:19 INFO - 'sessionrestore', 17:30:19 INFO - 'sessionrestore_no_auto_restore', 17:30:19 INFO - 'tabpaint']}, 17:30:19 INFO - 'other-e10s': {'tests': ['a11yr', 17:30:19 INFO - 'ts_paint', 17:30:19 INFO - 'tpaint', 17:30:19 INFO - 'sessionrestore', 17:30:19 INFO - 'sessionrestore_no_auto_restore', 17:30:19 INFO - 'tabpaint']}, 17:30:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:30:19 INFO - 'ts_paint', 17:30:19 INFO - 'tpaint', 17:30:19 INFO - 'sessionrestore', 17:30:19 INFO - 'sessionrestore_no_auto_restore', 17:30:19 INFO - 'tabpaint']}, 17:30:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:30:19 INFO - 'ts_paint', 17:30:19 INFO - 'tpaint', 17:30:19 INFO - 'sessionrestore', 17:30:19 INFO - 'sessionrestore_no_auto_restore', 17:30:19 INFO - 'tabpaint']}, 17:30:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:30:19 INFO - 'tests': ['a11yr', 17:30:19 INFO - 'ts_paint', 17:30:19 INFO - 'tpaint', 17:30:19 INFO - 'sessionrestore', 17:30:19 INFO - 'sessionrestore_no_auto_restore', 17:30:19 INFO - 'tabpaint']}, 17:30:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:30:19 INFO - 'tests': ['a11yr', 17:30:19 INFO - 'ts_paint', 17:30:19 INFO - 'tpaint', 17:30:19 INFO - 'sessionrestore', 17:30:19 INFO - 'sessionrestore_no_auto_restore', 17:30:19 INFO - 'tabpaint']}, 17:30:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:30:19 INFO - 'tests': ['tsvgx', 17:30:19 INFO - 'tsvgr_opacity', 17:30:19 INFO - 'tart', 17:30:19 INFO - 'tscrollx', 17:30:19 INFO - 'cart', 17:30:19 INFO - 'tsvg_static']}, 17:30:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:30:19 INFO - 'tsvgr_opacity', 17:30:19 INFO - 'tart', 17:30:19 INFO - 'tscrollx', 17:30:19 INFO - 'cart', 17:30:19 INFO - 'tsvg_static']}, 17:30:19 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:30:19 INFO - 'talos_options': ['--disable-e10s'], 17:30:19 INFO - 'tests': ['tp5o']}, 17:30:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:30:19 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:30:19 INFO - 'talos_options': ['--disable-e10s', 17:30:19 INFO - '--xperf_path', 17:30:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:30:19 INFO - 'tests': ['tp5n']}, 17:30:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:30:19 INFO - 'talos_options': ['--xperf_path', 17:30:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:30:19 INFO - 'tests': ['tp5n']}}, 17:30:19 INFO - 'talos.zip': {'path': '', 17:30:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:30:19 INFO - Running post-action listener: _resource_record_post_action 17:30:19 INFO - [mozharness: 2017-03-08 01:30:19.362107Z] Finished populate-webroot step (success) 17:30:19 INFO - [mozharness: 2017-03-08 01:30:19.362239Z] Running create-virtualenv step. 17:30:19 INFO - Running pre-action listener: _resource_record_pre_action 17:30:19 INFO - Running main action method: create_virtualenv 17:30:19 INFO - Creating virtualenv /builds/slave/test/build/venv 17:30:19 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 17:30:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:30:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:30:19 INFO - Using real prefix '/tools/python27' 17:30:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:30:20 INFO - Installing distribute.............................................................................................................................................................................................done. 17:30:23 INFO - Installing pip.................done. 17:30:23 INFO - Return code: 0 17:30:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:30:23 INFO - Reading from file tmpfile_stdout 17:30:23 INFO - Output received: 17:30:23 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) 17:30:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:23 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 0x105a5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f99573f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10583cd30>, '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 0x105763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105831be8>, '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.dphizwOYh6/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.X1JCCIk7fe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:30:23 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 17:30:23 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 17:30:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dphizwOYh6/Render', 17:30:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:30:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:30:23 INFO - 'HOME': '/Users/cltbld', 17:30:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:30:23 INFO - 'LOGNAME': 'cltbld', 17:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:23 INFO - 'MOZ_NO_REMOTE': '1', 17:30:23 INFO - 'NO_EM_RESTART': '1', 17:30:23 INFO - 'PAGER': '/bin/cat', 17:30:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:23 INFO - 'PWD': '/builds/slave/test', 17:30:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:30:23 INFO - 'SHELL': '/bin/bash', 17:30:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1JCCIk7fe/Listeners', 17:30:23 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:30:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:30:23 INFO - 'USER': 'cltbld', 17:30:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:30:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:23 INFO - 'XPC_FLAGS': '0x0', 17:30:23 INFO - 'XPC_SERVICE_NAME': '0', 17:30:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:23 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 17:30:23 INFO - Cleaning up... 17:30:23 INFO - Return code: 0 17:30:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:23 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 0x105a5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f99573f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10583cd30>, '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 0x105763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105831be8>, '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.dphizwOYh6/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.X1JCCIk7fe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:30:23 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 17:30:23 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 17:30:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dphizwOYh6/Render', 17:30:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:30:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:30:23 INFO - 'HOME': '/Users/cltbld', 17:30:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:30:23 INFO - 'LOGNAME': 'cltbld', 17:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:23 INFO - 'MOZ_NO_REMOTE': '1', 17:30:23 INFO - 'NO_EM_RESTART': '1', 17:30:23 INFO - 'PAGER': '/bin/cat', 17:30:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:23 INFO - 'PWD': '/builds/slave/test', 17:30:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:30:23 INFO - 'SHELL': '/bin/bash', 17:30:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1JCCIk7fe/Listeners', 17:30:23 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:30:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:30:23 INFO - 'USER': 'cltbld', 17:30:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:30:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:23 INFO - 'XPC_FLAGS': '0x0', 17:30:23 INFO - 'XPC_SERVICE_NAME': '0', 17:30:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:30:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:24 INFO - Downloading/unpacking psutil>=3.1.1 17:30: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 17:30: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 17:30: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 17:30: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 17:30: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 17:30: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 17:30:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:30:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:30:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:30:27 INFO - Installing collected packages: psutil 17:30:27 INFO - Running setup.py install for psutil 17:30:27 INFO - building 'psutil._psutil_osx' extension 17:30:27 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 17:30:27 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 17:30:28 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 17:30:28 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 17:30:28 INFO - building 'psutil._psutil_posix' extension 17:30:28 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 17:30:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:30:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:30:28 INFO - ^ 17:30:28 INFO - 1 warning generated. 17:30:28 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 17:30:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:30:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:30:28 INFO - Successfully installed psutil 17:30:28 INFO - Cleaning up... 17:30:28 INFO - Return code: 0 17:30:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:30:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:28 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 0x105a5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f99573f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10583cd30>, '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 0x105763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105831be8>, '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.dphizwOYh6/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.X1JCCIk7fe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:30:28 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 17:30:28 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 17:30:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dphizwOYh6/Render', 17:30:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:30:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:30:28 INFO - 'HOME': '/Users/cltbld', 17:30:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:30:28 INFO - 'LOGNAME': 'cltbld', 17:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:28 INFO - 'MOZ_NO_REMOTE': '1', 17:30:28 INFO - 'NO_EM_RESTART': '1', 17:30:28 INFO - 'PAGER': '/bin/cat', 17:30:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:28 INFO - 'PWD': '/builds/slave/test', 17:30:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:30:28 INFO - 'SHELL': '/bin/bash', 17:30:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1JCCIk7fe/Listeners', 17:30:28 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:30:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:30:28 INFO - 'USER': 'cltbld', 17:30:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:30:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:28 INFO - 'XPC_FLAGS': '0x0', 17:30:28 INFO - 'XPC_SERVICE_NAME': '0', 17:30:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:30:28 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 17:30:28 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 17:30:28 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 17:30:28 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 17:30:28 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 17:30:28 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 17:30:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:30:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:30:31 INFO - Installing collected packages: mozsystemmonitor 17:30:31 INFO - Running setup.py install for mozsystemmonitor 17:30:31 INFO - Successfully installed mozsystemmonitor 17:30:31 INFO - Cleaning up... 17:30:31 INFO - Return code: 0 17:30:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:30: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')]} 17:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30: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')]} 17:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:32 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 0x105a5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f99573f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10583cd30>, '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 0x105763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105831be8>, '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.dphizwOYh6/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.X1JCCIk7fe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:30:32 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 17:30:32 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 17:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dphizwOYh6/Render', 17:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:30:32 INFO - 'HOME': '/Users/cltbld', 17:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:30:32 INFO - 'LOGNAME': 'cltbld', 17:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:32 INFO - 'MOZ_NO_REMOTE': '1', 17:30:32 INFO - 'NO_EM_RESTART': '1', 17:30:32 INFO - 'PAGER': '/bin/cat', 17:30:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:32 INFO - 'PWD': '/builds/slave/test', 17:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:30:32 INFO - 'SHELL': '/bin/bash', 17:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1JCCIk7fe/Listeners', 17:30:32 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:30:32 INFO - 'USER': 'cltbld', 17:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:32 INFO - 'XPC_FLAGS': '0x0', 17:30:32 INFO - 'XPC_SERVICE_NAME': '0', 17:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:32 INFO - Downloading/unpacking jsonschema==2.5.1 17:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:30:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:30:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:30:35 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 17:30:35 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 17:30:35 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 17:30:35 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 17:30:35 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 17:30:35 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 17:30:35 INFO - Downloading functools32-3.2.3-2.tar.gz 17:30:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:30:35 INFO - warning: no files found matching '*.txt' 17:30:35 INFO - no previously-included directories found matching 'build' 17:30:35 INFO - no previously-included directories found matching 'dist' 17:30:35 INFO - no previously-included directories found matching '.git*' 17:30:35 INFO - Installing collected packages: jsonschema, functools32 17:30:35 INFO - Running setup.py install for functools32 17:30:35 INFO - warning: no files found matching '*.txt' 17:30:35 INFO - no previously-included directories found matching 'build' 17:30:35 INFO - no previously-included directories found matching 'dist' 17:30:35 INFO - no previously-included directories found matching '.git*' 17:30:35 INFO - Successfully installed jsonschema functools32 17:30:35 INFO - Cleaning up... 17:30:35 INFO - Return code: 0 17:30:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:30: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')]} 17:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30: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')]} 17:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:35 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 0x105a5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f99573f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10583cd30>, '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 0x105763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105831be8>, '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.dphizwOYh6/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.X1JCCIk7fe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:30:35 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 17:30:35 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 17:30:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dphizwOYh6/Render', 17:30:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:30:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:30:35 INFO - 'HOME': '/Users/cltbld', 17:30:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:30:35 INFO - 'LOGNAME': 'cltbld', 17:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:35 INFO - 'MOZ_NO_REMOTE': '1', 17:30:35 INFO - 'NO_EM_RESTART': '1', 17:30:35 INFO - 'PAGER': '/bin/cat', 17:30:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:35 INFO - 'PWD': '/builds/slave/test', 17:30:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:30:35 INFO - 'SHELL': '/bin/bash', 17:30:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1JCCIk7fe/Listeners', 17:30:35 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:30:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:30:35 INFO - 'USER': 'cltbld', 17:30:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:30:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:35 INFO - 'XPC_FLAGS': '0x0', 17:30:35 INFO - 'XPC_SERVICE_NAME': '0', 17:30:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:30:36 INFO - Cleaning up... 17:30:36 INFO - Return code: 0 17:30:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105a5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f99573f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10583cd30>, '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 0x105763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105831be8>, '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.dphizwOYh6/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.X1JCCIk7fe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:30:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:30:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:30:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dphizwOYh6/Render', 17:30:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:30:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:30:36 INFO - 'HOME': '/Users/cltbld', 17:30:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:30:36 INFO - 'LOGNAME': 'cltbld', 17:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:36 INFO - 'MOZ_NO_REMOTE': '1', 17:30:36 INFO - 'NO_EM_RESTART': '1', 17:30:36 INFO - 'PAGER': '/bin/cat', 17:30:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:36 INFO - 'PWD': '/builds/slave/test', 17:30:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:30:36 INFO - 'SHELL': '/bin/bash', 17:30:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1JCCIk7fe/Listeners', 17:30:36 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:30:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:30:36 INFO - 'USER': 'cltbld', 17:30:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:30:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:36 INFO - 'XPC_FLAGS': '0x0', 17:30:36 INFO - 'XPC_SERVICE_NAME': '0', 17:30:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:36 INFO - Downloading/unpacking blobuploader==1.2.4 17:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:30:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:39 INFO - Downloading blobuploader-1.2.4.tar.gz 17:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:30:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:30:39 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 17:30:39 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 17:30:39 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 17:30:39 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 17:30:39 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 17:30:39 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 17:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:30:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:30:39 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 17:30:39 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 17:30:39 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 17:30:39 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 17:30:39 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 17:30:39 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 17:30:40 INFO - Downloading docopt-0.6.1.tar.gz 17:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:30:40 INFO - Installing collected packages: blobuploader, requests, docopt 17:30:40 INFO - Running setup.py install for blobuploader 17:30:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:30:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:30:40 INFO - Running setup.py install for requests 17:30:41 INFO - Running setup.py install for docopt 17:30:41 INFO - Successfully installed blobuploader requests docopt 17:30:41 INFO - Cleaning up... 17:30:41 INFO - Return code: 0 17:30:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:41 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 0x105a5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f99573f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10583cd30>, '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 0x105763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105831be8>, '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.dphizwOYh6/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.X1JCCIk7fe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:30:41 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 17:30:41 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 17:30:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dphizwOYh6/Render', 17:30:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:30:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:30:41 INFO - 'HOME': '/Users/cltbld', 17:30:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:30:41 INFO - 'LOGNAME': 'cltbld', 17:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:41 INFO - 'MOZ_NO_REMOTE': '1', 17:30:41 INFO - 'NO_EM_RESTART': '1', 17:30:41 INFO - 'PAGER': '/bin/cat', 17:30:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:41 INFO - 'PWD': '/builds/slave/test', 17:30:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:30:41 INFO - 'SHELL': '/bin/bash', 17:30:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1JCCIk7fe/Listeners', 17:30:41 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:30:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:30:41 INFO - 'USER': 'cltbld', 17:30:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:30:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:41 INFO - 'XPC_FLAGS': '0x0', 17:30:41 INFO - 'XPC_SERVICE_NAME': '0', 17:30:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:41 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-NmwJO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:41 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-bn64eh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:41 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-jmppDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:41 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-os_1Ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:42 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-mFy5QN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:42 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-pnFLfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:42 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Rur1Xq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:42 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-nDJ02o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:42 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-45BiO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:42 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-hQdSlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:42 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-BjzBZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:42 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-_zu7Ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:42 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-bpbfo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:43 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-ss_mCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:43 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-8UNpjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:43 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-6azTFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:43 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Qfx12D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:30:43 INFO - Running setup.py install for manifestparser 17:30:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:30:43 INFO - Running setup.py install for mozcrash 17:30:43 INFO - Running setup.py install for mozdebug 17:30:43 INFO - Running setup.py install for mozdevice 17:30:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:30:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:30:44 INFO - Running setup.py install for mozfile 17:30:44 INFO - Running setup.py install for mozhttpd 17:30:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:30:44 INFO - Running setup.py install for mozinfo 17:30:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:30:44 INFO - Running setup.py install for mozInstall 17:30:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:30:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:30:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:30:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:30:44 INFO - Running setup.py install for mozleak 17:30:44 INFO - Running setup.py install for mozlog 17:30:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:30:44 INFO - Running setup.py install for moznetwork 17:30:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:30:45 INFO - Running setup.py install for mozprocess 17:30:45 INFO - Running setup.py install for mozprofile 17:30:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:30:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:30:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:30:45 INFO - Running setup.py install for mozrunner 17:30:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:30:45 INFO - Running setup.py install for mozscreenshot 17:30:45 INFO - Running setup.py install for moztest 17:30:45 INFO - Running setup.py install for mozversion 17:30:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:30:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:30:46 INFO - Cleaning up... 17:30:46 INFO - Return code: 0 17:30:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30: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')]} 17:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30: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')]} 17:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:46 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 0x105a5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f99573f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10583cd30>, '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 0x105763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105831be8>, '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.dphizwOYh6/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.X1JCCIk7fe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:30:46 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 17:30:46 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 17:30:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dphizwOYh6/Render', 17:30:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:30:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:30:46 INFO - 'HOME': '/Users/cltbld', 17:30:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:30:46 INFO - 'LOGNAME': 'cltbld', 17:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:46 INFO - 'MOZ_NO_REMOTE': '1', 17:30:46 INFO - 'NO_EM_RESTART': '1', 17:30:46 INFO - 'PAGER': '/bin/cat', 17:30:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:46 INFO - 'PWD': '/builds/slave/test', 17:30:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:30:46 INFO - 'SHELL': '/bin/bash', 17:30:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1JCCIk7fe/Listeners', 17:30:46 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:30:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:30:46 INFO - 'USER': 'cltbld', 17:30:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:30:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:46 INFO - 'XPC_FLAGS': '0x0', 17:30:46 INFO - 'XPC_SERVICE_NAME': '0', 17:30:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:46 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-4wfkxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:46 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)) 17:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:46 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-KCDGNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:46 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)) 17:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:46 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-cY8AHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:46 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)) 17:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:46 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-GzaBxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 17:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:46 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-wv0GiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:46 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)) 17:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:46 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-sN4Zgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:46 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)) 17:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:46 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-F3NNsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:47 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)) 17:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:47 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-1CkMZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:47 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)) 17:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:47 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-zYOSgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:47 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)) 17:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:47 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Njg9EE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:47 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)) 17:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:47 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-UpSw6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:47 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)) 17:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:47 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-a2HSGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 17:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:47 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-UGHyFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:47 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)) 17:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:47 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-JfLon0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:47 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)) 17:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:47 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-QSk5uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:47 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)) 17:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:47 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-3x7oTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:48 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-EWFDij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:48 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)) 17:30:48 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)) 17:30:48 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)) 17:30:48 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)) 17:30:48 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:30:48 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:30:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:30:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:50 INFO - Downloading blessings-1.6.tar.gz 17:30:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:30:50 INFO - Installing collected packages: blessings 17:30:50 INFO - Running setup.py install for blessings 17:30:51 INFO - Successfully installed blessings 17:30:51 INFO - Cleaning up... 17:30:51 INFO - Return code: 0 17:30:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:30:51 INFO - Reading from file tmpfile_stdout 17:30:51 INFO - Current package versions: 17:30:51 INFO - blessings == 1.6 17:30:51 INFO - blobuploader == 1.2.4 17:30:51 INFO - docopt == 0.6.1 17:30:51 INFO - functools32 == 3.2.3-2 17:30:51 INFO - jsonschema == 2.5.1 17:30:51 INFO - manifestparser == 1.1 17:30:51 INFO - mozInstall == 1.12 17:30:51 INFO - mozcrash == 1.0 17:30:51 INFO - mozdebug == 0.1 17:30:51 INFO - mozdevice == 0.49 17:30:51 INFO - mozfile == 1.2 17:30:51 INFO - mozhttpd == 0.7 17:30:51 INFO - mozinfo == 0.9 17:30:51 INFO - mozleak == 0.1 17:30:51 INFO - mozlog == 3.4 17:30:51 INFO - moznetwork == 0.27 17:30:51 INFO - mozprocess == 0.25 17:30:51 INFO - mozprofile == 0.28 17:30:51 INFO - mozrunner == 6.13 17:30:51 INFO - mozscreenshot == 0.1 17:30:51 INFO - mozsystemmonitor == 0.3 17:30:51 INFO - moztest == 0.8 17:30:51 INFO - mozversion == 1.4 17:30:51 INFO - psutil == 3.1.1 17:30:51 INFO - requests == 1.2.3 17:30:51 INFO - wsgiref == 0.1.2 17:30:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:51 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 0x105a5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f99573f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10583cd30>, '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 0x105763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105831be8>, '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.dphizwOYh6/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.X1JCCIk7fe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:30:51 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 17:30:51 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 17:30:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dphizwOYh6/Render', 17:30:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:30:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:30:51 INFO - 'HOME': '/Users/cltbld', 17:30:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:30:51 INFO - 'LOGNAME': 'cltbld', 17:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:51 INFO - 'MOZ_NO_REMOTE': '1', 17:30:51 INFO - 'NO_EM_RESTART': '1', 17:30:51 INFO - 'PAGER': '/bin/cat', 17:30:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:51 INFO - 'PWD': '/builds/slave/test', 17:30:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:30:51 INFO - 'SHELL': '/bin/bash', 17:30:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1JCCIk7fe/Listeners', 17:30:51 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:30:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:30:51 INFO - 'USER': 'cltbld', 17:30:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:30:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:51 INFO - 'XPC_FLAGS': '0x0', 17:30:51 INFO - 'XPC_SERVICE_NAME': '0', 17:30:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:51 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)) 17:30:51 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)) 17:30:51 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)) 17:30:51 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)) 17:30:51 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)) 17:30:51 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)) 17:30:51 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)) 17:30:51 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)) 17:30:51 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)) 17:30:51 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)) 17:30:51 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)) 17:30:51 INFO - Cleaning up... 17:30:51 INFO - Return code: 0 17:30:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:30:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105a5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f99573f50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10583cd30>, '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 0x105763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105831be8>, '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.dphizwOYh6/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.X1JCCIk7fe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 17:30:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:30:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dphizwOYh6/Render', 17:30:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:30:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:30:51 INFO - 'HOME': '/Users/cltbld', 17:30:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:30:51 INFO - 'LOGNAME': 'cltbld', 17:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:51 INFO - 'MOZ_NO_REMOTE': '1', 17:30:51 INFO - 'NO_EM_RESTART': '1', 17:30:51 INFO - 'PAGER': '/bin/cat', 17:30:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:51 INFO - 'PWD': '/builds/slave/test', 17:30:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:30:51 INFO - 'SHELL': '/bin/bash', 17:30:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1JCCIk7fe/Listeners', 17:30:51 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:30:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:30:51 INFO - 'USER': 'cltbld', 17:30:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:30:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:51 INFO - 'XPC_FLAGS': '0x0', 17:30:51 INFO - 'XPC_SERVICE_NAME': '0', 17:30:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:30:52 INFO - Cleaning up... 17:30:52 INFO - Return code: 0 17:30:52 INFO - Running post-action listener: _resource_record_post_action 17:30:52 INFO - Running post-action listener: _start_resource_monitoring 17:30:52 INFO - Starting resource monitoring. 17:30:52 INFO - [mozharness: 2017-03-08 01:30:52.076551Z] Finished create-virtualenv step (success) 17:30:52 INFO - [mozharness: 2017-03-08 01:30:52.077615Z] Running install step. 17:30:52 INFO - Running pre-action listener: _resource_record_pre_action 17:30:52 INFO - Running main action method: install 17:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:30:52 INFO - Reading from file tmpfile_stdout 17:30:52 INFO - Detecting whether we're running mozinstall >=1.0... 17:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:30:52 INFO - Reading from file tmpfile_stdout 17:30:52 INFO - Output received: 17:30:52 INFO - Usage: mozinstall [options] installer 17:30:52 INFO - Options: 17:30:52 INFO - -h, --help show this help message and exit 17:30:52 INFO - -d DEST, --destination=DEST 17:30:52 INFO - Directory to install application into. [default: 17:30:52 INFO - "/builds/slave/test"] 17:30:52 INFO - --app=APP Application being installed. [default: firefox] 17:30:52 INFO - mkdir: /builds/slave/test/build/application 17:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:31:12 INFO - Reading from file tmpfile_stdout 17:31:12 INFO - Output received: 17:31:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:31:12 INFO - Running post-action listener: _resource_record_post_action 17:31:12 INFO - [mozharness: 2017-03-08 01:31:12.618762Z] Finished install step (success) 17:31:12 INFO - [mozharness: 2017-03-08 01:31:12.618938Z] Running run-tests step. 17:31:12 INFO - Running pre-action listener: _resource_record_pre_action 17:31:12 INFO - Running main action method: run_tests 17:31:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:31:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:31:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:31:12 INFO - 2017-03-07 17:31:12.633 screenresolution[6764:134608] starting screenresolution argv=screenresolution get 17:31:12 INFO - 2017-03-07 17:31:12.637 screenresolution[6764:134608] Display 0: 1600x1200x32@60 17:31:12 INFO - 2017-03-07 17:31:12.643 screenresolution[6765:134614] starting screenresolution argv=screenresolution list 17:31:12 INFO - Available Modes on Display 0 17:31:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:31:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:31:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:31:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:31:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:31:12 INFO - Intel Iris: 17:31:12 INFO - Chipset Model: Intel Iris 17:31:12 INFO - Type: GPU 17:31:12 INFO - Bus: Built-In 17:31:12 INFO - VRAM (Dynamic, Max): 1536 MB 17:31:12 INFO - Vendor: Intel (0x8086) 17:31:12 INFO - Device ID: 0x0a2e 17:31:12 INFO - Revision ID: 0x0009 17:31:12 INFO - Displays: 17:31:12 INFO - Display: 17:31:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:31:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:31:12 INFO - Display Serial Number: 1600x1200 60 17:31:12 INFO - Main Display: Yes 17:31:12 INFO - Mirror: Off 17:31:12 INFO - Online: Yes 17:31:12 INFO - Rotation: Supported 17:31:12 INFO - Return code: 0 17:31:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:31:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:31:12 INFO - Python 2.7.3 17:31:12 INFO - Return code: 0 17:31:12 INFO - grabbing minidump binary from tooltool 17:31:12 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')]} 17:31:12 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 0x10583cd30>, '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 0x105763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105831be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:31:12 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 17:31:12 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 17:31:12 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 17:31:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:31:13 INFO - Return code: 0 17:31:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:31:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:31:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:31:13 INFO - ENV: RUST_BACKTRACE is now 1 17:31:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:31:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0191 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:31:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dphizwOYh6/Render', 17:31:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:31:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:31:13 INFO - 'HOME': '/Users/cltbld', 17:31:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:31:13 INFO - 'LOGNAME': 'cltbld', 17:31:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:31:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:13 INFO - 'MOZ_NO_REMOTE': '1', 17:31:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:31:13 INFO - 'NO_EM_RESTART': '1', 17:31:13 INFO - 'PAGER': '/bin/cat', 17:31:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:13 INFO - 'PWD': '/builds/slave/test', 17:31:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:31:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:31:13 INFO - 'RUST_BACKTRACE': '1', 17:31:13 INFO - 'SHELL': '/bin/bash', 17:31:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1JCCIk7fe/Listeners', 17:31:13 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 17:31:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:31:13 INFO - 'USER': 'cltbld', 17:31:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:31:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:13 INFO - 'XPC_FLAGS': '0x0', 17:31:13 INFO - 'XPC_SERVICE_NAME': '0', 17:31:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:31:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:31:13 INFO - mozversion application_buildid: 20170307162823 17:31:13 INFO - mozversion application_changeset: dd92d0734a266dab534af530c8ae35670aedd282 17:31:13 INFO - mozversion application_display_name: Nightly 17:31:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:31:13 INFO - mozversion application_name: Firefox 17:31:13 INFO - mozversion application_remotingname: firefox 17:31:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:31:13 INFO - mozversion application_vendor: Mozilla 17:31:13 INFO - mozversion application_version: 55.0a1 17:31:13 INFO - mozversion platform_buildid: 20170307162823 17:31:13 INFO - mozversion platform_changeset: dd92d0734a266dab534af530c8ae35670aedd282 17:31:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:31:13 INFO - mozversion platform_version: 55.0a1 17:31:13 INFO - using testdate: 1488936673 17:31:13 INFO - actual date: 1488936673 17:31:13 INFO - starting webserver on 'localhost:64189' 17:31:13 INFO - SUITE-START | Running 2 tests 17:31:13 INFO - TEST-START | dromaeo_css 17:31:13 INFO - operating with platform_type : mac_ 17:31:13 INFO - Initialising browser for dromaeo_css test... 17:31:13 INFO - TEST-INFO | started process 6775 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpby5HSF/profile http://localhost:64189/getInfo.html) 17:31:15 INFO - PROCESS | 6775 | 2017-03-07 17:31:15.921 plugin-container[6777:134788] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x914b, name = 'com.apple.tsm.portname' 17:31:15 INFO - PROCESS | 6775 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:31:15 INFO - PROCESS | 6775 | 2017-03-07 17:31:15.924 plugin-container[6777:134788] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960f, name = 'com.apple.CFPasteboardClient' 17:31:15 INFO - PROCESS | 6775 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:31:15 INFO - PROCESS | 6775 | 2017-03-07 17:31:15.924 plugin-container[6777:134788] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:31:15 INFO - PROCESS | 6775 | __metrics Screen width/height:1600/1200 17:31:15 INFO - PROCESS | 6775 | colorDepth:24 17:31:15 INFO - PROCESS | 6775 | Browser inner width/height: 1024/689 17:31:15 INFO - PROCESS | 6775 | __metrics 17:31:16 ERROR - PROCESS | 6775 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 17:31:16 INFO - TEST-INFO | 6775: exit 0 17:31:16 INFO - Browser initialized. 17:31:16 INFO - Running cycle 1/1 for dromaeo_css test... 17:31:16 INFO - TEST-INFO | started process 6778 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpby5HSF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:31:19 INFO - PROCESS | 6778 | 2017-03-07 17:31:19.479 plugin-container[6779:134918] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x894b, name = 'com.apple.tsm.portname' 17:31:19 INFO - PROCESS | 6778 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:31:19 INFO - PROCESS | 6778 | 2017-03-07 17:31:19.480 plugin-container[6779:134918] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 17:31:19 INFO - PROCESS | 6778 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:31:19 INFO - PROCESS | 6778 | 2017-03-07 17:31:19.480 plugin-container[6779:134918] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:31:29 INFO - PROCESS | 6778 | RSS: Main: 226897920 17:31:29 INFO - PROCESS | 6778 | 17:34:33 INFO - PROCESS | 6778 | Cycle 1(1): loaded http://localhost:64189/tests/dromaeo/cssquery-dojo.html (next: http://localhost:64189/tests/dromaeo/cssquery-ext.html) 17:34:34 INFO - PROCESS | 6778 | RSS: Main: 251097088 17:34:34 INFO - PROCESS | 6778 | 17:36:17 INFO - PROCESS | 6778 | ************************* 17:36:17 INFO - PROCESS | 6778 | A coding exception was thrown and uncaught in a Task. 17:36:17 INFO - PROCESS | 6778 | 17:36:17 ERROR - PROCESS | 6778 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:36:17 INFO - PROCESS | 6778 | Full stack: 17:36:17 INFO - PROCESS | 6778 | ************************* 17:37:37 INFO - PROCESS | 6778 | Cycle 1(1): loaded http://localhost:64189/tests/dromaeo/cssquery-ext.html (next: http://localhost:64189/tests/dromaeo/cssquery-jquery.html) 17:37:37 INFO - PROCESS | 6778 | RSS: Main: 258707456 17:37:37 INFO - PROCESS | 6778 | 17:40:39 INFO - PROCESS | 6778 | Cycle 1(1): loaded http://localhost:64189/tests/dromaeo/cssquery-jquery.html (next: http://localhost:64189/tests/dromaeo/cssquery-mootools.html) 17:40:40 INFO - PROCESS | 6778 | RSS: Main: 269873152 17:40:40 INFO - PROCESS | 6778 | 17:43:42 INFO - PROCESS | 6778 | Cycle 1(1): loaded http://localhost:64189/tests/dromaeo/cssquery-mootools.html (next: http://localhost:64189/tests/dromaeo/cssquery-prototype.html) 17:43:43 INFO - PROCESS | 6778 | RSS: Main: 262234112 17:43:43 INFO - PROCESS | 6778 | 17:46:46 INFO - PROCESS | 6778 | Cycle 1(1): loaded http://localhost:64189/tests/dromaeo/cssquery-prototype.html (next: http://localhost:64189/tests/dromaeo/cssquery-yui.html) 17:46:47 INFO - PROCESS | 6778 | RSS: Main: 272515072 17:46:47 INFO - PROCESS | 6778 | 17:49:50 INFO - PROCESS | 6778 | Cycle 1(1): loaded http://localhost:64189/tests/dromaeo/cssquery-yui.html (next: http://localhost:64189/tests/dromaeo/cssquery-dojo.html) 17:49:50 INFO - PROCESS | 6778 | RSS: Main: 266317824 17:49:50 INFO - PROCESS | 6778 | 17:49:50 INFO - PROCESS | 6778 | __start_tp_report 17:49:50 INFO - PROCESS | 6778 | _x_x_mozilla_page_load 17:49:50 INFO - PROCESS | 6778 | _x_x_mozilla_page_load_details 17:49:50 INFO - PROCESS | 6778 | |i|pagename|runs| 17:49:50 INFO - PROCESS | 6778 | |0;dojo.html;3377.1557110722306;3399.337129259794;3424.5205671206036;3429.365567370035;3446.9138271543225;5763.049096899017;5909.143174239733;5934.436228558291;5957.195778569891;6018.06719958406;5832.241808564881;5902.793402230923;5918.260217472816;5949.464548190662;5989.071693887444;4513.232750432418;5223.503767142115;5337.786488540453;5417.756200970946;5477.178423236526;5316.49493298138;5398.514133727964;5449.455054494539;5468.91796623051;5480.588955828309;4797.968436786107;4940.702573303395;5272.736363181825;5419.160030195326;5465.836024919259;5322.467753224685;5323.893522129536;5356.7857285708915;5360.839174824761;5410.594205434608;4084.530279017921;4857.53853383928;5304.92042619361;5322.973385133088;5356.544693701001;5380.381256155553;5678.6024978251535;5709.0580054290995;5817.418258174191;6014.0377539593455;4938.913439043405;5306.5754739621125;5552.972235138839;6249.875002499924;6273.62358258506;6381.8085457436355;6386.201724784402;6390.4248617624635;6398.29618741938;6400.743970241185;6081.695915204222;6086.969565152146;6131.47882429994;6133.3866613339205;6141.84645383869;4487.753173575485;5852;6152.861720581617;6348;6377.266614339317;5031.723255220924;6108.3280839107665;6212.4408803207925;6272.341404152589;6286.05709143623;6250.531210159256;6252.030935205051;6257.78097766576;6261.092141639437;6263.154474146023;6179.104029915637;6254;6259.405356491217;6271.874562508724;6279.246490421087;6117.449429551361;6136.8772624547255;6230.221222347207;6231.968840155861;6262.279837818709;4672.2991551266905;4736.218523943579;5064.924026139537;5112.616553758483;5196.896062078813;4592.15045216636;4599.954000460019;4604.493505714368;4608.5621865922685;4613.377194078824;6003.489703375175;6030.638161710312;6034.456898879122;6060.939390606126;6114.2357205349335;6188.628682279078;6221.377862213742;6222.315545290015;6287.937120628827;6333.68331583419;6068.089786532056;6071.9696401518595;6109.2974307955155;6111.816645500642;6118.449339559372;5631.436856314336;5636.4927156556105;5663.80176693822;5665.943340566624;5666;5264.842054738364;6153.538484613582;6432.388923052303;6533.085368048477;6539.149910511794;6206.10011548332;6208.379162083756;6282.057691346335;6376.458001069961;6389.393007664265;6437.839054023687;6481.449076828522;6491.350864913471;6494.350564943468;6500.967495162683;6154.230721159855;6234.971229747222;6283.057541368831;6290.119383286435;6312.4634406076;6224.128621992834;6230.50155987529;6237.469815065589;6255.030470277115;6290.056491526308;6211.596246243979;6256.468200203034;6263.530235232284;6267.96866015667;6281.089242059968;5981.371955944737;5988.401159883977;6065.363136870565;6113.266408031065;6220.0047992321015;5375.220593014069;5646.06839871433;5924.170616113834;6117.096718007379;6139.416755408229;5649.265595472562;5874.794382196603;6225.346338634559;6242.063690446469;6270.310265870659;6119.3268740437625;6179.443850053517;6199.659018753831;6203.131561581473;6210.844728881814 17:49:50 INFO - PROCESS | 6778 | |1;ext.html;710.8235869635752;768.6041688530404;779.4855395439012;801.1627848897903;949.0509490509495;31818.522722159185;37317.81341093294;38027.42958855616;39323;39668.20663586734;38004.23991520168;38252.42621360675;38297.808510957475;38368.61631383685;38383.04042398941;7756.844863102749;7922.088959769642;10462.895371046268;10700.143988480902;10870.076043536308;10429.530671119779;10531.420771857545;10532.631357902437;10537.683869483928;10565.630202942899;8786.472811631322;8898.955505222464;10488.056074953252;10557.73605659857;10573.735656608607;10460.372377657364;10489.527971241312;10513.421761803098;10514.474276286193;10520.316179448388;9786.706398808046;10132.24008199388;10345.948270258676;10486.895131048668;10572.99556542135;29201;30233.546496802566;32104.518432223536;34136.31727365463;34209.31581368384;3649.5073165122635;3680.3375392429352;3690.464882592036;3692.2523638506937;3700.796456194935;25074.997000119973;25177.748222517723;25262.621060684105;25357.239282821545;25799.613005804928;2965.4513914925806;3028.1218446650446;3041.3917216556556;3052.4047810676707;3056.6026416565705;328648;335122;335406.99999999756;335657;335885;43557.34663980043;43781;43917.560824391985;44115.338269925975;44170.895727607065;37630.62369376326;45160.41938532136;45822.31266531005;45984.770076150075;46097.7695111529;12692.936535317449;12792.360381980863;12869.613911582668;12905.677358066123;12923.15999460032;11018.834717479243;11026.614068507564;11029.503672334764;11035.227534072537;11041.447927603587;10140.340877842917;11849.69653338132;11863.406829658483;11877.990370818405;11988;5614.101743720985;5694.430556944272;5696.120280307488;5698.515626171739;5708.115242137475;981.6122631560636;1106.7343837478948;1133.0935251798528;1147.225622704671;1196.2882085159317;23869.403264918517;25668.74331256701;26466.80899359495;26678.733212668012;26697.33256668599;1130.1580322659672;1211.618340222827;1215.343714394235;1274.8597654258024;1281.6155153453926;13209.933950330187;13575.864241357658;13870.930645346709;13916.234607096707;13968.860311396958;445105;446024;450444;451621;452211;48156.759216203696;48466;55168.724156379765;55865;56056;1350.0414705559162;1372.2521225931891;1375.312343828086;1377.793331000358;1381.5026590427437;26692;26935.191944241706;26967.093018651372;27451.31371715723;27754.444911101262;39633;39726.40410393904;39764.00589985274;39858.800705996284;39929.40105898356;387260;407184.96407517773;429403;429511;430818;24351.878240609392;28578.285542861595;28875.42249154963;29708.108756737332;30843.920462783695;37708.62291377161;38360.616393835706;38583;39026;39358.60641393549;30489.390212196075;32134.87527936511;32276.031719048467;33347.83326083354;33348.83325583357;32198.517022245152;32269;33177.66822331746;33423.83288083544;33473;32432.837835811613;32432.837835811613;32444;32463.513047304783;32478.02565923026;25035;25893.223203303933;27385.45229095367;27607.447851043267;29318.120456386343;22680;22709.659355110016;22934;23007.309780706604;23174.188903388975 17:49:50 INFO - PROCESS | 6778 | |2;jquery.html;365.62888668001966;392.4153012012102;393.79719444486096;411.5575756062233;430.5135197227133;20084.09621567029;20478.976051197456;20871.269505567307;22016.669749953762;22090.88954555227;21762.347129586098;21887.452813679625;21994.230201942893;22236.444088897784;22392.21627243047;6519.934800652004;6819.318068193191;6872.244053154149;6879.587224766505;6882.759103431381;6481.254655714606;6567.573107747981;6587.538872278942;6640.701168447407;6670.6331151786635;6576.802695919107;6658;6666.333366663319;6677.1987361516685;6680.0981867448;6236.812895613139;6507.934920650804;6653.933460665404;6708.73165073394;6712.261651218362;6246.375362463763;6440.838979025515;6511.186101737283;6519.967400162969;6538.771143009972;24968.250952471273;25556.488870222678;25594.74405255942;25837.224883253344;25955.351116222057;2816.4930312543734;2826.4771017361854;2846.3168839478585;2851.9429611407863;2894.175160079388;13376.531821386205;15174.165420901845;15630.218489075502;15931.681366372724;16054.43809466663;2024.9190032398687;2076.532780124475;2121.225752600305;2121.31057406342;2121.512052227981;138169.61830381668;142320;149695;176700;179629;20290.391288261377;21453.570928581343;21468.926553672252;21597.840215978278;21651.891740541196;31974.680253197635;32021;32027;32183;32205.677943220267;9804.901950980542;9867.358621689711;10056.748581285527;10086.193104551618;10173.135283500986;8257.504549727035;8260.710875119343;8367.288780453608;8415.579221039046;8454.450460720034;8231.888695026095;8478.23695867375;9635.084666956627;9691.370060946016;9830.164436022875;3535.982320088383;4159.878840415053;4274.4443222381515;4305.74165550068;4306.397104405386;998.4608311511703;1027.6794319299663;1032.5456799008412;1059.163261023798;1068.8557044799009;19989.500262493264;20344.491387715425;20575.38273851787;20779.792202077784;20785.58428831415;1046.4872212615765;1050.9159267258601;1054.6625079974488;1088.3252383522267;1100.1803656276124;9498.335116541914;11002.559897604166;11048.558057677603;11074;11310.434478276053;694268;704124;704466;710102;721625;150469.24765376103;151745;153918.23040884724;155183.35807430206;157007;1240.3798100949525;1279.9872001280055;1307.20260641007;1310.6657802260554;1317.426919290112;14619.561413157622;19109.904450477658;20015.59968800645;20106.698399523728;20115;63492.73014539591;64335.678321608095;64374;64909;66437.33562664312;85955.57022214848;86672;87050.56474717586;88229.11770882466;89758.10241897759;9638.903610964082;11633.53465861356;11688.862498939663;11964;12116.878831211927;13532.797008045085;13566.86433135656;13790.793138102736;13964.092333998258;14125.717485650435;9816.411015339103;9916.454594997344;11664.94167529157;11906.464209110783;11937.820932685843;11161.051310638686;11588.536458541565;11650.88349116532;11723.41382930867;11913.463894124958;11628.709282267673;11803.763924721628;12058.336791476553;12069.818952715541;12140.02879769634;11652.35912024813;11742.530298787953;11943.761124777282;12008;12056.819147712968;11424;11637.650870473899;11943.462544185359;11976.640700778991;12012.519499220283 17:49:50 INFO - PROCESS | 6778 | |3;mootools.html;1528.577205129869;1536.9692606147871;1562.9296681649325;1578.1478001878986;1586.1514089961872;9103.271738260939;9354.485503297316;10238.795224095524;10761;10872.18458615605;9982.20142388609;10364.792704145932;10532.947335263332;10548.261621686484;10615.203859710515;2216.3350994701564;2262.4570103175283;2266.5240299537036;2266.9319920402413;2276.8633881967053;1939.9709004364945;2272.7954484096426;2306.6078766609635;2328.499372634883;2333.0084713996566;2306.411864974429;2313.375388645065;2319.130326127702;2333.8016268617102;2366.7751563601432;1790.274938649849;1969.2516843599483;2177.671620311609;2257.345369842744;2276.635738281875;1946.6787979983285;2244.674522194273;2245.2253972379503;2285.257291380308;2326.6161083421216;8901.287896968228;10638.521266543024;10727;10747.677569672922;10762.569497220102;4882.292067650206;5714.542836573064;5767.769289228426;5779.2775903012125;5791.5656325775735;2836.7872409569363;3297.6207736110414;3415.129142068781;3572.624874388204;3593.0119217215215;1140.1847678909537;1163.9941800291028;1213.8725433829497;1214.180428210954;1230.3048777440717;14873.925630371887;15030.699386012218;15044.172570508617;15048.924755376373;15078.924605377013;4727.361806156194;4917.754112294371;4919.0899683558655;4974.477679843637;5022.347094877642;12435.875641243652;12451;12495.93752031234;12502.74994500105;12536.686582835502;5771.59598828078;5813.476787089182;5888.263967004124;5912.497437717842;5912.852178695567;5042.571381432546;5079.492050794965;5125.56432703217;5142.51146141116;5165.74171291434;3929.233799409145;4039.8990025249605;4081.938695939077;4139.441175441277;4141.668666506672;1133.1897693149438;1133.8015847226702;1143.1483544759042;1150.1718762490993;1156.612534800851;728.3262981741973;811.5901469757853;894.9418287811402;905.3526728389234;933.3886304470583;6109;6345.206849143857;6349.714262858182;6458.773942912069;6463.385978332051;992.5878822804618;998.6005597760809;1004.542932965499;1010.8685870836891;1032.8140934631688;4014.8394064237687;4030.455888455021;4066.6949978751713;4110.547839737626;4159.147374788222;12769.61691149267;13026.023048271229;13181.60455186346;13202.73594528104;13219.008574356962;13009.089363744637;13076.738465230643;13083.41124649393;13083.476660933457;13091.41088651013;9637.37357071788;9694.079062489052;9827.557759900821;9907.00929907018;9960.452175130438;5085.720285384339;5947.167396564571;6173.104899789416;6195.4424101831055;6253.906191407042;11905.16663833558;12041.33772642513;12046.397680116135;12060.216085954737;12105.818412723993;11539.134564907497;11840.644780656594;11849.763004739685;12042.157049006486;12186.086043546593;8662.436941598777;8982.640694372152;9114.13415725505;9198.080191980749;9200.95399523025;10072.194224461879;10160.491975401053;10209;10215.336003159771;10236.897631023892;8401.285890699359;8782.956085219534;8883.866741999005;8916.821663566563;8962.596683149144;6828.9317106830285;8735.78160545966;8743.956280218557;8779.12208779117;8791.912080879109;7388.113426388868;7503.474756767192;7507.699692012477;7516.323530882247;7518.436117291334;5471.835844924659;5535.944640553704;5625.915611265917;5664.762418844616;5727.226824378656;5548.611597188319;5679.34687510925;5715.99521340254;5732.484076433141;5756.366799651951 17:49:50 INFO - PROCESS | 6778 | |4;prototype.html;359.57929222809315;371.840108753236;375.1970782525396;383.70646455461576;386.8356247694439;4217.852375166866;4306.461692288464;4739.9289010664825;4922.729249891255;5183.611229157814;4285.871423857281;4565.840195593155;4862.440819305781;4898.853034408965;5099.914551696226;3579.94630080549;3643.763155394904;4235.53409124996;4268.530461649231;4314.223439780838;3881.6312450317173;4221.387898754679;4285.949777460286;4308.870733877973;4309.26742453782;3457.94813077804;4196.223698615766;4279.159961216676;4297.892552686192;4346.521882592912;3715.9071023224637;3789.6399842015103;3808;3885.30064588374;4127.244207770914;3718.7025037997164;3832.42513622956;3892.703305050006;3914.823832927525;3971.721979461439;4170.35359519275;4186.623203911632;4486.967997360625;4828.875294870246;5053;4769.782051346106;4912.312276281324;4988.351514303154;5005.247114087238;5038.445770965191;4348.347747837818;4912.778924948386;5245.3020620462485;5511.118221084607;5557.44425557441;3891.241207964448;4250.234957707611;4389;4416.668749843775;4422.800973956179;4428.3800267962515;5339.385970613391;5474.616776825624;5831;5896.557758168155;4240.830366785294;4395.132068870717;4953.826616068421;4957.735777376746;4991.3511243538105;4559.270516717309;5043.36957880265;5093.770780314895;5154.871128221749;5157;5001.849204841953;5126.564242039469;5212.8175513857595;5235.3717553893775;5260.71066091361;4452.688311818207;4867.805287788448;5010.423801262828;5468.152436372368;5506.587005974568;1636.8281330460368;1684.1495045002441;1735.7743493345783;1753.3687872365938;1754.280744894587;1259.6207153167268;1293.9741205175844;1306.033535183976;1355.708522667631;1423.1745587559192;4583.977080114578;4650.86047418578;5120.180771076684;5170.586353091744;5305.681659100466;4840.540148685869;5067.771950262247;5684.744186511617;5785.978744772343;5863.501602363847;4641;4724.787384567703;4936.580390666833;4960.751962401865;4999.725015124203;2667.333166708323;2990.282332240247;2992.7905046646524;2993.5060714981782;3011.9849400752855;5151.892343146242;5392.595555333366;5485.875395543983;5588.804391846266;5637.928793529216;4923.630727695438;4981.42713587942;5516.662419236384;5774.249347584787;5782.855428614318;5281.630285879952;5663.858403539944;5870.8590903866225;5933.525317974551;5956.583039187302;4897.191963326011;5166.4058633257655;5719.628224165416;5777.768889244383;5825.650460972186;4725.692829966041;4877.853664390074;5143.8456846294675;5571.275734154696;5816.41835816415;4839.516048395132;4850.6362022847725;5612.747426365905;5836.883262334644;5855.297364316139;4152.626263636287;4164.979175104227;4508.047229064968;4522.977385113053;4527.524609915911;5069.670471419346;5347.839564813062;5441.80280338325;5614.550835933034;5618.775248989995;4686.367340409001;5384.703841288767;5515.806946756844;5568.498835104698;5627.368063261772;4602.65480088988;4810.687305325143;5368.033753924331;5530.281063461724;5545.667259964415;4584.793684284148;4880.95119048805;5085.669431486942;5336.493033161843;5493.807716729895;5241.266222728745;5309.8759696892685;5525.9447405527035;5546.833594992157;5591.720413979366;5020.623453240948;5345.850642111809;5383.084875571084;5387.218853266333;5471.425500322409 17:49:50 INFO - PROCESS | 6778 | |5;yui.html;703.1632357494578;721.7113154738104;722.9566226026438;725.858457600768;737.8966944627751;11404.600838970639;14962.32669529871;15691.84308156918;16147.354105835768;16180.919095404535;14743.852561474381;15078.09531428115;15148.318325675344;15224.923875380608;15281.235938203074;859.9785005374853;868.9348298877578;953.9856902146473;954.8376775948071;959.1176117971448;924.1267002682467;944.1031020530523;945.4469135555709;949.924006079512;965.6282331302464;858.8368210040104;1025.1952217509256;1027.1320733979776;1029.0481304793036;1031.4533297352377;1024.4519182237468;1024.7950409918046;1026.3995562595853;1027.244974943416;1028.100412139378;744.0922075068407;755.2447552447552;758.7913323835934;759.2521366454039;759.2938567132564;15862;16329.265183066791;16381.754273685905;16596.917015414965;16917.238724257437;11559.024273251429;13668.52160174399;13935.58193254204;13941.790873136912;13972.860271397258;11287.887121128766;11477.945331786628;11556;11769;12379.814302785375;2928.5021546337175;3543.7474634489317;3558.5195998540125;3624.58317293512;3634;96547;98073.5096324521;116288.41855790667;117852;118898;9242.399244049115;10368.948155259326;10780.353178809297;10791.83812242817;10887.292325998786;21638.134474621154;22016.88991555032;22271.443213919458;22278;22329.10683572639;5909.970450147808;7351.301626345488;7417.629118544051;7476.1776204617445;7662.693492260247;4134.545200027995;4148.771817550064;4229.873103806829;4231.344141658048;4263.829446822155;3441.0193094967813;3490.3542844573835;3491.790492570454;3523.2601153757487;3528.9647103528637;516.4886762105489;611.8317462697705;617.1113596421139;617.5337620096798;627.078195053271;642.4250295985161;684.5071548485083;687.1994126842172;691.837418206721;711.5410560188649;9440.858387124199;9676.661316853877;10132.34139780912;10138.596935859649;10395.376277423378;619.3589634728035;659.1595715462728;661.4179522020592;662.8110988368292;686.3513979289523;4573.382593349922;4870.318155458239;4911.901761964741;4912.606991440676;4926.753662316942;90842;98272;107540.46229768958;110175;110458.44770776095;60593;73532;77967.66097017098;81563.5921820399;84044;55831;87751.56124219594;87885.56057219673;89895.99999999869;90387.54806225927;10067;10298;10304.896951030394;11019.449027548751;11145.442727863736;38223.80888095653;47241.4499692621;52298.477015229364;52583.47416525786;53929;49370.259446107615;65053;65723;65785.35536611623;65793;10188.745281367728;12297.508099675913;13614.659633509242;13891;13901.930490347484;14144.504942326968;14282.785758213427;14345;14715.264236787903;14828.629284267548;9616.567254473706;12951.352432378531;13278.668033299246;13822.723545528832;13986.860131398962;10648.04647727047;13363.39864706071;13484.258365789976;13528.797068044185;14014.808741257108;12812.295323757282;13110.803337950128;13193.868061319265;13552.661183470098;13697.931510342385;8063.636273469264;8981.416207946462;9370.062993700576;10586.735331616772;10765.977232162932;10095.89904100979;10383.657397970634;10873.945630272114;10999.945000274947;11004.559817607525 17:49:50 INFO - PROCESS | 6778 | __end_tp_report 17:49:50 INFO - PROCESS | 6778 | __start_cc_report 17:49:50 INFO - PROCESS | 6778 | _x_x_mozilla_cycle_collect,438 17:49:50 INFO - PROCESS | 6778 | __end_cc_report 17:49:50 INFO - PROCESS | 6778 | __startTimestamp1488937790696__endTimestamp 17:49:50 INFO - PROCESS | 6778 | 17:49:50 INFO - PROCESS | 6778 | ------- Summary: start ------- 17:49:50 INFO - PROCESS | 6778 | Number of tests: 6 17:49:50 INFO - PROCESS | 6778 | 17:49:50 INFO - PROCESS | 6778 | [#0] dojo.html Cycles:165 Average:5796.32 Median:6086.97 stddev:670.81 (11.0%) stddev-sans-first:645.46 17:49:50 INFO - PROCESS | 6778 | Values: 3377.2 3399.3 3424.5 3429.4 3446.9 5763.0 5909.1 5934.4 5957.2 6018.1 5832.2 5902.8 5918.3 5949.5 5989.1 4513.2 5223.5 5337.8 5417.8 5477.2 5316.5 5398.5 5449.5 5468.9 5480.6 4798.0 4940.7 5272.7 5419.2 5465.8 5322.5 5323.9 5356.8 5360.8 5410.6 4084.5 4857.5 5304.9 5323.0 5356.5 5380.4 5678.6 5709.1 5817.4 6014.0 4938.9 5306.6 5553.0 6249.9 6273.6 6381.8 6386.2 6390.4 6398.3 6400.7 6081.7 6087.0 6131.5 6133.4 6141.8 4487.8 5852.0 6152.9 6348.0 6377.3 5031.7 6108.3 6212.4 6272.3 6286.1 6250.5 6252.0 6257.8 6261.1 6263.2 6179.1 6254.0 6259.4 6271.9 6279.2 6117.4 6136.9 6230.2 6232.0 6262.3 4672.3 4736.2 5064.9 5112.6 5196.9 4592.2 4600.0 4604.5 4608.6 4613.4 6003.5 6030.6 6034.5 6060.9 6114.2 6188.6 6221.4 6222.3 6287.9 6333.7 6068.1 6072.0 6109.3 6111.8 6118.4 5631.4 5636.5 5663.8 5665.9 5666.0 5264.8 6153.5 6432.4 6533.1 6539.1 6206.1 6208.4 6282.1 6376.5 6389.4 6437.8 6481.4 6491.4 6494.4 6501.0 6154.2 6235.0 6283.1 6290.1 6312.5 6224.1 6230.5 6237.5 6255.0 6290.1 6211.6 6256.5 6263.5 6268.0 6281.1 5981.4 5988.4 6065.4 6113.3 6220.0 5375.2 5646.1 5924.2 6117.1 6139.4 5649.3 5874.8 6225.3 6242.1 6270.3 6119.3 6179.4 6199.7 6203.1 6210.8 17:49:50 INFO - PROCESS | 6778 | 17:49:50 INFO - PROCESS | 6778 | [#1] ext.html Cycles:180 Average:52719.10 Median:16950.26 stddev:106946.89 (630.9%) stddev-sans-first:107175.62 17:49:50 INFO - PROCESS | 6778 | Values: 710.8 768.6 779.5 801.2 949.1 31818.5 37317.8 38027.4 39323.0 39668.2 38004.2 38252.4 38297.8 38368.6 38383.0 7756.8 7922.1 10462.9 10700.1 10870.1 10429.5 10531.4 10532.6 10537.7 10565.6 8786.5 8899.0 10488.1 10557.7 10573.7 10460.4 10489.5 10513.4 10514.5 10520.3 9786.7 10132.2 10345.9 10486.9 10573.0 29201.0 30233.5 32104.5 34136.3 34209.3 3649.5 3680.3 3690.5 3692.3 3700.8 25075.0 25177.7 25262.6 25357.2 25799.6 2965.5 3028.1 3041.4 3052.4 3056.6 328648.0 335122.0 335407.0 335657.0 335885.0 43557.3 43781.0 43917.6 44115.3 44170.9 37630.6 45160.4 45822.3 45984.8 46097.8 12692.9 12792.4 12869.6 12905.7 12923.2 11018.8 11026.6 11029.5 11035.2 11041.4 10140.3 11849.7 11863.4 11878.0 11988.0 5614.1 5694.4 5696.1 5698.5 5708.1 981.6 1106.7 1133.1 1147.2 1196.3 23869.4 25668.7 26466.8 26678.7 26697.3 1130.2 1211.6 1215.3 1274.9 1281.6 13209.9 13575.9 13870.9 13916.2 13968.9 445105.0 446024.0 450444.0 451621.0 452211.0 48156.8 48466.0 55168.7 55865.0 56056.0 1350.0 1372.3 1375.3 1377.8 1381.5 26692.0 26935.2 26967.1 27451.3 27754.4 39633.0 39726.4 39764.0 39858.8 39929.4 387260.0 407185.0 429403.0 429511.0 430818.0 24351.9 28578.3 28875.4 29708.1 30843.9 37708.6 38360.6 38583.0 39026.0 39358.6 30489.4 32134.9 32276.0 33347.8 33348.8 32198.5 32269.0 33177.7 33423.8 33473.0 32432.8 32432.8 32444.0 32463.5 32478.0 25035.0 25893.2 27385.5 27607.4 29318.1 22680.0 22709.7 22934.0 23007.3 23174.2 17:49:50 INFO - PROCESS | 6778 | 17:49:50 INFO - PROCESS | 6778 | [#2] jquery.html Cycles:180 Average:42068.37 Median:21707.12 stddev:118454.48 (545.7%) stddev-sans-first:118745.39 17:49:50 INFO - PROCESS | 6778 | Values: 365.6 392.4 393.8 411.6 430.5 20084.1 20479.0 20871.3 22016.7 22090.9 21762.3 21887.5 21994.2 22236.4 22392.2 6519.9 6819.3 6872.2 6879.6 6882.8 6481.3 6567.6 6587.5 6640.7 6670.6 6576.8 6658.0 6666.3 6677.2 6680.1 6236.8 6507.9 6653.9 6708.7 6712.3 6246.4 6440.8 6511.2 6520.0 6538.8 24968.3 25556.5 25594.7 25837.2 25955.4 2816.5 2826.5 2846.3 2851.9 2894.2 13376.5 15174.2 15630.2 15931.7 16054.4 2024.9 2076.5 2121.2 2121.3 2121.5 138169.6 142320.0 149695.0 176700.0 179629.0 20290.4 21453.6 21468.9 21597.8 21651.9 31974.7 32021.0 32027.0 32183.0 32205.7 9804.9 9867.4 10056.7 10086.2 10173.1 8257.5 8260.7 8367.3 8415.6 8454.5 8231.9 8478.2 9635.1 9691.4 9830.2 3536.0 4159.9 4274.4 4305.7 4306.4 998.5 1027.7 1032.5 1059.2 1068.9 19989.5 20344.5 20575.4 20779.8 20785.6 1046.5 1050.9 1054.7 1088.3 1100.2 9498.3 11002.6 11048.6 11074.0 11310.4 694268.0 704124.0 704466.0 710102.0 721625.0 150469.2 151745.0 153918.2 155183.4 157007.0 1240.4 1280.0 1307.2 1310.7 1317.4 14619.6 19109.9 20015.6 20106.7 20115.0 63492.7 64335.7 64374.0 64909.0 66437.3 85955.6 86672.0 87050.6 88229.1 89758.1 9638.9 11633.5 11688.9 11964.0 12116.9 13532.8 13566.9 13790.8 13964.1 14125.7 9816.4 9916.5 11664.9 11906.5 11937.8 11161.1 11588.5 11650.9 11723.4 11913.5 11628.7 11803.8 12058.3 12069.8 12140.0 11652.4 11742.5 11943.8 12008.0 12056.8 11424.0 11637.7 11943.5 11976.6 12012.5 17:49:50 INFO - PROCESS | 6778 | 17:49:50 INFO - PROCESS | 6778 | [#3] mootools.html Cycles:180 Average:6541.02 Median:3356.37 stddev:4108.30 (122.4%) stddev-sans-first:4102.56 17:49:50 INFO - PROCESS | 6778 | Values: 1528.6 1537.0 1562.9 1578.1 1586.2 9103.3 9354.5 10238.8 10761.0 10872.2 9982.2 10364.8 10532.9 10548.3 10615.2 2216.3 2262.5 2266.5 2266.9 2276.9 1940.0 2272.8 2306.6 2328.5 2333.0 2306.4 2313.4 2319.1 2333.8 2366.8 1790.3 1969.3 2177.7 2257.3 2276.6 1946.7 2244.7 2245.2 2285.3 2326.6 8901.3 10638.5 10727.0 10747.7 10762.6 4882.3 5714.5 5767.8 5779.3 5791.6 2836.8 3297.6 3415.1 3572.6 3593.0 1140.2 1164.0 1213.9 1214.2 1230.3 14873.9 15030.7 15044.2 15048.9 15078.9 4727.4 4917.8 4919.1 4974.5 5022.3 12435.9 12451.0 12495.9 12502.7 12536.7 5771.6 5813.5 5888.3 5912.5 5912.9 5042.6 5079.5 5125.6 5142.5 5165.7 3929.2 4039.9 4081.9 4139.4 4141.7 1133.2 1133.8 1143.1 1150.2 1156.6 728.3 811.6 894.9 905.4 933.4 6109.0 6345.2 6349.7 6458.8 6463.4 992.6 998.6 1004.5 1010.9 1032.8 4014.8 4030.5 4066.7 4110.5 4159.1 12769.6 13026.0 13181.6 13202.7 13219.0 13009.1 13076.7 13083.4 13083.5 13091.4 9637.4 9694.1 9827.6 9907.0 9960.5 5085.7 5947.2 6173.1 6195.4 6253.9 11905.2 12041.3 12046.4 12060.2 12105.8 11539.1 11840.6 11849.8 12042.2 12186.1 8662.4 8982.6 9114.1 9198.1 9201.0 10072.2 10160.5 10209.0 10215.3 10236.9 8401.3 8783.0 8883.9 8916.8 8962.6 6828.9 8735.8 8744.0 8779.1 8791.9 7388.1 7503.5 7507.7 7516.3 7518.4 5471.8 5535.9 5625.9 5664.8 5727.2 5548.6 5679.3 5716.0 5732.5 5756.4 17:49:50 INFO - PROCESS | 6778 | 17:49:50 INFO - PROCESS | 6778 | [#4] prototype.html Cycles:180 Average:4559.77 Median:4917.75 stddev:1236.68 (25.1%) stddev-sans-first:1199.30 17:49:50 INFO - PROCESS | 6778 | Values: 359.6 371.8 375.2 383.7 386.8 4217.9 4306.5 4739.9 4922.7 5183.6 4285.9 4565.8 4862.4 4898.9 5099.9 3579.9 3643.8 4235.5 4268.5 4314.2 3881.6 4221.4 4285.9 4308.9 4309.3 3457.9 4196.2 4279.2 4297.9 4346.5 3715.9 3789.6 3808.0 3885.3 4127.2 3718.7 3832.4 3892.7 3914.8 3971.7 4170.4 4186.6 4487.0 4828.9 5053.0 4769.8 4912.3 4988.4 5005.2 5038.4 4348.3 4912.8 5245.3 5511.1 5557.4 3891.2 4250.2 4389.0 4416.7 4422.8 4428.4 5339.4 5474.6 5831.0 5896.6 4240.8 4395.1 4953.8 4957.7 4991.4 4559.3 5043.4 5093.8 5154.9 5157.0 5001.8 5126.6 5212.8 5235.4 5260.7 4452.7 4867.8 5010.4 5468.2 5506.6 1636.8 1684.1 1735.8 1753.4 1754.3 1259.6 1294.0 1306.0 1355.7 1423.2 4584.0 4650.9 5120.2 5170.6 5305.7 4840.5 5067.8 5684.7 5786.0 5863.5 4641.0 4724.8 4936.6 4960.8 4999.7 2667.3 2990.3 2992.8 2993.5 3012.0 5151.9 5392.6 5485.9 5588.8 5637.9 4923.6 4981.4 5516.7 5774.2 5782.9 5281.6 5663.9 5870.9 5933.5 5956.6 4897.2 5166.4 5719.6 5777.8 5825.7 4725.7 4877.9 5143.8 5571.3 5816.4 4839.5 4850.6 5612.7 5836.9 5855.3 4152.6 4165.0 4508.0 4523.0 4527.5 5069.7 5347.8 5441.8 5614.6 5618.8 4686.4 5384.7 5515.8 5568.5 5627.4 4602.7 4810.7 5368.0 5530.3 5545.7 4584.8 4881.0 5085.7 5336.5 5493.8 5241.3 5309.9 5525.9 5546.8 5591.7 5020.6 5345.9 5383.1 5387.2 5471.4 17:49:50 INFO - PROCESS | 6778 | 17:49:50 INFO - PROCESS | 6778 | [#5] yui.html Cycles:180 Average:20099.00 Median:3491.07 stddev:29171.22 (835.6%) stddev-sans-first:29216.69 17:49:50 INFO - PROCESS | 6778 | Values: 703.2 721.7 723.0 725.9 737.9 11404.6 14962.3 15691.8 16147.4 16180.9 14743.9 15078.1 15148.3 15224.9 15281.2 860.0 868.9 954.0 954.8 959.1 924.1 944.1 945.4 949.9 965.6 858.8 1025.2 1027.1 1029.0 1031.5 1024.5 1024.8 1026.4 1027.2 1028.1 744.1 755.2 758.8 759.3 759.3 15862.0 16329.3 16381.8 16596.9 16917.2 11559.0 13668.5 13935.6 13941.8 13972.9 11287.9 11477.9 11556.0 11769.0 12379.8 2928.5 3543.7 3558.5 3624.6 3634.0 96547.0 98073.5 116288.4 117852.0 118898.0 9242.4 10368.9 10780.4 10791.8 10887.3 21638.1 22016.9 22271.4 22278.0 22329.1 5910.0 7351.3 7417.6 7476.2 7662.7 4134.5 4148.8 4229.9 4231.3 4263.8 3441.0 3490.4 3491.8 3523.3 3529.0 516.5 611.8 617.1 617.5 627.1 642.4 684.5 687.2 691.8 711.5 9440.9 9676.7 10132.3 10138.6 10395.4 619.4 659.2 661.4 662.8 686.4 4573.4 4870.3 4911.9 4912.6 4926.8 90842.0 98272.0 107540.5 110175.0 110458.4 60593.0 73532.0 77967.7 81563.6 84044.0 55831.0 87751.6 87885.6 89896.0 90387.5 10067.0 10298.0 10304.9 11019.4 11145.4 38223.8 47241.4 52298.5 52583.5 53929.0 49370.3 65053.0 65723.0 65785.4 65793.0 10188.7 12297.5 13614.7 13891.0 13901.9 14144.5 14282.8 14345.0 14715.3 14828.6 9616.6 12951.4 13278.7 13822.7 13986.9 10648.0 13363.4 13484.3 13528.8 14014.8 12812.3 13110.8 13193.9 13552.7 13697.9 8063.6 8981.4 9370.1 10586.7 10766.0 10095.9 10383.7 10873.9 10999.9 11004.6 17:49:50 INFO - PROCESS | 6778 | -------- Summary: end -------- 17:49:50 INFO - PROCESS | 6778 | 17:49:51 INFO - TEST-INFO | 6778: exit 0 17:49:51 INFO - TEST-OK | dromaeo_css | took 1118155ms 17:49:51 INFO - TEST-START | kraken 17:49:51 INFO - operating with platform_type : mac_ 17:49:51 INFO - Initialising browser for kraken test... 17:49:51 INFO - TEST-INFO | started process 6782 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpVSL7BV/profile http://localhost:64189/getInfo.html) 17:49:54 INFO - PROCESS | 6782 | 2017-03-07 17:49:54.144 plugin-container[6783:137057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 17:49:54 INFO - PROCESS | 6782 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:49:54 INFO - PROCESS | 6782 | 2017-03-07 17:49:54.149 plugin-container[6783:137057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f1f, name = 'com.apple.CFPasteboardClient' 17:49:54 INFO - PROCESS | 6782 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:49:54 INFO - PROCESS | 6782 | 2017-03-07 17:49:54.150 plugin-container[6783:137057] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:49:54 INFO - PROCESS | 6782 | __metrics Screen width/height:1600/1200 17:49:54 INFO - PROCESS | 6782 | colorDepth:24 17:49:54 INFO - PROCESS | 6782 | Browser inner width/height: 1024/689 17:49:54 INFO - PROCESS | 6782 | __metrics 17:49:54 ERROR - PROCESS | 6782 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 17:49:55 INFO - TEST-INFO | 6782: exit 0 17:49:55 INFO - Browser initialized. 17:49:55 INFO - Running cycle 1/1 for kraken test... 17:49:55 INFO - TEST-INFO | started process 6784 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpVSL7BV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:49:57 INFO - PROCESS | 6784 | 2017-03-07 17:49:57.594 plugin-container[6785:137183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473b, name = 'com.apple.tsm.portname' 17:49:57 INFO - PROCESS | 6784 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:49:57 INFO - PROCESS | 6784 | 2017-03-07 17:49:57.595 plugin-container[6785:137183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 17:49:57 INFO - PROCESS | 6784 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:49:57 INFO - PROCESS | 6784 | 2017-03-07 17:49:57.595 plugin-container[6785:137183] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:50:07 INFO - PROCESS | 6784 | RSS: Main: 228106240 17:50:07 INFO - PROCESS | 6784 | 17:50:10 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:64189/tests/kraken/driver.html?testName=audio-beat-detection) 17:50:11 INFO - PROCESS | 6784 | RSS: Main: 241184768 17:50:11 INFO - PROCESS | 6784 | 17:50:14 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:64189/tests/kraken/driver.html?testName=audio-dft) 17:50:14 INFO - PROCESS | 6784 | RSS: Main: 249700352 17:50:14 INFO - PROCESS | 6784 | 17:50:18 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:64189/tests/kraken/driver.html?testName=audio-fft) 17:50:18 INFO - PROCESS | 6784 | RSS: Main: 252215296 17:50:18 INFO - PROCESS | 6784 | 17:50:20 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:64189/tests/kraken/driver.html?testName=audio-oscillator) 17:50:21 INFO - PROCESS | 6784 | RSS: Main: 254451712 17:50:21 INFO - PROCESS | 6784 | 17:50:23 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:64189/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:50:24 INFO - PROCESS | 6784 | RSS: Main: 255152128 17:50:24 INFO - PROCESS | 6784 | 17:50:27 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:64189/tests/kraken/driver.html?testName=imaging-darkroom) 17:50:28 INFO - PROCESS | 6784 | RSS: Main: 261050368 17:50:28 INFO - PROCESS | 6784 | 17:50:31 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:64189/tests/kraken/driver.html?testName=imaging-desaturate) 17:50:32 INFO - PROCESS | 6784 | RSS: Main: 258674688 17:50:32 INFO - PROCESS | 6784 | 17:50:35 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:64189/tests/kraken/driver.html?testName=json-parse-financial) 17:50:36 INFO - PROCESS | 6784 | RSS: Main: 259743744 17:50:36 INFO - PROCESS | 6784 | 17:50:37 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:64189/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:50:38 INFO - PROCESS | 6784 | RSS: Main: 260247552 17:50:38 INFO - PROCESS | 6784 | 17:50:40 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:64189/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:50:41 INFO - PROCESS | 6784 | RSS: Main: 260186112 17:50:41 INFO - PROCESS | 6784 | 17:50:43 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:64189/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:50:43 INFO - PROCESS | 6784 | RSS: Main: 260268032 17:50:43 INFO - PROCESS | 6784 | 17:50:47 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:64189/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:50:47 INFO - PROCESS | 6784 | RSS: Main: 260714496 17:50:47 INFO - PROCESS | 6784 | 17:50:50 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:64189/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:50:51 INFO - PROCESS | 6784 | RSS: Main: 260599808 17:50:51 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | Cycle 1(1): loaded http://localhost:64189/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:64189/tests/kraken/driver.html?testName=ai-astar) 17:50:53 INFO - PROCESS | 6784 | RSS: Main: 264159232 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | __start_tp_report 17:50:53 INFO - PROCESS | 6784 | _x_x_mozilla_page_load 17:50:53 INFO - PROCESS | 6784 | _x_x_mozilla_page_load_details 17:50:53 INFO - PROCESS | 6784 | |i|pagename|runs| 17:50:53 INFO - PROCESS | 6784 | |0;ai-astar;106;105;150;105;105;165;175;173;119;122 17:50:53 INFO - PROCESS | 6784 | |1;audio-beat-detection;166;136;110;199;105;133;140;100;120;137 17:50:53 INFO - PROCESS | 6784 | |2;audio-dft;212;223;216;208;206;207;204;203;204;200 17:50:53 INFO - PROCESS | 6784 | |3;audio-fft;88;61;63;92;63;63;64;81;100;83 17:50:53 INFO - PROCESS | 6784 | |4;audio-oscillator;85;71;87;73;71;74;85;72;73;87 17:50:53 INFO - PROCESS | 6784 | |5;imaging-gaussian-blur;89;89;119;105;85;134;138;89;90;109 17:50:53 INFO - PROCESS | 6784 | |6;imaging-darkroom;98;141;154;97;97;96;97;98;107;154 17:50:53 INFO - PROCESS | 6784 | |7;imaging-desaturate;92;91;92;144;150;95;91;92;92;91 17:50:53 INFO - PROCESS | 6784 | |8;json-parse-financial;67;65;67;69;66;68;69;66;68;72 17:50:53 INFO - PROCESS | 6784 | |9;json-stringify-tinderbox;61;63;66;60;57;78;88;89;86;60 17:50:53 INFO - PROCESS | 6784 | |10;stanford-crypto-aes;110;86;91;93;91;124;77;81;81;86 17:50:53 INFO - PROCESS | 6784 | |11;stanford-crypto-ccm;176;155;156;144;151;163;163;175;199;158 17:50:53 INFO - PROCESS | 6784 | |12;stanford-crypto-pbkdf2;179;179;174;177;176;178;175;175;187;174 17:50:53 INFO - PROCESS | 6784 | |13;stanford-crypto-sha256-iterative;66;72;90;107;86;66;82;90;87;86 17:50:53 INFO - PROCESS | 6784 | __end_tp_report 17:50:53 INFO - PROCESS | 6784 | __start_cc_report 17:50:53 INFO - PROCESS | 6784 | _x_x_mozilla_cycle_collect,1017 17:50:53 INFO - PROCESS | 6784 | __end_cc_report 17:50:53 INFO - PROCESS | 6784 | __startTimestamp1488937853704__endTimestamp 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | ------- Summary: start ------- 17:50:53 INFO - PROCESS | 6784 | Number of tests: 14 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#0] ai-astar Cycles:10 Average:132.50 Median:136.00 stddev:29.95 (22.0%) stddev-sans-first:30.19 17:50:53 INFO - PROCESS | 6784 | Values: 106.0 105.0 150.0 105.0 105.0 165.0 175.0 173.0 119.0 122.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:136.50 stddev:29.93 (21.9%) stddev-sans-first:29.51 17:50:53 INFO - PROCESS | 6784 | Values: 166.0 136.0 110.0 199.0 105.0 133.0 140.0 100.0 120.0 137.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#2] audio-dft Cycles:10 Average:208.30 Median:207.50 stddev:6.91 (3.3%) stddev-sans-first:7.20 17:50:53 INFO - PROCESS | 6784 | Values: 212.0 223.0 216.0 208.0 206.0 207.0 204.0 203.0 204.0 200.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#3] audio-fft Cycles:10 Average:75.80 Median:72.50 stddev:14.63 (20.2%) stddev-sans-first:14.83 17:50:53 INFO - PROCESS | 6784 | Values: 88.0 61.0 63.0 92.0 63.0 63.0 64.0 81.0 100.0 83.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.50 stddev:7.15 (9.0%) stddev-sans-first:7.09 17:50:53 INFO - PROCESS | 6784 | Values: 85.0 71.0 87.0 73.0 71.0 74.0 85.0 72.0 73.0 87.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#5] imaging-gaussian-blur Cycles:10 Average:104.70 Median:87.00 stddev:19.82 (22.8%) stddev-sans-first:20.19 17:50:53 INFO - PROCESS | 6784 | Values: 89.0 89.0 119.0 105.0 85.0 134.0 138.0 89.0 90.0 109.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#6] imaging-darkroom Cycles:10 Average:113.90 Median:97.00 stddev:25.12 (25.9%) stddev-sans-first:25.98 17:50:53 INFO - PROCESS | 6784 | Values: 98.0 141.0 154.0 97.0 97.0 96.0 97.0 98.0 107.0 154.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#7] imaging-desaturate Cycles:10 Average:103.00 Median:92.00 stddev:23.26 (25.3%) stddev-sans-first:24.33 17:50:53 INFO - PROCESS | 6784 | Values: 92.0 91.0 92.0 144.0 150.0 95.0 91.0 92.0 92.0 91.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:68.00 stddev:2.00 (2.9%) stddev-sans-first:2.11 17:50:53 INFO - PROCESS | 6784 | Values: 67.0 65.0 67.0 69.0 66.0 68.0 69.0 66.0 68.0 72.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:72.00 stddev:12.97 (18.0%) stddev-sans-first:13.26 17:50:53 INFO - PROCESS | 6784 | Values: 61.0 63.0 66.0 60.0 57.0 78.0 88.0 89.0 86.0 60.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#10] stanford-crypto-aes Cycles:10 Average:92.00 Median:86.00 stddev:14.49 (16.9%) stddev-sans-first:13.83 17:50:53 INFO - PROCESS | 6784 | Values: 110.0 86.0 91.0 93.0 91.0 124.0 77.0 81.0 81.0 86.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#11] stanford-crypto-ccm Cycles:10 Average:164.00 Median:163.00 stddev:15.78 (9.7%) stddev-sans-first:16.13 17:50:53 INFO - PROCESS | 6784 | Values: 176.0 155.0 156.0 144.0 151.0 163.0 163.0 175.0 199.0 158.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.40 Median:177.50 stddev:3.86 (2.2%) stddev-sans-first:4.06 17:50:53 INFO - PROCESS | 6784 | Values: 179.0 179.0 174.0 177.0 176.0 178.0 175.0 175.0 187.0 174.0 17:50:53 INFO - PROCESS | 6784 | 17:50:53 INFO - PROCESS | 6784 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.20 Median:86.00 stddev:12.51 (14.5%) stddev-sans-first:11.61 17:50:53 INFO - PROCESS | 6784 | Values: 66.0 72.0 90.0 107.0 86.0 66.0 82.0 90.0 87.0 86.0 17:50:53 INFO - PROCESS | 6784 | -------- Summary: end -------- 17:50:53 INFO - PROCESS | 6784 | 17:50:54 INFO - TEST-INFO | 6784: exit 0 17:50:54 INFO - TEST-OK | kraken | took 63296ms 17:50:54 INFO - SUITE-END | took 1181s 17:50:54 INFO - Completed test suite (00:19:41) 17:50:54 INFO - javascript benchmark 17:50:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7567.286394508867, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3377.1557110722306, 3399.337129259794, 3424.5205671206036, 3429.365567370035, 3446.9138271543225, 5763.049096899017, 5909.143174239733, 5934.436228558291, 5957.195778569891, 6018.06719958406, 5832.241808564881, 5902.793402230923, 5918.260217472816, 5949.464548190662, 5989.071693887444, 4513.232750432418, 5223.503767142115, 5337.786488540453, 5417.756200970946, 5477.178423236526, 5316.49493298138, 5398.514133727964, 5449.455054494539, 5468.91796623051, 5480.588955828309, 4797.968436786107, 4940.702573303395, 5272.736363181825, 5419.160030195326, 5465.836024919259, 5322.467753224685, 5323.893522129536, 5356.7857285708915, 5360.839174824761, 5410.594205434608, 4084.530279017921, 4857.53853383928, 5304.92042619361, 5322.973385133088, 5356.544693701001, 5380.381256155553, 5678.6024978251535, 5709.0580054290995, 5817.418258174191, 6014.0377539593455, 4938.913439043405, 5306.5754739621125, 5552.972235138839, 6249.875002499924, 6273.62358258506, 6381.8085457436355, 6386.201724784402, 6390.4248617624635, 6398.29618741938, 6400.743970241185, 6081.695915204222, 6086.969565152146, 6131.47882429994, 6133.3866613339205, 6141.84645383869, 4487.753173575485, 5852.0, 6152.861720581617, 6348.0, 6377.266614339317, 5031.723255220924, 6108.3280839107665, 6212.4408803207925, 6272.341404152589, 6286.05709143623, 6250.531210159256, 6252.030935205051, 6257.78097766576, 6261.092141639437, 6263.154474146023, 6179.104029915637, 6254.0, 6259.405356491217, 6271.874562508724, 6279.246490421087, 6117.449429551361, 6136.8772624547255, 6230.221222347207, 6231.968840155861, 6262.279837818709, 4672.2991551266905, 4736.218523943579, 5064.924026139537, 5112.616553758483, 5196.896062078813, 4592.15045216636, 4599.954000460019, 4604.493505714368, 4608.5621865922685, 4613.377194078824, 6003.489703375175, 6030.638161710312, 6034.456898879122, 6060.939390606126, 6114.2357205349335, 6188.628682279078, 6221.377862213742, 6222.315545290015, 6287.937120628827, 6333.68331583419, 6068.089786532056, 6071.9696401518595, 6109.2974307955155, 6111.816645500642, 6118.449339559372, 5631.436856314336, 5636.4927156556105, 5663.80176693822, 5665.943340566624, 5666.0, 5264.842054738364, 6153.538484613582, 6432.388923052303, 6533.085368048477, 6539.149910511794, 6206.10011548332, 6208.379162083756, 6282.057691346335, 6376.458001069961, 6389.393007664265, 6437.839054023687, 6481.449076828522, 6491.350864913471, 6494.350564943468, 6500.967495162683, 6154.230721159855, 6234.971229747222, 6283.057541368831, 6290.119383286435, 6312.4634406076, 6224.128621992834, 6230.50155987529, 6237.469815065589, 6255.030470277115, 6290.056491526308, 6211.596246243979, 6256.468200203034, 6263.530235232284, 6267.96866015667, 6281.089242059968, 5981.371955944737, 5988.401159883977, 6065.363136870565, 6113.266408031065, 6220.0047992321015, 5375.220593014069, 5646.06839871433, 5924.170616113834, 6117.096718007379, 6139.416755408229, 5649.265595472562, 5874.794382196603, 6225.346338634559, 6242.063690446469, 6270.310265870659, 6119.3268740437625, 6179.443850053517, 6199.659018753831, 6203.131561581473, 6210.844728881814], "value": 5756.278851355388, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.8235869635752, 768.6041688530404, 779.4855395439012, 801.1627848897903, 949.0509490509495, 31818.522722159185, 37317.81341093294, 38027.42958855616, 39323.0, 39668.20663586734, 38004.23991520168, 38252.42621360675, 38297.808510957475, 38368.61631383685, 38383.04042398941, 7756.844863102749, 7922.088959769642, 10462.895371046268, 10700.143988480902, 10870.076043536308, 10429.530671119779, 10531.420771857545, 10532.631357902437, 10537.683869483928, 10565.630202942899, 8786.472811631322, 8898.955505222464, 10488.056074953252, 10557.73605659857, 10573.735656608607, 10460.372377657364, 10489.527971241312, 10513.421761803098, 10514.474276286193, 10520.316179448388, 9786.706398808046, 10132.24008199388, 10345.948270258676, 10486.895131048668, 10572.99556542135, 29201.0, 30233.546496802566, 32104.518432223536, 34136.31727365463, 34209.31581368384, 3649.5073165122635, 3680.3375392429352, 3690.464882592036, 3692.2523638506937, 3700.796456194935, 25074.997000119973, 25177.748222517723, 25262.621060684105, 25357.239282821545, 25799.613005804928, 2965.4513914925806, 3028.1218446650446, 3041.3917216556556, 3052.4047810676707, 3056.6026416565705, 328648.0, 335122.0, 335406.99999999756, 335657.0, 335885.0, 43557.34663980043, 43781.0, 43917.560824391985, 44115.338269925975, 44170.895727607065, 37630.62369376326, 45160.41938532136, 45822.31266531005, 45984.770076150075, 46097.7695111529, 12692.936535317449, 12792.360381980863, 12869.613911582668, 12905.677358066123, 12923.15999460032, 11018.834717479243, 11026.614068507564, 11029.503672334764, 11035.227534072537, 11041.447927603587, 10140.340877842917, 11849.69653338132, 11863.406829658483, 11877.990370818405, 11988.0, 5614.101743720985, 5694.430556944272, 5696.120280307488, 5698.515626171739, 5708.115242137475, 981.6122631560636, 1106.7343837478948, 1133.0935251798528, 1147.225622704671, 1196.2882085159317, 23869.403264918517, 25668.74331256701, 26466.80899359495, 26678.733212668012, 26697.33256668599, 1130.1580322659672, 1211.618340222827, 1215.343714394235, 1274.8597654258024, 1281.6155153453926, 13209.933950330187, 13575.864241357658, 13870.930645346709, 13916.234607096707, 13968.860311396958, 445105.0, 446024.0, 450444.0, 451621.0, 452211.0, 48156.759216203696, 48466.0, 55168.724156379765, 55865.0, 56056.0, 1350.0414705559162, 1372.2521225931891, 1375.312343828086, 1377.793331000358, 1381.5026590427437, 26692.0, 26935.191944241706, 26967.093018651372, 27451.31371715723, 27754.444911101262, 39633.0, 39726.40410393904, 39764.00589985274, 39858.800705996284, 39929.40105898356, 387260.0, 407184.96407517773, 429403.0, 429511.0, 430818.0, 24351.878240609392, 28578.285542861595, 28875.42249154963, 29708.108756737332, 30843.920462783695, 37708.62291377161, 38360.616393835706, 38583.0, 39026.0, 39358.60641393549, 30489.390212196075, 32134.87527936511, 32276.031719048467, 33347.83326083354, 33348.83325583357, 32198.517022245152, 32269.0, 33177.66822331746, 33423.83288083544, 33473.0, 32432.837835811613, 32432.837835811613, 32444.0, 32463.513047304783, 32478.02565923026, 25035.0, 25893.223203303933, 27385.45229095367, 27607.447851043267, 29318.120456386343, 22680.0, 22709.659355110016, 22934.0, 23007.309780706604, 23174.188903388975], "value": 17831.41150410885, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.62888668001966, 392.4153012012102, 393.79719444486096, 411.5575756062233, 430.5135197227133, 20084.09621567029, 20478.976051197456, 20871.269505567307, 22016.669749953762, 22090.88954555227, 21762.347129586098, 21887.452813679625, 21994.230201942893, 22236.444088897784, 22392.21627243047, 6519.934800652004, 6819.318068193191, 6872.244053154149, 6879.587224766505, 6882.759103431381, 6481.254655714606, 6567.573107747981, 6587.538872278942, 6640.701168447407, 6670.6331151786635, 6576.802695919107, 6658.0, 6666.333366663319, 6677.1987361516685, 6680.0981867448, 6236.812895613139, 6507.934920650804, 6653.933460665404, 6708.73165073394, 6712.261651218362, 6246.375362463763, 6440.838979025515, 6511.186101737283, 6519.967400162969, 6538.771143009972, 24968.250952471273, 25556.488870222678, 25594.74405255942, 25837.224883253344, 25955.351116222057, 2816.4930312543734, 2826.4771017361854, 2846.3168839478585, 2851.9429611407863, 2894.175160079388, 13376.531821386205, 15174.165420901845, 15630.218489075502, 15931.681366372724, 16054.43809466663, 2024.9190032398687, 2076.532780124475, 2121.225752600305, 2121.31057406342, 2121.512052227981, 138169.61830381668, 142320.0, 149695.0, 176700.0, 179629.0, 20290.391288261377, 21453.570928581343, 21468.926553672252, 21597.840215978278, 21651.891740541196, 31974.680253197635, 32021.0, 32027.0, 32183.0, 32205.677943220267, 9804.901950980542, 9867.358621689711, 10056.748581285527, 10086.193104551618, 10173.135283500986, 8257.504549727035, 8260.710875119343, 8367.288780453608, 8415.579221039046, 8454.450460720034, 8231.888695026095, 8478.23695867375, 9635.084666956627, 9691.370060946016, 9830.164436022875, 3535.982320088383, 4159.878840415053, 4274.4443222381515, 4305.74165550068, 4306.397104405386, 998.4608311511703, 1027.6794319299663, 1032.5456799008412, 1059.163261023798, 1068.8557044799009, 19989.500262493264, 20344.491387715425, 20575.38273851787, 20779.792202077784, 20785.58428831415, 1046.4872212615765, 1050.9159267258601, 1054.6625079974488, 1088.3252383522267, 1100.1803656276124, 9498.335116541914, 11002.559897604166, 11048.558057677603, 11074.0, 11310.434478276053, 694268.0, 704124.0, 704466.0, 710102.0, 721625.0, 150469.24765376103, 151745.0, 153918.23040884724, 155183.35807430206, 157007.0, 1240.3798100949525, 1279.9872001280055, 1307.20260641007, 1310.6657802260554, 1317.426919290112, 14619.561413157622, 19109.904450477658, 20015.59968800645, 20106.698399523728, 20115.0, 63492.73014539591, 64335.678321608095, 64374.0, 64909.0, 66437.33562664312, 85955.57022214848, 86672.0, 87050.56474717586, 88229.11770882466, 89758.10241897759, 9638.903610964082, 11633.53465861356, 11688.862498939663, 11964.0, 12116.878831211927, 13532.797008045085, 13566.86433135656, 13790.793138102736, 13964.092333998258, 14125.717485650435, 9816.411015339103, 9916.454594997344, 11664.94167529157, 11906.464209110783, 11937.820932685843, 11161.051310638686, 11588.536458541565, 11650.88349116532, 11723.41382930867, 11913.463894124958, 11628.709282267673, 11803.763924721628, 12058.336791476553, 12069.818952715541, 12140.02879769634, 11652.35912024813, 11742.530298787953, 11943.761124777282, 12008.0, 12056.819147712968, 11424.0, 11637.650870473899, 11943.462544185359, 11976.640700778991, 12012.519499220283], "value": 11628.974248717383, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1528.577205129869, 1536.9692606147871, 1562.9296681649325, 1578.1478001878986, 1586.1514089961872, 9103.271738260939, 9354.485503297316, 10238.795224095524, 10761.0, 10872.18458615605, 9982.20142388609, 10364.792704145932, 10532.947335263332, 10548.261621686484, 10615.203859710515, 2216.3350994701564, 2262.4570103175283, 2266.5240299537036, 2266.9319920402413, 2276.8633881967053, 1939.9709004364945, 2272.7954484096426, 2306.6078766609635, 2328.499372634883, 2333.0084713996566, 2306.411864974429, 2313.375388645065, 2319.130326127702, 2333.8016268617102, 2366.7751563601432, 1790.274938649849, 1969.2516843599483, 2177.671620311609, 2257.345369842744, 2276.635738281875, 1946.6787979983285, 2244.674522194273, 2245.2253972379503, 2285.257291380308, 2326.6161083421216, 8901.287896968228, 10638.521266543024, 10727.0, 10747.677569672922, 10762.569497220102, 4882.292067650206, 5714.542836573064, 5767.769289228426, 5779.2775903012125, 5791.5656325775735, 2836.7872409569363, 3297.6207736110414, 3415.129142068781, 3572.624874388204, 3593.0119217215215, 1140.1847678909537, 1163.9941800291028, 1213.8725433829497, 1214.180428210954, 1230.3048777440717, 14873.925630371887, 15030.699386012218, 15044.172570508617, 15048.924755376373, 15078.924605377013, 4727.361806156194, 4917.754112294371, 4919.0899683558655, 4974.477679843637, 5022.347094877642, 12435.875641243652, 12451.0, 12495.93752031234, 12502.74994500105, 12536.686582835502, 5771.59598828078, 5813.476787089182, 5888.263967004124, 5912.497437717842, 5912.852178695567, 5042.571381432546, 5079.492050794965, 5125.56432703217, 5142.51146141116, 5165.74171291434, 3929.233799409145, 4039.8990025249605, 4081.938695939077, 4139.441175441277, 4141.668666506672, 1133.1897693149438, 1133.8015847226702, 1143.1483544759042, 1150.1718762490993, 1156.612534800851, 728.3262981741973, 811.5901469757853, 894.9418287811402, 905.3526728389234, 933.3886304470583, 6109.0, 6345.206849143857, 6349.714262858182, 6458.773942912069, 6463.385978332051, 992.5878822804618, 998.6005597760809, 1004.542932965499, 1010.8685870836891, 1032.8140934631688, 4014.8394064237687, 4030.455888455021, 4066.6949978751713, 4110.547839737626, 4159.147374788222, 12769.61691149267, 13026.023048271229, 13181.60455186346, 13202.73594528104, 13219.008574356962, 13009.089363744637, 13076.738465230643, 13083.41124649393, 13083.476660933457, 13091.41088651013, 9637.37357071788, 9694.079062489052, 9827.557759900821, 9907.00929907018, 9960.452175130438, 5085.720285384339, 5947.167396564571, 6173.104899789416, 6195.4424101831055, 6253.906191407042, 11905.16663833558, 12041.33772642513, 12046.397680116135, 12060.216085954737, 12105.818412723993, 11539.134564907497, 11840.644780656594, 11849.763004739685, 12042.157049006486, 12186.086043546593, 8662.436941598777, 8982.640694372152, 9114.13415725505, 9198.080191980749, 9200.95399523025, 10072.194224461879, 10160.491975401053, 10209.0, 10215.336003159771, 10236.897631023892, 8401.285890699359, 8782.956085219534, 8883.866741999005, 8916.821663566563, 8962.596683149144, 6828.9317106830285, 8735.78160545966, 8743.956280218557, 8779.12208779117, 8791.912080879109, 7388.113426388868, 7503.474756767192, 7507.699692012477, 7516.323530882247, 7518.436117291334, 5471.835844924659, 5535.944640553704, 5625.915611265917, 5664.762418844616, 5727.226824378656, 5548.611597188319, 5679.34687510925, 5715.99521340254, 5732.484076433141, 5756.366799651951], "value": 4989.472533177133, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.57929222809315, 371.840108753236, 375.1970782525396, 383.70646455461576, 386.8356247694439, 4217.852375166866, 4306.461692288464, 4739.9289010664825, 4922.729249891255, 5183.611229157814, 4285.871423857281, 4565.840195593155, 4862.440819305781, 4898.853034408965, 5099.914551696226, 3579.94630080549, 3643.763155394904, 4235.53409124996, 4268.530461649231, 4314.223439780838, 3881.6312450317173, 4221.387898754679, 4285.949777460286, 4308.870733877973, 4309.26742453782, 3457.94813077804, 4196.223698615766, 4279.159961216676, 4297.892552686192, 4346.521882592912, 3715.9071023224637, 3789.6399842015103, 3808.0, 3885.30064588374, 4127.244207770914, 3718.7025037997164, 3832.42513622956, 3892.703305050006, 3914.823832927525, 3971.721979461439, 4170.35359519275, 4186.623203911632, 4486.967997360625, 4828.875294870246, 5053.0, 4769.782051346106, 4912.312276281324, 4988.351514303154, 5005.247114087238, 5038.445770965191, 4348.347747837818, 4912.778924948386, 5245.3020620462485, 5511.118221084607, 5557.44425557441, 3891.241207964448, 4250.234957707611, 4389.0, 4416.668749843775, 4422.800973956179, 4428.3800267962515, 5339.385970613391, 5474.616776825624, 5831.0, 5896.557758168155, 4240.830366785294, 4395.132068870717, 4953.826616068421, 4957.735777376746, 4991.3511243538105, 4559.270516717309, 5043.36957880265, 5093.770780314895, 5154.871128221749, 5157.0, 5001.849204841953, 5126.564242039469, 5212.8175513857595, 5235.3717553893775, 5260.71066091361, 4452.688311818207, 4867.805287788448, 5010.423801262828, 5468.152436372368, 5506.587005974568, 1636.8281330460368, 1684.1495045002441, 1735.7743493345783, 1753.3687872365938, 1754.280744894587, 1259.6207153167268, 1293.9741205175844, 1306.033535183976, 1355.708522667631, 1423.1745587559192, 4583.977080114578, 4650.86047418578, 5120.180771076684, 5170.586353091744, 5305.681659100466, 4840.540148685869, 5067.771950262247, 5684.744186511617, 5785.978744772343, 5863.501602363847, 4641.0, 4724.787384567703, 4936.580390666833, 4960.751962401865, 4999.725015124203, 2667.333166708323, 2990.282332240247, 2992.7905046646524, 2993.5060714981782, 3011.9849400752855, 5151.892343146242, 5392.595555333366, 5485.875395543983, 5588.804391846266, 5637.928793529216, 4923.630727695438, 4981.42713587942, 5516.662419236384, 5774.249347584787, 5782.855428614318, 5281.630285879952, 5663.858403539944, 5870.8590903866225, 5933.525317974551, 5956.583039187302, 4897.191963326011, 5166.4058633257655, 5719.628224165416, 5777.768889244383, 5825.650460972186, 4725.692829966041, 4877.853664390074, 5143.8456846294675, 5571.275734154696, 5816.41835816415, 4839.516048395132, 4850.6362022847725, 5612.747426365905, 5836.883262334644, 5855.297364316139, 4152.626263636287, 4164.979175104227, 4508.047229064968, 4522.977385113053, 4527.524609915911, 5069.670471419346, 5347.839564813062, 5441.80280338325, 5614.550835933034, 5618.775248989995, 4686.367340409001, 5384.703841288767, 5515.806946756844, 5568.498835104698, 5627.368063261772, 4602.65480088988, 4810.687305325143, 5368.033753924331, 5530.281063461724, 5545.667259964415, 4584.793684284148, 4880.95119048805, 5085.669431486942, 5336.493033161843, 5493.807716729895, 5241.266222728745, 5309.8759696892685, 5525.9447405527035, 5546.833594992157, 5591.720413979366, 5020.623453240948, 5345.850642111809, 5383.084875571084, 5387.218853266333, 5471.425500322409], "value": 4215.01400251946, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.1632357494578, 721.7113154738104, 722.9566226026438, 725.858457600768, 737.8966944627751, 11404.600838970639, 14962.32669529871, 15691.84308156918, 16147.354105835768, 16180.919095404535, 14743.852561474381, 15078.09531428115, 15148.318325675344, 15224.923875380608, 15281.235938203074, 859.9785005374853, 868.9348298877578, 953.9856902146473, 954.8376775948071, 959.1176117971448, 924.1267002682467, 944.1031020530523, 945.4469135555709, 949.924006079512, 965.6282331302464, 858.8368210040104, 1025.1952217509256, 1027.1320733979776, 1029.0481304793036, 1031.4533297352377, 1024.4519182237468, 1024.7950409918046, 1026.3995562595853, 1027.244974943416, 1028.100412139378, 744.0922075068407, 755.2447552447552, 758.7913323835934, 759.2521366454039, 759.2938567132564, 15862.0, 16329.265183066791, 16381.754273685905, 16596.917015414965, 16917.238724257437, 11559.024273251429, 13668.52160174399, 13935.58193254204, 13941.790873136912, 13972.860271397258, 11287.887121128766, 11477.945331786628, 11556.0, 11769.0, 12379.814302785375, 2928.5021546337175, 3543.7474634489317, 3558.5195998540125, 3624.58317293512, 3634.0, 96547.0, 98073.5096324521, 116288.41855790667, 117852.0, 118898.0, 9242.399244049115, 10368.948155259326, 10780.353178809297, 10791.83812242817, 10887.292325998786, 21638.134474621154, 22016.88991555032, 22271.443213919458, 22278.0, 22329.10683572639, 5909.970450147808, 7351.301626345488, 7417.629118544051, 7476.1776204617445, 7662.693492260247, 4134.545200027995, 4148.771817550064, 4229.873103806829, 4231.344141658048, 4263.829446822155, 3441.0193094967813, 3490.3542844573835, 3491.790492570454, 3523.2601153757487, 3528.9647103528637, 516.4886762105489, 611.8317462697705, 617.1113596421139, 617.5337620096798, 627.078195053271, 642.4250295985161, 684.5071548485083, 687.1994126842172, 691.837418206721, 711.5410560188649, 9440.858387124199, 9676.661316853877, 10132.34139780912, 10138.596935859649, 10395.376277423378, 619.3589634728035, 659.1595715462728, 661.4179522020592, 662.8110988368292, 686.3513979289523, 4573.382593349922, 4870.318155458239, 4911.901761964741, 4912.606991440676, 4926.753662316942, 90842.0, 98272.0, 107540.46229768958, 110175.0, 110458.44770776095, 60593.0, 73532.0, 77967.66097017098, 81563.5921820399, 84044.0, 55831.0, 87751.56124219594, 87885.56057219673, 89895.99999999869, 90387.54806225927, 10067.0, 10298.0, 10304.896951030394, 11019.449027548751, 11145.442727863736, 38223.80888095653, 47241.4499692621, 52298.477015229364, 52583.47416525786, 53929.0, 49370.259446107615, 65053.0, 65723.0, 65785.35536611623, 65793.0, 10188.745281367728, 12297.508099675913, 13614.659633509242, 13891.0, 13901.930490347484, 14144.504942326968, 14282.785758213427, 14345.0, 14715.264236787903, 14828.629284267548, 9616.567254473706, 12951.352432378531, 13278.668033299246, 13822.723545528832, 13986.860131398962, 10648.04647727047, 13363.39864706071, 13484.258365789976, 13528.797068044185, 14014.808741257108, 12812.295323757282, 13110.803337950128, 13193.868061319265, 13552.661183470098, 13697.931510342385, 8063.636273469264, 8981.416207946462, 9370.062993700576, 10586.735331616772, 10765.977232162932, 10095.89904100979, 10383.657397970634, 10873.945630272114, 10999.945000274947, 11004.559817607525], "value": 7479.584592718376, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1605.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 150.0, 105.0, 105.0, 165.0, 175.0, 173.0, 119.0, 122.0], "value": 132.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 136.0, 110.0, 199.0, 105.0, 133.0, 140.0, 100.0, 120.0, 137.0], "value": 134.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 223.0, 216.0, 208.0, 206.0, 207.0, 204.0, 203.0, 204.0, 200.0], "value": 208.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 61.0, 63.0, 92.0, 63.0, 63.0, 64.0, 81.0, 100.0, 83.0], "value": 75.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 87.0, 73.0, 71.0, 74.0, 85.0, 72.0, 73.0, 87.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 119.0, 105.0, 85.0, 134.0, 138.0, 89.0, 90.0, 109.0], "value": 104.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 141.0, 154.0, 97.0, 97.0, 96.0, 97.0, 98.0, 107.0, 154.0], "value": 113.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 92.0, 144.0, 150.0, 95.0, 91.0, 92.0, 92.0, 91.0], "value": 103.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 67.0, 69.0, 66.0, 68.0, 69.0, 66.0, 68.0, 72.0], "value": 67.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 66.0, 60.0, 57.0, 78.0, 88.0, 89.0, 86.0, 60.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 86.0, 91.0, 93.0, 91.0, 124.0, 77.0, 81.0, 81.0, 86.0], "value": 92.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 155.0, 156.0, 144.0, 151.0, 163.0, 163.0, 175.0, 199.0, 158.0], "value": 164.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 179.0, 174.0, 177.0, 176.0, 178.0, 175.0, 175.0, 187.0, 174.0], "value": 177.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 90.0, 107.0, 86.0, 66.0, 82.0, 90.0, 87.0, 86.0], "value": 83.2, "unit": "score"}]}]} 17:50:54 INFO - Return code: 0 17:50:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:50:54 ERROR - # TBPL SUCCESS # 17:50:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:50:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:50:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:50:55 INFO - 2017-03-07 17:50:55.015 screenresolution[6789:137570] starting screenresolution argv=screenresolution get 17:50:55 INFO - 2017-03-07 17:50:55.018 screenresolution[6789:137570] Display 0: 1600x1200x32@60 17:50:55 INFO - 2017-03-07 17:50:55.024 screenresolution[6790:137573] starting screenresolution argv=screenresolution list 17:50:55 INFO - Available Modes on Display 0 17:50:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:50:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:50:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:50:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:50:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:50:55 INFO - Intel Iris: 17:50:55 INFO - Chipset Model: Intel Iris 17:50:55 INFO - Type: GPU 17:50:55 INFO - Bus: Built-In 17:50:55 INFO - VRAM (Dynamic, Max): 1536 MB 17:50:55 INFO - Vendor: Intel (0x8086) 17:50:55 INFO - Device ID: 0x0a2e 17:50:55 INFO - Revision ID: 0x0009 17:50:55 INFO - Displays: 17:50:55 INFO - Display: 17:50:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:50:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:50:55 INFO - Display Serial Number: 1600x1200 60 17:50:55 INFO - Main Display: Yes 17:50:55 INFO - Mirror: Off 17:50:55 INFO - Online: Yes 17:50:55 INFO - Rotation: Supported 17:50:55 INFO - Return code: 0 17:50:55 INFO - Running post-action listener: _resource_record_post_action 17:50:55 INFO - [mozharness: 2017-03-08 01:50:55.265551Z] Finished run-tests step (success) 17:50:55 INFO - Running post-run listener: _resource_record_post_run 17:50:55 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 295424; Write bytes: 512238592; Read time: 3; Write time: 2981 17:50:55 INFO - TinderboxPrint: CPU usage
54.8% 17:50:55 INFO - TinderboxPrint: I/O read bytes / time
295,424 / 3 17:50:55 INFO - TinderboxPrint: I/O write bytes / time
512,238,592 / 2,981 17:50:55 INFO - TinderboxPrint: CPU idle
2,174.3 (45.2%) 17:50:55 INFO - TinderboxPrint: CPU system
970.1 (20.2%) 17:50:55 INFO - TinderboxPrint: CPU user
1,665.3 (34.6%) 17:50:55 INFO - TinderboxPrint: Swap in / out
210,075,648 / 0 17:50:55 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205453824; Write bytes: 201154560; Read time: 15654; Write time: 627 17:50:55 INFO - run-tests - Wall time: 1183s; CPU: 55.0%; Read bytes: 294912; Write bytes: 298315264; Read time: 3; Write time: 2268 17:50:55 INFO - Running post-run listener: _upload_blobber_files 17:50:55 INFO - Blob upload gear active. 17:50:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:50:55 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 17:50:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:50:56 INFO - (blobuploader) - INFO - Open directory for files ... 17:50:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:50:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:50:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:56 INFO - (blobuploader) - INFO - Done attempting. 17:50:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:50:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:50:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:57 INFO - (blobuploader) - INFO - Done attempting. 17:50:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:50:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:50:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:58 INFO - (blobuploader) - INFO - Done attempting. 17:50:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:50:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:51:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:51:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:51:00 INFO - (blobuploader) - INFO - Done attempting. 17:51:00 INFO - (blobuploader) - INFO - Iteration through files over. 17:51:00 INFO - Return code: 0 17:51:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:51:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:51:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51478ed29cea66101263705221864e1d299f6e177d90b79a4808743a8d5717091c9a04c4a30e15270604150735af1a6bdc8712f0582235d44fe74f21720663cb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eac038943c4fa3c6f4cb4d7eb0928a2207eb0ffd971b0a602a7e904f4857029a8b0259a269f87834146895e86cb3bca9ac6ced484ce5dc15f141036995fa31be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9dd7090721b6d59dfd649494b835ce4b6bf6733e0b1f28b8ea35af5aa86f9df51f839c394ba622f6c2efc987b73791c58a710a1176e959e8aee69d92cf322d71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7200b57f9436b527978d83192eba82f3de7c4c69f7967a08a73c15c44b68d4cc8898fde8f19ab4c1576fcd49c9098e15972e853ab583fb43b79be1aaaedfebf5"} 17:51:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:51:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:51:00 INFO - Contents: 17:51:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51478ed29cea66101263705221864e1d299f6e177d90b79a4808743a8d5717091c9a04c4a30e15270604150735af1a6bdc8712f0582235d44fe74f21720663cb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eac038943c4fa3c6f4cb4d7eb0928a2207eb0ffd971b0a602a7e904f4857029a8b0259a269f87834146895e86cb3bca9ac6ced484ce5dc15f141036995fa31be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9dd7090721b6d59dfd649494b835ce4b6bf6733e0b1f28b8ea35af5aa86f9df51f839c394ba622f6c2efc987b73791c58a710a1176e959e8aee69d92cf322d71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7200b57f9436b527978d83192eba82f3de7c4c69f7967a08a73c15c44b68d4cc8898fde8f19ab4c1576fcd49c9098e15972e853ab583fb43b79be1aaaedfebf5"} 17:51:00 INFO - Running post-run listener: copy_logs_to_upload_dir 17:51:00 INFO - Copying logs to upload dir... 17:51:00 INFO - mkdir: /builds/slave/test/build/upload/logs 17:51:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.732444 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-07 17:51:00.720540) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-07 17:51:00.724251) ========= 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.dphizwOYh6/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.X1JCCIk7fe/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51478ed29cea66101263705221864e1d299f6e177d90b79a4808743a8d5717091c9a04c4a30e15270604150735af1a6bdc8712f0582235d44fe74f21720663cb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eac038943c4fa3c6f4cb4d7eb0928a2207eb0ffd971b0a602a7e904f4857029a8b0259a269f87834146895e86cb3bca9ac6ced484ce5dc15f141036995fa31be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9dd7090721b6d59dfd649494b835ce4b6bf6733e0b1f28b8ea35af5aa86f9df51f839c394ba622f6c2efc987b73791c58a710a1176e959e8aee69d92cf322d71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7200b57f9436b527978d83192eba82f3de7c4c69f7967a08a73c15c44b68d4cc8898fde8f19ab4c1576fcd49c9098e15972e853ab583fb43b79be1aaaedfebf5"} build_url:https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011498 build_url: 'https://queue.taskcluster.net/v1/task/cZuJhUXmRee3oY-OaQ6_0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51478ed29cea66101263705221864e1d299f6e177d90b79a4808743a8d5717091c9a04c4a30e15270604150735af1a6bdc8712f0582235d44fe74f21720663cb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eac038943c4fa3c6f4cb4d7eb0928a2207eb0ffd971b0a602a7e904f4857029a8b0259a269f87834146895e86cb3bca9ac6ced484ce5dc15f141036995fa31be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9dd7090721b6d59dfd649494b835ce4b6bf6733e0b1f28b8ea35af5aa86f9df51f839c394ba622f6c2efc987b73791c58a710a1176e959e8aee69d92cf322d71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7200b57f9436b527978d83192eba82f3de7c4c69f7967a08a73c15c44b68d4cc8898fde8f19ab4c1576fcd49c9098e15972e853ab583fb43b79be1aaaedfebf5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-07 17:51:00.760254) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 17:51:00.760581) ========= 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.dphizwOYh6/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.X1JCCIk7fe/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.005212 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 17:51:00.809886) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-07 17:51:00.810199) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-07 17:51:00.810580) ========= ========= Total master_lag: 0.91 =========