builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0178 starttime: 1491903536.7 results: success (0) buildid: 20170411011827 builduid: 1638bd6c86fd48f88b8da4f76aeb68e2 revision: 7031c811659d90275e7b8e1557027791081767c5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:56.703848) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:56.704404) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:56.704751) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:57.071109) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:57.071542) ========= 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.Clj5OSCP0o/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.Dg0hBO6241/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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-04-11 02:38:57-- 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% 7.86M=0.001s 2017-04-11 02:38:57 (7.86 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.577345 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:57.734234) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:57.734686) ========= 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.Clj5OSCP0o/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.Dg0hBO6241/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.029545 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:57.818642) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-11 02:38:57.819089) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7031c811659d90275e7b8e1557027791081767c5 --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 7031c811659d90275e7b8e1557027791081767c5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Clj5OSCP0o/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.Dg0hBO6241/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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-04-11 02:38:57,947 truncating revision to first 12 chars 2017-04-11 02:38:57,947 Setting DEBUG logging. 2017-04-11 02:38:57,947 attempt 1/10 2017-04-11 02:38:57,947 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7031c811659d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 02:39:00,293 unpacking tar archive at: mozilla-central-7031c811659d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.615726 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-11 02:39:00.469830) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 02:39:00.470197) ========= script_repo_revision: 7031c811659d90275e7b8e1557027791081767c5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 02:39:00.470651) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 02:39:00.470955) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 02:39:00.507822) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-11 02:39:00.508168) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Clj5OSCP0o/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.Dg0hBO6241/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:39:00 INFO - MultiFileLogger online at 20170411 02:39:00 in /builds/slave/test 02:39:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:39:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:39:00 INFO - {'append_to_log': False, 02:39:00 INFO - 'base_work_dir': '/builds/slave/test', 02:39:00 INFO - 'blob_upload_branch': 'Firefox', 02:39:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:39:00 INFO - 'branch': 'Firefox', 02:39:00 INFO - 'buildbot_json_path': 'buildprops.json', 02:39:00 INFO - 'config_files': ('talos/mac_config.py',), 02:39:00 INFO - 'default_actions': ('clobber', 02:39:00 INFO - 'read-buildbot-config', 02:39:00 INFO - 'download-and-extract', 02:39:00 INFO - 'populate-webroot', 02:39:00 INFO - 'create-virtualenv', 02:39:00 INFO - 'install', 02:39:00 INFO - 'run-tests'), 02:39:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:39:00 INFO - 'download_minidump_stackwalk': True, 02:39:00 INFO - 'download_symbols': 'ondemand', 02:39:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:39:00 INFO - 'tooltool.py': '/tools/tooltool.py', 02:39:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:39:00 INFO - '/tools/misc-python/virtualenv.py')}, 02:39:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:39:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:39:00 INFO - 'gecko_profile': False, 02:39:00 INFO - 'gecko_profile_interval': 0, 02:39:00 INFO - 'installer_path': 'installer.exe', 02:39:00 INFO - 'log_level': 'info', 02:39:00 INFO - 'log_name': 'talos', 02:39:00 INFO - 'log_to_console': True, 02:39:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:39:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:39:00 INFO - 'opt_config_files': (), 02:39:00 INFO - 'pip_index': False, 02:39:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:39:00 INFO - 'cmd': ('bash', 02:39:00 INFO - '-c', 02:39:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:39:00 INFO - 'enabled': True, 02:39:00 INFO - 'halt_on_failure': False, 02:39:00 INFO - 'name': 'check_screen_resolution'},), 02:39:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:39:00 INFO - 'cmd': ('bash', 02:39:00 INFO - '-c', 02:39:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:39:00 INFO - 'enabled': True, 02:39:00 INFO - 'halt_on_failure': False, 02:39:00 INFO - 'name': 'check_screen_resolution'},), 02:39:00 INFO - 'run_cmd_checks_enabled': True, 02:39:00 INFO - 'suite': 'dromaeojs-e10s', 02:39:00 INFO - 'system_bits': '32', 02:39:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:39:00 INFO - 'title': 't-yosemite-r7-0178', 02:39:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:39:00 INFO - 'use_talos_json': True, 02:39:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:39:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:39:00 INFO - 'work_dir': 'build'} 02:39:00 INFO - [mozharness: 2017-04-11 09:39:00.733196Z] Running clobber step. 02:39:00 INFO - Running pre-action listener: _resource_record_pre_action 02:39:00 INFO - Running main action method: clobber 02:39:00 INFO - rmtree: /builds/slave/test/build 02:39:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:39:03 INFO - Running post-action listener: _resource_record_post_action 02:39:03 INFO - [mozharness: 2017-04-11 09:39:03.306701Z] Finished clobber step (success) 02:39:03 INFO - [mozharness: 2017-04-11 09:39:03.306850Z] Running read-buildbot-config step. 02:39:03 INFO - Running pre-action listener: _resource_record_pre_action 02:39:03 INFO - Running main action method: read_buildbot_config 02:39:03 INFO - Using buildbot properties: 02:39:03 INFO - { 02:39:03 INFO - "project": "", 02:39:03 INFO - "product": "firefox", 02:39:03 INFO - "script_repo_revision": "production", 02:39:03 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 02:39:03 INFO - "repository": "", 02:39:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 02:39:03 INFO - "buildid": "20170411011827", 02:39:03 INFO - "pgo_build": "False", 02:39:03 INFO - "basedir": "/builds/slave/test", 02:39:03 INFO - "buildnumber": 28, 02:39:03 INFO - "slavename": "t-yosemite-r7-0178", 02:39:03 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:39:03 INFO - "platform": "yosemite_r7", 02:39:03 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:39:03 INFO - "branch": "mozilla-central", 02:39:03 INFO - "repo_path": "mozilla-central", 02:39:03 INFO - "revision": "7031c811659d90275e7b8e1557027791081767c5", 02:39:03 INFO - "stage_platform": "macosx64", 02:39:03 INFO - "builduid": "1638bd6c86fd48f88b8da4f76aeb68e2", 02:39:03 INFO - "slavebuilddir": "test" 02:39:03 INFO - } 02:39:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 02:39:03 INFO - Running post-action listener: _resource_record_post_action 02:39:03 INFO - [mozharness: 2017-04-11 09:39:03.310155Z] Finished read-buildbot-config step (success) 02:39:03 INFO - [mozharness: 2017-04-11 09:39:03.310283Z] Running download-and-extract step. 02:39:03 INFO - Running pre-action listener: _resource_record_pre_action 02:39:03 INFO - Running main action method: download_and_extract 02:39:03 INFO - mkdir: /builds/slave/test/build/tests 02:39:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:03 INFO - trying https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:39:03 INFO - Downloading https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/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 02:39:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/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 02:39:04 INFO - Downloaded 1325 bytes. 02:39:04 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:39:04 INFO - Using the following test package requirements: 02:39:04 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:39:04 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:39:04 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:39:04 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:39:04 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:39:04 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:39:04 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:39:04 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:39:04 INFO - u'jsshell-mac.zip'], 02:39:04 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:39:04 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:39:04 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:39:04 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:39:04 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:39:04 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:39:04 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:39:04 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:39:04 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 02:39:04 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:39:04 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:39:04 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:39:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:39:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:39:04 INFO - Fetch https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:39:06 INFO - Expected file size: 52582329 02:39:06 INFO - Obtained file size: 52582329 02:39:22 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 02:39:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:39:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:39:22 INFO - Fetch https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:39:24 INFO - Expected file size: 52582329 02:39:24 INFO - Obtained file size: 52582329 02:39:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:39:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:39:39 INFO - Fetch https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:39:41 INFO - Expected file size: 14405596 02:39:41 INFO - Obtained file size: 14405596 02:39: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')]} 02:39:41 INFO - trying https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:39:41 INFO - Downloading https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:39:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/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 02:39:43 INFO - Downloaded 70926108 bytes. 02:39:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:39:43 INFO - mkdir: /builds/slave/test/properties 02:39:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:39:43 INFO - Writing to file /builds/slave/test/properties/build_url 02:39:43 INFO - Contents: 02:39:43 INFO - build_url:https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:39:44 INFO - Running post-action listener: _resource_record_post_action 02:39:44 INFO - Running post-action listener: set_extra_try_arguments 02:39:44 INFO - [mozharness: 2017-04-11 09:39:44.123351Z] Finished download-and-extract step (success) 02:39:44 INFO - [mozharness: 2017-04-11 09:39:44.123494Z] Running populate-webroot step. 02:39:44 INFO - Running pre-action listener: _resource_record_pre_action 02:39:44 INFO - Running main action method: populate_webroot 02:39:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:39:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:39:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:39:44 INFO - '--tppagecycles', 02:39:44 INFO - '7'], 02:39:44 INFO - 'tests': ['tsvgm']}}, 02:39:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:39:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:39:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:39:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:39:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:39:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:39:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:39:44 INFO - 'talos_options': ['--disable-e10s'], 02:39:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:39:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:39:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:39:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:39:44 INFO - 'talos_options': ['--disable-e10s'], 02:39:44 INFO - 'tests': ['damp', 'tps']}, 02:39:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:39:44 INFO - 'tests': ['damp', 'tps']}, 02:39:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:39:44 INFO - 'tests': ['dromaeo_dom']}, 02:39:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:39:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:39:44 INFO - 'tests': ['basic_compositor_video']}, 02:39:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:39:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:39:44 INFO - 'tests': ['a11yr', 02:39:44 INFO - 'ts_paint', 02:39:44 INFO - 'tpaint', 02:39:44 INFO - 'sessionrestore', 02:39:44 INFO - 'sessionrestore_no_auto_restore', 02:39:44 INFO - 'tabpaint']}, 02:39:44 INFO - 'other-e10s': {'tests': ['a11yr', 02:39:44 INFO - 'ts_paint', 02:39:44 INFO - 'tpaint', 02:39:44 INFO - 'sessionrestore', 02:39:44 INFO - 'sessionrestore_no_auto_restore', 02:39:44 INFO - 'tabpaint']}, 02:39:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:39:44 INFO - 'ts_paint', 02:39:44 INFO - 'tpaint', 02:39:44 INFO - 'sessionrestore', 02:39:44 INFO - 'sessionrestore_no_auto_restore', 02:39:44 INFO - 'tabpaint']}, 02:39:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:39:44 INFO - 'ts_paint', 02:39:44 INFO - 'tpaint', 02:39:44 INFO - 'sessionrestore', 02:39:44 INFO - 'sessionrestore_no_auto_restore', 02:39:44 INFO - 'tabpaint']}, 02:39:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:39:44 INFO - 'tests': ['a11yr', 02:39:44 INFO - 'ts_paint', 02:39:44 INFO - 'tpaint', 02:39:44 INFO - 'sessionrestore', 02:39:44 INFO - 'sessionrestore_no_auto_restore', 02:39:44 INFO - 'tabpaint']}, 02:39:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:39:44 INFO - 'tests': ['a11yr', 02:39:44 INFO - 'ts_paint', 02:39:44 INFO - 'tpaint', 02:39:44 INFO - 'sessionrestore', 02:39:44 INFO - 'sessionrestore_no_auto_restore', 02:39:44 INFO - 'tabpaint']}, 02:39:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:39:44 INFO - 'tests': ['tsvgx', 02:39:44 INFO - 'tsvgr_opacity', 02:39:44 INFO - 'tart', 02:39:44 INFO - 'tscrollx', 02:39:44 INFO - 'cart', 02:39:44 INFO - 'tsvg_static']}, 02:39:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:39:44 INFO - 'tsvgr_opacity', 02:39:44 INFO - 'tart', 02:39:44 INFO - 'tscrollx', 02:39:44 INFO - 'cart', 02:39:44 INFO - 'tsvg_static']}, 02:39:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:39:44 INFO - 'talos_options': ['--disable-e10s'], 02:39:44 INFO - 'tests': ['tp5o']}, 02:39:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:39:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:39:44 INFO - 'talos_options': ['--disable-e10s', 02:39:44 INFO - '--xperf_path', 02:39:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:39:44 INFO - 'tests': ['tp5n']}, 02:39:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:39:44 INFO - 'talos_options': ['--xperf_path', 02:39:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:39:44 INFO - 'tests': ['tp5n']}}, 02:39:44 INFO - 'talos.zip': {'path': '', 02:39:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:39:44 INFO - Running post-action listener: _resource_record_post_action 02:39:44 INFO - [mozharness: 2017-04-11 09:39:44.142081Z] Finished populate-webroot step (success) 02:39:44 INFO - [mozharness: 2017-04-11 09:39:44.142217Z] Running create-virtualenv step. 02:39:44 INFO - Running pre-action listener: _resource_record_pre_action 02:39:44 INFO - Running main action method: create_virtualenv 02:39:44 INFO - Creating virtualenv /builds/slave/test/build/venv 02:39:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:39:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:39:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:39:44 INFO - Using real prefix '/tools/python27' 02:39:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:39:45 INFO - Installing distribute.............................................................................................................................................................................................done. 02:39:47 INFO - Installing pip.................done. 02:39:47 INFO - Return code: 0 02:39:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:39:48 INFO - Reading from file tmpfile_stdout 02:39:48 INFO - Output received: 02:39:48 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:39:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103353b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103356768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f358c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103131d30>, '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 0x103058db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103126be8>, '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.Clj5OSCP0o/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.Dg0hBO6241/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:39:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:39:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Clj5OSCP0o/Render', 02:39:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:48 INFO - 'HOME': '/Users/cltbld', 02:39:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:48 INFO - 'LOGNAME': 'cltbld', 02:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:48 INFO - 'MOZ_NO_REMOTE': '1', 02:39:48 INFO - 'NO_EM_RESTART': '1', 02:39:48 INFO - 'PAGER': '/bin/cat', 02:39:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:48 INFO - 'PWD': '/builds/slave/test', 02:39:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:48 INFO - 'SHELL': '/bin/bash', 02:39:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Dg0hBO6241/Listeners', 02:39:48 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 02:39:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:48 INFO - 'USER': 'cltbld', 02:39:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:48 INFO - 'XPC_FLAGS': '0x0', 02:39:48 INFO - 'XPC_SERVICE_NAME': '0', 02:39:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:39:48 INFO - Cleaning up... 02:39:48 INFO - Return code: 0 02:39:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103353b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103356768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f358c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103131d30>, '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 0x103058db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103126be8>, '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.Clj5OSCP0o/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.Dg0hBO6241/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:39:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:39:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Clj5OSCP0o/Render', 02:39:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:48 INFO - 'HOME': '/Users/cltbld', 02:39:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:48 INFO - 'LOGNAME': 'cltbld', 02:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:48 INFO - 'MOZ_NO_REMOTE': '1', 02:39:48 INFO - 'NO_EM_RESTART': '1', 02:39:48 INFO - 'PAGER': '/bin/cat', 02:39:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:48 INFO - 'PWD': '/builds/slave/test', 02:39:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:48 INFO - 'SHELL': '/bin/bash', 02:39:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Dg0hBO6241/Listeners', 02:39:48 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 02:39:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:48 INFO - 'USER': 'cltbld', 02:39:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:48 INFO - 'XPC_FLAGS': '0x0', 02:39:48 INFO - 'XPC_SERVICE_NAME': '0', 02:39:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:48 INFO - Downloading/unpacking psutil>=3.1.1 02:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:39:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:39:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:39:52 INFO - Installing collected packages: psutil 02:39:52 INFO - Running setup.py install for psutil 02:39:52 INFO - building 'psutil._psutil_osx' extension 02:39:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:39:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:39:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:39:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:39:53 INFO - building 'psutil._psutil_posix' extension 02:39:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:39:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:39:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:39:53 INFO - ^ 02:39:53 INFO - 1 warning generated. 02:39:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:39:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:39:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:39:53 INFO - Successfully installed psutil 02:39:53 INFO - Cleaning up... 02:39:53 INFO - Return code: 0 02:39:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:39:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103353b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103356768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f358c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103131d30>, '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 0x103058db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103126be8>, '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.Clj5OSCP0o/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.Dg0hBO6241/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:39: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:39: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 mozsystemmonitor==0.3 02:39:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Clj5OSCP0o/Render', 02:39:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:54 INFO - 'HOME': '/Users/cltbld', 02:39:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:54 INFO - 'LOGNAME': 'cltbld', 02:39:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:54 INFO - 'MOZ_NO_REMOTE': '1', 02:39:54 INFO - 'NO_EM_RESTART': '1', 02:39:54 INFO - 'PAGER': '/bin/cat', 02:39:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:54 INFO - 'PWD': '/builds/slave/test', 02:39:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:54 INFO - 'SHELL': '/bin/bash', 02:39:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Dg0hBO6241/Listeners', 02:39:54 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 02:39:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:54 INFO - 'USER': 'cltbld', 02:39:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:54 INFO - 'XPC_FLAGS': '0x0', 02:39:54 INFO - 'XPC_SERVICE_NAME': '0', 02:39:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:39:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:39:57 INFO - Installing collected packages: mozsystemmonitor 02:39:57 INFO - Running setup.py install for mozsystemmonitor 02:39:57 INFO - Successfully installed mozsystemmonitor 02:39:57 INFO - Cleaning up... 02:39:57 INFO - Return code: 0 02:39:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:39:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:57 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 0x103353b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103356768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f358c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103131d30>, '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 0x103058db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103126be8>, '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.Clj5OSCP0o/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.Dg0hBO6241/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:39:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:39:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:39:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Clj5OSCP0o/Render', 02:39:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:57 INFO - 'HOME': '/Users/cltbld', 02:39:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:57 INFO - 'LOGNAME': 'cltbld', 02:39:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:57 INFO - 'MOZ_NO_REMOTE': '1', 02:39:57 INFO - 'NO_EM_RESTART': '1', 02:39:57 INFO - 'PAGER': '/bin/cat', 02:39:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:57 INFO - 'PWD': '/builds/slave/test', 02:39:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:57 INFO - 'SHELL': '/bin/bash', 02:39:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Dg0hBO6241/Listeners', 02:39:57 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 02:39:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:57 INFO - 'USER': 'cltbld', 02:39:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:57 INFO - 'XPC_FLAGS': '0x0', 02:39:57 INFO - 'XPC_SERVICE_NAME': '0', 02:39:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:57 INFO - Downloading/unpacking jsonschema==2.5.1 02:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:40:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:40:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:40:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:40:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:40:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:40:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:40:01 INFO - Downloading functools32-3.2.3-2.tar.gz 02:40:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:40:01 INFO - warning: no files found matching '*.txt' 02:40:01 INFO - no previously-included directories found matching 'build' 02:40:01 INFO - no previously-included directories found matching 'dist' 02:40:01 INFO - no previously-included directories found matching '.git*' 02:40:01 INFO - Installing collected packages: jsonschema, functools32 02:40:01 INFO - Running setup.py install for functools32 02:40:01 INFO - warning: no files found matching '*.txt' 02:40:01 INFO - no previously-included directories found matching 'build' 02:40:01 INFO - no previously-included directories found matching 'dist' 02:40:01 INFO - no previously-included directories found matching '.git*' 02:40:01 INFO - Successfully installed jsonschema functools32 02:40:01 INFO - Cleaning up... 02:40:01 INFO - Return code: 0 02:40:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:40:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:40:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103353b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103356768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f358c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103131d30>, '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 0x103058db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103126be8>, '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.Clj5OSCP0o/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.Dg0hBO6241/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:40:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:40:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:40:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Clj5OSCP0o/Render', 02:40:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:40:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:40:01 INFO - 'HOME': '/Users/cltbld', 02:40:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:40:01 INFO - 'LOGNAME': 'cltbld', 02:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:40:01 INFO - 'MOZ_NO_REMOTE': '1', 02:40:01 INFO - 'NO_EM_RESTART': '1', 02:40:01 INFO - 'PAGER': '/bin/cat', 02:40:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:40:01 INFO - 'PWD': '/builds/slave/test', 02:40:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:40:01 INFO - 'SHELL': '/bin/bash', 02:40:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Dg0hBO6241/Listeners', 02:40:01 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 02:40:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:40:01 INFO - 'USER': 'cltbld', 02:40:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:40:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:40:01 INFO - 'XPC_FLAGS': '0x0', 02:40:01 INFO - 'XPC_SERVICE_NAME': '0', 02:40:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:40:01 INFO - Cleaning up... 02:40:01 INFO - Return code: 0 02:40:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:40:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:40:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103353b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103356768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f358c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103131d30>, '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 0x103058db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103126be8>, '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.Clj5OSCP0o/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.Dg0hBO6241/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:40:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:40:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:40:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Clj5OSCP0o/Render', 02:40:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:40:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:40:01 INFO - 'HOME': '/Users/cltbld', 02:40:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:40:01 INFO - 'LOGNAME': 'cltbld', 02:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:40:01 INFO - 'MOZ_NO_REMOTE': '1', 02:40:01 INFO - 'NO_EM_RESTART': '1', 02:40:01 INFO - 'PAGER': '/bin/cat', 02:40:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:40:01 INFO - 'PWD': '/builds/slave/test', 02:40:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:40:01 INFO - 'SHELL': '/bin/bash', 02:40:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Dg0hBO6241/Listeners', 02:40:01 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 02:40:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:40:01 INFO - 'USER': 'cltbld', 02:40:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:40:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:40:01 INFO - 'XPC_FLAGS': '0x0', 02:40:01 INFO - 'XPC_SERVICE_NAME': '0', 02:40:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:40:02 INFO - Downloading/unpacking blobuploader==1.2.4 02:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:40:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:40:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:40:04 INFO - Downloading blobuploader-1.2.4.tar.gz 02:40:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:40:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:40:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:40:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:40:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:40:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:40:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:40:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:40:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:40:05 INFO - Downloading docopt-0.6.1.tar.gz 02:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:40:05 INFO - Installing collected packages: blobuploader, requests, docopt 02:40:05 INFO - Running setup.py install for blobuploader 02:40:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:40:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:40:06 INFO - Running setup.py install for requests 02:40:06 INFO - Running setup.py install for docopt 02:40:06 INFO - Successfully installed blobuploader requests docopt 02:40:06 INFO - Cleaning up... 02:40:06 INFO - Return code: 0 02:40:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:40:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:40:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:06 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 0x103353b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103356768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f358c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103131d30>, '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 0x103058db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103126be8>, '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.Clj5OSCP0o/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.Dg0hBO6241/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:40:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:40:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:40:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Clj5OSCP0o/Render', 02:40:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:40:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:40:06 INFO - 'HOME': '/Users/cltbld', 02:40:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:40:06 INFO - 'LOGNAME': 'cltbld', 02:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:40:06 INFO - 'MOZ_NO_REMOTE': '1', 02:40:06 INFO - 'NO_EM_RESTART': '1', 02:40:06 INFO - 'PAGER': '/bin/cat', 02:40:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:40:06 INFO - 'PWD': '/builds/slave/test', 02:40:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:40:06 INFO - 'SHELL': '/bin/bash', 02:40:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Dg0hBO6241/Listeners', 02:40:06 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 02:40:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:40:06 INFO - 'USER': 'cltbld', 02:40:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:40:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:40:06 INFO - 'XPC_FLAGS': '0x0', 02:40:06 INFO - 'XPC_SERVICE_NAME': '0', 02:40:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:40:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:40:07 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-0indtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:40:07 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-RsZou7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:40:07 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-fUHu7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:40:07 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-3q17NB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:40:07 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-RoBP6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:40:07 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-_mxbKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:40:07 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-9PWQDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:40:07 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-B0zoD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:40:07 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-t4dX2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:40:07 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-va0fMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:40:08 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-ESHgdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:40:08 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Wu4K9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:40:08 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-MuZgeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:40:08 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-RLTAk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:40:08 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-f7FH36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:40:08 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-C2AeIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:40:08 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-PLK4ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:40:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:40:08 INFO - Running setup.py install for manifestparser 02:40:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:40:08 INFO - Running setup.py install for mozcrash 02:40:09 INFO - Running setup.py install for mozdebug 02:40:09 INFO - Running setup.py install for mozdevice 02:40:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:40:09 INFO - Running setup.py install for mozfile 02:40:09 INFO - Running setup.py install for mozhttpd 02:40:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:40:09 INFO - Running setup.py install for mozinfo 02:40:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:40:09 INFO - Running setup.py install for mozInstall 02:40:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:40:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:40:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:40:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:40:09 INFO - Running setup.py install for mozleak 02:40:10 INFO - Running setup.py install for mozlog 02:40:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:40:10 INFO - Running setup.py install for moznetwork 02:40:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:40:10 INFO - Running setup.py install for mozprocess 02:40:10 INFO - Running setup.py install for mozprofile 02:40:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:40:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:40:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:40:10 INFO - Running setup.py install for mozrunner 02:40:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:40:10 INFO - Running setup.py install for mozscreenshot 02:40:11 INFO - Running setup.py install for moztest 02:40:11 INFO - Running setup.py install for mozversion 02:40:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:40:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:40:11 INFO - Cleaning up... 02:40:11 INFO - Return code: 0 02:40:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:40:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:40:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:11 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 0x103353b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103356768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f358c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103131d30>, '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 0x103058db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103126be8>, '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.Clj5OSCP0o/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.Dg0hBO6241/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:40:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:40:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Clj5OSCP0o/Render', 02:40:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:40:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:40:11 INFO - 'HOME': '/Users/cltbld', 02:40:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:40:11 INFO - 'LOGNAME': 'cltbld', 02:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:40:11 INFO - 'MOZ_NO_REMOTE': '1', 02:40:11 INFO - 'NO_EM_RESTART': '1', 02:40:11 INFO - 'PAGER': '/bin/cat', 02:40:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:40:11 INFO - 'PWD': '/builds/slave/test', 02:40:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:40:11 INFO - 'SHELL': '/bin/bash', 02:40:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Dg0hBO6241/Listeners', 02:40:11 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 02:40:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:40:11 INFO - 'USER': 'cltbld', 02:40:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:40:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:40:11 INFO - 'XPC_FLAGS': '0x0', 02:40:11 INFO - 'XPC_SERVICE_NAME': '0', 02:40:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:40:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:40:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-RY_P00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:40:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:40:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-PFGRXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:40:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:40:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-hOxBbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:40:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:40:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-oXuBMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:40:12 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-GEmh5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:40:12 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-ok2iAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:40:12 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-xddS8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:40:12 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-EHTxiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:40:12 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-NfNfbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:40:12 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-0NxemE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:40:12 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-yLLXcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:40:12 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-oHkNFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:40:12 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)) 02:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:40:12 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-S2KmDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:40:13 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-0DYboE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:40:13 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-VUDJ2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:40:13 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-VpY26J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:40:13 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-U_KkkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:40:13 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:40:13 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:40:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:40:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:40:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:40:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:40:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:40:16 INFO - Downloading blessings-1.6.tar.gz 02:40:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:40:16 INFO - Installing collected packages: blessings 02:40:16 INFO - Running setup.py install for blessings 02:40:16 INFO - Successfully installed blessings 02:40:16 INFO - Cleaning up... 02:40:16 INFO - Return code: 0 02:40:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:40:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:40:16 INFO - Reading from file tmpfile_stdout 02:40:16 INFO - Current package versions: 02:40:16 INFO - blessings == 1.6 02:40:16 INFO - blobuploader == 1.2.4 02:40:16 INFO - docopt == 0.6.1 02:40:16 INFO - functools32 == 3.2.3-2 02:40:16 INFO - jsonschema == 2.5.1 02:40:16 INFO - manifestparser == 1.1 02:40:16 INFO - mozInstall == 1.12 02:40:16 INFO - mozcrash == 1.0 02:40:16 INFO - mozdebug == 0.1 02:40:16 INFO - mozdevice == 0.50 02:40:16 INFO - mozfile == 1.2 02:40:16 INFO - mozhttpd == 0.7 02:40:16 INFO - mozinfo == 0.9 02:40:16 INFO - mozleak == 0.1 02:40:16 INFO - mozlog == 3.4 02:40:16 INFO - moznetwork == 0.27 02:40:16 INFO - mozprocess == 0.25 02:40:16 INFO - mozprofile == 0.28 02:40:16 INFO - mozrunner == 6.13 02:40:16 INFO - mozscreenshot == 0.1 02:40:16 INFO - mozsystemmonitor == 0.3 02:40:16 INFO - moztest == 0.8 02:40:16 INFO - mozversion == 1.4 02:40:16 INFO - psutil == 3.1.1 02:40:16 INFO - requests == 1.2.3 02:40:16 INFO - wsgiref == 0.1.2 02:40:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:40:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:40:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:16 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 0x103353b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103356768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f358c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103131d30>, '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 0x103058db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103126be8>, '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.Clj5OSCP0o/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.Dg0hBO6241/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:40:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:40:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Clj5OSCP0o/Render', 02:40:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:40:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:40:16 INFO - 'HOME': '/Users/cltbld', 02:40:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:40:16 INFO - 'LOGNAME': 'cltbld', 02:40:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:40:16 INFO - 'MOZ_NO_REMOTE': '1', 02:40:16 INFO - 'NO_EM_RESTART': '1', 02:40:16 INFO - 'PAGER': '/bin/cat', 02:40:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:40:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:40:16 INFO - 'PWD': '/builds/slave/test', 02:40:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:40:16 INFO - 'SHELL': '/bin/bash', 02:40:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Dg0hBO6241/Listeners', 02:40:16 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 02:40:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:40:16 INFO - 'USER': 'cltbld', 02:40:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:40:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:40:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:40:16 INFO - 'XPC_FLAGS': '0x0', 02:40:16 INFO - 'XPC_SERVICE_NAME': '0', 02:40:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:40:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:40:16 INFO - Cleaning up... 02:40:16 INFO - Return code: 0 02:40:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:40:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:40:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:16 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 0x103353b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103356768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8f358c230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103131d30>, '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 0x103058db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103126be8>, '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.Clj5OSCP0o/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.Dg0hBO6241/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:40:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:40:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Clj5OSCP0o/Render', 02:40:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:40:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:40:16 INFO - 'HOME': '/Users/cltbld', 02:40:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:40:16 INFO - 'LOGNAME': 'cltbld', 02:40:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:40:16 INFO - 'MOZ_NO_REMOTE': '1', 02:40:16 INFO - 'NO_EM_RESTART': '1', 02:40:16 INFO - 'PAGER': '/bin/cat', 02:40:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:40:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:40:16 INFO - 'PWD': '/builds/slave/test', 02:40:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:40:16 INFO - 'SHELL': '/bin/bash', 02:40:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Dg0hBO6241/Listeners', 02:40:16 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 02:40:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:40:16 INFO - 'USER': 'cltbld', 02:40:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:40:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:40:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:40:16 INFO - 'XPC_FLAGS': '0x0', 02:40:16 INFO - 'XPC_SERVICE_NAME': '0', 02:40:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:40:17 INFO - Cleaning up... 02:40:17 INFO - Return code: 0 02:40:17 INFO - Running post-action listener: _resource_record_post_action 02:40:17 INFO - Running post-action listener: _start_resource_monitoring 02:40:17 INFO - Starting resource monitoring. 02:40:17 INFO - [mozharness: 2017-04-11 09:40:17.242667Z] Finished create-virtualenv step (success) 02:40:17 INFO - [mozharness: 2017-04-11 09:40:17.243637Z] Running install step. 02:40:17 INFO - Running pre-action listener: _resource_record_pre_action 02:40:17 INFO - Running main action method: install 02:40:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:40:17 INFO - Reading from file tmpfile_stdout 02:40:17 INFO - Detecting whether we're running mozinstall >=1.0... 02:40:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:40:17 INFO - Reading from file tmpfile_stdout 02:40:17 INFO - Output received: 02:40:17 INFO - Usage: mozinstall [options] installer 02:40:17 INFO - Options: 02:40:17 INFO - -h, --help show this help message and exit 02:40:17 INFO - -d DEST, --destination=DEST 02:40:17 INFO - Directory to install application into. [default: 02:40:17 INFO - "/builds/slave/test"] 02:40:17 INFO - --app=APP Application being installed. [default: firefox] 02:40:17 INFO - mkdir: /builds/slave/test/build/application 02:40:17 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'] 02:40:17 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 02:40:37 INFO - Reading from file tmpfile_stdout 02:40:37 INFO - Output received: 02:40:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:40:37 INFO - Running post-action listener: _resource_record_post_action 02:40:37 INFO - [mozharness: 2017-04-11 09:40:37.451574Z] Finished install step (success) 02:40:37 INFO - [mozharness: 2017-04-11 09:40:37.451717Z] Running run-tests step. 02:40:37 INFO - Running pre-action listener: _resource_record_pre_action 02:40:37 INFO - Running main action method: run_tests 02:40:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:40:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:40:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:40:37 INFO - 2017-04-11 02:40:37.464 screenresolution[1623:7491] starting screenresolution argv=screenresolution get 02:40:37 INFO - 2017-04-11 02:40:37.466 screenresolution[1623:7491] Display 0: 1600x1200x32@60 02:40:37 INFO - 2017-04-11 02:40:37.471 screenresolution[1624:7494] starting screenresolution argv=screenresolution list 02:40:37 INFO - Available Modes on Display 0 02:40:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:40:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:40:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:40:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:40:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:40:37 INFO - Intel Iris: 02:40:37 INFO - Chipset Model: Intel Iris 02:40:37 INFO - Type: GPU 02:40:37 INFO - Bus: Built-In 02:40:37 INFO - VRAM (Dynamic, Max): 1536 MB 02:40:37 INFO - Vendor: Intel (0x8086) 02:40:37 INFO - Device ID: 0x0a2e 02:40:37 INFO - Revision ID: 0x0009 02:40:37 INFO - Displays: 02:40:37 INFO - Display: 02:40:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:40:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:40:37 INFO - Display Serial Number: 1600x1200 60 02:40:37 INFO - Main Display: Yes 02:40:37 INFO - Mirror: Off 02:40:37 INFO - Online: Yes 02:40:37 INFO - Rotation: Supported 02:40:37 INFO - Return code: 0 02:40:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:40:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:40:37 INFO - Python 2.7.3 02:40:37 INFO - Return code: 0 02:40:37 INFO - grabbing minidump binary from tooltool 02:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:37 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 0x103131d30>, '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 0x103058db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103126be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:40:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:40:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:40:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:40:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:40:37 INFO - Return code: 0 02:40:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:40:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:40:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:40:37 INFO - ENV: RUST_BACKTRACE is now 1 02:40:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:40:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:40:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:40:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0178 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:40:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Clj5OSCP0o/Render', 02:40:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:40:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:40:37 INFO - 'HOME': '/Users/cltbld', 02:40:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:40:37 INFO - 'LOGNAME': 'cltbld', 02:40:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:40:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:40:37 INFO - 'MOZ_NO_REMOTE': '1', 02:40:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:40:37 INFO - 'NO_EM_RESTART': '1', 02:40:37 INFO - 'PAGER': '/bin/cat', 02:40:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:40:37 INFO - 'PWD': '/builds/slave/test', 02:40:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:40:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:40:37 INFO - 'RUST_BACKTRACE': '1', 02:40:37 INFO - 'SHELL': '/bin/bash', 02:40:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Dg0hBO6241/Listeners', 02:40:37 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 02:40:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:40:37 INFO - 'USER': 'cltbld', 02:40:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:40:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:40:37 INFO - 'XPC_FLAGS': '0x0', 02:40:37 INFO - 'XPC_SERVICE_NAME': '0', 02:40:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:40:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:40:38 INFO - mozversion application_buildid: 20170411011827 02:40:38 INFO - mozversion application_changeset: 7031c811659d90275e7b8e1557027791081767c5 02:40:38 INFO - mozversion application_display_name: Nightly 02:40:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:40:38 INFO - mozversion application_name: Firefox 02:40:38 INFO - mozversion application_remotingname: firefox 02:40:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:40:38 INFO - mozversion application_vendor: Mozilla 02:40:38 INFO - mozversion application_version: 55.0a1 02:40:38 INFO - mozversion platform_buildid: 20170411011827 02:40:38 INFO - mozversion platform_changeset: 7031c811659d90275e7b8e1557027791081767c5 02:40:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:40:38 INFO - mozversion platform_version: 55.0a1 02:40:38 INFO - using testdate: 1491903638 02:40:38 INFO - actual date: 1491903638 02:40:38 INFO - starting webserver on 'localhost:49242' 02:40:38 INFO - SUITE-START | Running 2 tests 02:40:38 INFO - TEST-START | dromaeo_css 02:40:38 INFO - operating with platform_type : mac_ 02:40:38 INFO - Initialising browser for dromaeo_css test... 02:40:38 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpzBfyJm/profile http://localhost:49242/getInfo.html) 02:40:41 INFO - PID 1634 | 2017-04-11 02:40:41.206 plugin-container[1636:7671] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 02:40:41 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:40:41 INFO - PID 1634 | 2017-04-11 02:40:41.208 plugin-container[1636:7671] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b17, name = 'com.apple.CFPasteboardClient' 02:40:41 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:40:41 INFO - PID 1634 | 2017-04-11 02:40:41.209 plugin-container[1636:7671] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:40:41 INFO - PID 1634 | __metrics Screen width/height:1600/1200 02:40:41 INFO - PID 1634 | colorDepth:24 02:40:41 INFO - PID 1634 | Browser inner width/height: 1024/689 02:40:41 INFO - PID 1634 | __metrics 02:40:41 ERROR - PID 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 02:40:42 INFO - TEST-INFO | 1634: exit 0 02:40:42 INFO - Browser initialized. 02:40:42 INFO - Running cycle 1/1 for dromaeo_css test... 02:40:42 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpzBfyJm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:40:44 INFO - PID 1637 | 2017-04-11 02:40:44.972 plugin-container[1638:7810] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 02:40:44 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:40:44 INFO - PID 1637 | 2017-04-11 02:40:44.972 plugin-container[1638:7810] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 02:40:44 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:40:44 INFO - PID 1637 | 2017-04-11 02:40:44.972 plugin-container[1638:7810] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:40:54 INFO - PID 1637 | RSS: Main: 235393024 02:40:54 INFO - PID 1637 | 02:43:58 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 02:43:59 INFO - PID 1637 | RSS: Main: 253587456 02:43:59 INFO - PID 1637 | 02:45:42 INFO - PID 1637 | ************************* 02:45:42 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 02:45:42 INFO - PID 1637 | 02:45:42 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:45:42 INFO - PID 1637 | Full stack: 02:45:42 INFO - PID 1637 | ************************* 02:45:42 INFO - PID 1637 | ************************* 02:45:42 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 02:45:42 INFO - PID 1637 | 02:45:42 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:45:42 INFO - PID 1637 | Full stack: 02:45:42 INFO - PID 1637 | ************************* 02:45:42 INFO - PID 1637 | ************************* 02:45:42 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 02:45:42 INFO - PID 1637 | 02:45:42 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:45:42 INFO - PID 1637 | Full stack: 02:45:42 INFO - PID 1637 | ************************* 02:45:42 INFO - PID 1637 | ************************* 02:45:42 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 02:45:42 INFO - PID 1637 | 02:45:42 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:45:42 INFO - PID 1637 | Full stack: 02:45:42 INFO - PID 1637 | ************************* 02:47:01 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 02:47:02 INFO - PID 1637 | RSS: Main: 262000640 02:47:02 INFO - PID 1637 | 02:50:03 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 02:50:04 INFO - PID 1637 | RSS: Main: 273199104 02:50:04 INFO - PID 1637 | 02:53:06 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 02:53:06 INFO - PID 1637 | RSS: Main: 268865536 02:53:06 INFO - PID 1637 | 02:56:09 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 02:56:10 INFO - PID 1637 | RSS: Main: 280055808 02:56:10 INFO - PID 1637 | 02:59:12 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 02:59:12 INFO - PID 1637 | RSS: Main: 273797120 02:59:12 INFO - PID 1637 | 02:59:12 INFO - PID 1637 | __start_tp_report 02:59:12 INFO - PID 1637 | _x_x_mozilla_page_load 02:59:12 INFO - PID 1637 | _x_x_mozilla_page_load_details 02:59:12 INFO - PID 1637 | |i|pagename|runs| 02:59:12 INFO - PID 1637 | |0;dojo.html;3452.395830729622;3561.323348563771;3561.9821900890493;3597.6222496637843;3598.964010359896;6202.782902598416;6215.782447614334;6238.625682459045;6243.250809902806;6254.812355629333;4914.950850491494;6092.8701720992985;6113.724882380292;6193.792210518951;6197.19436473259;4224.450821393222;4942.752862356886;5433.538149257318;5523.337199536041;5563.582731295149;5567.192757050226;5574.470425309589;5574.888502229953;5577.358603760579;5579.163125531182;682.5066055808119;4860.975695121537;5092.701361152919;5426.430224826416;5428.45715428456;4944.678595891256;5478.342598888119;5488.807891723811;5507;5542.501174894279;5463.562914966793;5474.233607294982;5477.04151773438;5484.122540393558;5504.779808807642;4892.315075889378;5975.894459524959;6157.507399408037;6160.599561028564;6182.1345011698395;5655.519280861091;6699.531032827704;6712.060311556386;6753.1220941277315;6765.917453207512;5823.621464604787;6815.965920170367;6841.657917104175;6866;6896.310368963113;6229.0967809668255;6268.592541484789;6352.523560732917;6353.459955903708;6374.426301632782;6774.695138718769;6827.69275382609;6847.281035491302;6852.794416167523;6875.312468753084;6528;6548.443382312556;6590.868182636416;6614.669266536654;6632.83417914556;5057.519535644108;6653.700583473756;6659.334066593302;6721.932780672229;6746.966265168741;6678.565893216925;6723.899141512881;6732.629705366154;6754.898676519856;6761.695723692445;6634.170728658918;6734.764283250062;6751.831204219836;6767.627780472121;6774.559653622498;5242.318498595235;5338.519533242027;5398.433164517748;5405.189221616711;5415.945840541623;4757.643176761757;4792.161371759928;4823.879403014953;4847.321375007502;4899;5148.897022059537;6132.202813634199;6357.809265722036;6364.64994425311;6384.3934826191435;6769.627670478072;6780.898286525706;6858.622775747282;6863.588184708933;6880.827979300558;6166.229221347332;6273.560850740404;6313.2424109107205;6336.683165841781;6357;6038.788642397495;6062.211912451353;6076.8784624308155;6089.908651370233;6103.542234332443;6866.416354609814;6936.306369363023;6952.617606031717;6955.582665040114;6960.408365289007;5545.611807173459;6547.214334279917;6619.073329733745;6768.898466522907;6803.353681400259;6903.206131294764;6906.067680863019;6918.446524277945;6924.446044316342;6925.89611155823;5092.879566495365;6077.6353418795015;6754.358335958076;6772.424343930821;6785.558938668971;6549.7380104795275;6666;6756.628385438848;6776.830579235559;6813.693383797642;5243.973780131075;6600.933990660032;6614.66926653675;6632.701528431136;6653.201615806134;6385.13800636908;6603.141591593254;6627.701753421011;6633.402993730394;6641.468682505485;6137.693115344304;6253.96873015632;6449.193850768654;6484.967575162283;6494.058361537645;6575.769848055486;6613.66931653425;6619.801405957829;6622.106015688013;6657.2677005528385;6374.617522948638;6429.38920802523;6436.292007879223;6443.130177425987;6457.61254324742 02:59:12 INFO - PID 1637 | |1;ext.html;746.9841016217946;799.2207597592347;826.5495305058743;1286.9549565765194;1291.0639786155045;38375.80812095939;38914.610853891456;38962.61037389632;39040.804795976015;39181.824545263655;38353.61646383535;38447;38632.68203907768;38676;38715.22569548607;7986.480878742877;8148.470349427298;8299.170082991714;8539.615717292705;8542.530160841152;8420.831583368328;8443;8556.272716819078;8560.571971401436;8564.528950907697;8452.830943381128;8496.702615408461;8508.659653613848;8578.87131693022;8582.270507006911;8441.704540341088;8510.361722870779;8547.059823419419;8547.572621368907;8557.614907329154;8345.123762005023;8398.370122240856;8419.368547358912;8426.747197584082;8427.578621068984;37434;37568.81215593904;37580.68467603621;37734.81132594347;37794.43308350377;3029.3184033592484;3148.819192802699;3551.94672079919;3592.910177245564;3595.7303202259673;25944.221673349828;29008;30652.233694157825;30726.539101913488;30748.080307150747;2637.8021648376257;3170.1282147409415;3179.8410079496166;3182.4112539180096;3187.4581321175224;232677;238613;267686;288602.67095993576;289561;47610.80972975634;47823.80440489016;47916.76041619769;47937.760311198916;47954.28068578974;49168.754156229705;49656.25515617269;49797.75101124471;50018;50033.499665002884;12114.81827772584;14223.85776142246;14239.644008899653;14365.497207597684;14486;11769.999550038172;11919.165658404001;11934.283942963277;11955.222910510789;12012.87987120135;13247.337633118306;13337.66655833612;13386.995975301697;13436.328183590782;13467.259300738357;5493.368262649767;5686.800961966312;6008.789692360834;6044.274687037584;6075.513958883367;1015.7562185075528;1028.8919663435381;1029.7322696099068;1063.4948399510326;1069.4011353641986;18862.71705924412;23279;24162.671053091897;24424.26727198187;24573.262802115965;1123.719070232442;1125.3360517294836;1163.2787671643634;1165.0737663557493;1167.6613782003294;11971.580994665144;12498.50005999768;12540.623781286577;12555.435005424777;12598.748025039447;373735;376506;377251.2511811877;377280;377630;51714.70713232199;52053;52087;52098.47901521012;52138;1500.0299407173857;1507.6306304955137;1550.627849316156;1550.8914375993572;1564.6010267381744;23870.283891483283;23989.400264993514;24042.918068686602;24090.27729168128;24191.63712544278;37403.81298093601;37415.25169496649;37515.62484375121;37591;37630.247395052495;324675;324868;325629;326818;327689;34022.31955360829;34343.31313373768;34511.30977380388;35511;35556;45698;45997.400259973736;46248.075038499715;46363;46473.53526464692;30058;30220.093397198118;31468.2132946669;31987.680123198472;32766.83616581902;31324.561055843387;31749.36501270008;31957.840210798797;32081.197970050936;32108;37548;37612.435813462274;37684.62315376921;37691.81154094212;37751.81124094362;29919.55120673148;30473.54289685612;31870.52194217042;31911.84044079765;32732.690692372038;21446.571068578854;23510.942007609356;24081.51836963286;24573.877130614233;25102.12142575074 02:59:12 INFO - PID 1637 | |2;jquery.html;422.95770422957725;444.63095630626583;446.1634435433561;455.4602795687111;455.83817744700633;20078.89960550197;21668.34994950152;22407.887960560216;22583.209587664434;22604.88697556514;22334.21830235942;22942;23631.409214769596;23683.407914802094;23759.287221383387;5451.209574611679;6233.158523599302;6408.711607977641;6607.108040414555;6624.039514270428;6368.840778980516;6422.100905873158;6508.088867558546;6510.6744662766905;6522.706478208492;6208.099825525319;6373.968130159343;6406.167198264243;6407.231132264134;6459.515536334794;6252.843678908018;6343.4925205983645;6380.872382552369;6418.775342863024;6436.485081193517;5668.943310566883;5687.061634830289;5728;5729.169270455761;5729.598928075036;27616.309592260353;27840.886364545393;27883.302917427023;27972.300692482648;28159.15522534327;2239.507308392151;2266.875321857297;2290.4502919299416;2291.346966114649;2291.5531471363092;15032.473863414838;15534.689306213813;17263.30946762128;18399.632007359913;18592.44222673322;2200.2959053103014;2208.4810069633622;2235.083615717548;2247.606668832948;2251.234580242709;146673;148015;148485.7727134094;149268;149938.7509187363;12354.38228088592;12864.099513033998;12886.484540618458;12886.742265154644;12952.611421657177;16988;17060.744088838677;17078.146092695562;17078.23147958345;17086.48740537786;9065.320100992452;9107.453552786854;9300.627974881065;9472.194863436685;9474.810503790024;7113.786586402416;8941.821163576822;8948.149925757043;8949.821003579891;9108.81782364349;7989.760307190793;8088.312493438124;8120.918790812135;8138.593070346459;8144.5520496373265;1943.6112777444569;1965.3907288740536;1975.9110840012233;2220.5669894370767;2403.315055209257;803.9919600804034;876.8775966762554;995.4326034160604;1004.7387679203459;1035.9430231337199;20439;21197.89401052985;21227.68158477624;21278.829664368608;21305.89347053255;929.809389075238;1015.6902144845893;1016.2682868334787;1046.4140081554353;1050.4800123938699;11037.39294338803;11251;11482.02402795755;11501.654950351503;11557.24877882935;713627;721337;728876;731949;740771;130786.69213307745;154109;158876;161407;166657;1189.1141099880472;1194.1103877611063;1248.039009962324;1249.6438515023174;1250.1686378558338;20641.277555285495;21613;23100;23270.72011039409;23804;65739;66431.66784166048;67007.65984680182;67135;67538.96074676553;58721.70639146777;93217.60173597265;94102.52948735212;95778.56332155163;96802;8797.560121994004;8854.734357969272;8881.644734210558;9142;9235.445873247627;14524.055936364102;14585;14682;14838;14875.776863347275;11878.762424751283;12135.635930922086;12237.816432753338;12295.139340246455;12366.381680916098;8809.867851982353;11466.598669046543;11521.596744113916;11663.708407289885;11770.94114529456;9644.662436814679;11985;12260.141790074966;12288.447019884305;12324.445399957203;9008.684696035869;9065.864012039956;9158.939238671246;9181.540922953693;9192.080791920755;9641.42151470914;11403;11885.196737044063;12006;12151.270923744603 02:59:12 INFO - PID 1637 | |3;mootools.html;1232.8952039076678;1432.6704857882687;1435.3684378873295;1450.8113945187124;1476.2028504607513;11633.36016519091;11975.700607484818;12142;12148.392580370979;12208.755824883498;12017.879821201785;12169.57406490773;12191.878081219207;12214.877851221485;12221.816672749894;2530.936726581832;2553.668023156987;2555.718870924197;2570.7300733423003;2572.4855028994184;1881.6236752649456;2481.5036992601463;2512.8617926014063;2529.8229123961332;2607.8174527783062;2478.950420991588;2491.638712386703;2527.2418274517663;2528.620706893962;2530.6204069389646;2072.77199508054;2179.553191595719;2469.2839076667833;2476.5418397596504;2511.234073607549;2432.9391765205833;2515.5220508103585;2530.519201351756;2533.8606376649277;2542.0594380079488;11878.109141814311;11940.044796416265;12033.51865925362;12039.09706771987;12067.396630168456;5755.625884317506;6480.930646443331;6783.660816959182;6830.419414349736;6841;3997.6801855851463;4231.534531201565;4311.159323931804;4314.956850431487;4333.263345231318;1214.884585964332;1218.9634310970596;1233.9753204935942;1267.2713189915835;1268.3277862732766;15085.170315632637;15603.92198039014;16658.250378732988;16791.244394002177;16800.32798688051;5511.641743286674;5736.196932429463;5926.496247818897;5948.256467941507;5958.493528050078;12403.875961240272;12439.937800310941;12502.81245781314;12531.43608537618;12562.497500099893;5417.485338892799;6666.566673166229;6682.198136223684;6688;6695.46436285096;6073.96963015191;6090.269167699905;6144.354842741448;6206.534509911775;6210.534209934273;4397.714148580332;4453.799579018952;4495.348174514743;4503.594676479132;4507.3689683444345;1058.8200005999;1316.1510825517487;1320.8679132086907;1338.8326459192601;1339.5378594384922;777.6928113395177;800.9787520910885;802.3701394405364;804.5373910001772;811.5130921447085;5601.187827764951;6507.349265073455;6722.159730033746;6753.594784312957;7180.246074162138;864.7103220421103;954.6944977607238;964.9228061755183;971.5579411367811;991.9603215871284;4633.4208223972;4638.165130276582;4804.399450068741;4840.830570930071;4872.853814385574;14857.47998820036;14914.403423863141;15008.324625391884;15417.152056636993;15484.845151548341;14996.175210363535;15297.464588739564;15344.769828452581;15480.380784768708;15520.456784012506;10749.570017199225;11018.283811552223;11293.15301352402;11295;11317.490712917774;7057.858842823218;7064.858702825812;7318.1949985502615;7330.780076597711;7421.146568144733;11368.94315528417;13886.95847811398;13965;13974.860251397356;14174.14955102697;13840.23878686682;14049.929750351183;14052.227127507676;14070.085444446078;14166.716665666423;9650.469224192457;9683.370580912218;9784.951075244579;9869.457179855177;9883.406995580288;8901.421407608485;9755.512224388895;10813.945930270298;11008;11024.944875275572;8299.875501867356;9305.674301399418;9312.301577381588;9347;9455.952720236355;9154.176124148858;9263.823001594485;9271.211946984582;9336.813263734823;9420.293477989042;8220.876686849582;8291.668333266602;8392.160783921803;8405.033421156646;8418;5424.810131645373;6075.665838378932;6145.477634401126;6151.046587778723;6281.466075383643;5098.566621837185;5716.599838011466;5953.821385358447;6232.844178895564;6312.400321969406 02:59:12 INFO - PID 1637 | |4;prototype.html;404.5125623623534;406.8392984771016;408.1591920643474;411.3356928560374;412.30732369619045;5382.89234215316;5962.04607262838;6159.753609855612;6187.288461826891;6195.287541932679;5200.13343888947;5590.776368945248;5869.207656966309;5903.557233207505;5921.674307913063;4502.2571275739465;4527.343535187396;4576;4577.679562430631;4580.839670611529;4560.042391097873;4562.429696287964;4582.243929751604;4587.490569714171;4588.719065867904;3918.74528155669;4011.4785077939973;4239.724417912841;4490.326451032339;4536.818527258905;4140.840564641905;4312;4484.97757511212;4492.820287188509;4494.505604383515;3474.947875781865;4035.1122752994293;4061.97969010156;4085.5097388313297;4086.978255436141;5207.713575753332;5251.949610077962;5381.919271210935;5543.223948647193;5775.826725198251;5380.596455265871;5417.945820541823;5444.265024221759;5448.591355648303;5461.15352120422;6161.98327275999;6430.839229019265;6433.935660643428;6460.224773027267;6481.0602462642655;4050.764516822368;4159.417681524589;4609.239475486541;4611.21609326412;4622.352870598119;6251.531135164881;6365.649889256089;6397.5521713480075;6652.7338906444165;6778.932210677879;3719.330520506335;4145.253854306254;4572.177008138567;4765.046990601893;4770.95229047712;4852.878678033077;4857.247126695368;4859.59071869161;4863.610911127102;4867.269909513601;5332.2801421808335;5402.945970540323;5433.701146436905;5441.455854414527;5503.889922201533;5389.380221274603;6441.742330306735;6453.225612926479;6466.612003279818;6474.773382931575;1627.3002608878296;1651.2404294024643;1651.5953591370192;1661.3271624991899;1663.1351697117432;975.6048800235916;1128.7178205448638;1143.1597775634903;1151.654503648902;1155.5320095361392;6057.18228039209;6067.575269731076;6076.848078798065;6081.665508396992;6082.635041897501;6032.095185722089;6070.423309785563;6415.518836087312;6657.633830139292;6667.799966001027;5353.946460535422;6045.153678485016;6055.303640081359;6089.878202435926;6113.816585502442;2810.775137988956;3453.568303962005;3463.9088687063495;3469.6988629264024;3497.615262321143;5695.316562012502;6453.225612926479;6676.6327851967635;6790.879504881738;6929.2928283369165;5571.916421253684;6580.269188286808;6890.517663763489;6920;6948.235694073648;5959.284885813643;6152.323244443108;6778.966105169443;6799.1840979081835;6831.9658401707675;6659.334066593496;6670.9332906670315;6682.46540276767;6682.69927853238;6684.766033189009;6288.748450061947;6316.210473690789;6602;6626.867462650624;6644.83387915306;5539.9169012464035;6226.159468471698;6450.0002499610755;6510.18622672166;6666.799996000128;3398.5581874356176;3651.8356673950266;4372.387865698868;4382.189294980439;4385.1010542838;6342.207224096988;6641.335866413513;6657.500687448558;6667.633280169637;6674.666266686743;5622.971885140548;6435.4529864962005;6448.935510644834;6464.935350646433;6529.608223506605;5950.285965684146;6455.580387274812;6490;6506.41442270198;6510.967445162744;6297.496200303873;6362.459190968634;6370.968145159244;6372.299047104908;6376.585521941059;6460.35396460369;6486.183868516823;6515.381038801306;6520.510961677798;6549.8690026200165;6137.447629713169;6250.906236406367;6258.906116408349;6309.801137783807;6333.493320534439 02:59:12 INFO - PID 1637 | |5;yui.html;813.7884150120967;837.4012581004577;852.4416507187792;862.4437237981502;864.3906046237402;14419.134851908893;15165.848341516608;16643.50069497914;16898.662026759474;17068.658626827473;16553.172341382942;17469.3012279509;17587.208575614095;17646;17692.523046773462;948.7865230323191;967.8306296398138;972.4019727867346;973.4013581647307;974.1281553010052;1003.3979612232639;1005.8792944846629;1006.6527048168371;1008.7780688248573;1008.9091981721643;1065;1067.4022547373495;1069.310294859815;1071.0467683761458;1072.0351683484848;891.4339394484484;1054.9736256593646;1062.1555863088863;1063.5798859450513;1075.983860242097;787.582581231948;787.9369650427952;789.1122487201898;789.6801795272867;794.265304593249;16358;16732.079735614538;16935;16950.06774627395;17000.064996425193;15114.622134446618;16251.349946002145;16278.75581866273;16844.83155168445;17006.404775832787;13450.730985380336;13800.378982945791;13981.02132850701;13988.790168147385;14010.719785604231;3427.7943323400677;3459.60214575322;3469.930601387983;3494.9554510611324;3560.2345495718546;85755;111081.88918110715;111614.88385116126;112825.05851302276;113329;11226.270292430965;12913.677158071123;13362.599122026355;13366;13372.531961381304;24436.87781561081;24529;25143.371415714755;25153.87423062873;25446.491070178494;8934.4192627479;9239.630414783467;9240.815183696288;9241.861372079424;9256.305777066747;4751.52484751529;4763.118823017753;4819.6867203631655;4902.950970490249;4945.802167913315;4227.788610569459;4299.935500967488;4316.093620339764;4352.956470435255;4355.499117601516;733.7321877514669;929.9488528131018;938.1931538876561;939.9953000235092;941.9858702119474;638.7955854126727;657.1260223902199;657.3919124809532;665.9667016649148;674.8515326628134;9719.65981190655;11076.446177691083;11328.037116844996;11474.885251147549;11812.940935295268;647.3591144766647;651.1469974333638;658.1575583253443;659.6998365743575;675.1425689374466;4339.457567804025;5080.593552515864;5253.894922101536;5434.565234781208;5519.530839878656;77128;91518.54240728753;93704;94541.05458945323;95925;83374;84237.15762842294;84318.22948396622;85477.29045419057;87367.44744144214;76498.61750691323;77856.61071694718;77958.61020694861;78341;78560;9640.373375730554;11219.775604488028;11515.481803318704;11751.76496470083;12109.576164834189;37182.070448239014;37482.625173749;37700.24599508049;37940.810295948344;38065.23869522649;49568.51294461172;50605;50761;66805.9853715353;69646;15038.398464061314;15314.468553145041;16398.83601163973;16552.503424897273;16777.91611041937;18175.81857179279;18205.635887281915;18209.362672306404;18439.07804609791;18495.260189592267;16730.414435495186;16788.58028549296;16914.985100893984;16936.830631693527;16967.49097527076;16078.919605401898;16262.837371626132;16798.832011679726;16830.831691682928;16924.238409271366;16094.67810643804;16448.753268701217;16496.25766840519;16606.335746570003;16685.998840069635;11705.356205408785;11938.1046421517;11945.343006134746;12029.278243305429;12071.094667899766;12672;12779.1054626179;12783.424745886643;12799.424025919043;12992.02559808037 02:59:12 INFO - PID 1637 | __end_tp_report 02:59:12 INFO - PID 1637 | __start_cc_report 02:59:12 INFO - PID 1637 | _x_x_mozilla_cycle_collect,434 02:59:12 INFO - PID 1637 | __end_cc_report 02:59:12 INFO - PID 1637 | __startTimestamp1491904752266__endTimestamp 02:59:12 INFO - PID 1637 | 02:59:12 INFO - PID 1637 | ------- Summary: start ------- 02:59:12 INFO - PID 1637 | Number of tests: 6 02:59:12 INFO - PID 1637 | 02:59:12 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6099.55 Median:6374.62 stddev:883.83 (13.9%) stddev-sans-first:861.80 02:59:12 INFO - PID 1637 | Values: 3452.4 3561.3 3562.0 3597.6 3599.0 6202.8 6215.8 6238.6 6243.3 6254.8 4915.0 6092.9 6113.7 6193.8 6197.2 4224.5 4942.8 5433.5 5523.3 5563.6 5567.2 5574.5 5574.9 5577.4 5579.2 682.5 4861.0 5092.7 5426.4 5428.5 4944.7 5478.3 5488.8 5507.0 5542.5 5463.6 5474.2 5477.0 5484.1 5504.8 4892.3 5975.9 6157.5 6160.6 6182.1 5655.5 6699.5 6712.1 6753.1 6765.9 5823.6 6816.0 6841.7 6866.0 6896.3 6229.1 6268.6 6352.5 6353.5 6374.4 6774.7 6827.7 6847.3 6852.8 6875.3 6528.0 6548.4 6590.9 6614.7 6632.8 5057.5 6653.7 6659.3 6721.9 6747.0 6678.6 6723.9 6732.6 6754.9 6761.7 6634.2 6734.8 6751.8 6767.6 6774.6 5242.3 5338.5 5398.4 5405.2 5415.9 4757.6 4792.2 4823.9 4847.3 4899.0 5148.9 6132.2 6357.8 6364.6 6384.4 6769.6 6780.9 6858.6 6863.6 6880.8 6166.2 6273.6 6313.2 6336.7 6357.0 6038.8 6062.2 6076.9 6089.9 6103.5 6866.4 6936.3 6952.6 6955.6 6960.4 5545.6 6547.2 6619.1 6768.9 6803.4 6903.2 6906.1 6918.4 6924.4 6925.9 5092.9 6077.6 6754.4 6772.4 6785.6 6549.7 6666.0 6756.6 6776.8 6813.7 5244.0 6600.9 6614.7 6632.7 6653.2 6385.1 6603.1 6627.7 6633.4 6641.5 6137.7 6254.0 6449.2 6485.0 6494.1 6575.8 6613.7 6619.8 6622.1 6657.3 6374.6 6429.4 6436.3 6443.1 6457.6 02:59:12 INFO - PID 1637 | 02:59:12 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:47009.16 Median:34427.31 stddev:85720.95 (249.0%) stddev-sans-first:85891.05 02:59:12 INFO - PID 1637 | Values: 747.0 799.2 826.5 1287.0 1291.1 38375.8 38914.6 38962.6 39040.8 39181.8 38353.6 38447.0 38632.7 38676.0 38715.2 7986.5 8148.5 8299.2 8539.6 8542.5 8420.8 8443.0 8556.3 8560.6 8564.5 8452.8 8496.7 8508.7 8578.9 8582.3 8441.7 8510.4 8547.1 8547.6 8557.6 8345.1 8398.4 8419.4 8426.7 8427.6 37434.0 37568.8 37580.7 37734.8 37794.4 3029.3 3148.8 3551.9 3592.9 3595.7 25944.2 29008.0 30652.2 30726.5 30748.1 2637.8 3170.1 3179.8 3182.4 3187.5 232677.0 238613.0 267686.0 288602.7 289561.0 47610.8 47823.8 47916.8 47937.8 47954.3 49168.8 49656.3 49797.8 50018.0 50033.5 12114.8 14223.9 14239.6 14365.5 14486.0 11770.0 11919.2 11934.3 11955.2 12012.9 13247.3 13337.7 13387.0 13436.3 13467.3 5493.4 5686.8 6008.8 6044.3 6075.5 1015.8 1028.9 1029.7 1063.5 1069.4 18862.7 23279.0 24162.7 24424.3 24573.3 1123.7 1125.3 1163.3 1165.1 1167.7 11971.6 12498.5 12540.6 12555.4 12598.7 373735.0 376506.0 377251.3 377280.0 377630.0 51714.7 52053.0 52087.0 52098.5 52138.0 1500.0 1507.6 1550.6 1550.9 1564.6 23870.3 23989.4 24042.9 24090.3 24191.6 37403.8 37415.3 37515.6 37591.0 37630.2 324675.0 324868.0 325629.0 326818.0 327689.0 34022.3 34343.3 34511.3 35511.0 35556.0 45698.0 45997.4 46248.1 46363.0 46473.5 30058.0 30220.1 31468.2 31987.7 32766.8 31324.6 31749.4 31957.8 32081.2 32108.0 37548.0 37612.4 37684.6 37691.8 37751.8 29919.6 30473.5 31870.5 31911.8 32732.7 21446.6 23510.9 24081.5 24573.9 25102.1 02:59:12 INFO - PID 1637 | 02:59:12 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:41833.09 Median:23021.00 stddev:121610.54 (528.3%) stddev-sans-first:121911.94 02:59:12 INFO - PID 1637 | Values: 423.0 444.6 446.2 455.5 455.8 20078.9 21668.3 22407.9 22583.2 22604.9 22334.2 22942.0 23631.4 23683.4 23759.3 5451.2 6233.2 6408.7 6607.1 6624.0 6368.8 6422.1 6508.1 6510.7 6522.7 6208.1 6374.0 6406.2 6407.2 6459.5 6252.8 6343.5 6380.9 6418.8 6436.5 5668.9 5687.1 5728.0 5729.2 5729.6 27616.3 27840.9 27883.3 27972.3 28159.2 2239.5 2266.9 2290.5 2291.3 2291.6 15032.5 15534.7 17263.3 18399.6 18592.4 2200.3 2208.5 2235.1 2247.6 2251.2 146673.0 148015.0 148485.8 149268.0 149938.8 12354.4 12864.1 12886.5 12886.7 12952.6 16988.0 17060.7 17078.1 17078.2 17086.5 9065.3 9107.5 9300.6 9472.2 9474.8 7113.8 8941.8 8948.1 8949.8 9108.8 7989.8 8088.3 8120.9 8138.6 8144.6 1943.6 1965.4 1975.9 2220.6 2403.3 804.0 876.9 995.4 1004.7 1035.9 20439.0 21197.9 21227.7 21278.8 21305.9 929.8 1015.7 1016.3 1046.4 1050.5 11037.4 11251.0 11482.0 11501.7 11557.2 713627.0 721337.0 728876.0 731949.0 740771.0 130786.7 154109.0 158876.0 161407.0 166657.0 1189.1 1194.1 1248.0 1249.6 1250.2 20641.3 21613.0 23100.0 23270.7 23804.0 65739.0 66431.7 67007.7 67135.0 67539.0 58721.7 93217.6 94102.5 95778.6 96802.0 8797.6 8854.7 8881.6 9142.0 9235.4 14524.1 14585.0 14682.0 14838.0 14875.8 11878.8 12135.6 12237.8 12295.1 12366.4 8809.9 11466.6 11521.6 11663.7 11770.9 9644.7 11985.0 12260.1 12288.4 12324.4 9008.7 9065.9 9158.9 9181.5 9192.1 9641.4 11403.0 11885.2 12006.0 12151.3 02:59:12 INFO - PID 1637 | 02:59:12 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:7302.11 Median:2571.61 stddev:4624.77 (179.8%) stddev-sans-first:4615.25 02:59:12 INFO - PID 1637 | Values: 1232.9 1432.7 1435.4 1450.8 1476.2 11633.4 11975.7 12142.0 12148.4 12208.8 12017.9 12169.6 12191.9 12214.9 12221.8 2530.9 2553.7 2555.7 2570.7 2572.5 1881.6 2481.5 2512.9 2529.8 2607.8 2479.0 2491.6 2527.2 2528.6 2530.6 2072.8 2179.6 2469.3 2476.5 2511.2 2432.9 2515.5 2530.5 2533.9 2542.1 11878.1 11940.0 12033.5 12039.1 12067.4 5755.6 6480.9 6783.7 6830.4 6841.0 3997.7 4231.5 4311.2 4315.0 4333.3 1214.9 1219.0 1234.0 1267.3 1268.3 15085.2 15603.9 16658.3 16791.2 16800.3 5511.6 5736.2 5926.5 5948.3 5958.5 12403.9 12439.9 12502.8 12531.4 12562.5 5417.5 6666.6 6682.2 6688.0 6695.5 6074.0 6090.3 6144.4 6206.5 6210.5 4397.7 4453.8 4495.3 4503.6 4507.4 1058.8 1316.2 1320.9 1338.8 1339.5 777.7 801.0 802.4 804.5 811.5 5601.2 6507.3 6722.2 6753.6 7180.2 864.7 954.7 964.9 971.6 992.0 4633.4 4638.2 4804.4 4840.8 4872.9 14857.5 14914.4 15008.3 15417.2 15484.8 14996.2 15297.5 15344.8 15480.4 15520.5 10749.6 11018.3 11293.2 11295.0 11317.5 7057.9 7064.9 7318.2 7330.8 7421.1 11368.9 13887.0 13965.0 13974.9 14174.1 13840.2 14049.9 14052.2 14070.1 14166.7 9650.5 9683.4 9785.0 9869.5 9883.4 8901.4 9755.5 10813.9 11008.0 11024.9 8299.9 9305.7 9312.3 9347.0 9456.0 9154.2 9263.8 9271.2 9336.8 9420.3 8220.9 8291.7 8392.2 8405.0 8418.0 5424.8 6075.7 6145.5 6151.0 6281.5 5098.6 5716.6 5953.8 6232.8 6312.4 02:59:12 INFO - PID 1637 | 02:59:12 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5245.08 Median:5935.98 stddev:1598.60 (26.9%) stddev-sans-first:1561.25 02:59:12 INFO - PID 1637 | Values: 404.5 406.8 408.2 411.3 412.3 5382.9 5962.0 6159.8 6187.3 6195.3 5200.1 5590.8 5869.2 5903.6 5921.7 4502.3 4527.3 4576.0 4577.7 4580.8 4560.0 4562.4 4582.2 4587.5 4588.7 3918.7 4011.5 4239.7 4490.3 4536.8 4140.8 4312.0 4485.0 4492.8 4494.5 3474.9 4035.1 4062.0 4085.5 4087.0 5207.7 5251.9 5381.9 5543.2 5775.8 5380.6 5417.9 5444.3 5448.6 5461.2 6162.0 6430.8 6433.9 6460.2 6481.1 4050.8 4159.4 4609.2 4611.2 4622.4 6251.5 6365.6 6397.6 6652.7 6778.9 3719.3 4145.3 4572.2 4765.0 4771.0 4852.9 4857.2 4859.6 4863.6 4867.3 5332.3 5402.9 5433.7 5441.5 5503.9 5389.4 6441.7 6453.2 6466.6 6474.8 1627.3 1651.2 1651.6 1661.3 1663.1 975.6 1128.7 1143.2 1151.7 1155.5 6057.2 6067.6 6076.8 6081.7 6082.6 6032.1 6070.4 6415.5 6657.6 6667.8 5353.9 6045.2 6055.3 6089.9 6113.8 2810.8 3453.6 3463.9 3469.7 3497.6 5695.3 6453.2 6676.6 6790.9 6929.3 5571.9 6580.3 6890.5 6920.0 6948.2 5959.3 6152.3 6779.0 6799.2 6832.0 6659.3 6670.9 6682.5 6682.7 6684.8 6288.7 6316.2 6602.0 6626.9 6644.8 5539.9 6226.2 6450.0 6510.2 6666.8 3398.6 3651.8 4372.4 4382.2 4385.1 6342.2 6641.3 6657.5 6667.6 6674.7 5623.0 6435.5 6448.9 6464.9 6529.6 5950.3 6455.6 6490.0 6506.4 6511.0 6297.5 6362.5 6371.0 6372.3 6376.6 6460.4 6486.2 6515.4 6520.5 6549.9 6137.4 6250.9 6258.9 6309.8 6333.5 02:59:12 INFO - PID 1637 | 02:59:12 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:20548.91 Median:21466.07 stddev:27418.58 (127.7%) stddev-sans-first:27455.45 02:59:12 INFO - PID 1637 | Values: 813.8 837.4 852.4 862.4 864.4 14419.1 15165.8 16643.5 16898.7 17068.7 16553.2 17469.3 17587.2 17646.0 17692.5 948.8 967.8 972.4 973.4 974.1 1003.4 1005.9 1006.7 1008.8 1008.9 1065.0 1067.4 1069.3 1071.0 1072.0 891.4 1055.0 1062.2 1063.6 1076.0 787.6 787.9 789.1 789.7 794.3 16358.0 16732.1 16935.0 16950.1 17000.1 15114.6 16251.3 16278.8 16844.8 17006.4 13450.7 13800.4 13981.0 13988.8 14010.7 3427.8 3459.6 3469.9 3495.0 3560.2 85755.0 111081.9 111614.9 112825.1 113329.0 11226.3 12913.7 13362.6 13366.0 13372.5 24436.9 24529.0 25143.4 25153.9 25446.5 8934.4 9239.6 9240.8 9241.9 9256.3 4751.5 4763.1 4819.7 4903.0 4945.8 4227.8 4299.9 4316.1 4353.0 4355.5 733.7 929.9 938.2 940.0 942.0 638.8 657.1 657.4 666.0 674.9 9719.7 11076.4 11328.0 11474.9 11812.9 647.4 651.1 658.2 659.7 675.1 4339.5 5080.6 5253.9 5434.6 5519.5 77128.0 91518.5 93704.0 94541.1 95925.0 83374.0 84237.2 84318.2 85477.3 87367.4 76498.6 77856.6 77958.6 78341.0 78560.0 9640.4 11219.8 11515.5 11751.8 12109.6 37182.1 37482.6 37700.2 37940.8 38065.2 49568.5 50605.0 50761.0 66806.0 69646.0 15038.4 15314.5 16398.8 16552.5 16777.9 18175.8 18205.6 18209.4 18439.1 18495.3 16730.4 16788.6 16915.0 16936.8 16967.5 16078.9 16262.8 16798.8 16830.8 16924.2 16094.7 16448.8 16496.3 16606.3 16686.0 11705.4 11938.1 11945.3 12029.3 12071.1 12672.0 12779.1 12783.4 12799.4 12992.0 02:59:12 INFO - PID 1637 | -------- Summary: end -------- 02:59:12 INFO - PID 1637 | 02:59:12 INFO - TEST-INFO | 1637: exit 0 02:59:12 INFO - TEST-OK | dromaeo_css | took 1114837ms 02:59:12 INFO - TEST-START | kraken 02:59:12 INFO - operating with platform_type : mac_ 02:59:12 INFO - Initialising browser for kraken test... 02:59:12 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpmzlpRq/profile http://localhost:49242/getInfo.html) 02:59:16 INFO - PID 1649 | 2017-04-11 02:59:16.014 plugin-container[1650:10213] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 02:59:16 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:59:16 INFO - PID 1649 | 2017-04-11 02:59:16.016 plugin-container[1650:10213] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 02:59:16 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:59:16 INFO - PID 1649 | 2017-04-11 02:59:16.016 plugin-container[1650:10213] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:59:16 INFO - PID 1649 | __metrics Screen width/height:1600/1200 02:59:16 INFO - PID 1649 | colorDepth:24 02:59:16 INFO - PID 1649 | Browser inner width/height: 1024/689 02:59:16 INFO - PID 1649 | __metrics 02:59:16 ERROR - PID 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 02:59:17 INFO - TEST-INFO | 1649: exit 0 02:59:17 INFO - Browser initialized. 02:59:17 INFO - Running cycle 1/1 for kraken test... 02:59:17 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpmzlpRq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:59:19 INFO - PID 1651 | 2017-04-11 02:59:19.797 plugin-container[1652:10343] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 02:59:19 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:59:19 INFO - PID 1651 | 2017-04-11 02:59:19.797 plugin-container[1652:10343] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 02:59:19 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:59:19 INFO - PID 1651 | 2017-04-11 02:59:19.797 plugin-container[1652:10343] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:59:29 INFO - PID 1651 | RSS: Main: 234823680 02:59:29 INFO - PID 1651 | 02:59:32 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 02:59:33 INFO - PID 1651 | RSS: Main: 250191872 02:59:33 INFO - PID 1651 | 02:59:35 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 02:59:36 INFO - PID 1651 | RSS: Main: 258818048 02:59:36 INFO - PID 1651 | 02:59:40 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 02:59:40 INFO - PID 1651 | RSS: Main: 261083136 02:59:40 INFO - PID 1651 | 02:59:42 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 02:59:43 INFO - PID 1651 | RSS: Main: 263118848 02:59:43 INFO - PID 1651 | 02:59:45 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:59:46 INFO - PID 1651 | RSS: Main: 263741440 02:59:46 INFO - PID 1651 | 02:59:49 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 02:59:50 INFO - PID 1651 | RSS: Main: 266457088 02:59:50 INFO - PID 1651 | 02:59:53 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 02:59:54 INFO - PID 1651 | RSS: Main: 268591104 02:59:54 INFO - PID 1651 | 02:59:57 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 02:59:58 INFO - PID 1651 | RSS: Main: 268300288 02:59:58 INFO - PID 1651 | 02:59:59 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:00:00 INFO - PID 1651 | RSS: Main: 268304384 03:00:00 INFO - PID 1651 | 03:00:02 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:00:02 INFO - PID 1651 | RSS: Main: 268406784 03:00:02 INFO - PID 1651 | 03:00:05 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:00:05 INFO - PID 1651 | RSS: Main: 268308480 03:00:05 INFO - PID 1651 | 03:00:08 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:00:09 INFO - PID 1651 | RSS: Main: 268640256 03:00:09 INFO - PID 1651 | 03:00:12 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:00:13 INFO - PID 1651 | RSS: Main: 268476416 03:00:13 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 03:00:15 INFO - PID 1651 | RSS: Main: 271683584 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | __start_tp_report 03:00:15 INFO - PID 1651 | _x_x_mozilla_page_load 03:00:15 INFO - PID 1651 | _x_x_mozilla_page_load_details 03:00:15 INFO - PID 1651 | |i|pagename|runs| 03:00:15 INFO - PID 1651 | |0;ai-astar;110;123;122;123;121;122;123;122;123;123 03:00:15 INFO - PID 1651 | |1;audio-beat-detection;151;113;119;154;117;116;115;132;174;117 03:00:15 INFO - PID 1651 | |2;audio-dft;224;225;273;248;215;230;226;294;293;211 03:00:15 INFO - PID 1651 | |3;audio-fft;82;59;57;84;63;61;63;77;102;64 03:00:15 INFO - PID 1651 | |4;audio-oscillator;86;78;124;113;111;58;117;110;108;74 03:00:15 INFO - PID 1651 | |5;imaging-gaussian-blur;91;105;140;104;141;129;142;141;102;145 03:00:15 INFO - PID 1651 | |6;imaging-darkroom;113;150;103;105;101;103;101;101;100;101 03:00:15 INFO - PID 1651 | |7;imaging-desaturate;104;100;99;162;108;99;103;104;103;104 03:00:15 INFO - PID 1651 | |8;json-parse-financial;52;53;51;52;54;54;52;53;51;54 03:00:15 INFO - PID 1651 | |9;json-stringify-tinderbox;59;63;76;103;98;61;60;61;60;62 03:00:15 INFO - PID 1651 | |10;stanford-crypto-aes;91;92;91;89;86;90;90;90;89;88 03:00:15 INFO - PID 1651 | |11;stanford-crypto-ccm;182;171;167;170;166;172;151;167;222;177 03:00:15 INFO - PID 1651 | |12;stanford-crypto-pbkdf2;200;198;196;196;198;195;204;196;198;198 03:00:15 INFO - PID 1651 | |13;stanford-crypto-sha256-iterative;72;71;71;75;87;116;79;72;72;73 03:00:15 INFO - PID 1651 | __end_tp_report 03:00:15 INFO - PID 1651 | __start_cc_report 03:00:15 INFO - PID 1651 | _x_x_mozilla_cycle_collect,1003 03:00:15 INFO - PID 1651 | __end_cc_report 03:00:15 INFO - PID 1651 | __startTimestamp1491904815577__endTimestamp 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | ------- Summary: start ------- 03:00:15 INFO - PID 1651 | Number of tests: 14 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#0] ai-astar Cycles:10 Average:121.20 Median:123.00 stddev:3.99 (3.2%) stddev-sans-first:0.73 03:00:15 INFO - PID 1651 | Values: 110.0 123.0 122.0 123.0 121.0 122.0 123.0 122.0 123.0 123.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#1] audio-beat-detection Cycles:10 Average:130.80 Median:125.50 stddev:21.39 (17.0%) stddev-sans-first:21.41 03:00:15 INFO - PID 1651 | Values: 151.0 113.0 119.0 154.0 117.0 116.0 115.0 132.0 174.0 117.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#2] audio-dft Cycles:10 Average:243.90 Median:239.00 stddev:31.57 (13.2%) stddev-sans-first:32.65 03:00:15 INFO - PID 1651 | Values: 224.0 225.0 273.0 248.0 215.0 230.0 226.0 294.0 293.0 211.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#3] audio-fft Cycles:10 Average:71.20 Median:63.50 stddev:14.54 (22.9%) stddev-sans-first:14.89 03:00:15 INFO - PID 1651 | Values: 82.0 59.0 57.0 84.0 63.0 61.0 63.0 77.0 102.0 64.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:91.00 stddev:22.10 (24.3%) stddev-sans-first:23.02 03:00:15 INFO - PID 1651 | Values: 86.0 78.0 124.0 113.0 111.0 58.0 117.0 110.0 108.0 74.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:124.00 Median:141.00 stddev:20.97 (14.9%) stddev-sans-first:18.53 03:00:15 INFO - PID 1651 | Values: 91.0 105.0 140.0 104.0 141.0 129.0 142.0 141.0 102.0 145.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#6] imaging-darkroom Cycles:10 Average:107.80 Median:103.00 stddev:15.30 (14.9%) stddev-sans-first:16.12 03:00:15 INFO - PID 1651 | Values: 113.0 150.0 103.0 105.0 101.0 103.0 101.0 101.0 100.0 101.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#7] imaging-desaturate Cycles:10 Average:108.60 Median:106.00 stddev:18.96 (17.9%) stddev-sans-first:20.04 03:00:15 INFO - PID 1651 | Values: 104.0 100.0 99.0 162.0 108.0 99.0 103.0 104.0 103.0 104.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:53.00 stddev:1.17 (2.2%) stddev-sans-first:1.22 03:00:15 INFO - PID 1651 | Values: 52.0 53.0 51.0 52.0 54.0 54.0 52.0 53.0 51.0 54.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:61.50 stddev:16.68 (27.1%) stddev-sans-first:17.18 03:00:15 INFO - PID 1651 | Values: 59.0 63.0 76.0 103.0 98.0 61.0 60.0 61.0 60.0 62.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:90.00 stddev:1.71 (1.9%) stddev-sans-first:1.74 03:00:15 INFO - PID 1651 | Values: 91.0 92.0 91.0 89.0 86.0 90.0 90.0 90.0 89.0 88.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:174.50 Median:171.50 stddev:18.54 (10.8%) stddev-sans-first:19.47 03:00:15 INFO - PID 1651 | Values: 182.0 171.0 167.0 170.0 166.0 172.0 151.0 167.0 222.0 177.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.90 Median:198.00 stddev:2.60 (1.3%) stddev-sans-first:2.65 03:00:15 INFO - PID 1651 | Values: 200.0 198.0 196.0 196.0 198.0 195.0 204.0 196.0 198.0 198.0 03:00:15 INFO - PID 1651 | 03:00:15 INFO - PID 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.80 Median:72.50 stddev:13.98 (19.3%) stddev-sans-first:14.61 03:00:15 INFO - PID 1651 | Values: 72.0 71.0 71.0 75.0 87.0 116.0 79.0 72.0 72.0 73.0 03:00:15 INFO - PID 1651 | -------- Summary: end -------- 03:00:15 INFO - PID 1651 | 03:00:16 INFO - TEST-INFO | 1651: exit 0 03:00:16 INFO - TEST-OK | kraken | took 63972ms 03:00:16 INFO - SUITE-END | took 1178s 03:00:17 INFO - Completed test suite (00:19:39) 03:00:17 INFO - javascript benchmark 03:00:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7946.209855405971, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3452.395830729622, 3561.323348563771, 3561.9821900890493, 3597.6222496637843, 3598.964010359896, 6202.782902598416, 6215.782447614334, 6238.625682459045, 6243.250809902806, 6254.812355629333, 4914.950850491494, 6092.8701720992985, 6113.724882380292, 6193.792210518951, 6197.19436473259, 4224.450821393222, 4942.752862356886, 5433.538149257318, 5523.337199536041, 5563.582731295149, 5567.192757050226, 5574.470425309589, 5574.888502229953, 5577.358603760579, 5579.163125531182, 682.5066055808119, 4860.975695121537, 5092.701361152919, 5426.430224826416, 5428.45715428456, 4944.678595891256, 5478.342598888119, 5488.807891723811, 5507.0, 5542.501174894279, 5463.562914966793, 5474.233607294982, 5477.04151773438, 5484.122540393558, 5504.779808807642, 4892.315075889378, 5975.894459524959, 6157.507399408037, 6160.599561028564, 6182.1345011698395, 5655.519280861091, 6699.531032827704, 6712.060311556386, 6753.1220941277315, 6765.917453207512, 5823.621464604787, 6815.965920170367, 6841.657917104175, 6866.0, 6896.310368963113, 6229.0967809668255, 6268.592541484789, 6352.523560732917, 6353.459955903708, 6374.426301632782, 6774.695138718769, 6827.69275382609, 6847.281035491302, 6852.794416167523, 6875.312468753084, 6528.0, 6548.443382312556, 6590.868182636416, 6614.669266536654, 6632.83417914556, 5057.519535644108, 6653.700583473756, 6659.334066593302, 6721.932780672229, 6746.966265168741, 6678.565893216925, 6723.899141512881, 6732.629705366154, 6754.898676519856, 6761.695723692445, 6634.170728658918, 6734.764283250062, 6751.831204219836, 6767.627780472121, 6774.559653622498, 5242.318498595235, 5338.519533242027, 5398.433164517748, 5405.189221616711, 5415.945840541623, 4757.643176761757, 4792.161371759928, 4823.879403014953, 4847.321375007502, 4899.0, 5148.897022059537, 6132.202813634199, 6357.809265722036, 6364.64994425311, 6384.3934826191435, 6769.627670478072, 6780.898286525706, 6858.622775747282, 6863.588184708933, 6880.827979300558, 6166.229221347332, 6273.560850740404, 6313.2424109107205, 6336.683165841781, 6357.0, 6038.788642397495, 6062.211912451353, 6076.8784624308155, 6089.908651370233, 6103.542234332443, 6866.416354609814, 6936.306369363023, 6952.617606031717, 6955.582665040114, 6960.408365289007, 5545.611807173459, 6547.214334279917, 6619.073329733745, 6768.898466522907, 6803.353681400259, 6903.206131294764, 6906.067680863019, 6918.446524277945, 6924.446044316342, 6925.89611155823, 5092.879566495365, 6077.6353418795015, 6754.358335958076, 6772.424343930821, 6785.558938668971, 6549.7380104795275, 6666.0, 6756.628385438848, 6776.830579235559, 6813.693383797642, 5243.973780131075, 6600.933990660032, 6614.66926653675, 6632.701528431136, 6653.201615806134, 6385.13800636908, 6603.141591593254, 6627.701753421011, 6633.402993730394, 6641.468682505485, 6137.693115344304, 6253.96873015632, 6449.193850768654, 6484.967575162283, 6494.058361537645, 6575.769848055486, 6613.66931653425, 6619.801405957829, 6622.106015688013, 6657.2677005528385, 6374.617522948638, 6429.38920802523, 6436.292007879223, 6443.130177425987, 6457.61254324742], "value": 6042.381081689904, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.9841016217946, 799.2207597592347, 826.5495305058743, 1286.9549565765194, 1291.0639786155045, 38375.80812095939, 38914.610853891456, 38962.61037389632, 39040.804795976015, 39181.824545263655, 38353.61646383535, 38447.0, 38632.68203907768, 38676.0, 38715.22569548607, 7986.480878742877, 8148.470349427298, 8299.170082991714, 8539.615717292705, 8542.530160841152, 8420.831583368328, 8443.0, 8556.272716819078, 8560.571971401436, 8564.528950907697, 8452.830943381128, 8496.702615408461, 8508.659653613848, 8578.87131693022, 8582.270507006911, 8441.704540341088, 8510.361722870779, 8547.059823419419, 8547.572621368907, 8557.614907329154, 8345.123762005023, 8398.370122240856, 8419.368547358912, 8426.747197584082, 8427.578621068984, 37434.0, 37568.81215593904, 37580.68467603621, 37734.81132594347, 37794.43308350377, 3029.3184033592484, 3148.819192802699, 3551.94672079919, 3592.910177245564, 3595.7303202259673, 25944.221673349828, 29008.0, 30652.233694157825, 30726.539101913488, 30748.080307150747, 2637.8021648376257, 3170.1282147409415, 3179.8410079496166, 3182.4112539180096, 3187.4581321175224, 232677.0, 238613.0, 267686.0, 288602.67095993576, 289561.0, 47610.80972975634, 47823.80440489016, 47916.76041619769, 47937.760311198916, 47954.28068578974, 49168.754156229705, 49656.25515617269, 49797.75101124471, 50018.0, 50033.499665002884, 12114.81827772584, 14223.85776142246, 14239.644008899653, 14365.497207597684, 14486.0, 11769.999550038172, 11919.165658404001, 11934.283942963277, 11955.222910510789, 12012.87987120135, 13247.337633118306, 13337.66655833612, 13386.995975301697, 13436.328183590782, 13467.259300738357, 5493.368262649767, 5686.800961966312, 6008.789692360834, 6044.274687037584, 6075.513958883367, 1015.7562185075528, 1028.8919663435381, 1029.7322696099068, 1063.4948399510326, 1069.4011353641986, 18862.71705924412, 23279.0, 24162.671053091897, 24424.26727198187, 24573.262802115965, 1123.719070232442, 1125.3360517294836, 1163.2787671643634, 1165.0737663557493, 1167.6613782003294, 11971.580994665144, 12498.50005999768, 12540.623781286577, 12555.435005424777, 12598.748025039447, 373735.0, 376506.0, 377251.2511811877, 377280.0, 377630.0, 51714.70713232199, 52053.0, 52087.0, 52098.47901521012, 52138.0, 1500.0299407173857, 1507.6306304955137, 1550.627849316156, 1550.8914375993572, 1564.6010267381744, 23870.283891483283, 23989.400264993514, 24042.918068686602, 24090.27729168128, 24191.63712544278, 37403.81298093601, 37415.25169496649, 37515.62484375121, 37591.0, 37630.247395052495, 324675.0, 324868.0, 325629.0, 326818.0, 327689.0, 34022.31955360829, 34343.31313373768, 34511.30977380388, 35511.0, 35556.0, 45698.0, 45997.400259973736, 46248.075038499715, 46363.0, 46473.53526464692, 30058.0, 30220.093397198118, 31468.2132946669, 31987.680123198472, 32766.83616581902, 31324.561055843387, 31749.36501270008, 31957.840210798797, 32081.197970050936, 32108.0, 37548.0, 37612.435813462274, 37684.62315376921, 37691.81154094212, 37751.81124094362, 29919.55120673148, 30473.54289685612, 31870.52194217042, 31911.84044079765, 32732.690692372038, 21446.571068578854, 23510.942007609356, 24081.51836963286, 24573.877130614233, 25102.12142575074], "value": 17684.115644441586, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.95770422957725, 444.63095630626583, 446.1634435433561, 455.4602795687111, 455.83817744700633, 20078.89960550197, 21668.34994950152, 22407.887960560216, 22583.209587664434, 22604.88697556514, 22334.21830235942, 22942.0, 23631.409214769596, 23683.407914802094, 23759.287221383387, 5451.209574611679, 6233.158523599302, 6408.711607977641, 6607.108040414555, 6624.039514270428, 6368.840778980516, 6422.100905873158, 6508.088867558546, 6510.6744662766905, 6522.706478208492, 6208.099825525319, 6373.968130159343, 6406.167198264243, 6407.231132264134, 6459.515536334794, 6252.843678908018, 6343.4925205983645, 6380.872382552369, 6418.775342863024, 6436.485081193517, 5668.943310566883, 5687.061634830289, 5728.0, 5729.169270455761, 5729.598928075036, 27616.309592260353, 27840.886364545393, 27883.302917427023, 27972.300692482648, 28159.15522534327, 2239.507308392151, 2266.875321857297, 2290.4502919299416, 2291.346966114649, 2291.5531471363092, 15032.473863414838, 15534.689306213813, 17263.30946762128, 18399.632007359913, 18592.44222673322, 2200.2959053103014, 2208.4810069633622, 2235.083615717548, 2247.606668832948, 2251.234580242709, 146673.0, 148015.0, 148485.7727134094, 149268.0, 149938.7509187363, 12354.38228088592, 12864.099513033998, 12886.484540618458, 12886.742265154644, 12952.611421657177, 16988.0, 17060.744088838677, 17078.146092695562, 17078.23147958345, 17086.48740537786, 9065.320100992452, 9107.453552786854, 9300.627974881065, 9472.194863436685, 9474.810503790024, 7113.786586402416, 8941.821163576822, 8948.149925757043, 8949.821003579891, 9108.81782364349, 7989.760307190793, 8088.312493438124, 8120.918790812135, 8138.593070346459, 8144.5520496373265, 1943.6112777444569, 1965.3907288740536, 1975.9110840012233, 2220.5669894370767, 2403.315055209257, 803.9919600804034, 876.8775966762554, 995.4326034160604, 1004.7387679203459, 1035.9430231337199, 20439.0, 21197.89401052985, 21227.68158477624, 21278.829664368608, 21305.89347053255, 929.809389075238, 1015.6902144845893, 1016.2682868334787, 1046.4140081554353, 1050.4800123938699, 11037.39294338803, 11251.0, 11482.02402795755, 11501.654950351503, 11557.24877882935, 713627.0, 721337.0, 728876.0, 731949.0, 740771.0, 130786.69213307745, 154109.0, 158876.0, 161407.0, 166657.0, 1189.1141099880472, 1194.1103877611063, 1248.039009962324, 1249.6438515023174, 1250.1686378558338, 20641.277555285495, 21613.0, 23100.0, 23270.72011039409, 23804.0, 65739.0, 66431.66784166048, 67007.65984680182, 67135.0, 67538.96074676553, 58721.70639146777, 93217.60173597265, 94102.52948735212, 95778.56332155163, 96802.0, 8797.560121994004, 8854.734357969272, 8881.644734210558, 9142.0, 9235.445873247627, 14524.055936364102, 14585.0, 14682.0, 14838.0, 14875.776863347275, 11878.762424751283, 12135.635930922086, 12237.816432753338, 12295.139340246455, 12366.381680916098, 8809.867851982353, 11466.598669046543, 11521.596744113916, 11663.708407289885, 11770.94114529456, 9644.662436814679, 11985.0, 12260.141790074966, 12288.447019884305, 12324.445399957203, 9008.684696035869, 9065.864012039956, 9158.939238671246, 9181.540922953693, 9192.080791920755, 9641.42151470914, 11403.0, 11885.196737044063, 12006.0, 12151.270923744603], "value": 10872.197565070672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1232.8952039076678, 1432.6704857882687, 1435.3684378873295, 1450.8113945187124, 1476.2028504607513, 11633.36016519091, 11975.700607484818, 12142.0, 12148.392580370979, 12208.755824883498, 12017.879821201785, 12169.57406490773, 12191.878081219207, 12214.877851221485, 12221.816672749894, 2530.936726581832, 2553.668023156987, 2555.718870924197, 2570.7300733423003, 2572.4855028994184, 1881.6236752649456, 2481.5036992601463, 2512.8617926014063, 2529.8229123961332, 2607.8174527783062, 2478.950420991588, 2491.638712386703, 2527.2418274517663, 2528.620706893962, 2530.6204069389646, 2072.77199508054, 2179.553191595719, 2469.2839076667833, 2476.5418397596504, 2511.234073607549, 2432.9391765205833, 2515.5220508103585, 2530.519201351756, 2533.8606376649277, 2542.0594380079488, 11878.109141814311, 11940.044796416265, 12033.51865925362, 12039.09706771987, 12067.396630168456, 5755.625884317506, 6480.930646443331, 6783.660816959182, 6830.419414349736, 6841.0, 3997.6801855851463, 4231.534531201565, 4311.159323931804, 4314.956850431487, 4333.263345231318, 1214.884585964332, 1218.9634310970596, 1233.9753204935942, 1267.2713189915835, 1268.3277862732766, 15085.170315632637, 15603.92198039014, 16658.250378732988, 16791.244394002177, 16800.32798688051, 5511.641743286674, 5736.196932429463, 5926.496247818897, 5948.256467941507, 5958.493528050078, 12403.875961240272, 12439.937800310941, 12502.81245781314, 12531.43608537618, 12562.497500099893, 5417.485338892799, 6666.566673166229, 6682.198136223684, 6688.0, 6695.46436285096, 6073.96963015191, 6090.269167699905, 6144.354842741448, 6206.534509911775, 6210.534209934273, 4397.714148580332, 4453.799579018952, 4495.348174514743, 4503.594676479132, 4507.3689683444345, 1058.8200005999, 1316.1510825517487, 1320.8679132086907, 1338.8326459192601, 1339.5378594384922, 777.6928113395177, 800.9787520910885, 802.3701394405364, 804.5373910001772, 811.5130921447085, 5601.187827764951, 6507.349265073455, 6722.159730033746, 6753.594784312957, 7180.246074162138, 864.7103220421103, 954.6944977607238, 964.9228061755183, 971.5579411367811, 991.9603215871284, 4633.4208223972, 4638.165130276582, 4804.399450068741, 4840.830570930071, 4872.853814385574, 14857.47998820036, 14914.403423863141, 15008.324625391884, 15417.152056636993, 15484.845151548341, 14996.175210363535, 15297.464588739564, 15344.769828452581, 15480.380784768708, 15520.456784012506, 10749.570017199225, 11018.283811552223, 11293.15301352402, 11295.0, 11317.490712917774, 7057.858842823218, 7064.858702825812, 7318.1949985502615, 7330.780076597711, 7421.146568144733, 11368.94315528417, 13886.95847811398, 13965.0, 13974.860251397356, 14174.14955102697, 13840.23878686682, 14049.929750351183, 14052.227127507676, 14070.085444446078, 14166.716665666423, 9650.469224192457, 9683.370580912218, 9784.951075244579, 9869.457179855177, 9883.406995580288, 8901.421407608485, 9755.512224388895, 10813.945930270298, 11008.0, 11024.944875275572, 8299.875501867356, 9305.674301399418, 9312.301577381588, 9347.0, 9455.952720236355, 9154.176124148858, 9263.823001594485, 9271.211946984582, 9336.813263734823, 9420.293477989042, 8220.876686849582, 8291.668333266602, 8392.160783921803, 8405.033421156646, 8418.0, 5424.810131645373, 6075.665838378932, 6145.477634401126, 6151.046587778723, 6281.466075383643, 5098.566621837185, 5716.599838011466, 5953.821385358447, 6232.844178895564, 6312.400321969406], "value": 5493.227466384229, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.5125623623534, 406.8392984771016, 408.1591920643474, 411.3356928560374, 412.30732369619045, 5382.89234215316, 5962.04607262838, 6159.753609855612, 6187.288461826891, 6195.287541932679, 5200.13343888947, 5590.776368945248, 5869.207656966309, 5903.557233207505, 5921.674307913063, 4502.2571275739465, 4527.343535187396, 4576.0, 4577.679562430631, 4580.839670611529, 4560.042391097873, 4562.429696287964, 4582.243929751604, 4587.490569714171, 4588.719065867904, 3918.74528155669, 4011.4785077939973, 4239.724417912841, 4490.326451032339, 4536.818527258905, 4140.840564641905, 4312.0, 4484.97757511212, 4492.820287188509, 4494.505604383515, 3474.947875781865, 4035.1122752994293, 4061.97969010156, 4085.5097388313297, 4086.978255436141, 5207.713575753332, 5251.949610077962, 5381.919271210935, 5543.223948647193, 5775.826725198251, 5380.596455265871, 5417.945820541823, 5444.265024221759, 5448.591355648303, 5461.15352120422, 6161.98327275999, 6430.839229019265, 6433.935660643428, 6460.224773027267, 6481.0602462642655, 4050.764516822368, 4159.417681524589, 4609.239475486541, 4611.21609326412, 4622.352870598119, 6251.531135164881, 6365.649889256089, 6397.5521713480075, 6652.7338906444165, 6778.932210677879, 3719.330520506335, 4145.253854306254, 4572.177008138567, 4765.046990601893, 4770.95229047712, 4852.878678033077, 4857.247126695368, 4859.59071869161, 4863.610911127102, 4867.269909513601, 5332.2801421808335, 5402.945970540323, 5433.701146436905, 5441.455854414527, 5503.889922201533, 5389.380221274603, 6441.742330306735, 6453.225612926479, 6466.612003279818, 6474.773382931575, 1627.3002608878296, 1651.2404294024643, 1651.5953591370192, 1661.3271624991899, 1663.1351697117432, 975.6048800235916, 1128.7178205448638, 1143.1597775634903, 1151.654503648902, 1155.5320095361392, 6057.18228039209, 6067.575269731076, 6076.848078798065, 6081.665508396992, 6082.635041897501, 6032.095185722089, 6070.423309785563, 6415.518836087312, 6657.633830139292, 6667.799966001027, 5353.946460535422, 6045.153678485016, 6055.303640081359, 6089.878202435926, 6113.816585502442, 2810.775137988956, 3453.568303962005, 3463.9088687063495, 3469.6988629264024, 3497.615262321143, 5695.316562012502, 6453.225612926479, 6676.6327851967635, 6790.879504881738, 6929.2928283369165, 5571.916421253684, 6580.269188286808, 6890.517663763489, 6920.0, 6948.235694073648, 5959.284885813643, 6152.323244443108, 6778.966105169443, 6799.1840979081835, 6831.9658401707675, 6659.334066593496, 6670.9332906670315, 6682.46540276767, 6682.69927853238, 6684.766033189009, 6288.748450061947, 6316.210473690789, 6602.0, 6626.867462650624, 6644.83387915306, 5539.9169012464035, 6226.159468471698, 6450.0002499610755, 6510.18622672166, 6666.799996000128, 3398.5581874356176, 3651.8356673950266, 4372.387865698868, 4382.189294980439, 4385.1010542838, 6342.207224096988, 6641.335866413513, 6657.500687448558, 6667.633280169637, 6674.666266686743, 5622.971885140548, 6435.4529864962005, 6448.935510644834, 6464.935350646433, 6529.608223506605, 5950.285965684146, 6455.580387274812, 6490.0, 6506.41442270198, 6510.967445162744, 6297.496200303873, 6362.459190968634, 6370.968145159244, 6372.299047104908, 6376.585521941059, 6460.35396460369, 6486.183868516823, 6515.381038801306, 6520.510961677798, 6549.8690026200165, 6137.447629713169, 6250.906236406367, 6258.906116408349, 6309.801137783807, 6333.493320534439], "value": 4759.226691596797, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.7884150120967, 837.4012581004577, 852.4416507187792, 862.4437237981502, 864.3906046237402, 14419.134851908893, 15165.848341516608, 16643.50069497914, 16898.662026759474, 17068.658626827473, 16553.172341382942, 17469.3012279509, 17587.208575614095, 17646.0, 17692.523046773462, 948.7865230323191, 967.8306296398138, 972.4019727867346, 973.4013581647307, 974.1281553010052, 1003.3979612232639, 1005.8792944846629, 1006.6527048168371, 1008.7780688248573, 1008.9091981721643, 1065.0, 1067.4022547373495, 1069.310294859815, 1071.0467683761458, 1072.0351683484848, 891.4339394484484, 1054.9736256593646, 1062.1555863088863, 1063.5798859450513, 1075.983860242097, 787.582581231948, 787.9369650427952, 789.1122487201898, 789.6801795272867, 794.265304593249, 16358.0, 16732.079735614538, 16935.0, 16950.06774627395, 17000.064996425193, 15114.622134446618, 16251.349946002145, 16278.75581866273, 16844.83155168445, 17006.404775832787, 13450.730985380336, 13800.378982945791, 13981.02132850701, 13988.790168147385, 14010.719785604231, 3427.7943323400677, 3459.60214575322, 3469.930601387983, 3494.9554510611324, 3560.2345495718546, 85755.0, 111081.88918110715, 111614.88385116126, 112825.05851302276, 113329.0, 11226.270292430965, 12913.677158071123, 13362.599122026355, 13366.0, 13372.531961381304, 24436.87781561081, 24529.0, 25143.371415714755, 25153.87423062873, 25446.491070178494, 8934.4192627479, 9239.630414783467, 9240.815183696288, 9241.861372079424, 9256.305777066747, 4751.52484751529, 4763.118823017753, 4819.6867203631655, 4902.950970490249, 4945.802167913315, 4227.788610569459, 4299.935500967488, 4316.093620339764, 4352.956470435255, 4355.499117601516, 733.7321877514669, 929.9488528131018, 938.1931538876561, 939.9953000235092, 941.9858702119474, 638.7955854126727, 657.1260223902199, 657.3919124809532, 665.9667016649148, 674.8515326628134, 9719.65981190655, 11076.446177691083, 11328.037116844996, 11474.885251147549, 11812.940935295268, 647.3591144766647, 651.1469974333638, 658.1575583253443, 659.6998365743575, 675.1425689374466, 4339.457567804025, 5080.593552515864, 5253.894922101536, 5434.565234781208, 5519.530839878656, 77128.0, 91518.54240728753, 93704.0, 94541.05458945323, 95925.0, 83374.0, 84237.15762842294, 84318.22948396622, 85477.29045419057, 87367.44744144214, 76498.61750691323, 77856.61071694718, 77958.61020694861, 78341.0, 78560.0, 9640.373375730554, 11219.775604488028, 11515.481803318704, 11751.76496470083, 12109.576164834189, 37182.070448239014, 37482.625173749, 37700.24599508049, 37940.810295948344, 38065.23869522649, 49568.51294461172, 50605.0, 50761.0, 66805.9853715353, 69646.0, 15038.398464061314, 15314.468553145041, 16398.83601163973, 16552.503424897273, 16777.91611041937, 18175.81857179279, 18205.635887281915, 18209.362672306404, 18439.07804609791, 18495.260189592267, 16730.414435495186, 16788.58028549296, 16914.985100893984, 16936.830631693527, 16967.49097527076, 16078.919605401898, 16262.837371626132, 16798.832011679726, 16830.831691682928, 16924.238409271366, 16094.67810643804, 16448.753268701217, 16496.25766840519, 16606.335746570003, 16685.998840069635, 11705.356205408785, 11938.1046421517, 11945.343006134746, 12029.278243305429, 12071.094667899766, 12672.0, 12779.1054626179, 12783.424745886643, 12799.424025919043, 12992.02559808037], "value": 8288.124306521555, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1669.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 123.0, 122.0, 123.0, 121.0, 122.0, 123.0, 122.0, 123.0, 123.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 113.0, 119.0, 154.0, 117.0, 116.0, 115.0, 132.0, 174.0, 117.0], "value": 130.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 225.0, 273.0, 248.0, 215.0, 230.0, 226.0, 294.0, 293.0, 211.0], "value": 243.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 57.0, 84.0, 63.0, 61.0, 63.0, 77.0, 102.0, 64.0], "value": 71.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 78.0, 124.0, 113.0, 111.0, 58.0, 117.0, 110.0, 108.0, 74.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 105.0, 140.0, 104.0, 141.0, 129.0, 142.0, 141.0, 102.0, 145.0], "value": 124.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 150.0, 103.0, 105.0, 101.0, 103.0, 101.0, 101.0, 100.0, 101.0], "value": 107.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 100.0, 99.0, 162.0, 108.0, 99.0, 103.0, 104.0, 103.0, 104.0], "value": 108.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 51.0, 52.0, 54.0, 54.0, 52.0, 53.0, 51.0, 54.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 76.0, 103.0, 98.0, 61.0, 60.0, 61.0, 60.0, 62.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 89.0, 86.0, 90.0, 90.0, 90.0, 89.0, 88.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 171.0, 167.0, 170.0, 166.0, 172.0, 151.0, 167.0, 222.0, 177.0], "value": 174.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 198.0, 196.0, 196.0, 198.0, 195.0, 204.0, 196.0, 198.0, 198.0], "value": 197.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 71.0, 75.0, 87.0, 116.0, 79.0, 72.0, 72.0, 73.0], "value": 78.8, "unit": "score"}]}]} 03:00:17 INFO - Return code: 0 03:00:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:00:17 ERROR - # TBPL SUCCESS # 03:00:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:00:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:00:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:00:17 INFO - 2017-04-11 03:00:17.405 screenresolution[1656:10731] starting screenresolution argv=screenresolution get 03:00:17 INFO - 2017-04-11 03:00:17.408 screenresolution[1656:10731] Display 0: 1600x1200x32@60 03:00:17 INFO - 2017-04-11 03:00:17.414 screenresolution[1657:10734] starting screenresolution argv=screenresolution list 03:00:17 INFO - Available Modes on Display 0 03:00:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:00:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:00:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:00:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:00:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:00:17 INFO - Intel Iris: 03:00:17 INFO - Chipset Model: Intel Iris 03:00:17 INFO - Type: GPU 03:00:17 INFO - Bus: Built-In 03:00:17 INFO - VRAM (Dynamic, Max): 1536 MB 03:00:17 INFO - Vendor: Intel (0x8086) 03:00:17 INFO - Device ID: 0x0a2e 03:00:17 INFO - Revision ID: 0x0009 03:00:17 INFO - Displays: 03:00:17 INFO - Display: 03:00:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:00:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:00:17 INFO - Display Serial Number: 1600x1200 60 03:00:17 INFO - Main Display: Yes 03:00:17 INFO - Mirror: Off 03:00:17 INFO - Online: Yes 03:00:17 INFO - Rotation: Supported 03:00:17 INFO - Return code: 0 03:00:17 INFO - Running post-action listener: _resource_record_post_action 03:00:17 INFO - [mozharness: 2017-04-11 10:00:17.650739Z] Finished run-tests step (success) 03:00:17 INFO - Running post-run listener: _resource_record_post_run 03:00:17 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 40339968; Write bytes: 522457088; Read time: 387; Write time: 1653 03:00:17 INFO - TinderboxPrint: CPU usage
54.0% 03:00:17 INFO - TinderboxPrint: I/O read bytes / time
40,339,968 / 387 03:00:17 INFO - TinderboxPrint: I/O write bytes / time
522,457,088 / 1,653 03:00:17 INFO - TinderboxPrint: CPU idle
2,204.6 (46.0%) 03:00:17 INFO - TinderboxPrint: CPU system
962.1 (20.1%) 03:00:17 INFO - TinderboxPrint: CPU user
1,631.1 (34.0%) 03:00:17 INFO - TinderboxPrint: Swap in / out
248,246,272 / 0 03:00:17 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207346176; Write bytes: 204261376; Read time: 16067; Write time: 392 03:00:17 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35527168; Write bytes: 310966272; Read time: 352; Write time: 1232 03:00:18 INFO - Running post-run listener: _upload_blobber_files 03:00:18 INFO - Blob upload gear active. 03:00:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:00:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:00:18 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'] 03:00:18 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 03:00:18 INFO - (blobuploader) - INFO - Open directory for files ... 03:00:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:00:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:00:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:00:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:00:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:00:19 INFO - (blobuploader) - INFO - Done attempting. 03:00:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:00:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:00:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:00:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:00:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:00:20 INFO - (blobuploader) - INFO - Done attempting. 03:00:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:00:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:00:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:00:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:00:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:00:21 INFO - (blobuploader) - INFO - Done attempting. 03:00:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:00:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:00:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:00:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:00:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:00:23 INFO - (blobuploader) - INFO - Done attempting. 03:00:23 INFO - (blobuploader) - INFO - Iteration through files over. 03:00:23 INFO - Return code: 0 03:00:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:00:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:00:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bdbe703d4069ba97474bcd1d1cc20673277dc0136c58336e9e6ec9c991342ad9236893efd986ed6a924e7b2c762e4a016da25eb50c1bd8b837b27890ad6553a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a6d97b9704c007905d76f93436323cac535855f8a575babe31a6f3fe9728e1b6beac784d6d5f8d702199e0033d6d7aefd657a997a7963f207dda52c6eecb878", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbc3ab485a93010258a1643301f50dc561da92b94c8b41209adc2b9b88002e6e1dbbc5db31199103d2c1201cbd3d2c39f463130a13323befef4eba57b84dcf17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d8aea9f483572ae6d40c10225e656bbbe992078494663a55f4b1c9a397fcdeafc9f52ea71df35cc0d870741be43ca668417b3bf26fe55d6ce745c56f1cacb49"} 03:00:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:00:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:00:23 INFO - Contents: 03:00:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bdbe703d4069ba97474bcd1d1cc20673277dc0136c58336e9e6ec9c991342ad9236893efd986ed6a924e7b2c762e4a016da25eb50c1bd8b837b27890ad6553a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a6d97b9704c007905d76f93436323cac535855f8a575babe31a6f3fe9728e1b6beac784d6d5f8d702199e0033d6d7aefd657a997a7963f207dda52c6eecb878", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbc3ab485a93010258a1643301f50dc561da92b94c8b41209adc2b9b88002e6e1dbbc5db31199103d2c1201cbd3d2c39f463130a13323befef4eba57b84dcf17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d8aea9f483572ae6d40c10225e656bbbe992078494663a55f4b1c9a397fcdeafc9f52ea71df35cc0d870741be43ca668417b3bf26fe55d6ce745c56f1cacb49"} 03:00:23 INFO - Running post-run listener: copy_logs_to_upload_dir 03:00:23 INFO - Copying logs to upload dir... 03:00:23 INFO - mkdir: /builds/slave/test/build/upload/logs 03:00:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.070551 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-11 03:00:23.622771) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 03:00:23.625943) ========= 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.Clj5OSCP0o/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.Dg0hBO6241/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bdbe703d4069ba97474bcd1d1cc20673277dc0136c58336e9e6ec9c991342ad9236893efd986ed6a924e7b2c762e4a016da25eb50c1bd8b837b27890ad6553a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a6d97b9704c007905d76f93436323cac535855f8a575babe31a6f3fe9728e1b6beac784d6d5f8d702199e0033d6d7aefd657a997a7963f207dda52c6eecb878", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbc3ab485a93010258a1643301f50dc561da92b94c8b41209adc2b9b88002e6e1dbbc5db31199103d2c1201cbd3d2c39f463130a13323befef4eba57b84dcf17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d8aea9f483572ae6d40c10225e656bbbe992078494663a55f4b1c9a397fcdeafc9f52ea71df35cc0d870741be43ca668417b3bf26fe55d6ce745c56f1cacb49"} build_url:https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011135 build_url: 'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bdbe703d4069ba97474bcd1d1cc20673277dc0136c58336e9e6ec9c991342ad9236893efd986ed6a924e7b2c762e4a016da25eb50c1bd8b837b27890ad6553a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a6d97b9704c007905d76f93436323cac535855f8a575babe31a6f3fe9728e1b6beac784d6d5f8d702199e0033d6d7aefd657a997a7963f207dda52c6eecb878", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbc3ab485a93010258a1643301f50dc561da92b94c8b41209adc2b9b88002e6e1dbbc5db31199103d2c1201cbd3d2c39f463130a13323befef4eba57b84dcf17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d8aea9f483572ae6d40c10225e656bbbe992078494663a55f4b1c9a397fcdeafc9f52ea71df35cc0d870741be43ca668417b3bf26fe55d6ce745c56f1cacb49"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 03:00:23.660922) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 03:00:23.661242) ========= 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.Clj5OSCP0o/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.Dg0hBO6241/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.005159 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 03:00:23.710435) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 03:00:23.710758) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 03:00:23.711085) ========= ========= Total master_lag: 0.29 =========