builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0096 starttime: 1490483436.64 results: success (0) buildid: 20170325153315 builduid: b2986aec16c842ca9b629c0b7a97e963 revision: 2ecf610d3185de940cb7b4ed2c214dc9e2367cee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:36.639142) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:36.639748) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:36.640160) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:36.672284) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:36.672643) ========= 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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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-25 16:10:36-- 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% 164M=0s 2017-03-25 16:10:37 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.491377 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:37.187948) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:37.188247) ========= 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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.021243 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:37.240029) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-25 16:10:37.240454) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2ecf610d3185de940cb7b4ed2c214dc9e2367cee --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 2ecf610d3185de940cb7b4ed2c214dc9e2367cee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3h9fdsMKwS/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.cl5lwvhuoY/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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-25 16:10:37,301 truncating revision to first 12 chars 2017-03-25 16:10:37,301 Setting DEBUG logging. 2017-03-25 16:10:37,301 attempt 1/10 2017-03-25 16:10:37,301 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2ecf610d3185?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-25 16:10:38,058 unpacking tar archive at: mozilla-central-2ecf610d3185/testing/mozharness/ program finished with exit code 0 elapsedTime=1.034188 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-25 16:10:38.300643) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:38.300997) ========= script_repo_revision: 2ecf610d3185de940cb7b4ed2c214dc9e2367cee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:38.301411) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:38.301685) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-25 16:10:38.323002) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-25 16:10:38.323308) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3h9fdsMKwS/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.cl5lwvhuoY/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:10:38 INFO - MultiFileLogger online at 20170325 16:10:38 in /builds/slave/test 16:10:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:10:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:10:38 INFO - {'append_to_log': False, 16:10:38 INFO - 'base_work_dir': '/builds/slave/test', 16:10:38 INFO - 'blob_upload_branch': 'Firefox', 16:10:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:10:38 INFO - 'branch': 'Firefox', 16:10:38 INFO - 'buildbot_json_path': 'buildprops.json', 16:10:38 INFO - 'config_files': ('talos/mac_config.py',), 16:10:38 INFO - 'default_actions': ('clobber', 16:10:38 INFO - 'read-buildbot-config', 16:10:38 INFO - 'download-and-extract', 16:10:38 INFO - 'populate-webroot', 16:10:38 INFO - 'create-virtualenv', 16:10:38 INFO - 'install', 16:10:38 INFO - 'run-tests'), 16:10:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:10:38 INFO - 'download_minidump_stackwalk': True, 16:10:38 INFO - 'download_symbols': 'ondemand', 16:10:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:10:38 INFO - 'tooltool.py': '/tools/tooltool.py', 16:10:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:10:38 INFO - '/tools/misc-python/virtualenv.py')}, 16:10:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:10:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:10:38 INFO - 'gecko_profile': False, 16:10:38 INFO - 'gecko_profile_interval': 0, 16:10:38 INFO - 'installer_path': 'installer.exe', 16:10:38 INFO - 'log_level': 'info', 16:10:38 INFO - 'log_name': 'talos', 16:10:38 INFO - 'log_to_console': True, 16:10:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:10:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:10:38 INFO - 'opt_config_files': (), 16:10:38 INFO - 'pip_index': False, 16:10:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:10:38 INFO - 'cmd': ('bash', 16:10:38 INFO - '-c', 16:10:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:10:38 INFO - 'enabled': True, 16:10:38 INFO - 'halt_on_failure': False, 16:10:38 INFO - 'name': 'check_screen_resolution'},), 16:10:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:10:38 INFO - 'cmd': ('bash', 16:10:38 INFO - '-c', 16:10:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:10:38 INFO - 'enabled': True, 16:10:38 INFO - 'halt_on_failure': False, 16:10:38 INFO - 'name': 'check_screen_resolution'},), 16:10:38 INFO - 'run_cmd_checks_enabled': True, 16:10:38 INFO - 'suite': 'dromaeojs', 16:10:38 INFO - 'system_bits': '32', 16:10:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:10:38 INFO - 'title': 't-yosemite-r7-0096', 16:10:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:10:38 INFO - 'use_talos_json': True, 16:10:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:10:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:10:38 INFO - 'work_dir': 'build'} 16:10:38 INFO - [mozharness: 2017-03-25 23:10:38.512173Z] Running clobber step. 16:10:38 INFO - Running pre-action listener: _resource_record_pre_action 16:10:38 INFO - Running main action method: clobber 16:10:38 INFO - rmtree: /builds/slave/test/build 16:10:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:10:41 INFO - Running post-action listener: _resource_record_post_action 16:10:41 INFO - [mozharness: 2017-03-25 23:10:41.796349Z] Finished clobber step (success) 16:10:41 INFO - [mozharness: 2017-03-25 23:10:41.796474Z] Running read-buildbot-config step. 16:10:41 INFO - Running pre-action listener: _resource_record_pre_action 16:10:41 INFO - Running main action method: read_buildbot_config 16:10:41 INFO - Using buildbot properties: 16:10:41 INFO - { 16:10:41 INFO - "project": "", 16:10:41 INFO - "product": "firefox", 16:10:41 INFO - "script_repo_revision": "production", 16:10:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:10:41 INFO - "repository": "", 16:10:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:10:41 INFO - "buildid": "20170325153315", 16:10:41 INFO - "pgo_build": "False", 16:10:41 INFO - "basedir": "/builds/slave/test", 16:10:41 INFO - "buildnumber": 87, 16:10:41 INFO - "slavename": "t-yosemite-r7-0096", 16:10:41 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:10:41 INFO - "platform": "yosemite_r7", 16:10:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:10:41 INFO - "branch": "mozilla-central", 16:10:41 INFO - "repo_path": "mozilla-central", 16:10:41 INFO - "revision": "2ecf610d3185de940cb7b4ed2c214dc9e2367cee", 16:10:41 INFO - "stage_platform": "macosx64", 16:10:41 INFO - "builduid": "b2986aec16c842ca9b629c0b7a97e963", 16:10:41 INFO - "slavebuilddir": "test" 16:10:41 INFO - } 16:10:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:10:41 INFO - Running post-action listener: _resource_record_post_action 16:10:41 INFO - [mozharness: 2017-03-25 23:10:41.800019Z] Finished read-buildbot-config step (success) 16:10:41 INFO - [mozharness: 2017-03-25 23:10:41.800141Z] Running download-and-extract step. 16:10:41 INFO - Running pre-action listener: _resource_record_pre_action 16:10:41 INFO - Running main action method: download_and_extract 16:10:41 INFO - mkdir: /builds/slave/test/build/tests 16:10: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')]} 16:10:41 INFO - trying https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:10:41 INFO - Downloading https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/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 16:10:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/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 16:10:43 INFO - Downloaded 1325 bytes. 16:10:43 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:10:43 INFO - Using the following test package requirements: 16:10:43 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:43 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:10:43 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:10:43 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:43 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:10:43 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:43 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:10:43 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:43 INFO - u'jsshell-mac.zip'], 16:10:43 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:43 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:10:43 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:10:43 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:43 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:10:43 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:43 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:10:43 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:43 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:10:43 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:43 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:10:43 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:10:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:10:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:10:43 INFO - Fetch https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:10:45 INFO - Expected file size: 52411811 16:10:45 INFO - Obtained file size: 52411811 16:11:01 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 16:11:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:11:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:11:01 INFO - Fetch https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:11:03 INFO - Expected file size: 52411811 16:11:03 INFO - Obtained file size: 52411811 16:11:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:11:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:11:18 INFO - Fetch https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:11:19 INFO - Expected file size: 14404171 16:11:19 INFO - Obtained file size: 14404171 16:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:19 INFO - trying https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:11:19 INFO - Downloading https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:11:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/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 16:11:22 INFO - Downloaded 75592109 bytes. 16:11:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:11:22 INFO - mkdir: /builds/slave/test/properties 16:11:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:11:22 INFO - Writing to file /builds/slave/test/properties/build_url 16:11:22 INFO - Contents: 16:11:22 INFO - build_url:https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:11:22 INFO - Running post-action listener: _resource_record_post_action 16:11:22 INFO - Running post-action listener: set_extra_try_arguments 16:11:22 INFO - [mozharness: 2017-03-25 23:11:22.792464Z] Finished download-and-extract step (success) 16:11:22 INFO - [mozharness: 2017-03-25 23:11:22.792620Z] Running populate-webroot step. 16:11:22 INFO - Running pre-action listener: _resource_record_pre_action 16:11:22 INFO - Running main action method: populate_webroot 16:11:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:11:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:11:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:11:22 INFO - '--tppagecycles', 16:11:22 INFO - '7'], 16:11:22 INFO - 'tests': ['tsvgm']}}, 16:11:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:11:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:11:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:11:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:11:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:11:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:11:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:11:22 INFO - 'talos_options': ['--disable-e10s'], 16:11:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:11:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:11:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:11:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:11:22 INFO - 'talos_options': ['--disable-e10s'], 16:11:22 INFO - 'tests': ['damp', 'tps']}, 16:11:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:11:22 INFO - 'tests': ['damp', 'tps']}, 16:11:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:11:22 INFO - 'tests': ['dromaeo_dom']}, 16:11:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:11:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:11:22 INFO - 'tests': ['basic_compositor_video']}, 16:11:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:11:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:11:22 INFO - 'tests': ['a11yr', 16:11:22 INFO - 'ts_paint', 16:11:22 INFO - 'tpaint', 16:11:22 INFO - 'sessionrestore', 16:11:22 INFO - 'sessionrestore_no_auto_restore', 16:11:22 INFO - 'tabpaint']}, 16:11:22 INFO - 'other-e10s': {'tests': ['a11yr', 16:11:22 INFO - 'ts_paint', 16:11:22 INFO - 'tpaint', 16:11:22 INFO - 'sessionrestore', 16:11:22 INFO - 'sessionrestore_no_auto_restore', 16:11:22 INFO - 'tabpaint']}, 16:11:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:11:22 INFO - 'ts_paint', 16:11:22 INFO - 'tpaint', 16:11:22 INFO - 'sessionrestore', 16:11:22 INFO - 'sessionrestore_no_auto_restore', 16:11:22 INFO - 'tabpaint']}, 16:11:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:11:22 INFO - 'ts_paint', 16:11:22 INFO - 'tpaint', 16:11:22 INFO - 'sessionrestore', 16:11:22 INFO - 'sessionrestore_no_auto_restore', 16:11:22 INFO - 'tabpaint']}, 16:11:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:11:22 INFO - 'tests': ['a11yr', 16:11:22 INFO - 'ts_paint', 16:11:22 INFO - 'tpaint', 16:11:22 INFO - 'sessionrestore', 16:11:22 INFO - 'sessionrestore_no_auto_restore', 16:11:22 INFO - 'tabpaint']}, 16:11:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:11:22 INFO - 'tests': ['a11yr', 16:11:22 INFO - 'ts_paint', 16:11:22 INFO - 'tpaint', 16:11:22 INFO - 'sessionrestore', 16:11:22 INFO - 'sessionrestore_no_auto_restore', 16:11:22 INFO - 'tabpaint']}, 16:11:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:11:22 INFO - 'tests': ['tsvgx', 16:11:22 INFO - 'tsvgr_opacity', 16:11:22 INFO - 'tart', 16:11:22 INFO - 'tscrollx', 16:11:22 INFO - 'cart', 16:11:22 INFO - 'tsvg_static']}, 16:11:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:11:22 INFO - 'tsvgr_opacity', 16:11:22 INFO - 'tart', 16:11:22 INFO - 'tscrollx', 16:11:22 INFO - 'cart', 16:11:22 INFO - 'tsvg_static']}, 16:11:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:11:22 INFO - 'talos_options': ['--disable-e10s'], 16:11:22 INFO - 'tests': ['tp5o']}, 16:11:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:11:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:11:22 INFO - 'talos_options': ['--disable-e10s', 16:11:22 INFO - '--xperf_path', 16:11:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:11:22 INFO - 'tests': ['tp5n']}, 16:11:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:11:22 INFO - 'talos_options': ['--xperf_path', 16:11:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:11:22 INFO - 'tests': ['tp5n']}}, 16:11:22 INFO - 'talos.zip': {'path': '', 16:11:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:11:22 INFO - Running post-action listener: _resource_record_post_action 16:11:22 INFO - [mozharness: 2017-03-25 23:11:22.810818Z] Finished populate-webroot step (success) 16:11:22 INFO - [mozharness: 2017-03-25 23:11:22.810938Z] Running create-virtualenv step. 16:11:22 INFO - Running pre-action listener: _resource_record_pre_action 16:11:22 INFO - Running main action method: create_virtualenv 16:11:22 INFO - Creating virtualenv /builds/slave/test/build/venv 16:11:22 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 16:11:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:11:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:11:22 INFO - Using real prefix '/tools/python27' 16:11:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:11:23 INFO - Installing distribute.............................................................................................................................................................................................done. 16:11:26 INFO - Installing pip.................done. 16:11:26 INFO - Return code: 0 16:11:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:11:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:11:26 INFO - Reading from file tmpfile_stdout 16:11:26 INFO - Output received: 16:11:26 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) 16:11:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102396b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102399768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98124c8af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102174d30>, '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 0x10209bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102169be8>, '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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:11:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:11:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:11:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3h9fdsMKwS/Render', 16:11:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:26 INFO - 'HOME': '/Users/cltbld', 16:11:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:26 INFO - 'LOGNAME': 'cltbld', 16:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:26 INFO - 'MOZ_NO_REMOTE': '1', 16:11:26 INFO - 'NO_EM_RESTART': '1', 16:11:26 INFO - 'PAGER': '/bin/cat', 16:11:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:26 INFO - 'PWD': '/builds/slave/test', 16:11:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:26 INFO - 'SHELL': '/bin/bash', 16:11:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cl5lwvhuoY/Listeners', 16:11:26 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:11:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:26 INFO - 'USER': 'cltbld', 16:11:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:26 INFO - 'XPC_FLAGS': '0x0', 16:11:26 INFO - 'XPC_SERVICE_NAME': '0', 16:11:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:27 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 16:11:27 INFO - Cleaning up... 16:11:27 INFO - Return code: 0 16:11:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102396b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102399768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98124c8af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102174d30>, '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 0x10209bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102169be8>, '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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:11:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:11:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3h9fdsMKwS/Render', 16:11:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:27 INFO - 'HOME': '/Users/cltbld', 16:11:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:27 INFO - 'LOGNAME': 'cltbld', 16:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:27 INFO - 'MOZ_NO_REMOTE': '1', 16:11:27 INFO - 'NO_EM_RESTART': '1', 16:11:27 INFO - 'PAGER': '/bin/cat', 16:11:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:27 INFO - 'PWD': '/builds/slave/test', 16:11:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:27 INFO - 'SHELL': '/bin/bash', 16:11:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cl5lwvhuoY/Listeners', 16:11:27 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:11:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:27 INFO - 'USER': 'cltbld', 16:11:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:27 INFO - 'XPC_FLAGS': '0x0', 16:11:27 INFO - 'XPC_SERVICE_NAME': '0', 16:11:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:27 INFO - Downloading/unpacking psutil>=3.1.1 16:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:11:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:11:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:11:31 INFO - Installing collected packages: psutil 16:11:31 INFO - Running setup.py install for psutil 16:11:31 INFO - building 'psutil._psutil_osx' extension 16:11:31 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 16:11:31 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 16:11:31 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 16:11:31 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 16:11:31 INFO - building 'psutil._psutil_posix' extension 16:11:31 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 16:11:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:11:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:11:31 INFO - ^ 16:11:32 INFO - 1 warning generated. 16:11:32 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 16:11:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:11:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:11:32 INFO - Successfully installed psutil 16:11:32 INFO - Cleaning up... 16:11:32 INFO - Return code: 0 16:11:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:11:32 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')]} 16:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:32 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')]} 16:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102396b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102399768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98124c8af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102174d30>, '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 0x10209bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102169be8>, '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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:11: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:11: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 mozsystemmonitor==0.3 16:11:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3h9fdsMKwS/Render', 16:11:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:32 INFO - 'HOME': '/Users/cltbld', 16:11:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:32 INFO - 'LOGNAME': 'cltbld', 16:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:32 INFO - 'MOZ_NO_REMOTE': '1', 16:11:32 INFO - 'NO_EM_RESTART': '1', 16:11:32 INFO - 'PAGER': '/bin/cat', 16:11:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:32 INFO - 'PWD': '/builds/slave/test', 16:11:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:32 INFO - 'SHELL': '/bin/bash', 16:11:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cl5lwvhuoY/Listeners', 16:11:32 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:11:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:32 INFO - 'USER': 'cltbld', 16:11:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:32 INFO - 'XPC_FLAGS': '0x0', 16:11:32 INFO - 'XPC_SERVICE_NAME': '0', 16:11:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:11:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:11:35 INFO - Installing collected packages: mozsystemmonitor 16:11:35 INFO - Running setup.py install for mozsystemmonitor 16:11:35 INFO - Successfully installed mozsystemmonitor 16:11:35 INFO - Cleaning up... 16:11:35 INFO - Return code: 0 16:11:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:11: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')]} 16:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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')]} 16:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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', '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 0x102396b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102399768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98124c8af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102174d30>, '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 0x10209bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102169be8>, '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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:11: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:11: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 jsonschema==2.5.1 16:11:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3h9fdsMKwS/Render', 16:11:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:35 INFO - 'HOME': '/Users/cltbld', 16:11:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:35 INFO - 'LOGNAME': 'cltbld', 16:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:35 INFO - 'MOZ_NO_REMOTE': '1', 16:11:35 INFO - 'NO_EM_RESTART': '1', 16:11:35 INFO - 'PAGER': '/bin/cat', 16:11:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:35 INFO - 'PWD': '/builds/slave/test', 16:11:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:35 INFO - 'SHELL': '/bin/bash', 16:11:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cl5lwvhuoY/Listeners', 16:11:35 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:11:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:35 INFO - 'USER': 'cltbld', 16:11:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:35 INFO - 'XPC_FLAGS': '0x0', 16:11:35 INFO - 'XPC_SERVICE_NAME': '0', 16:11:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:35 INFO - Downloading/unpacking jsonschema==2.5.1 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:11:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:11:38 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 16:11:38 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 16:11:38 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 16:11:38 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 16:11:38 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 16:11:38 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 16:11:38 INFO - Downloading functools32-3.2.3-2.tar.gz 16:11:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:11:38 INFO - warning: no files found matching '*.txt' 16:11:38 INFO - no previously-included directories found matching 'build' 16:11:38 INFO - no previously-included directories found matching 'dist' 16:11:38 INFO - no previously-included directories found matching '.git*' 16:11:38 INFO - Installing collected packages: jsonschema, functools32 16:11:38 INFO - Running setup.py install for functools32 16:11:39 INFO - warning: no files found matching '*.txt' 16:11:39 INFO - no previously-included directories found matching 'build' 16:11:39 INFO - no previously-included directories found matching 'dist' 16:11:39 INFO - no previously-included directories found matching '.git*' 16:11:39 INFO - Successfully installed jsonschema functools32 16:11:39 INFO - Cleaning up... 16:11:39 INFO - Return code: 0 16:11:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:11: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')]} 16:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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')]} 16:11:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:39 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 0x102396b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102399768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98124c8af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102174d30>, '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 0x10209bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102169be8>, '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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:11:39 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 16:11:39 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 16:11:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3h9fdsMKwS/Render', 16:11:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:39 INFO - 'HOME': '/Users/cltbld', 16:11:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:39 INFO - 'LOGNAME': 'cltbld', 16:11:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:39 INFO - 'MOZ_NO_REMOTE': '1', 16:11:39 INFO - 'NO_EM_RESTART': '1', 16:11:39 INFO - 'PAGER': '/bin/cat', 16:11:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:39 INFO - 'PWD': '/builds/slave/test', 16:11:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:39 INFO - 'SHELL': '/bin/bash', 16:11:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cl5lwvhuoY/Listeners', 16:11:39 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:11:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:39 INFO - 'USER': 'cltbld', 16:11:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:39 INFO - 'XPC_FLAGS': '0x0', 16:11:39 INFO - 'XPC_SERVICE_NAME': '0', 16:11:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:11:39 INFO - Cleaning up... 16:11:39 INFO - Return code: 0 16:11:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:11: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')]} 16:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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')]} 16:11:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:39 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 0x102396b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102399768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98124c8af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102174d30>, '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 0x10209bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102169be8>, '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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:11:39 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 16:11:39 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 16:11:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3h9fdsMKwS/Render', 16:11:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:39 INFO - 'HOME': '/Users/cltbld', 16:11:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:39 INFO - 'LOGNAME': 'cltbld', 16:11:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:39 INFO - 'MOZ_NO_REMOTE': '1', 16:11:39 INFO - 'NO_EM_RESTART': '1', 16:11:39 INFO - 'PAGER': '/bin/cat', 16:11:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:39 INFO - 'PWD': '/builds/slave/test', 16:11:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:39 INFO - 'SHELL': '/bin/bash', 16:11:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cl5lwvhuoY/Listeners', 16:11:39 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:11:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:39 INFO - 'USER': 'cltbld', 16:11:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:39 INFO - 'XPC_FLAGS': '0x0', 16:11:39 INFO - 'XPC_SERVICE_NAME': '0', 16:11:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:39 INFO - Downloading/unpacking blobuploader==1.2.4 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11:42 INFO - Downloading blobuploader-1.2.4.tar.gz 16:11:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:11:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:11:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:11:43 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 16:11:43 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 16:11:43 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 16:11:43 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 16:11:43 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 16:11:43 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 16:11:43 INFO - Downloading docopt-0.6.1.tar.gz 16:11:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:11:43 INFO - Installing collected packages: blobuploader, requests, docopt 16:11:43 INFO - Running setup.py install for blobuploader 16:11:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:11:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:11:43 INFO - Running setup.py install for requests 16:11:44 INFO - Running setup.py install for docopt 16:11:44 INFO - Successfully installed blobuploader requests docopt 16:11:44 INFO - Cleaning up... 16:11:44 INFO - Return code: 0 16:11:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:44 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 0x102396b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102399768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98124c8af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102174d30>, '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 0x10209bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102169be8>, '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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:11:44 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 16:11:44 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 16:11:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3h9fdsMKwS/Render', 16:11:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:44 INFO - 'HOME': '/Users/cltbld', 16:11:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:44 INFO - 'LOGNAME': 'cltbld', 16:11:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:44 INFO - 'MOZ_NO_REMOTE': '1', 16:11:44 INFO - 'NO_EM_RESTART': '1', 16:11:44 INFO - 'PAGER': '/bin/cat', 16:11:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:44 INFO - 'PWD': '/builds/slave/test', 16:11:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:44 INFO - 'SHELL': '/bin/bash', 16:11:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cl5lwvhuoY/Listeners', 16:11:44 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:11:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:44 INFO - 'USER': 'cltbld', 16:11:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:44 INFO - 'XPC_FLAGS': '0x0', 16:11:44 INFO - 'XPC_SERVICE_NAME': '0', 16:11:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:11:44 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-MGBSdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:11:44 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-rsz7zW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:11:44 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-mU30fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:11:45 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Fwd4Cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:11:45 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-zdldzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:11:45 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-R2H2n1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:11:45 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-XxWGIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:11:45 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-XLyLYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:11:45 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-pQ2WXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:11:45 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-VFhf6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:11:45 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-j4Ms5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:11:45 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-IgF7vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:11:46 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ZXs5JZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:11:46 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-p9aus3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:46 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-2XIWUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:11:46 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-NGI0pB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:11:46 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-i_1Yf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:11:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:11:46 INFO - Running setup.py install for manifestparser 16:11:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:11:46 INFO - Running setup.py install for mozcrash 16:11:46 INFO - Running setup.py install for mozdebug 16:11:46 INFO - Running setup.py install for mozdevice 16:11:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:11:47 INFO - Running setup.py install for mozfile 16:11:47 INFO - Running setup.py install for mozhttpd 16:11:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:11:47 INFO - Running setup.py install for mozinfo 16:11:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:11:47 INFO - Running setup.py install for mozInstall 16:11:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:11:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:11:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:11:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:11:47 INFO - Running setup.py install for mozleak 16:11:47 INFO - Running setup.py install for mozlog 16:11:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:11:48 INFO - Running setup.py install for moznetwork 16:11:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:11:48 INFO - Running setup.py install for mozprocess 16:11:48 INFO - Running setup.py install for mozprofile 16:11:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:11:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:11:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:11:48 INFO - Running setup.py install for mozrunner 16:11:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:11:48 INFO - Running setup.py install for mozscreenshot 16:11:48 INFO - Running setup.py install for moztest 16:11:48 INFO - Running setup.py install for mozversion 16:11:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:11:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:11:49 INFO - Cleaning up... 16:11:49 INFO - Return code: 0 16:11:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:49 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 0x102396b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102399768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98124c8af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102174d30>, '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 0x10209bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102169be8>, '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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:11:49 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 16:11:49 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 16:11:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3h9fdsMKwS/Render', 16:11:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:49 INFO - 'HOME': '/Users/cltbld', 16:11:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:49 INFO - 'LOGNAME': 'cltbld', 16:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:49 INFO - 'MOZ_NO_REMOTE': '1', 16:11:49 INFO - 'NO_EM_RESTART': '1', 16:11:49 INFO - 'PAGER': '/bin/cat', 16:11:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:49 INFO - 'PWD': '/builds/slave/test', 16:11:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:49 INFO - 'SHELL': '/bin/bash', 16:11:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cl5lwvhuoY/Listeners', 16:11:49 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:11:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:49 INFO - 'USER': 'cltbld', 16:11:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:49 INFO - 'XPC_FLAGS': '0x0', 16:11:49 INFO - 'XPC_SERVICE_NAME': '0', 16:11:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:11:49 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-tSqgzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:11:49 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)) 16:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:11:49 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-zBRaD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:11:49 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)) 16:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:11:49 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-R8ZNeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:11:49 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)) 16:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:11:49 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-bCQh4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:11:49 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)) 16:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:11:49 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ADyjsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:11:49 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)) 16:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:11:49 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-N52N6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:11:49 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)) 16:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:11:49 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-IjpIFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:11:50 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)) 16:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:11:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-dVXMM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:11:50 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)) 16:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:11:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-b0_jLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:11:50 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)) 16:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:11:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-l6p8eJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:11:50 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)) 16:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:11:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-kfkxU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:11:50 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)) 16:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:11:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-AksA4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:11:50 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)) 16:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:11:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-jgd4Pr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:11:50 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)) 16:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:11:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-i7Ls_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:11:50 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)) 16:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-IpvCJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:50 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)) 16:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:11:50 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-61NNOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:11:51 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)) 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:11:51 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-PUserm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:11:51 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)) 16:11:51 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)) 16:11:51 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)) 16:11:51 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)) 16:11:51 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)) 16:11:51 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)) 16:11:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:11:51 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 16:11:51 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 16:11:51 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 16:11:51 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 16:11:51 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 16:11:51 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 16:11:53 INFO - Downloading blessings-1.6.tar.gz 16:11:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:11:53 INFO - Installing collected packages: blessings 16:11:53 INFO - Running setup.py install for blessings 16:11:54 INFO - Successfully installed blessings 16:11:54 INFO - Cleaning up... 16:11:54 INFO - Return code: 0 16:11:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:11:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:11:54 INFO - Reading from file tmpfile_stdout 16:11:54 INFO - Current package versions: 16:11:54 INFO - blessings == 1.6 16:11:54 INFO - blobuploader == 1.2.4 16:11:54 INFO - docopt == 0.6.1 16:11:54 INFO - functools32 == 3.2.3-2 16:11:54 INFO - jsonschema == 2.5.1 16:11:54 INFO - manifestparser == 1.1 16:11:54 INFO - mozInstall == 1.12 16:11:54 INFO - mozcrash == 1.0 16:11:54 INFO - mozdebug == 0.1 16:11:54 INFO - mozdevice == 0.49 16:11:54 INFO - mozfile == 1.2 16:11:54 INFO - mozhttpd == 0.7 16:11:54 INFO - mozinfo == 0.9 16:11:54 INFO - mozleak == 0.1 16:11:54 INFO - mozlog == 3.4 16:11:54 INFO - moznetwork == 0.27 16:11:54 INFO - mozprocess == 0.25 16:11:54 INFO - mozprofile == 0.28 16:11:54 INFO - mozrunner == 6.13 16:11:54 INFO - mozscreenshot == 0.1 16:11:54 INFO - mozsystemmonitor == 0.3 16:11:54 INFO - moztest == 0.8 16:11:54 INFO - mozversion == 1.4 16:11:54 INFO - psutil == 3.1.1 16:11:54 INFO - requests == 1.2.3 16:11:54 INFO - wsgiref == 0.1.2 16:11:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:54 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 0x102396b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102399768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98124c8af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102174d30>, '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 0x10209bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102169be8>, '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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:11:54 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 16:11:54 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 16:11:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3h9fdsMKwS/Render', 16:11:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:54 INFO - 'HOME': '/Users/cltbld', 16:11:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:54 INFO - 'LOGNAME': 'cltbld', 16:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:54 INFO - 'MOZ_NO_REMOTE': '1', 16:11:54 INFO - 'NO_EM_RESTART': '1', 16:11:54 INFO - 'PAGER': '/bin/cat', 16:11:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:54 INFO - 'PWD': '/builds/slave/test', 16:11:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:54 INFO - 'SHELL': '/bin/bash', 16:11:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cl5lwvhuoY/Listeners', 16:11:54 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:11:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:54 INFO - 'USER': 'cltbld', 16:11:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:54 INFO - 'XPC_FLAGS': '0x0', 16:11:54 INFO - 'XPC_SERVICE_NAME': '0', 16:11:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:54 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)) 16:11:54 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)) 16:11:54 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)) 16:11:54 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)) 16:11:54 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)) 16:11:54 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)) 16:11:54 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)) 16:11:54 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)) 16:11:54 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)) 16:11:54 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)) 16:11:54 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)) 16:11:54 INFO - Cleaning up... 16:11:54 INFO - Return code: 0 16:11:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:54 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 0x102396b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102399768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98124c8af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102174d30>, '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 0x10209bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102169be8>, '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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 16:11:54 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 16:11:54 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 16:11:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3h9fdsMKwS/Render', 16:11:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:54 INFO - 'HOME': '/Users/cltbld', 16:11:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:54 INFO - 'LOGNAME': 'cltbld', 16:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:54 INFO - 'MOZ_NO_REMOTE': '1', 16:11:54 INFO - 'NO_EM_RESTART': '1', 16:11:54 INFO - 'PAGER': '/bin/cat', 16:11:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:54 INFO - 'PWD': '/builds/slave/test', 16:11:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:54 INFO - 'SHELL': '/bin/bash', 16:11:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cl5lwvhuoY/Listeners', 16:11:54 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:11:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:54 INFO - 'USER': 'cltbld', 16:11:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:54 INFO - 'XPC_FLAGS': '0x0', 16:11:54 INFO - 'XPC_SERVICE_NAME': '0', 16:11:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:11:54 INFO - Cleaning up... 16:11:54 INFO - Return code: 0 16:11:54 INFO - Running post-action listener: _resource_record_post_action 16:11:54 INFO - Running post-action listener: _start_resource_monitoring 16:11:54 INFO - Starting resource monitoring. 16:11:54 INFO - [mozharness: 2017-03-25 23:11:54.997782Z] Finished create-virtualenv step (success) 16:11:54 INFO - [mozharness: 2017-03-25 23:11:54.998790Z] Running install step. 16:11:54 INFO - Running pre-action listener: _resource_record_pre_action 16:11:54 INFO - Running main action method: install 16:11:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:11:55 INFO - Reading from file tmpfile_stdout 16:11:55 INFO - Detecting whether we're running mozinstall >=1.0... 16:11:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:11:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:11:55 INFO - Reading from file tmpfile_stdout 16:11:55 INFO - Output received: 16:11:55 INFO - Usage: mozinstall [options] installer 16:11:55 INFO - Options: 16:11:55 INFO - -h, --help show this help message and exit 16:11:55 INFO - -d DEST, --destination=DEST 16:11:55 INFO - Directory to install application into. [default: 16:11:55 INFO - "/builds/slave/test"] 16:11:55 INFO - --app=APP Application being installed. [default: firefox] 16:11:55 INFO - mkdir: /builds/slave/test/build/application 16:11:55 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'] 16:11:55 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 16:12:15 INFO - Reading from file tmpfile_stdout 16:12:15 INFO - Output received: 16:12:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:12:15 INFO - Running post-action listener: _resource_record_post_action 16:12:15 INFO - [mozharness: 2017-03-25 23:12:15.615322Z] Finished install step (success) 16:12:15 INFO - [mozharness: 2017-03-25 23:12:15.615461Z] Running run-tests step. 16:12:15 INFO - Running pre-action listener: _resource_record_pre_action 16:12:15 INFO - Running main action method: run_tests 16:12:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:12:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:12:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:12:15 INFO - 2017-03-25 16:12:15.630 screenresolution[1905:60623] starting screenresolution argv=screenresolution get 16:12:15 INFO - 2017-03-25 16:12:15.633 screenresolution[1905:60623] Display 0: 1600x1200x32@60 16:12:15 INFO - 2017-03-25 16:12:15.638 screenresolution[1906:60626] starting screenresolution argv=screenresolution list 16:12:15 INFO - Available Modes on Display 0 16:12:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:12:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:12:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:12:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:12:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:12:15 INFO - Intel Iris: 16:12:15 INFO - Chipset Model: Intel Iris 16:12:15 INFO - Type: GPU 16:12:15 INFO - Bus: Built-In 16:12:15 INFO - VRAM (Dynamic, Max): 1536 MB 16:12:15 INFO - Vendor: Intel (0x8086) 16:12:15 INFO - Device ID: 0x0a2e 16:12:15 INFO - Revision ID: 0x0009 16:12:15 INFO - Displays: 16:12:15 INFO - Display: 16:12:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:12:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:12:15 INFO - Display Serial Number: 1600x1200 60 16:12:15 INFO - Main Display: Yes 16:12:15 INFO - Mirror: Off 16:12:15 INFO - Online: Yes 16:12:15 INFO - Rotation: Supported 16:12:15 INFO - Return code: 0 16:12:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:12:15 INFO - Python 2.7.3 16:12:15 INFO - Return code: 0 16:12:15 INFO - grabbing minidump binary from tooltool 16:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:15 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 0x102174d30>, '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 0x10209bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102169be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:12:15 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 16:12:15 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 16:12:15 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 16:12:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:12:15 INFO - Return code: 0 16:12:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:12:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:12:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:12:15 INFO - ENV: RUST_BACKTRACE is now 1 16:12:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:12:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:12:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0096 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:12:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3h9fdsMKwS/Render', 16:12:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:12:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:12:16 INFO - 'HOME': '/Users/cltbld', 16:12:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:12:16 INFO - 'LOGNAME': 'cltbld', 16:12:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:12:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:16 INFO - 'MOZ_NO_REMOTE': '1', 16:12:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:12:16 INFO - 'NO_EM_RESTART': '1', 16:12:16 INFO - 'PAGER': '/bin/cat', 16:12:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:16 INFO - 'PWD': '/builds/slave/test', 16:12:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:12:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:12:16 INFO - 'RUST_BACKTRACE': '1', 16:12:16 INFO - 'SHELL': '/bin/bash', 16:12:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cl5lwvhuoY/Listeners', 16:12:16 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 16:12:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:12:16 INFO - 'USER': 'cltbld', 16:12:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:12:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:16 INFO - 'XPC_FLAGS': '0x0', 16:12:16 INFO - 'XPC_SERVICE_NAME': '0', 16:12:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:12:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:12:16 INFO - mozversion application_buildid: 20170325153315 16:12:16 INFO - mozversion application_changeset: 2ecf610d3185de940cb7b4ed2c214dc9e2367cee 16:12:16 INFO - mozversion application_display_name: Nightly 16:12:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:12:16 INFO - mozversion application_name: Firefox 16:12:16 INFO - mozversion application_remotingname: firefox 16:12:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:12:16 INFO - mozversion application_vendor: Mozilla 16:12:16 INFO - mozversion application_version: 55.0a1 16:12:16 INFO - mozversion platform_buildid: 20170325153315 16:12:16 INFO - mozversion platform_changeset: 2ecf610d3185de940cb7b4ed2c214dc9e2367cee 16:12:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:12:16 INFO - mozversion platform_version: 55.0a1 16:12:16 INFO - using testdate: 1490483536 16:12:16 INFO - actual date: 1490483536 16:12:16 INFO - starting webserver on 'localhost:63575' 16:12:16 INFO - SUITE-START | Running 2 tests 16:12:16 INFO - TEST-START | dromaeo_css 16:12:16 INFO - operating with platform_type : mac_ 16:12:16 INFO - Initialising browser for dromaeo_css test... 16:12:16 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmp5N0tdG/profile http://localhost:63575/getInfo.html) 16:12:18 INFO - PID 1916 | __metrics Screen width/height:1600/1200 16:12:18 INFO - PID 1916 | colorDepth:24 16:12:18 INFO - PID 1916 | Browser inner width/height: 1024/689 16:12:18 INFO - PID 1916 | __metrics 16:12:18 INFO - PID 1916 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:12:18 INFO - PID 1916 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:12:19 INFO - TEST-INFO | 1916: exit 0 16:12:19 INFO - Browser initialized. 16:12:19 INFO - Running cycle 1/1 for dromaeo_css test... 16:12:19 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmp5N0tdG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:12:32 INFO - PID 1918 | RSS: Main: 235151360 16:12:32 INFO - PID 1918 | 16:15:36 INFO - PID 1918 | Cycle 1(1): loaded http://localhost:63575/tests/dromaeo/cssquery-dojo.html (next: http://localhost:63575/tests/dromaeo/cssquery-ext.html) 16:15:39 INFO - PID 1918 | RSS: Main: 427282432 16:15:39 INFO - PID 1918 | 16:17:21 INFO - PID 1918 | ************************* 16:17:21 INFO - PID 1918 | A coding exception was thrown and uncaught in a Task. 16:17:21 INFO - PID 1918 | 16:17:21 ERROR - PID 1918 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:17:21 INFO - PID 1918 | Full stack: 16:17:21 INFO - PID 1918 | ************************* 16:18:42 INFO - PID 1918 | Cycle 1(1): loaded http://localhost:63575/tests/dromaeo/cssquery-ext.html (next: http://localhost:63575/tests/dromaeo/cssquery-jquery.html) 16:18:42 INFO - PID 1918 | RSS: Main: 368988160 16:18:42 INFO - PID 1918 | 16:21:45 INFO - PID 1918 | Cycle 1(1): loaded http://localhost:63575/tests/dromaeo/cssquery-jquery.html (next: http://localhost:63575/tests/dromaeo/cssquery-mootools.html) 16:21:45 INFO - PID 1918 | RSS: Main: 344309760 16:21:45 INFO - PID 1918 | 16:24:48 INFO - PID 1918 | Cycle 1(1): loaded http://localhost:63575/tests/dromaeo/cssquery-mootools.html (next: http://localhost:63575/tests/dromaeo/cssquery-prototype.html) 16:24:48 INFO - PID 1918 | RSS: Main: 338403328 16:24:48 INFO - PID 1918 | 16:27:52 INFO - PID 1918 | Cycle 1(1): loaded http://localhost:63575/tests/dromaeo/cssquery-prototype.html (next: http://localhost:63575/tests/dromaeo/cssquery-yui.html) 16:27:53 INFO - PID 1918 | RSS: Main: 336052224 16:27:53 INFO - PID 1918 | 16:30:56 INFO - PID 1918 | Cycle 1(1): loaded http://localhost:63575/tests/dromaeo/cssquery-yui.html (next: http://localhost:63575/tests/dromaeo/cssquery-dojo.html) 16:30:56 INFO - PID 1918 | RSS: Main: 385179648 16:30:56 INFO - PID 1918 | 16:30:56 INFO - PID 1918 | __start_tp_report 16:30:56 INFO - PID 1918 | _x_x_mozilla_page_load 16:30:56 INFO - PID 1918 | _x_x_mozilla_page_load_details 16:30:56 INFO - PID 1918 | |i|pagename|runs| 16:30:56 INFO - PID 1918 | |0;dojo.html;3468.8439020244086;3486.459598762193;3500.7199424046075;3515.63085875983;3541.5395998520203;4863.173260545707;6081.756729730817;6129.417705317993;6131.141640170375;6166.1059146423795;6003.849903752408;6074.240719910012;6080.300765411979;6087.330393656705;6099.420555047274;5387.973060134694;5422.213779002042;5429.864253393677;5433.592480563953;5574.581906357019;5547.029269877776;5554.638948468357;5560.944390556083;5565.109582466806;5628.549716022709;5588.636738611977;5597.992361374951;5601.60788744788;5605.887882242353;5608.46719561643;5577.609567330289;5591.524720398771;5592.133219351007;5605.215269862182;5605.691686957195;4657.4876763555985;5355.651882627617;5501.5323697485765;5515.669059856422;5559.332880054379;6207.596506227081;6243.750249989995;6257.092721555395;6259.123722678829;6266.091416744592;6339.36606339367;6479.028145778124;6479.676016199218;6501.252355979028;6531.118299029617;6470.029495575654;6488.578242414275;6517.543771935966;6561.835954101138;6592.077109204715;5561.944380556223;6035.008447212409;6260.87478250437;6282.685865706696;6286.245650521967;6697.631630260285;6743.1908171019795;6758.05387245786;6758.189017317954;6771.864562708817;6309.305976342599;6330.525210609223;6335.904961425582;6388.968055159695;6394.61632302054;5375.489328513785;6529.869402611921;6559.868802623921;6576.342365763481;6577.440917521969;6419.422251997343;6430.292667806538;6457.935420645734;6479.514036447285;6483.092367068614;6341.968290158612;6405.647689377129;6561.967190164114;6569.277379488252;6578.572392794453;5206.062908676475;5267.26258323835;5310.840674779763;5320.52115309624;5325.094733895247;4700.294955756595;4706.458757242961;4720.669553131316;4749.857504274877;4758.072175925661;6077.4226448487325;6219.222597175353;6234.657093859791;6240.687965601702;6248.031555108965;6406.263279722798;6465.612063276219;6468.126802881645;6564.934350656432;6639.568428052257;4934.679245849008;6254.15568898202;6255;6268.874622507525;6281.560290779693;5788.247527821441;5804.622699524517;5806.854828629233;5811.593188476851;5864.091065884794;6488.967555162194;6529.542931994904;6674.532782705164;6698.196216454056;6712.127423434922;6446.4520515756685;6500.512461565307;6528.706208220547;6533;6537.640429776408;6633.502487313374;6639.800805975829;6669.899951500634;6670.733170673118;6692.6988285526295;6386.137871387489;6420.678966051585;6443;6456.967715161394;6476.708548115252;6346.873062538816;6398.328175541687;6539.509536784665;6555.2133743951035;6560.950247960303;6286.811395658137;6288;6304.306526282014;6306.148669929685;6313.084602732486;6268.435840774352;6297;6304.211973503312;6357.96821015892;6369.713362898588;6268.435840774352;6281.937180628135;6282.591631544118;6283.654399008098;6290.276618188784;6334.398232167937;6353.872922541431;6362.650054246876;6366.267879193952;6375.043743438521;6184.103305020837;6234.096056071753;6240.4071613196675;6240.719167637377;6267.811965641038 16:30:56 INFO - PID 1918 | |1;ext.html;791.1020991175017;808.8382323535291;1126.003486914081;1148.798960181968;1151.1596534529788;36660.083497912565;37489.625103748986;37494;37600.871973840796;37602.62397376025;38486.84539463821;38858.417123743166;38956.02609934753;38975.61024389755;38992.80503597478;6087.421694938974;6199.287081985562;8510.148985101503;8669;8681.435706679045;8173.427860049799;8442.57787110645;8460.576971151419;8462.15378462155;8465.06884242736;8496.277816385615;8538.87191692122;8545.060043395222;8555.828883422328;8556.70051548196;8477.406581539295;8533.359998000175;8540.829183416328;8544.401891867572;8551.230389264934;8433.662653493853;8571.357148213909;8575.399722019461;8581.39930204886;8589.742307730778;38022.80988595067;38133;38258.80870595657;38259;38262.617373826186;2844.2462747371965;2919.226405002676;2991.7307442330075;2996.865141068657;3063.9234019149476;19826.900865495725;26051.08821191268;26125.477490450274;26576;27045.864770676217;2492.4142826435773;2786.2755683522228;2789.511835428792;2817.6618805743237;2892.334763004521;254885;256905.71547142332;256965;259271;312911.57972622995;38399;39194.41208381877;39295.21409571793;48476;48478;48892.75553622209;49339.51981440563;49429;49477.50522494801;49506.00987980292;9910.15763660097;12383.6284911451;14124.646883827781;14278.357473913698;14321.6419589509;11256.380899050468;11362.659120226406;11369.602063927889;11390.487428065757;11409;13415.463381464631;13447.588003259712;13507.729845403233;13579.932100339434;13697.452101916011;5866.794662186803;5879.853003674857;5930.28836539618;5938.851528711731;5948.67282299469;948.7343543807744;955.7467271173144;960.706984369774;991.2218908157062;993.1558175550869;22858.657120143213;24148;24248.393790155034;24255.514889702106;24310.513789724107;942.5617088054088;1052.8104941110673;1054.1408751867205;1080.8216644253766;1089.3387583934564;10014.49927503622;10626.574937002433;12211.389430528438;12212.57255996036;12216.877831221575;502038;503422;504585.58729713765;504980;505436;45930.770346148725;56139;56629.43370566241;56795.432045679016;56966.71516642473;1392.2968900705282;1401.5865319730704;1455.7088582283586;1464.6484843637663;1465.4724299252048;23274.06903723832;23811.64282535798;23812.16657416982;23835.284941451784;23853.045878164678;38165.809170953966;38276.6172338273;38329.041773954756;38331.041723957125;38405.4239186418;306145;359990;370902;443753.5624643712;444588;27473;32640.183995400304;32760.344793104483;32993.84521541734;33012;35315.823420882734;43524.78237608792;43918.780406097765;45296.773516132205;45399.546004540854;26933.192004239903;29848.850755746083;29873;30691;30995.845020774752;28191.71808281891;29931.85034074816;30265.697343026288;30403.847980759954;30468.695313047472;33152.336953261285;35800.8209958959;36118.27763444769;36386.8180659095;36621.816890915376;25432.109876154987;29434.411311774074;29975.950841720434;30638.693613063584;30992;19480.610387792447;21237.6814347788;23957.880210598836;24134.758652413955;24595.877020614782 16:30:56 INFO - PID 1918 | |2;jquery.html;358.65748210459014;388.7881104797885;425.1878911279456;434.37450071896467;441.30714777798846;13044;14963.850361496381;21060.473488162766;21431.357059288228;21457.8198199099;22025;22122.778772212274;22770.88614556929;23036.884815575897;23418.88290558549;5859.853503662422;6120.540959428038;6174.351693072219;6204.844878878019;6216.471599914013;5927.436893495111;6159.692015399212;6198.690065496729;6204.069389591552;6204.751809927598;5272.762725677335;6101.816945491642;6177.907331390033;6182.103594978726;6188.102725104857;5936.109583562457;6061.575689701723;6144.84637884052;6173.351798061206;6173.475254603341;5424.267723857308;5426.023315803154;5431.538319242869;5431.646942948735;5434.3207099112615;27846.5823012655;28006.439871202667;28333.86664533437;28463.430731385462;28488.715112848815;2004.258424382973;2010.9899450502653;2034.4608678700156;2056.4961584411767;2116.016052535578;13342.599722008355;15849.920750396173;16102.03387796736;16876.66246675072;17271;1608.4692051622937;1649.6370798424327;1666.2002238925263;1698.1424380687674;1734.0982689001776;148695;158123;158643;158888.20555897147;161757.99999999884;10198.592056317664;12137.722814839944;12250.754984900252;12295.385230738428;12353.3205673687;16265.51203463898;16347.34610615586;16356.263968121462;16363.018218906904;16417.83582164187;7751.806204844924;8519.190676885686;8879.023307436177;9005.234555062892;9042.63829446828;7974.800629984296;8451.66193352259;8476.152384761475;8652.221300083023;8694.347923905605;7557.6221188940335;7687.192844751333;7712.15166331703;7726.768196953988;7733.8453230935065;1766.5848525596475;2035.4809523571394;2036.8390017690072;2069.2343832781967;2299.9655005174936;880.7269746378643;887.8179973105498;899.5232526760827;943.8631398447187;949.5537097564134;19789.505262368555;20345.389638310873;20497.48756281075;20529.2814751483;20733.934029294127;902.5487256371814;947.1246931076507;963.6530848894392;975.1613612293422;980.1717548671361;10413.219008574388;10717.410542420086;10785.514651840685;10786.73033174177;10835.404052777172;506495;685997.5700121467;698565;702593;714757;147480.26259868845;151026;153336.46663533445;156451;156516;1085.3054045411102;1112.3437172068357;1142.6800495861173;1144.2848219862585;1150.0339714639742;17663.20515576828;17747.378841740476;17909.820901790816;17935.282588696307;18476.168572414543;41502.584974149875;57395.713021434625;58542.12186817116;61241;61901;88797;89423;89453;89510.20979580241;90416;8177.9591102044105;8230.176982301722;8481.787955301166;8586.313094952264;8597.18326758957;13257.933710331386;13376.924804711794;13422.127561708458;13572.1856688599;13817;9383.953080234829;11009.669709908716;11277.32336059839;11450.427478626201;11480.770384592428;10068.244881634053;10241.283110182216;10314;10649.89350106489;10750.301230419998;11451.59919402817;11763;11902.821457678316;11953.370258543107;11965.76068478643;8267.586620669063;8402.201790829862;8432.494050356998;8486.40595158333;8563.357748168788;10868.565257389615;11334.60328888485;11391.088712902781;11572.710682233012;11594.420278986187 16:30:56 INFO - PID 1918 | |3;mootools.html;1195.8146487294462;1362.598033580094;1432.4699861051413;1455.2214565207612;1460.9342579583918;9354.81290374192;9792.216622670187;10869.347839129658;11178.217524773268;11180.66458006259;10784.514696838642;10867.619633312815;10870.510827012784;10903.890961090387;10911.563537458493;2013.5570174561647;2027.4931267183167;2485.7017157941073;2487.465194983078;2493.4015836199274;2139.764625891151;2164.9783502164933;2406.3262286559793;2525.9747402525923;2527.3934255778663;2458.1642241637837;2476.6904136982876;2498.537770512461;2502.799776017914;2510.723820379757;2483.4039830440743;2502.4995000999875;2502.962455563168;2509.2723110297993;2517.874106294696;2446.1287115356768;2480.7271200167966;2484.254723582918;2492.140211626986;2493.7556199420487;10873.891261087367;11130.666080017612;11136.944315278453;11137.777244455147;11144.108471322275;6169.2288463942;6410.551261411703;6420.357964203542;6421.486281097547;6435.131257280254;3691.0956815580084;3827.7129215308964;4023.155137421145;4032.4354590357375;4037.576054514263;1160.8897154770284;1170.0405667352775;1170.2627344772739;1177.5010700030875;1199.7960346741074;15084.698306033928;15239.314230859527;15420.691586168214;15443.768343474856;15444;5412.242286079952;5742.913856292159;5799.101139323411;5821.301443826768;5864.677442740605;12845.743085138245;12855.678608034874;12856.357182140855;12881.162724422882;12887.677808054686;6064.090386442069;6406.8718625627225;6410.55126141175;6451.806445806634;6478.708458119396;4708.293755936637;5877;5881.882362352814;5882.617629854131;5883.0881213411985;3374.4769560718128;4438.844640437569;4468;4496.617787488035;4498.2577874650315;1180.4097951024487;1403.5368328451586;1406.8593140686055;1425.209009000015;1429.756941319978;692.757534862794;701.7614011236102;708.3412426443457;712.7932899459203;729.6351824087956;6036.245469316335;6286.748530058838;6523.217213934264;6579.078928949951;6653.334666533308;893.7487517475586;899.3344924755634;908.8182363527321;910.3718434280325;921.764949937775;3846.3268927937497;4025.3156963316305;4094.242565125461;4198.265303571863;4200.663946884242;13357.866421335662;14043.297835108408;14175.503857364944;14197.574072777834;14224.928875355765;13601.540171483104;14085.71828563423;14101.929490352482;14138.293085345691;14139.929300353639;10051.547680354402;10300.536475858604;10308.587656493657;10314.638987635546;10317.226208034277;6147.108669242845;6183.193227269705;6216.191895053614;6385.393387628168;6446.4520515756685;12211.450484728386;12638.2417054977;12698.809517857055;12701.745965080832;12748.87251127514;12526.930901929085;12629.179103358252;12760.361981901053;12857.549985750455;12992.675183120498;7430.256974302527;9024.458532488072;9077.4099683523;9115.088491150831;9162.862557061782;8901.955490222508;9166.266698663958;9171.128742769426;9900.356476828983;10199.745006374604;7229;8543.273821725223;8654;8691.087435819149;8749.693760718344;6844.726210951705;8106.675732970615;8498.872516912375;8500.404971651926;8545.401821872409;7657.885131722917;7658.081030276402;7679.155292917663;7718.57547834852;7769.912212290397;5758.107493338539;5805.796797112081;5865.472107510344;5926.822195334147;5972.850678733066;4864.635152363658;5914.408559144051;5935.228420305332;5948;6196.659183744757 16:30:56 INFO - PID 1918 | |4;prototype.html;395.873320537428;399.8400639744102;403.1795296571478;410.52379240081495;411.5884115884116;4699;6060.787872424466;6114.008271893547;6123.479504242145;6130.2643682758135;4702.8007857996245;4726.620466366548;5548.77804887804;5694.658320500763;6006;3891.416287556861;3931.783751893645;4051.918961620766;4641.327007583898;4731.668783185179;4358;4708.857469851227;4734.834280800155;4736.123817093849;4757.738324392157;4104.056067104568;4520.660950428714;4646.767661616923;4690.569376340215;4703.894584772575;4184.811683474251;4437.866863994053;4623.398958135454;4655.13879932212;4677.859664210079;4119.258533463975;4122.381642753581;4126.442930204414;4128.761371588512;4167.874963751062;5505.449455054502;5661.971690141564;5809.7095145242565;5846.100017494331;5848.941510584882;4917.868890155249;5198.766055527511;5300.734963251822;5423.891522169534;5463.754131064112;5284.497972692588;6476.805695829085;6477.125588045601;6509.934900651027;6531;3333.8331583945424;4280.5291417944;4306.849260275875;4329.177456283296;4336.934945975813;5698.629589076697;5776;6577.927952694886;6597.47220222381;6762.492813039042;4292.12011537634;4367.10474352757;4378.8905277367685;4386.692931494765;4405.207062728676;4065.207284579538;4134.855280065243;4224.704270701083;4344.1311737652595;4472.373867658531;4883.389576302962;5087.974560127176;5099.796008159632;5160.483951604884;5270.49930256625;5757.740901659436;6412.2625898022325;6500.609963402211;6516.609003459807;6534.444572211415;1487.2192099147817;1517.4309633887292;1517.6130086827789;1527.343242405754;1531.3032570180765;874.5583480342386;943.4150826487621;977.7897751983215;978.8791833350773;1015.1979935850743;5141.2288156776785;6090.65014242165;6104.969475152596;6118.265808103056;6118.694065296718;5623.188405797101;6624.668766561749;6651.900221496681;6668.133142691419;6707.0610114584;5491.917621235684;5785.595008349375;6095.1771510845465;6118.632882027093;6152;3068.2636167319683;3450.6721861423125;3499.2826470573473;3501.77238479498;3504.351694936445;6085.6750343449485;6252.718627661766;6691.631960242234;6811.421029212573;6935.4104901082965;5701.4298570142655;6822.658867056627;6861.279565645636;6895.655217239118;6905.896411553831;6047.7278522466595;6713.899291510729;6764.154480689914;6854.897176542356;6900.2064762550945;5419.268398766274;6305.558610897193;6350.491960643045;6583.247548897083;6661.168178750882;5807.216025836458;6420.775272865428;6626.53614246998;6642.169728783902;6650.634215118215;6002.369751176064;6268.780592679417;6549.377809108127;6552.6723663815665;6910.516263861481;3477.7739446935866;3553.875614353485;4426.291793313055;4428.911421771611;4433.268510695699;5375.951689420525;5586.916196257141;6263.561550691408;6750.054992300984;6761.154855643044;6485.984017431917;6538.346165383424;6540.411362977165;6553.377429144223;6562.278149403657;6172.290186628596;6479.902801458076;6516.706748196248;6537.123665587126;6563.7162084522415;5481.835544933659;6368.840778980562;6398;6402.455791257796;6412.6793660317735;6477.931141361622;6478.870422591427;6484.5460817742305;6559.967200163968;6561.212654481493;5922.940770592239;6213.2233470816145;6300.873982520416;6325.493960483058;6370.649614271259 16:30:56 INFO - PID 1918 | |5;yui.html;715.6743681624861;716.4769718105782;733.2630706140328;737.365865355794;767.2136060537948;14626.780598291021;15559.844401555994;15697;17426.912865435654;18340.358087466942;14389.64025899353;16440.917795411035;17041.403550875722;17159.313627454918;17203.655926881456;928.7167413938746;956.3449037409397;961.2214106573698;962.1004360922525;962.3648392061241;918.1874041473311;952.8570714392828;953.880764904387;967.3712087143343;969.0551712080735;1019.2610357490834;1019.9235057370689;1019.94390308533;1020.4948550467492;1020.6682828080903;1012.4937531234383;1013.5591017907234;1014.6905193915853;1016.2124353625925;1018.063381688848;745.2137994415889;746.6080307838374;749.8800191969259;751.031169790969;751.2862780358627;15781.92109039459;15816.841831581653;16057.197140142945;16127.871049026573;16280.430184943589;12188.878111218863;12192.756144877141;12281.57014504497;12498.75002499954;12648.051396145234;11508.163032309585;11748;11835.408229588485;11835.467403966755;12063.577774777928;2672.14491362764;2737.397772490049;2747.6290700755544;2748.6014527893544;2828.490871643103;109798;114606.99999999917;114938;114941;117186;11428.771424571461;11543.249688770204;11728.237664551947;11854.288742675468;11933.52265909354;24969.5006099877;25189.874050629995;25196;25197.496050078895;25226.243212703648;7691;7760.767176984699;7861.095973962953;7909.24862138096;8030.799230019179;3979.880603581897;4044.1507283470214;4048.959510404917;4058.127502586958;4101.589841015874;4145.54399016108;4168.0205151789305;4202.936955945664;4244.766537840448;4257.361395790656;785.8467598818175;793.5080250244885;827.5324441690426;830.2444775254489;851.3912552524974;536.5358964495761;554.1070712967619;585.55497821655;590.9881802363929;593.3947373678909;9158.542072896462;9819.16537094355;10725.678229653124;11142.442877856074;11303.604373846876;519.857039314184;553.714836859018;578.1558923971049;584.3105135939632;586.794138046319;4676.649251306166;4719.646026548023;4721.315409265637;4734.597559207437;4739.3838800955655;71686.64156679288;71720.2827971724;74551.25448745443;76185.23814761892;80891.59554202309;67058.65882682319;69507.65246173737;78854;86009;87528.56235718908;70104.57951872524;72543.2745672547;73517;73912.63043684747;75003.87494187591;8459.83080338402;9513.381630194015;9556.426614403159;9604;9642.951785241028;32431.837840810644;35240.766573169814;36286.455703165004;36491.90524284276;37076.62923370732;46420.30369544392;46885.29672054854;46923.296150557086;47460.05079898452;48486.75756621194;11468.139889508153;11583.189176757884;11585.67838570831;11700.2979821211;11840.940795295968;12336;12386.86268872728;12568.937155314165;12656.43046062911;12714.809277860655;11516.30902145874;11714.23857449263;11724.648260552198;11815.17293789461;11976.401179940794;11551.42242887869;11633.59933135671;11640.064953067542;11673.941630291794;11905.702357440787;9772.823832254688;11617.593384231512;11619;11621.535138594361;12073.456694448596;7399.112106547249;8515.574221289035;8518.36112291593;8652.480851148952;9008.729738107868;8390.958045209734;9191.816163676824;9921;9933.850992235266;10098.495075246356 16:30:56 INFO - PID 1918 | __end_tp_report 16:30:56 INFO - PID 1918 | __start_cc_report 16:30:56 INFO - PID 1918 | _x_x_mozilla_cycle_collect,2110 16:30:56 INFO - PID 1918 | __end_cc_report 16:30:56 INFO - PID 1918 | __startTimestamp1490484656817__endTimestamp 16:30:56 INFO - PID 1918 | 16:30:56 INFO - PID 1918 | ------- Summary: start ------- 16:30:56 INFO - PID 1918 | Number of tests: 6 16:30:56 INFO - PID 1918 | 16:30:56 INFO - PID 1918 | [#0] dojo.html Cycles:165 Average:6042.77 Median:6282.59 stddev:669.63 (10.7%) stddev-sans-first:640.51 16:30:56 INFO - PID 1918 | Values: 3468.8 3486.5 3500.7 3515.6 3541.5 4863.2 6081.8 6129.4 6131.1 6166.1 6003.8 6074.2 6080.3 6087.3 6099.4 5388.0 5422.2 5429.9 5433.6 5574.6 5547.0 5554.6 5560.9 5565.1 5628.5 5588.6 5598.0 5601.6 5605.9 5608.5 5577.6 5591.5 5592.1 5605.2 5605.7 4657.5 5355.7 5501.5 5515.7 5559.3 6207.6 6243.8 6257.1 6259.1 6266.1 6339.4 6479.0 6479.7 6501.3 6531.1 6470.0 6488.6 6517.5 6561.8 6592.1 5561.9 6035.0 6260.9 6282.7 6286.2 6697.6 6743.2 6758.1 6758.2 6771.9 6309.3 6330.5 6335.9 6389.0 6394.6 5375.5 6529.9 6559.9 6576.3 6577.4 6419.4 6430.3 6457.9 6479.5 6483.1 6342.0 6405.6 6562.0 6569.3 6578.6 5206.1 5267.3 5310.8 5320.5 5325.1 4700.3 4706.5 4720.7 4749.9 4758.1 6077.4 6219.2 6234.7 6240.7 6248.0 6406.3 6465.6 6468.1 6564.9 6639.6 4934.7 6254.2 6255.0 6268.9 6281.6 5788.2 5804.6 5806.9 5811.6 5864.1 6489.0 6529.5 6674.5 6698.2 6712.1 6446.5 6500.5 6528.7 6533.0 6537.6 6633.5 6639.8 6669.9 6670.7 6692.7 6386.1 6420.7 6443.0 6457.0 6476.7 6346.9 6398.3 6539.5 6555.2 6561.0 6286.8 6288.0 6304.3 6306.1 6313.1 6268.4 6297.0 6304.2 6358.0 6369.7 6268.4 6281.9 6282.6 6283.7 6290.3 6334.4 6353.9 6362.7 6366.3 6375.0 6184.1 6234.1 6240.4 6240.7 6267.8 16:30:56 INFO - PID 1918 | 16:30:56 INFO - PID 1918 | [#1] ext.html Cycles:180 Average:51614.63 Median:198054.14 stddev:106348.74 (53.7%) stddev-sans-first:106578.61 16:30:56 INFO - PID 1918 | Values: 791.1 808.8 1126.0 1148.8 1151.2 36660.1 37489.6 37494.0 37600.9 37602.6 38486.8 38858.4 38956.0 38975.6 38992.8 6087.4 6199.3 8510.1 8669.0 8681.4 8173.4 8442.6 8460.6 8462.2 8465.1 8496.3 8538.9 8545.1 8555.8 8556.7 8477.4 8533.4 8540.8 8544.4 8551.2 8433.7 8571.4 8575.4 8581.4 8589.7 38022.8 38133.0 38258.8 38259.0 38262.6 2844.2 2919.2 2991.7 2996.9 3063.9 19826.9 26051.1 26125.5 26576.0 27045.9 2492.4 2786.3 2789.5 2817.7 2892.3 254885.0 256905.7 256965.0 259271.0 312911.6 38399.0 39194.4 39295.2 48476.0 48478.0 48892.8 49339.5 49429.0 49477.5 49506.0 9910.2 12383.6 14124.6 14278.4 14321.6 11256.4 11362.7 11369.6 11390.5 11409.0 13415.5 13447.6 13507.7 13579.9 13697.5 5866.8 5879.9 5930.3 5938.9 5948.7 948.7 955.7 960.7 991.2 993.2 22858.7 24148.0 24248.4 24255.5 24310.5 942.6 1052.8 1054.1 1080.8 1089.3 10014.5 10626.6 12211.4 12212.6 12216.9 502038.0 503422.0 504585.6 504980.0 505436.0 45930.8 56139.0 56629.4 56795.4 56966.7 1392.3 1401.6 1455.7 1464.6 1465.5 23274.1 23811.6 23812.2 23835.3 23853.0 38165.8 38276.6 38329.0 38331.0 38405.4 306145.0 359990.0 370902.0 443753.6 444588.0 27473.0 32640.2 32760.3 32993.8 33012.0 35315.8 43524.8 43918.8 45296.8 45399.5 26933.2 29848.9 29873.0 30691.0 30995.8 28191.7 29931.9 30265.7 30403.8 30468.7 33152.3 35800.8 36118.3 36386.8 36621.8 25432.1 29434.4 29976.0 30638.7 30992.0 19480.6 21237.7 23957.9 24134.8 24595.9 16:30:56 INFO - PID 1918 | 16:30:56 INFO - PID 1918 | [#2] jquery.html Cycles:180 Average:39400.90 Median:12535.43 stddev:112339.57 (896.2%) stddev-sans-first:112616.46 16:30:56 INFO - PID 1918 | Values: 358.7 388.8 425.2 434.4 441.3 13044.0 14963.9 21060.5 21431.4 21457.8 22025.0 22122.8 22770.9 23036.9 23418.9 5859.9 6120.5 6174.4 6204.8 6216.5 5927.4 6159.7 6198.7 6204.1 6204.8 5272.8 6101.8 6177.9 6182.1 6188.1 5936.1 6061.6 6144.8 6173.4 6173.5 5424.3 5426.0 5431.5 5431.6 5434.3 27846.6 28006.4 28333.9 28463.4 28488.7 2004.3 2011.0 2034.5 2056.5 2116.0 13342.6 15849.9 16102.0 16876.7 17271.0 1608.5 1649.6 1666.2 1698.1 1734.1 148695.0 158123.0 158643.0 158888.2 161758.0 10198.6 12137.7 12250.8 12295.4 12353.3 16265.5 16347.3 16356.3 16363.0 16417.8 7751.8 8519.2 8879.0 9005.2 9042.6 7974.8 8451.7 8476.2 8652.2 8694.3 7557.6 7687.2 7712.2 7726.8 7733.8 1766.6 2035.5 2036.8 2069.2 2300.0 880.7 887.8 899.5 943.9 949.6 19789.5 20345.4 20497.5 20529.3 20733.9 902.5 947.1 963.7 975.2 980.2 10413.2 10717.4 10785.5 10786.7 10835.4 506495.0 685997.6 698565.0 702593.0 714757.0 147480.3 151026.0 153336.5 156451.0 156516.0 1085.3 1112.3 1142.7 1144.3 1150.0 17663.2 17747.4 17909.8 17935.3 18476.2 41502.6 57395.7 58542.1 61241.0 61901.0 88797.0 89423.0 89453.0 89510.2 90416.0 8178.0 8230.2 8481.8 8586.3 8597.2 13257.9 13376.9 13422.1 13572.2 13817.0 9384.0 11009.7 11277.3 11450.4 11480.8 10068.2 10241.3 10314.0 10649.9 10750.3 11451.6 11763.0 11902.8 11953.4 11965.8 8267.6 8402.2 8432.5 8486.4 8563.4 10868.6 11334.6 11391.1 11572.7 11594.4 16:30:56 INFO - PID 1918 | 16:30:56 INFO - PID 1918 | [#3] mootools.html Cycles:180 Average:6805.55 Median:3759.40 stddev:4252.39 (113.1%) stddev-sans-first:4243.42 16:30:56 INFO - PID 1918 | Values: 1195.8 1362.6 1432.5 1455.2 1460.9 9354.8 9792.2 10869.3 11178.2 11180.7 10784.5 10867.6 10870.5 10903.9 10911.6 2013.6 2027.5 2485.7 2487.5 2493.4 2139.8 2165.0 2406.3 2526.0 2527.4 2458.2 2476.7 2498.5 2502.8 2510.7 2483.4 2502.5 2503.0 2509.3 2517.9 2446.1 2480.7 2484.3 2492.1 2493.8 10873.9 11130.7 11136.9 11137.8 11144.1 6169.2 6410.6 6420.4 6421.5 6435.1 3691.1 3827.7 4023.2 4032.4 4037.6 1160.9 1170.0 1170.3 1177.5 1199.8 15084.7 15239.3 15420.7 15443.8 15444.0 5412.2 5742.9 5799.1 5821.3 5864.7 12845.7 12855.7 12856.4 12881.2 12887.7 6064.1 6406.9 6410.6 6451.8 6478.7 4708.3 5877.0 5881.9 5882.6 5883.1 3374.5 4438.8 4468.0 4496.6 4498.3 1180.4 1403.5 1406.9 1425.2 1429.8 692.8 701.8 708.3 712.8 729.6 6036.2 6286.7 6523.2 6579.1 6653.3 893.7 899.3 908.8 910.4 921.8 3846.3 4025.3 4094.2 4198.3 4200.7 13357.9 14043.3 14175.5 14197.6 14224.9 13601.5 14085.7 14101.9 14138.3 14139.9 10051.5 10300.5 10308.6 10314.6 10317.2 6147.1 6183.2 6216.2 6385.4 6446.5 12211.5 12638.2 12698.8 12701.7 12748.9 12526.9 12629.2 12760.4 12857.5 12992.7 7430.3 9024.5 9077.4 9115.1 9162.9 8902.0 9166.3 9171.1 9900.4 10199.7 7229.0 8543.3 8654.0 8691.1 8749.7 6844.7 8106.7 8498.9 8500.4 8545.4 7657.9 7658.1 7679.2 7718.6 7769.9 5758.1 5805.8 5865.5 5926.8 5972.9 4864.6 5914.4 5935.2 5948.0 6196.7 16:30:56 INFO - PID 1918 | 16:30:56 INFO - PID 1918 | [#4] prototype.html Cycles:180 Average:5186.01 Median:5796.41 stddev:1619.39 (27.9%) stddev-sans-first:1583.52 16:30:56 INFO - PID 1918 | Values: 395.9 399.8 403.2 410.5 411.6 4699.0 6060.8 6114.0 6123.5 6130.3 4702.8 4726.6 5548.8 5694.7 6006.0 3891.4 3931.8 4051.9 4641.3 4731.7 4358.0 4708.9 4734.8 4736.1 4757.7 4104.1 4520.7 4646.8 4690.6 4703.9 4184.8 4437.9 4623.4 4655.1 4677.9 4119.3 4122.4 4126.4 4128.8 4167.9 5505.4 5662.0 5809.7 5846.1 5848.9 4917.9 5198.8 5300.7 5423.9 5463.8 5284.5 6476.8 6477.1 6509.9 6531.0 3333.8 4280.5 4306.8 4329.2 4336.9 5698.6 5776.0 6577.9 6597.5 6762.5 4292.1 4367.1 4378.9 4386.7 4405.2 4065.2 4134.9 4224.7 4344.1 4472.4 4883.4 5088.0 5099.8 5160.5 5270.5 5757.7 6412.3 6500.6 6516.6 6534.4 1487.2 1517.4 1517.6 1527.3 1531.3 874.6 943.4 977.8 978.9 1015.2 5141.2 6090.7 6105.0 6118.3 6118.7 5623.2 6624.7 6651.9 6668.1 6707.1 5491.9 5785.6 6095.2 6118.6 6152.0 3068.3 3450.7 3499.3 3501.8 3504.4 6085.7 6252.7 6691.6 6811.4 6935.4 5701.4 6822.7 6861.3 6895.7 6905.9 6047.7 6713.9 6764.2 6854.9 6900.2 5419.3 6305.6 6350.5 6583.2 6661.2 5807.2 6420.8 6626.5 6642.2 6650.6 6002.4 6268.8 6549.4 6552.7 6910.5 3477.8 3553.9 4426.3 4428.9 4433.3 5376.0 5586.9 6263.6 6750.1 6761.2 6486.0 6538.3 6540.4 6553.4 6562.3 6172.3 6479.9 6516.7 6537.1 6563.7 5481.8 6368.8 6398.0 6402.5 6412.7 6477.9 6478.9 6484.5 6560.0 6561.2 5922.9 6213.2 6300.9 6325.5 6370.6 16:30:56 INFO - PID 1918 | 16:30:56 INFO - PID 1918 | [#5] yui.html Cycles:180 Average:18340.24 Median:4053.54 stddev:26209.98 (646.6%) stddev-sans-first:26250.10 16:30:56 INFO - PID 1918 | Values: 715.7 716.5 733.3 737.4 767.2 14626.8 15559.8 15697.0 17426.9 18340.4 14389.6 16440.9 17041.4 17159.3 17203.7 928.7 956.3 961.2 962.1 962.4 918.2 952.9 953.9 967.4 969.1 1019.3 1019.9 1019.9 1020.5 1020.7 1012.5 1013.6 1014.7 1016.2 1018.1 745.2 746.6 749.9 751.0 751.3 15781.9 15816.8 16057.2 16127.9 16280.4 12188.9 12192.8 12281.6 12498.8 12648.1 11508.2 11748.0 11835.4 11835.5 12063.6 2672.1 2737.4 2747.6 2748.6 2828.5 109798.0 114607.0 114938.0 114941.0 117186.0 11428.8 11543.2 11728.2 11854.3 11933.5 24969.5 25189.9 25196.0 25197.5 25226.2 7691.0 7760.8 7861.1 7909.2 8030.8 3979.9 4044.2 4049.0 4058.1 4101.6 4145.5 4168.0 4202.9 4244.8 4257.4 785.8 793.5 827.5 830.2 851.4 536.5 554.1 585.6 591.0 593.4 9158.5 9819.2 10725.7 11142.4 11303.6 519.9 553.7 578.2 584.3 586.8 4676.6 4719.6 4721.3 4734.6 4739.4 71686.6 71720.3 74551.3 76185.2 80891.6 67058.7 69507.7 78854.0 86009.0 87528.6 70104.6 72543.3 73517.0 73912.6 75003.9 8459.8 9513.4 9556.4 9604.0 9643.0 32431.8 35240.8 36286.5 36491.9 37076.6 46420.3 46885.3 46923.3 47460.1 48486.8 11468.1 11583.2 11585.7 11700.3 11840.9 12336.0 12386.9 12568.9 12656.4 12714.8 11516.3 11714.2 11724.6 11815.2 11976.4 11551.4 11633.6 11640.1 11673.9 11905.7 9772.8 11617.6 11619.0 11621.5 12073.5 7399.1 8515.6 8518.4 8652.5 9008.7 8391.0 9191.8 9921.0 9933.9 10098.5 16:30:56 INFO - PID 1918 | -------- Summary: end -------- 16:30:56 INFO - PID 1918 | 16:30:57 INFO - TEST-INFO | 1918: exit 0 16:30:57 INFO - TEST-OK | dromaeo_css | took 1121289ms 16:30:57 INFO - TEST-START | kraken 16:30:57 INFO - operating with platform_type : mac_ 16:30:57 INFO - Initialising browser for kraken test... 16:30:57 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmp0idHfG/profile http://localhost:63575/getInfo.html) 16:30:59 INFO - PID 1922 | __metrics Screen width/height:1600/1200 16:30:59 INFO - PID 1922 | colorDepth:24 16:30:59 INFO - PID 1922 | Browser inner width/height: 1024/689 16:30:59 INFO - PID 1922 | __metrics 16:30:59 INFO - PID 1922 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:30:59 INFO - PID 1922 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:31:00 INFO - TEST-INFO | 1922: exit 0 16:31:00 INFO - Browser initialized. 16:31:00 INFO - Running cycle 1/1 for kraken test... 16:31:00 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmp0idHfG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:31:13 INFO - PID 1923 | RSS: Main: 233984000 16:31:13 INFO - PID 1923 | 16:31:16 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:63575/tests/kraken/driver.html?testName=audio-beat-detection) 16:31:17 INFO - PID 1923 | RSS: Main: 285691904 16:31:17 INFO - PID 1923 | 16:31:19 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:63575/tests/kraken/driver.html?testName=audio-dft) 16:31:20 INFO - PID 1923 | RSS: Main: 289046528 16:31:20 INFO - PID 1923 | 16:31:23 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:63575/tests/kraken/driver.html?testName=audio-fft) 16:31:24 INFO - PID 1923 | RSS: Main: 377659392 16:31:24 INFO - PID 1923 | 16:31:26 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:63575/tests/kraken/driver.html?testName=audio-oscillator) 16:31:26 INFO - PID 1923 | RSS: Main: 281460736 16:31:26 INFO - PID 1923 | 16:31:29 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:63575/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:31:29 INFO - PID 1923 | RSS: Main: 284274688 16:31:29 INFO - PID 1923 | 16:31:32 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:63575/tests/kraken/driver.html?testName=imaging-darkroom) 16:31:33 INFO - PID 1923 | RSS: Main: 326078464 16:31:33 INFO - PID 1923 | 16:31:36 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:63575/tests/kraken/driver.html?testName=imaging-desaturate) 16:31:36 INFO - PID 1923 | RSS: Main: 334053376 16:31:36 INFO - PID 1923 | 16:31:39 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:63575/tests/kraken/driver.html?testName=json-parse-financial) 16:31:40 INFO - PID 1923 | RSS: Main: 337457152 16:31:40 INFO - PID 1923 | 16:31:42 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:63575/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:31:42 INFO - PID 1923 | RSS: Main: 303050752 16:31:42 INFO - PID 1923 | 16:31:44 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:63575/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:31:45 INFO - PID 1923 | RSS: Main: 305872896 16:31:45 INFO - PID 1923 | 16:31:47 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:63575/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:31:48 INFO - PID 1923 | RSS: Main: 307691520 16:31:48 INFO - PID 1923 | 16:31:51 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:63575/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:31:52 INFO - PID 1923 | RSS: Main: 327036928 16:31:52 INFO - PID 1923 | 16:31:55 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:63575/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:31:55 INFO - PID 1923 | RSS: Main: 320671744 16:31:55 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:63575/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:63575/tests/kraken/driver.html?testName=ai-astar) 16:31:57 INFO - PID 1923 | RSS: Main: 382238720 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | __start_tp_report 16:31:57 INFO - PID 1923 | _x_x_mozilla_page_load 16:31:57 INFO - PID 1923 | _x_x_mozilla_page_load_details 16:31:57 INFO - PID 1923 | |i|pagename|runs| 16:31:57 INFO - PID 1923 | |0;ai-astar;112;112;114;157;143;111;111;112;112;111 16:31:57 INFO - PID 1923 | |1;audio-beat-detection;150;109;111;153;112;111;114;138;165;112 16:31:57 INFO - PID 1923 | |2;audio-dft;186;184;185;182;182;186;186;184;188;183 16:31:57 INFO - PID 1923 | |3;audio-fft;81;58;62;93;62;60;60;57;75;64 16:31:57 INFO - PID 1923 | |4;audio-oscillator;90;70;94;100;70;70;83;69;68;85 16:31:57 INFO - PID 1923 | |5;imaging-gaussian-blur;88;86;86;87;85;86;86;87;85;86 16:31:57 INFO - PID 1923 | |6;imaging-darkroom;94;95;95;94;94;94;94;93;93;94 16:31:57 INFO - PID 1923 | |7;imaging-desaturate;83;84;84;85;85;84;83;84;84;83 16:31:57 INFO - PID 1923 | |8;json-parse-financial;65;63;61;60;62;62;63;61;62;61 16:31:57 INFO - PID 1923 | |9;json-stringify-tinderbox;54;54;66;60;66;58;58;59;59;58 16:31:57 INFO - PID 1923 | |10;stanford-crypto-aes;87;100;106;105;84;81;82;82;84;83 16:31:57 INFO - PID 1923 | |11;stanford-crypto-ccm;140;138;165;152;148;139;137;158;197;182 16:31:57 INFO - PID 1923 | |12;stanford-crypto-pbkdf2;188;188;188;187;187;191;194;186;189;181 16:31:57 INFO - PID 1923 | |13;stanford-crypto-sha256-iterative;67;69;68;67;68;69;69;68;68;68 16:31:57 INFO - PID 1923 | __end_tp_report 16:31:57 INFO - PID 1923 | __start_cc_report 16:31:57 INFO - PID 1923 | _x_x_mozilla_cycle_collect,2221 16:31:57 INFO - PID 1923 | __end_cc_report 16:31:57 INFO - PID 1923 | __startTimestamp1490484717829__endTimestamp 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | ------- Summary: start ------- 16:31:57 INFO - PID 1923 | Number of tests: 14 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#0] ai-astar Cycles:10 Average:119.50 Median:112.00 stddev:16.43 (14.7%) stddev-sans-first:17.20 16:31:57 INFO - PID 1923 | Values: 112.0 112.0 114.0 157.0 143.0 111.0 111.0 112.0 112.0 111.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:126.00 stddev:21.66 (17.2%) stddev-sans-first:21.39 16:31:57 INFO - PID 1923 | Values: 150.0 109.0 111.0 153.0 112.0 111.0 114.0 138.0 165.0 112.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.50 stddev:1.96 (1.1%) stddev-sans-first:2.01 16:31:57 INFO - PID 1923 | Values: 186.0 184.0 185.0 182.0 182.0 186.0 186.0 184.0 188.0 183.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#3] audio-fft Cycles:10 Average:67.20 Median:63.00 stddev:11.90 (18.9%) stddev-sans-first:11.52 16:31:57 INFO - PID 1923 | Values: 81.0 58.0 62.0 93.0 62.0 60.0 60.0 57.0 75.0 64.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:76.50 stddev:11.99 (15.7%) stddev-sans-first:12.15 16:31:57 INFO - PID 1923 | Values: 90.0 70.0 94.0 100.0 70.0 70.0 83.0 69.0 68.0 85.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:86.00 stddev:0.92 (1.1%) stddev-sans-first:0.71 16:31:57 INFO - PID 1923 | Values: 88.0 86.0 86.0 87.0 85.0 86.0 86.0 87.0 85.0 86.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 16:31:57 INFO - PID 1923 | Values: 94.0 95.0 95.0 94.0 94.0 94.0 94.0 93.0 93.0 94.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.00 stddev:0.74 (0.9%) stddev-sans-first:0.71 16:31:57 INFO - PID 1923 | Values: 83.0 84.0 84.0 85.0 85.0 84.0 83.0 84.0 84.0 83.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:62.00 stddev:1.41 (2.3%) stddev-sans-first:1.00 16:31:57 INFO - PID 1923 | Values: 65.0 63.0 61.0 60.0 62.0 62.0 63.0 61.0 62.0 61.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:4.10 (7.0%) stddev-sans-first:3.90 16:31:57 INFO - PID 1923 | Values: 54.0 54.0 66.0 60.0 66.0 58.0 58.0 59.0 59.0 58.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:82.50 stddev:10.09 (12.2%) stddev-sans-first:10.67 16:31:57 INFO - PID 1923 | Values: 87.0 100.0 106.0 105.0 84.0 81.0 82.0 82.0 84.0 83.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#11] stanford-crypto-ccm Cycles:10 Average:155.60 Median:155.00 stddev:20.41 (13.2%) stddev-sans-first:20.86 16:31:57 INFO - PID 1923 | Values: 140.0 138.0 165.0 152.0 148.0 139.0 137.0 158.0 197.0 182.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.90 Median:188.00 stddev:3.35 (1.8%) stddev-sans-first:3.55 16:31:57 INFO - PID 1923 | Values: 188.0 188.0 188.0 187.0 187.0 191.0 194.0 186.0 189.0 181.0 16:31:57 INFO - PID 1923 | 16:31:57 INFO - PID 1923 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 16:31:57 INFO - PID 1923 | Values: 67.0 69.0 68.0 67.0 68.0 69.0 69.0 68.0 68.0 68.0 16:31:57 INFO - PID 1923 | -------- Summary: end -------- 16:31:57 INFO - PID 1923 | 16:31:58 INFO - TEST-INFO | 1923: exit 0 16:31:58 INFO - TEST-OK | kraken | took 61258ms 16:31:58 INFO - SUITE-END | took 1182s 16:31:58 INFO - Completed test suite (00:19:42) 16:31:58 INFO - javascript benchmark 16:31:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7537.536397133183, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3468.8439020244086, 3486.459598762193, 3500.7199424046075, 3515.63085875983, 3541.5395998520203, 4863.173260545707, 6081.756729730817, 6129.417705317993, 6131.141640170375, 6166.1059146423795, 6003.849903752408, 6074.240719910012, 6080.300765411979, 6087.330393656705, 6099.420555047274, 5387.973060134694, 5422.213779002042, 5429.864253393677, 5433.592480563953, 5574.581906357019, 5547.029269877776, 5554.638948468357, 5560.944390556083, 5565.109582466806, 5628.549716022709, 5588.636738611977, 5597.992361374951, 5601.60788744788, 5605.887882242353, 5608.46719561643, 5577.609567330289, 5591.524720398771, 5592.133219351007, 5605.215269862182, 5605.691686957195, 4657.4876763555985, 5355.651882627617, 5501.5323697485765, 5515.669059856422, 5559.332880054379, 6207.596506227081, 6243.750249989995, 6257.092721555395, 6259.123722678829, 6266.091416744592, 6339.36606339367, 6479.028145778124, 6479.676016199218, 6501.252355979028, 6531.118299029617, 6470.029495575654, 6488.578242414275, 6517.543771935966, 6561.835954101138, 6592.077109204715, 5561.944380556223, 6035.008447212409, 6260.87478250437, 6282.685865706696, 6286.245650521967, 6697.631630260285, 6743.1908171019795, 6758.05387245786, 6758.189017317954, 6771.864562708817, 6309.305976342599, 6330.525210609223, 6335.904961425582, 6388.968055159695, 6394.61632302054, 5375.489328513785, 6529.869402611921, 6559.868802623921, 6576.342365763481, 6577.440917521969, 6419.422251997343, 6430.292667806538, 6457.935420645734, 6479.514036447285, 6483.092367068614, 6341.968290158612, 6405.647689377129, 6561.967190164114, 6569.277379488252, 6578.572392794453, 5206.062908676475, 5267.26258323835, 5310.840674779763, 5320.52115309624, 5325.094733895247, 4700.294955756595, 4706.458757242961, 4720.669553131316, 4749.857504274877, 4758.072175925661, 6077.4226448487325, 6219.222597175353, 6234.657093859791, 6240.687965601702, 6248.031555108965, 6406.263279722798, 6465.612063276219, 6468.126802881645, 6564.934350656432, 6639.568428052257, 4934.679245849008, 6254.15568898202, 6255.0, 6268.874622507525, 6281.560290779693, 5788.247527821441, 5804.622699524517, 5806.854828629233, 5811.593188476851, 5864.091065884794, 6488.967555162194, 6529.542931994904, 6674.532782705164, 6698.196216454056, 6712.127423434922, 6446.4520515756685, 6500.512461565307, 6528.706208220547, 6533.0, 6537.640429776408, 6633.502487313374, 6639.800805975829, 6669.899951500634, 6670.733170673118, 6692.6988285526295, 6386.137871387489, 6420.678966051585, 6443.0, 6456.967715161394, 6476.708548115252, 6346.873062538816, 6398.328175541687, 6539.509536784665, 6555.2133743951035, 6560.950247960303, 6286.811395658137, 6288.0, 6304.306526282014, 6306.148669929685, 6313.084602732486, 6268.435840774352, 6297.0, 6304.211973503312, 6357.96821015892, 6369.713362898588, 6268.435840774352, 6281.937180628135, 6282.591631544118, 6283.654399008098, 6290.276618188784, 6334.398232167937, 6353.872922541431, 6362.650054246876, 6366.267879193952, 6375.043743438521, 6184.103305020837, 6234.096056071753, 6240.4071613196675, 6240.719167637377, 6267.811965641038], "value": 6001.156443204805, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.1020991175017, 808.8382323535291, 1126.003486914081, 1148.798960181968, 1151.1596534529788, 36660.083497912565, 37489.625103748986, 37494.0, 37600.871973840796, 37602.62397376025, 38486.84539463821, 38858.417123743166, 38956.02609934753, 38975.61024389755, 38992.80503597478, 6087.421694938974, 6199.287081985562, 8510.148985101503, 8669.0, 8681.435706679045, 8173.427860049799, 8442.57787110645, 8460.576971151419, 8462.15378462155, 8465.06884242736, 8496.277816385615, 8538.87191692122, 8545.060043395222, 8555.828883422328, 8556.70051548196, 8477.406581539295, 8533.359998000175, 8540.829183416328, 8544.401891867572, 8551.230389264934, 8433.662653493853, 8571.357148213909, 8575.399722019461, 8581.39930204886, 8589.742307730778, 38022.80988595067, 38133.0, 38258.80870595657, 38259.0, 38262.617373826186, 2844.2462747371965, 2919.226405002676, 2991.7307442330075, 2996.865141068657, 3063.9234019149476, 19826.900865495725, 26051.08821191268, 26125.477490450274, 26576.0, 27045.864770676217, 2492.4142826435773, 2786.2755683522228, 2789.511835428792, 2817.6618805743237, 2892.334763004521, 254885.0, 256905.71547142332, 256965.0, 259271.0, 312911.57972622995, 38399.0, 39194.41208381877, 39295.21409571793, 48476.0, 48478.0, 48892.75553622209, 49339.51981440563, 49429.0, 49477.50522494801, 49506.00987980292, 9910.15763660097, 12383.6284911451, 14124.646883827781, 14278.357473913698, 14321.6419589509, 11256.380899050468, 11362.659120226406, 11369.602063927889, 11390.487428065757, 11409.0, 13415.463381464631, 13447.588003259712, 13507.729845403233, 13579.932100339434, 13697.452101916011, 5866.794662186803, 5879.853003674857, 5930.28836539618, 5938.851528711731, 5948.67282299469, 948.7343543807744, 955.7467271173144, 960.706984369774, 991.2218908157062, 993.1558175550869, 22858.657120143213, 24148.0, 24248.393790155034, 24255.514889702106, 24310.513789724107, 942.5617088054088, 1052.8104941110673, 1054.1408751867205, 1080.8216644253766, 1089.3387583934564, 10014.49927503622, 10626.574937002433, 12211.389430528438, 12212.57255996036, 12216.877831221575, 502038.0, 503422.0, 504585.58729713765, 504980.0, 505436.0, 45930.770346148725, 56139.0, 56629.43370566241, 56795.432045679016, 56966.71516642473, 1392.2968900705282, 1401.5865319730704, 1455.7088582283586, 1464.6484843637663, 1465.4724299252048, 23274.06903723832, 23811.64282535798, 23812.16657416982, 23835.284941451784, 23853.045878164678, 38165.809170953966, 38276.6172338273, 38329.041773954756, 38331.041723957125, 38405.4239186418, 306145.0, 359990.0, 370902.0, 443753.5624643712, 444588.0, 27473.0, 32640.183995400304, 32760.344793104483, 32993.84521541734, 33012.0, 35315.823420882734, 43524.78237608792, 43918.780406097765, 45296.773516132205, 45399.546004540854, 26933.192004239903, 29848.850755746083, 29873.0, 30691.0, 30995.845020774752, 28191.71808281891, 29931.85034074816, 30265.697343026288, 30403.847980759954, 30468.695313047472, 33152.336953261285, 35800.8209958959, 36118.27763444769, 36386.8180659095, 36621.816890915376, 25432.109876154987, 29434.411311774074, 29975.950841720434, 30638.693613063584, 30992.0, 19480.610387792447, 21237.6814347788, 23957.880210598836, 24134.758652413955, 24595.877020614782], "value": 17249.269497039244, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.65748210459014, 388.7881104797885, 425.1878911279456, 434.37450071896467, 441.30714777798846, 13044.0, 14963.850361496381, 21060.473488162766, 21431.357059288228, 21457.8198199099, 22025.0, 22122.778772212274, 22770.88614556929, 23036.884815575897, 23418.88290558549, 5859.853503662422, 6120.540959428038, 6174.351693072219, 6204.844878878019, 6216.471599914013, 5927.436893495111, 6159.692015399212, 6198.690065496729, 6204.069389591552, 6204.751809927598, 5272.762725677335, 6101.816945491642, 6177.907331390033, 6182.103594978726, 6188.102725104857, 5936.109583562457, 6061.575689701723, 6144.84637884052, 6173.351798061206, 6173.475254603341, 5424.267723857308, 5426.023315803154, 5431.538319242869, 5431.646942948735, 5434.3207099112615, 27846.5823012655, 28006.439871202667, 28333.86664533437, 28463.430731385462, 28488.715112848815, 2004.258424382973, 2010.9899450502653, 2034.4608678700156, 2056.4961584411767, 2116.016052535578, 13342.599722008355, 15849.920750396173, 16102.03387796736, 16876.66246675072, 17271.0, 1608.4692051622937, 1649.6370798424327, 1666.2002238925263, 1698.1424380687674, 1734.0982689001776, 148695.0, 158123.0, 158643.0, 158888.20555897147, 161757.99999999884, 10198.592056317664, 12137.722814839944, 12250.754984900252, 12295.385230738428, 12353.3205673687, 16265.51203463898, 16347.34610615586, 16356.263968121462, 16363.018218906904, 16417.83582164187, 7751.806204844924, 8519.190676885686, 8879.023307436177, 9005.234555062892, 9042.63829446828, 7974.800629984296, 8451.66193352259, 8476.152384761475, 8652.221300083023, 8694.347923905605, 7557.6221188940335, 7687.192844751333, 7712.15166331703, 7726.768196953988, 7733.8453230935065, 1766.5848525596475, 2035.4809523571394, 2036.8390017690072, 2069.2343832781967, 2299.9655005174936, 880.7269746378643, 887.8179973105498, 899.5232526760827, 943.8631398447187, 949.5537097564134, 19789.505262368555, 20345.389638310873, 20497.48756281075, 20529.2814751483, 20733.934029294127, 902.5487256371814, 947.1246931076507, 963.6530848894392, 975.1613612293422, 980.1717548671361, 10413.219008574388, 10717.410542420086, 10785.514651840685, 10786.73033174177, 10835.404052777172, 506495.0, 685997.5700121467, 698565.0, 702593.0, 714757.0, 147480.26259868845, 151026.0, 153336.46663533445, 156451.0, 156516.0, 1085.3054045411102, 1112.3437172068357, 1142.6800495861173, 1144.2848219862585, 1150.0339714639742, 17663.20515576828, 17747.378841740476, 17909.820901790816, 17935.282588696307, 18476.168572414543, 41502.584974149875, 57395.713021434625, 58542.12186817116, 61241.0, 61901.0, 88797.0, 89423.0, 89453.0, 89510.20979580241, 90416.0, 8177.9591102044105, 8230.176982301722, 8481.787955301166, 8586.313094952264, 8597.18326758957, 13257.933710331386, 13376.924804711794, 13422.127561708458, 13572.1856688599, 13817.0, 9383.953080234829, 11009.669709908716, 11277.32336059839, 11450.427478626201, 11480.770384592428, 10068.244881634053, 10241.283110182216, 10314.0, 10649.89350106489, 10750.301230419998, 11451.59919402817, 11763.0, 11902.821457678316, 11953.370258543107, 11965.76068478643, 8267.586620669063, 8402.201790829862, 8432.494050356998, 8486.40595158333, 8563.357748168788, 10868.565257389615, 11334.60328888485, 11391.088712902781, 11572.710682233012, 11594.420278986187], "value": 10186.3750239863, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1195.8146487294462, 1362.598033580094, 1432.4699861051413, 1455.2214565207612, 1460.9342579583918, 9354.81290374192, 9792.216622670187, 10869.347839129658, 11178.217524773268, 11180.66458006259, 10784.514696838642, 10867.619633312815, 10870.510827012784, 10903.890961090387, 10911.563537458493, 2013.5570174561647, 2027.4931267183167, 2485.7017157941073, 2487.465194983078, 2493.4015836199274, 2139.764625891151, 2164.9783502164933, 2406.3262286559793, 2525.9747402525923, 2527.3934255778663, 2458.1642241637837, 2476.6904136982876, 2498.537770512461, 2502.799776017914, 2510.723820379757, 2483.4039830440743, 2502.4995000999875, 2502.962455563168, 2509.2723110297993, 2517.874106294696, 2446.1287115356768, 2480.7271200167966, 2484.254723582918, 2492.140211626986, 2493.7556199420487, 10873.891261087367, 11130.666080017612, 11136.944315278453, 11137.777244455147, 11144.108471322275, 6169.2288463942, 6410.551261411703, 6420.357964203542, 6421.486281097547, 6435.131257280254, 3691.0956815580084, 3827.7129215308964, 4023.155137421145, 4032.4354590357375, 4037.576054514263, 1160.8897154770284, 1170.0405667352775, 1170.2627344772739, 1177.5010700030875, 1199.7960346741074, 15084.698306033928, 15239.314230859527, 15420.691586168214, 15443.768343474856, 15444.0, 5412.242286079952, 5742.913856292159, 5799.101139323411, 5821.301443826768, 5864.677442740605, 12845.743085138245, 12855.678608034874, 12856.357182140855, 12881.162724422882, 12887.677808054686, 6064.090386442069, 6406.8718625627225, 6410.55126141175, 6451.806445806634, 6478.708458119396, 4708.293755936637, 5877.0, 5881.882362352814, 5882.617629854131, 5883.0881213411985, 3374.4769560718128, 4438.844640437569, 4468.0, 4496.617787488035, 4498.2577874650315, 1180.4097951024487, 1403.5368328451586, 1406.8593140686055, 1425.209009000015, 1429.756941319978, 692.757534862794, 701.7614011236102, 708.3412426443457, 712.7932899459203, 729.6351824087956, 6036.245469316335, 6286.748530058838, 6523.217213934264, 6579.078928949951, 6653.334666533308, 893.7487517475586, 899.3344924755634, 908.8182363527321, 910.3718434280325, 921.764949937775, 3846.3268927937497, 4025.3156963316305, 4094.242565125461, 4198.265303571863, 4200.663946884242, 13357.866421335662, 14043.297835108408, 14175.503857364944, 14197.574072777834, 14224.928875355765, 13601.540171483104, 14085.71828563423, 14101.929490352482, 14138.293085345691, 14139.929300353639, 10051.547680354402, 10300.536475858604, 10308.587656493657, 10314.638987635546, 10317.226208034277, 6147.108669242845, 6183.193227269705, 6216.191895053614, 6385.393387628168, 6446.4520515756685, 12211.450484728386, 12638.2417054977, 12698.809517857055, 12701.745965080832, 12748.87251127514, 12526.930901929085, 12629.179103358252, 12760.361981901053, 12857.549985750455, 12992.675183120498, 7430.256974302527, 9024.458532488072, 9077.4099683523, 9115.088491150831, 9162.862557061782, 8901.955490222508, 9166.266698663958, 9171.128742769426, 9900.356476828983, 10199.745006374604, 7229.0, 8543.273821725223, 8654.0, 8691.087435819149, 8749.693760718344, 6844.726210951705, 8106.675732970615, 8498.872516912375, 8500.404971651926, 8545.401821872409, 7657.885131722917, 7658.081030276402, 7679.155292917663, 7718.57547834852, 7769.912212290397, 5758.107493338539, 5805.796797112081, 5865.472107510344, 5926.822195334147, 5972.850678733066, 4864.635152363658, 5914.408559144051, 5935.228420305332, 5948.0, 6196.659183744757], "value": 5176.728767516735, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.873320537428, 399.8400639744102, 403.1795296571478, 410.52379240081495, 411.5884115884116, 4699.0, 6060.787872424466, 6114.008271893547, 6123.479504242145, 6130.2643682758135, 4702.8007857996245, 4726.620466366548, 5548.77804887804, 5694.658320500763, 6006.0, 3891.416287556861, 3931.783751893645, 4051.918961620766, 4641.327007583898, 4731.668783185179, 4358.0, 4708.857469851227, 4734.834280800155, 4736.123817093849, 4757.738324392157, 4104.056067104568, 4520.660950428714, 4646.767661616923, 4690.569376340215, 4703.894584772575, 4184.811683474251, 4437.866863994053, 4623.398958135454, 4655.13879932212, 4677.859664210079, 4119.258533463975, 4122.381642753581, 4126.442930204414, 4128.761371588512, 4167.874963751062, 5505.449455054502, 5661.971690141564, 5809.7095145242565, 5846.100017494331, 5848.941510584882, 4917.868890155249, 5198.766055527511, 5300.734963251822, 5423.891522169534, 5463.754131064112, 5284.497972692588, 6476.805695829085, 6477.125588045601, 6509.934900651027, 6531.0, 3333.8331583945424, 4280.5291417944, 4306.849260275875, 4329.177456283296, 4336.934945975813, 5698.629589076697, 5776.0, 6577.927952694886, 6597.47220222381, 6762.492813039042, 4292.12011537634, 4367.10474352757, 4378.8905277367685, 4386.692931494765, 4405.207062728676, 4065.207284579538, 4134.855280065243, 4224.704270701083, 4344.1311737652595, 4472.373867658531, 4883.389576302962, 5087.974560127176, 5099.796008159632, 5160.483951604884, 5270.49930256625, 5757.740901659436, 6412.2625898022325, 6500.609963402211, 6516.609003459807, 6534.444572211415, 1487.2192099147817, 1517.4309633887292, 1517.6130086827789, 1527.343242405754, 1531.3032570180765, 874.5583480342386, 943.4150826487621, 977.7897751983215, 978.8791833350773, 1015.1979935850743, 5141.2288156776785, 6090.65014242165, 6104.969475152596, 6118.265808103056, 6118.694065296718, 5623.188405797101, 6624.668766561749, 6651.900221496681, 6668.133142691419, 6707.0610114584, 5491.917621235684, 5785.595008349375, 6095.1771510845465, 6118.632882027093, 6152.0, 3068.2636167319683, 3450.6721861423125, 3499.2826470573473, 3501.77238479498, 3504.351694936445, 6085.6750343449485, 6252.718627661766, 6691.631960242234, 6811.421029212573, 6935.4104901082965, 5701.4298570142655, 6822.658867056627, 6861.279565645636, 6895.655217239118, 6905.896411553831, 6047.7278522466595, 6713.899291510729, 6764.154480689914, 6854.897176542356, 6900.2064762550945, 5419.268398766274, 6305.558610897193, 6350.491960643045, 6583.247548897083, 6661.168178750882, 5807.216025836458, 6420.775272865428, 6626.53614246998, 6642.169728783902, 6650.634215118215, 6002.369751176064, 6268.780592679417, 6549.377809108127, 6552.6723663815665, 6910.516263861481, 3477.7739446935866, 3553.875614353485, 4426.291793313055, 4428.911421771611, 4433.268510695699, 5375.951689420525, 5586.916196257141, 6263.561550691408, 6750.054992300984, 6761.154855643044, 6485.984017431917, 6538.346165383424, 6540.411362977165, 6553.377429144223, 6562.278149403657, 6172.290186628596, 6479.902801458076, 6516.706748196248, 6537.123665587126, 6563.7162084522415, 5481.835544933659, 6368.840778980562, 6398.0, 6402.455791257796, 6412.6793660317735, 6477.931141361622, 6478.870422591427, 6484.5460817742305, 6559.967200163968, 6561.212654481493, 5922.940770592239, 6213.2233470816145, 6300.873982520416, 6325.493960483058, 6370.649614271259], "value": 4683.6840186813415, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.6743681624861, 716.4769718105782, 733.2630706140328, 737.365865355794, 767.2136060537948, 14626.780598291021, 15559.844401555994, 15697.0, 17426.912865435654, 18340.358087466942, 14389.64025899353, 16440.917795411035, 17041.403550875722, 17159.313627454918, 17203.655926881456, 928.7167413938746, 956.3449037409397, 961.2214106573698, 962.1004360922525, 962.3648392061241, 918.1874041473311, 952.8570714392828, 953.880764904387, 967.3712087143343, 969.0551712080735, 1019.2610357490834, 1019.9235057370689, 1019.94390308533, 1020.4948550467492, 1020.6682828080903, 1012.4937531234383, 1013.5591017907234, 1014.6905193915853, 1016.2124353625925, 1018.063381688848, 745.2137994415889, 746.6080307838374, 749.8800191969259, 751.031169790969, 751.2862780358627, 15781.92109039459, 15816.841831581653, 16057.197140142945, 16127.871049026573, 16280.430184943589, 12188.878111218863, 12192.756144877141, 12281.57014504497, 12498.75002499954, 12648.051396145234, 11508.163032309585, 11748.0, 11835.408229588485, 11835.467403966755, 12063.577774777928, 2672.14491362764, 2737.397772490049, 2747.6290700755544, 2748.6014527893544, 2828.490871643103, 109798.0, 114606.99999999917, 114938.0, 114941.0, 117186.0, 11428.771424571461, 11543.249688770204, 11728.237664551947, 11854.288742675468, 11933.52265909354, 24969.5006099877, 25189.874050629995, 25196.0, 25197.496050078895, 25226.243212703648, 7691.0, 7760.767176984699, 7861.095973962953, 7909.24862138096, 8030.799230019179, 3979.880603581897, 4044.1507283470214, 4048.959510404917, 4058.127502586958, 4101.589841015874, 4145.54399016108, 4168.0205151789305, 4202.936955945664, 4244.766537840448, 4257.361395790656, 785.8467598818175, 793.5080250244885, 827.5324441690426, 830.2444775254489, 851.3912552524974, 536.5358964495761, 554.1070712967619, 585.55497821655, 590.9881802363929, 593.3947373678909, 9158.542072896462, 9819.16537094355, 10725.678229653124, 11142.442877856074, 11303.604373846876, 519.857039314184, 553.714836859018, 578.1558923971049, 584.3105135939632, 586.794138046319, 4676.649251306166, 4719.646026548023, 4721.315409265637, 4734.597559207437, 4739.3838800955655, 71686.64156679288, 71720.2827971724, 74551.25448745443, 76185.23814761892, 80891.59554202309, 67058.65882682319, 69507.65246173737, 78854.0, 86009.0, 87528.56235718908, 70104.57951872524, 72543.2745672547, 73517.0, 73912.63043684747, 75003.87494187591, 8459.83080338402, 9513.381630194015, 9556.426614403159, 9604.0, 9642.951785241028, 32431.837840810644, 35240.766573169814, 36286.455703165004, 36491.90524284276, 37076.62923370732, 46420.30369544392, 46885.29672054854, 46923.296150557086, 47460.05079898452, 48486.75756621194, 11468.139889508153, 11583.189176757884, 11585.67838570831, 11700.2979821211, 11840.940795295968, 12336.0, 12386.86268872728, 12568.937155314165, 12656.43046062911, 12714.809277860655, 11516.30902145874, 11714.23857449263, 11724.648260552198, 11815.17293789461, 11976.401179940794, 11551.42242887869, 11633.59933135671, 11640.064953067542, 11673.941630291794, 11905.702357440787, 9772.823832254688, 11617.593384231512, 11619.0, 11621.535138594361, 12073.456694448596, 7399.112106547249, 8515.574221289035, 8518.36112291593, 8652.480851148952, 9008.729738107868, 8390.958045209734, 9191.816163676824, 9921.0, 9933.850992235266, 10098.495075246356], "value": 7172.599513706136, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1465.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 114.0, 157.0, 143.0, 111.0, 111.0, 112.0, 112.0, 111.0], "value": 119.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 109.0, 111.0, 153.0, 112.0, 111.0, 114.0, 138.0, 165.0, 112.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 185.0, 182.0, 182.0, 186.0, 186.0, 184.0, 188.0, 183.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 62.0, 93.0, 62.0, 60.0, 60.0, 57.0, 75.0, 64.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 94.0, 100.0, 70.0, 70.0, 83.0, 69.0, 68.0, 85.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 86.0, 87.0, 85.0, 86.0, 86.0, 87.0, 85.0, 86.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 85.0, 85.0, 84.0, 83.0, 84.0, 84.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 61.0, 60.0, 62.0, 62.0, 63.0, 61.0, 62.0, 61.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 66.0, 60.0, 66.0, 58.0, 58.0, 59.0, 59.0, 58.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 100.0, 106.0, 105.0, 84.0, 81.0, 82.0, 82.0, 84.0, 83.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 165.0, 152.0, 148.0, 139.0, 137.0, 158.0, 197.0, 182.0], "value": 155.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 188.0, 188.0, 187.0, 187.0, 191.0, 194.0, 186.0, 189.0, 181.0], "value": 187.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 67.0, 68.0, 69.0, 69.0, 68.0, 68.0, 68.0], "value": 68.1, "unit": "score"}]}]} 16:31:59 INFO - Return code: 0 16:31:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:31:59 ERROR - # TBPL SUCCESS # 16:31:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:31:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:31:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:31:59 INFO - 2017-03-25 16:31:59.142 screenresolution[1927:62679] starting screenresolution argv=screenresolution get 16:31:59 INFO - 2017-03-25 16:31:59.145 screenresolution[1927:62679] Display 0: 1600x1200x32@60 16:31:59 INFO - 2017-03-25 16:31:59.152 screenresolution[1928:62682] starting screenresolution argv=screenresolution list 16:31:59 INFO - Available Modes on Display 0 16:31:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:31:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:31:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:31:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:31:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:31:59 INFO - Intel Iris: 16:31:59 INFO - Chipset Model: Intel Iris 16:31:59 INFO - Type: GPU 16:31:59 INFO - Bus: Built-In 16:31:59 INFO - VRAM (Dynamic, Max): 1536 MB 16:31:59 INFO - Vendor: Intel (0x8086) 16:31:59 INFO - Device ID: 0x0a2e 16:31:59 INFO - Revision ID: 0x0009 16:31:59 INFO - Displays: 16:31:59 INFO - Display: 16:31:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:31:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:31:59 INFO - Display Serial Number: 1600x1200 60 16:31:59 INFO - Main Display: Yes 16:31:59 INFO - Mirror: Off 16:31:59 INFO - Online: Yes 16:31:59 INFO - Rotation: Supported 16:31:59 INFO - Return code: 0 16:31:59 INFO - Running post-action listener: _resource_record_post_action 16:31:59 INFO - [mozharness: 2017-03-25 23:31:59.395362Z] Finished run-tests step (success) 16:31:59 INFO - Running post-run listener: _resource_record_post_run 16:31:59 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 2396160; Write bytes: 513038848; Read time: 8; Write time: 1777 16:31:59 INFO - TinderboxPrint: CPU usage
53.3% 16:31:59 INFO - TinderboxPrint: I/O read bytes / time
2,396,160 / 8 16:31:59 INFO - TinderboxPrint: I/O write bytes / time
513,038,848 / 1,777 16:31:59 INFO - TinderboxPrint: CPU idle
2,248.7 (46.7%) 16:31:59 INFO - TinderboxPrint: CPU system
957.6 (19.9%) 16:31:59 INFO - TinderboxPrint: CPU user
1,607.5 (33.4%) 16:31:59 INFO - TinderboxPrint: Swap in / out
213,303,296 / 0 16:31:59 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204734976; Write bytes: 203822592; Read time: 15634; Write time: 564 16:31:59 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 2396160; Write bytes: 298394624; Read time: 8; Write time: 1119 16:31:59 INFO - Running post-run listener: _upload_blobber_files 16:31:59 INFO - Blob upload gear active. 16:31:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:31:59 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 16:31:59 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'] 16:31:59 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 16:32:00 INFO - (blobuploader) - INFO - Open directory for files ... 16:32:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:32:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:32:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:01 INFO - (blobuploader) - INFO - Done attempting. 16:32:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:32:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:32:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:02 INFO - (blobuploader) - INFO - Done attempting. 16:32:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:32:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:32:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:03 INFO - (blobuploader) - INFO - Done attempting. 16:32:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:32:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:32:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:06 INFO - (blobuploader) - INFO - Done attempting. 16:32:06 INFO - (blobuploader) - INFO - Iteration through files over. 16:32:06 INFO - Return code: 0 16:32:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:32:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:32:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a003f5f1fb8d14e36ce988e3a01618d4091c234d67cd9cb6844c663595f07d08cee5f2b8c174f9871ea0ce214b5ed9510ce388b489570d64907e91d015399915", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75007b1e06750b3cc5335ec3f00e04c4d8cfcd9b89a21ff4cce3b2953b7488629017fb214d7abc8c73b9a177676053321afdbd7970d94dae07308cac30b5baf8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f74bf9e9628ee86fdcacedd91838d587c46a512d6566718b8f032e4adb21a2ee80b95d588622eb8dcc304db92538c6caef64097690aeb7e2e1f2bb9b929ecae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecd18622e62867f3570c3af20bafd39720f5284797e65c3380eba0c36d539f9c5643938dd4a37b7f4cd6c653717616294c0f9228a42f57434b03d5f4d361530e"} 16:32:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:32:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:32:06 INFO - Contents: 16:32:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a003f5f1fb8d14e36ce988e3a01618d4091c234d67cd9cb6844c663595f07d08cee5f2b8c174f9871ea0ce214b5ed9510ce388b489570d64907e91d015399915", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75007b1e06750b3cc5335ec3f00e04c4d8cfcd9b89a21ff4cce3b2953b7488629017fb214d7abc8c73b9a177676053321afdbd7970d94dae07308cac30b5baf8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f74bf9e9628ee86fdcacedd91838d587c46a512d6566718b8f032e4adb21a2ee80b95d588622eb8dcc304db92538c6caef64097690aeb7e2e1f2bb9b929ecae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecd18622e62867f3570c3af20bafd39720f5284797e65c3380eba0c36d539f9c5643938dd4a37b7f4cd6c653717616294c0f9228a42f57434b03d5f4d361530e"} 16:32:06 INFO - Running post-run listener: copy_logs_to_upload_dir 16:32:06 INFO - Copying logs to upload dir... 16:32:06 INFO - mkdir: /builds/slave/test/build/upload/logs 16:32:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.828831 ========= master_lag: -0.26 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-25 16:32:05.888740) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-25 16:32:05.892456) ========= 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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a003f5f1fb8d14e36ce988e3a01618d4091c234d67cd9cb6844c663595f07d08cee5f2b8c174f9871ea0ce214b5ed9510ce388b489570d64907e91d015399915", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75007b1e06750b3cc5335ec3f00e04c4d8cfcd9b89a21ff4cce3b2953b7488629017fb214d7abc8c73b9a177676053321afdbd7970d94dae07308cac30b5baf8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f74bf9e9628ee86fdcacedd91838d587c46a512d6566718b8f032e4adb21a2ee80b95d588622eb8dcc304db92538c6caef64097690aeb7e2e1f2bb9b929ecae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecd18622e62867f3570c3af20bafd39720f5284797e65c3380eba0c36d539f9c5643938dd4a37b7f4cd6c653717616294c0f9228a42f57434b03d5f4d361530e"} build_url:https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010724 build_url: 'https://queue.taskcluster.net/v1/task/KyFtNNKjS6KmJeYKyhRUDA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a003f5f1fb8d14e36ce988e3a01618d4091c234d67cd9cb6844c663595f07d08cee5f2b8c174f9871ea0ce214b5ed9510ce388b489570d64907e91d015399915", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75007b1e06750b3cc5335ec3f00e04c4d8cfcd9b89a21ff4cce3b2953b7488629017fb214d7abc8c73b9a177676053321afdbd7970d94dae07308cac30b5baf8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f74bf9e9628ee86fdcacedd91838d587c46a512d6566718b8f032e4adb21a2ee80b95d588622eb8dcc304db92538c6caef64097690aeb7e2e1f2bb9b929ecae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecd18622e62867f3570c3af20bafd39720f5284797e65c3380eba0c36d539f9c5643938dd4a37b7f4cd6c653717616294c0f9228a42f57434b03d5f4d361530e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-25 16:32:05.926528) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:32:05.926852) ========= 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.3h9fdsMKwS/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.cl5lwvhuoY/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.005411 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-25 16:32:05.975830) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-25 16:32:05.976172) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-25 16:32:05.976504) ========= ========= Total master_lag: -0.12 =========