builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0101 starttime: 1492075609.39 results: success (0) buildid: 20170413004025 builduid: 5cc30a4fa32b4eb9b7a70620589e82a8 revision: 1f1c921f172cfb6f299e9ed810b748de6bede180 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:49.393955) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:49.394399) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:49.394674) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:49.421933) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:49.422350) ========= 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.VE548wLRkk/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.kmzVI7bKj2/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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-13 02:26:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.1M=0.001s 2017-04-13 02:26:49 (14.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118947 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:49.566118) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:49.566433) ========= 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.VE548wLRkk/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.kmzVI7bKj2/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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.029669 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:49.618482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 02:26:49.618834) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1f1c921f172cfb6f299e9ed810b748de6bede180 --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 1f1c921f172cfb6f299e9ed810b748de6bede180 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VE548wLRkk/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.kmzVI7bKj2/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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-13 02:26:49,737 truncating revision to first 12 chars 2017-04-13 02:26:49,737 Setting DEBUG logging. 2017-04-13 02:26:49,737 attempt 1/10 2017-04-13 02:26:49,737 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1f1c921f172c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 02:26:52,026 unpacking tar archive at: mozilla-central-1f1c921f172c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.566012 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 02:26:52.208287) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:52.208610) ========= script_repo_revision: 1f1c921f172cfb6f299e9ed810b748de6bede180 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:52.208985) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:52.209260) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 02:26:52.228812) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-13 02:26:52.229125) ========= /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.VE548wLRkk/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.kmzVI7bKj2/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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:26:52 INFO - MultiFileLogger online at 20170413 02:26:52 in /builds/slave/test 02:26:52 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:26:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:26:52 INFO - {'append_to_log': False, 02:26:52 INFO - 'base_work_dir': '/builds/slave/test', 02:26:52 INFO - 'blob_upload_branch': 'Firefox', 02:26:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:26:52 INFO - 'branch': 'Firefox', 02:26:52 INFO - 'buildbot_json_path': 'buildprops.json', 02:26:52 INFO - 'config_files': ('talos/mac_config.py',), 02:26:52 INFO - 'default_actions': ('clobber', 02:26:52 INFO - 'read-buildbot-config', 02:26:52 INFO - 'download-and-extract', 02:26:52 INFO - 'populate-webroot', 02:26:52 INFO - 'create-virtualenv', 02:26:52 INFO - 'install', 02:26:52 INFO - 'run-tests'), 02:26:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:26:52 INFO - 'download_minidump_stackwalk': True, 02:26:52 INFO - 'download_symbols': 'ondemand', 02:26:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:26:52 INFO - 'tooltool.py': '/tools/tooltool.py', 02:26:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:26:52 INFO - '/tools/misc-python/virtualenv.py')}, 02:26:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:26:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:26:52 INFO - 'gecko_profile': False, 02:26:52 INFO - 'gecko_profile_interval': 0, 02:26:52 INFO - 'installer_path': 'installer.exe', 02:26:52 INFO - 'log_level': 'info', 02:26:52 INFO - 'log_name': 'talos', 02:26:52 INFO - 'log_to_console': True, 02:26:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:26:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:26:52 INFO - 'opt_config_files': (), 02:26:52 INFO - 'pip_index': False, 02:26:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:26:52 INFO - 'cmd': ('bash', 02:26:52 INFO - '-c', 02:26:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:26:52 INFO - 'enabled': True, 02:26:52 INFO - 'halt_on_failure': False, 02:26:52 INFO - 'name': 'check_screen_resolution'},), 02:26:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:26:52 INFO - 'cmd': ('bash', 02:26:52 INFO - '-c', 02:26:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:26:52 INFO - 'enabled': True, 02:26:52 INFO - 'halt_on_failure': False, 02:26:52 INFO - 'name': 'check_screen_resolution'},), 02:26:52 INFO - 'run_cmd_checks_enabled': True, 02:26:52 INFO - 'suite': 'dromaeojs-e10s', 02:26:52 INFO - 'system_bits': '32', 02:26:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:26:52 INFO - 'title': 't-yosemite-r7-0101', 02:26:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:26:52 INFO - 'use_talos_json': True, 02:26:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:26:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:26:52 INFO - 'work_dir': 'build'} 02:26:52 INFO - [mozharness: 2017-04-13 09:26:52.455691Z] Running clobber step. 02:26:52 INFO - Running pre-action listener: _resource_record_pre_action 02:26:52 INFO - Running main action method: clobber 02:26:52 INFO - rmtree: /builds/slave/test/build 02:26:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:26:55 INFO - Running post-action listener: _resource_record_post_action 02:26:55 INFO - [mozharness: 2017-04-13 09:26:55.179569Z] Finished clobber step (success) 02:26:55 INFO - [mozharness: 2017-04-13 09:26:55.179781Z] Running read-buildbot-config step. 02:26:55 INFO - Running pre-action listener: _resource_record_pre_action 02:26:55 INFO - Running main action method: read_buildbot_config 02:26:55 INFO - Using buildbot properties: 02:26:55 INFO - { 02:26:55 INFO - "project": "", 02:26:55 INFO - "product": "firefox", 02:26:55 INFO - "script_repo_revision": "production", 02:26:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 02:26:55 INFO - "repository": "", 02:26:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 02:26:55 INFO - "buildid": "20170413004025", 02:26:55 INFO - "pgo_build": "False", 02:26:55 INFO - "basedir": "/builds/slave/test", 02:26:55 INFO - "buildnumber": 42, 02:26:55 INFO - "slavename": "t-yosemite-r7-0101", 02:26:55 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:26:55 INFO - "platform": "yosemite_r7", 02:26:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:26:55 INFO - "branch": "mozilla-central", 02:26:55 INFO - "repo_path": "mozilla-central", 02:26:55 INFO - "revision": "1f1c921f172cfb6f299e9ed810b748de6bede180", 02:26:55 INFO - "stage_platform": "macosx64", 02:26:55 INFO - "builduid": "5cc30a4fa32b4eb9b7a70620589e82a8", 02:26:55 INFO - "slavebuilddir": "test" 02:26:55 INFO - } 02:26:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 02:26:55 INFO - Running post-action listener: _resource_record_post_action 02:26:55 INFO - [mozharness: 2017-04-13 09:26:55.185235Z] Finished read-buildbot-config step (success) 02:26:55 INFO - [mozharness: 2017-04-13 09:26:55.185434Z] Running download-and-extract step. 02:26:55 INFO - Running pre-action listener: _resource_record_pre_action 02:26:55 INFO - Running main action method: download_and_extract 02:26:55 INFO - mkdir: /builds/slave/test/build/tests 02:26:55 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:26:55 INFO - trying https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:26:55 INFO - Downloading https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/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:26:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/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:26:56 INFO - Downloaded 1325 bytes. 02:26:56 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:26:56 INFO - Using the following test package requirements: 02:26:56 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:26:56 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:26:56 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:26:56 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:26:56 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:26:56 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:26:56 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:26:56 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:26:56 INFO - u'jsshell-mac.zip'], 02:26:56 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:26:56 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:26:56 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:26:56 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:26:56 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:26:56 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:26:56 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:26:56 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:26:56 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 02:26:56 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:26:56 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:26:56 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:26:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:26:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:26:56 INFO - Fetch https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:26:57 INFO - Expected file size: 52596488 02:26:57 INFO - Obtained file size: 52596488 02:27:14 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:27:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:27:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:27:14 INFO - Fetch https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:27:16 INFO - Expected file size: 52596488 02:27:16 INFO - Obtained file size: 52596488 02:27:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:27:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:27:31 INFO - Fetch https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:27:33 INFO - Expected file size: 14407739 02:27:33 INFO - Obtained file size: 14407739 02:27:33 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:27:33 INFO - trying https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:27:33 INFO - Downloading https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:27:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/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:27:35 INFO - Downloaded 71068722 bytes. 02:27:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:27:35 INFO - mkdir: /builds/slave/test/properties 02:27:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:27:35 INFO - Writing to file /builds/slave/test/properties/build_url 02:27:35 INFO - Contents: 02:27:35 INFO - build_url:https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:27:36 INFO - Running post-action listener: _resource_record_post_action 02:27:36 INFO - Running post-action listener: set_extra_try_arguments 02:27:36 INFO - [mozharness: 2017-04-13 09:27:36.289142Z] Finished download-and-extract step (success) 02:27:36 INFO - [mozharness: 2017-04-13 09:27:36.289293Z] Running populate-webroot step. 02:27:36 INFO - Running pre-action listener: _resource_record_pre_action 02:27:36 INFO - Running main action method: populate_webroot 02:27:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:27:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:27:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:27:36 INFO - '--tppagecycles', 02:27:36 INFO - '7'], 02:27:36 INFO - 'tests': ['tsvgm']}}, 02:27:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:27:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:27:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:27:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:27:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:27:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:27:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:27:36 INFO - 'talos_options': ['--disable-e10s'], 02:27:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:27:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:27:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:27:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:27:36 INFO - 'talos_options': ['--disable-e10s'], 02:27:36 INFO - 'tests': ['damp', 'tps']}, 02:27:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:27:36 INFO - 'tests': ['damp', 'tps']}, 02:27:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:27:36 INFO - 'tests': ['dromaeo_dom']}, 02:27:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:27:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:27:36 INFO - 'tests': ['basic_compositor_video']}, 02:27:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:27:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:27:36 INFO - 'tests': ['a11yr', 02:27:36 INFO - 'ts_paint', 02:27:36 INFO - 'tpaint', 02:27:36 INFO - 'sessionrestore', 02:27:36 INFO - 'sessionrestore_no_auto_restore', 02:27:36 INFO - 'tabpaint']}, 02:27:36 INFO - 'other-e10s': {'tests': ['a11yr', 02:27:36 INFO - 'ts_paint', 02:27:36 INFO - 'tpaint', 02:27:36 INFO - 'sessionrestore', 02:27:36 INFO - 'sessionrestore_no_auto_restore', 02:27:36 INFO - 'tabpaint']}, 02:27:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:27:36 INFO - 'ts_paint', 02:27:36 INFO - 'tpaint', 02:27:36 INFO - 'sessionrestore', 02:27:36 INFO - 'sessionrestore_no_auto_restore', 02:27:36 INFO - 'tabpaint']}, 02:27:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:27:36 INFO - 'ts_paint', 02:27:36 INFO - 'tpaint', 02:27:36 INFO - 'sessionrestore', 02:27:36 INFO - 'sessionrestore_no_auto_restore', 02:27:36 INFO - 'tabpaint']}, 02:27:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:27:36 INFO - 'tests': ['a11yr', 02:27:36 INFO - 'ts_paint', 02:27:36 INFO - 'tpaint', 02:27:36 INFO - 'sessionrestore', 02:27:36 INFO - 'sessionrestore_no_auto_restore', 02:27:36 INFO - 'tabpaint']}, 02:27:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:27:36 INFO - 'tests': ['a11yr', 02:27:36 INFO - 'ts_paint', 02:27:36 INFO - 'tpaint', 02:27:36 INFO - 'sessionrestore', 02:27:36 INFO - 'sessionrestore_no_auto_restore', 02:27:36 INFO - 'tabpaint']}, 02:27:36 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 02:27:36 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 02:27:36 INFO - 'bloom_basic_ref']}, 02:27:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:27:36 INFO - 'tests': ['tsvgx', 02:27:36 INFO - 'tsvgr_opacity', 02:27:36 INFO - 'tart', 02:27:36 INFO - 'tscrollx', 02:27:36 INFO - 'cart', 02:27:36 INFO - 'tsvg_static']}, 02:27:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:27:36 INFO - 'tsvgr_opacity', 02:27:36 INFO - 'tart', 02:27:36 INFO - 'tscrollx', 02:27:36 INFO - 'cart', 02:27:36 INFO - 'tsvg_static']}, 02:27:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:27:36 INFO - 'talos_options': ['--disable-e10s'], 02:27:36 INFO - 'tests': ['tp5o']}, 02:27:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:27:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:27:36 INFO - 'talos_options': ['--disable-e10s', 02:27:36 INFO - '--xperf_path', 02:27:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:27:36 INFO - 'tests': ['tp5n']}, 02:27:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:27:36 INFO - 'talos_options': ['--xperf_path', 02:27:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:27:36 INFO - 'tests': ['tp5n']}}, 02:27:36 INFO - 'talos.zip': {'path': '', 02:27:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:27:36 INFO - Running post-action listener: _resource_record_post_action 02:27:36 INFO - [mozharness: 2017-04-13 09:27:36.308456Z] Finished populate-webroot step (success) 02:27:36 INFO - [mozharness: 2017-04-13 09:27:36.308568Z] Running create-virtualenv step. 02:27:36 INFO - Running pre-action listener: _resource_record_pre_action 02:27:36 INFO - Running main action method: create_virtualenv 02:27:36 INFO - Creating virtualenv /builds/slave/test/build/venv 02:27:36 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:27:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:27:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:27:36 INFO - Using real prefix '/tools/python27' 02:27:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:27:37 INFO - Installing distribute.............................................................................................................................................................................................done. 02:27:40 INFO - Installing pip.................done. 02:27:40 INFO - Return code: 0 02:27:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:27:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:27:40 INFO - Reading from file tmpfile_stdout 02:27:40 INFO - Output received: 02:27:40 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:27:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:27:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:40 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 0x10ad6cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd092cae810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ad30>, '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 0x10aa71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab3fbe8>, '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.VE548wLRkk/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.kmzVI7bKj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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:27:40 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:27:40 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:27:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VE548wLRkk/Render', 02:27:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:40 INFO - 'HOME': '/Users/cltbld', 02:27:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:40 INFO - 'LOGNAME': 'cltbld', 02:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:40 INFO - 'MOZ_NO_REMOTE': '1', 02:27:40 INFO - 'NO_EM_RESTART': '1', 02:27:40 INFO - 'PAGER': '/bin/cat', 02:27:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:40 INFO - 'PWD': '/builds/slave/test', 02:27:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:40 INFO - 'SHELL': '/bin/bash', 02:27:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmzVI7bKj2/Listeners', 02:27:40 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 02:27:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:40 INFO - 'USER': 'cltbld', 02:27:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:40 INFO - 'XPC_FLAGS': '0x0', 02:27:40 INFO - 'XPC_SERVICE_NAME': '0', 02:27:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:40 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:27:40 INFO - Cleaning up... 02:27:40 INFO - Return code: 0 02:27:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:27:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:40 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 0x10ad6cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd092cae810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ad30>, '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 0x10aa71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab3fbe8>, '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.VE548wLRkk/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.kmzVI7bKj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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:27:40 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:27:40 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:27:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VE548wLRkk/Render', 02:27:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:40 INFO - 'HOME': '/Users/cltbld', 02:27:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:40 INFO - 'LOGNAME': 'cltbld', 02:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:40 INFO - 'MOZ_NO_REMOTE': '1', 02:27:40 INFO - 'NO_EM_RESTART': '1', 02:27:40 INFO - 'PAGER': '/bin/cat', 02:27:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:40 INFO - 'PWD': '/builds/slave/test', 02:27:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:40 INFO - 'SHELL': '/bin/bash', 02:27:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmzVI7bKj2/Listeners', 02:27:40 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 02:27:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:40 INFO - 'USER': 'cltbld', 02:27:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:40 INFO - 'XPC_FLAGS': '0x0', 02:27:40 INFO - 'XPC_SERVICE_NAME': '0', 02:27:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:40 INFO - Downloading/unpacking psutil>=3.1.1 02:27:40 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:27:40 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:27:40 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:27:40 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:27:40 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:27:40 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:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:27:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:27:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:27:44 INFO - Installing collected packages: psutil 02:27:44 INFO - Running setup.py install for psutil 02:27:44 INFO - building 'psutil._psutil_osx' extension 02:27:44 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:27:45 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:27:45 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:27:45 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:27:45 INFO - building 'psutil._psutil_posix' extension 02:27:45 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:27:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:27:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:27:45 INFO - ^ 02:27:45 INFO - 1 warning generated. 02:27:45 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:27:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:27:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:27:45 INFO - Successfully installed psutil 02:27:45 INFO - Cleaning up... 02:27:45 INFO - Return code: 0 02:27:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:46 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 0x10ad6cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd092cae810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ad30>, '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 0x10aa71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab3fbe8>, '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.VE548wLRkk/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.kmzVI7bKj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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:27:46 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:27:46 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:27:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VE548wLRkk/Render', 02:27:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:46 INFO - 'HOME': '/Users/cltbld', 02:27:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:46 INFO - 'LOGNAME': 'cltbld', 02:27:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:46 INFO - 'MOZ_NO_REMOTE': '1', 02:27:46 INFO - 'NO_EM_RESTART': '1', 02:27:46 INFO - 'PAGER': '/bin/cat', 02:27:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:46 INFO - 'PWD': '/builds/slave/test', 02:27:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:46 INFO - 'SHELL': '/bin/bash', 02:27:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmzVI7bKj2/Listeners', 02:27:46 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 02:27:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:46 INFO - 'USER': 'cltbld', 02:27:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:46 INFO - 'XPC_FLAGS': '0x0', 02:27:46 INFO - 'XPC_SERVICE_NAME': '0', 02:27:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:27:46 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:27:46 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:27:46 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:27:46 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:27:46 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:27:46 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:27:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:27:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:27:49 INFO - Installing collected packages: mozsystemmonitor 02:27:49 INFO - Running setup.py install for mozsystemmonitor 02:27:50 INFO - Successfully installed mozsystemmonitor 02:27:50 INFO - Cleaning up... 02:27:50 INFO - Return code: 0 02:27:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:27:50 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:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:50 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:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:50 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 0x10ad6cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd092cae810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ad30>, '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 0x10aa71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab3fbe8>, '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.VE548wLRkk/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.kmzVI7bKj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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:27:50 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:27:50 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:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VE548wLRkk/Render', 02:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:50 INFO - 'HOME': '/Users/cltbld', 02:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:50 INFO - 'LOGNAME': 'cltbld', 02:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:50 INFO - 'MOZ_NO_REMOTE': '1', 02:27:50 INFO - 'NO_EM_RESTART': '1', 02:27:50 INFO - 'PAGER': '/bin/cat', 02:27:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:50 INFO - 'PWD': '/builds/slave/test', 02:27:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:50 INFO - 'SHELL': '/bin/bash', 02:27:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmzVI7bKj2/Listeners', 02:27:50 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 02:27:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:50 INFO - 'USER': 'cltbld', 02:27:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:50 INFO - 'XPC_FLAGS': '0x0', 02:27:50 INFO - 'XPC_SERVICE_NAME': '0', 02:27:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:50 INFO - Downloading/unpacking jsonschema==2.5.1 02:27:50 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:27:50 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:27:50 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:27:50 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:27:50 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:27:50 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:27:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:27:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:27:52 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:27:52 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:27:52 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:27:52 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:27:52 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:27:52 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:27:53 INFO - Downloading functools32-3.2.3-2.tar.gz 02:27:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:27:53 INFO - warning: no files found matching '*.txt' 02:27:53 INFO - no previously-included directories found matching 'build' 02:27:53 INFO - no previously-included directories found matching 'dist' 02:27:53 INFO - no previously-included directories found matching '.git*' 02:27:53 INFO - Installing collected packages: jsonschema, functools32 02:27:53 INFO - Running setup.py install for functools32 02:27:53 INFO - warning: no files found matching '*.txt' 02:27:53 INFO - no previously-included directories found matching 'build' 02:27:53 INFO - no previously-included directories found matching 'dist' 02:27:53 INFO - no previously-included directories found matching '.git*' 02:27:53 INFO - Successfully installed jsonschema functools32 02:27:53 INFO - Cleaning up... 02:27:53 INFO - Return code: 0 02:27:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:27: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:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27: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:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:53 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 0x10ad6cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd092cae810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ad30>, '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 0x10aa71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab3fbe8>, '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.VE548wLRkk/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.kmzVI7bKj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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:27:53 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:27:53 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:27:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VE548wLRkk/Render', 02:27:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:53 INFO - 'HOME': '/Users/cltbld', 02:27:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:53 INFO - 'LOGNAME': 'cltbld', 02:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:53 INFO - 'MOZ_NO_REMOTE': '1', 02:27:53 INFO - 'NO_EM_RESTART': '1', 02:27:53 INFO - 'PAGER': '/bin/cat', 02:27:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:53 INFO - 'PWD': '/builds/slave/test', 02:27:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:53 INFO - 'SHELL': '/bin/bash', 02:27:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmzVI7bKj2/Listeners', 02:27:53 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 02:27:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:53 INFO - 'USER': 'cltbld', 02:27:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:53 INFO - 'XPC_FLAGS': '0x0', 02:27:53 INFO - 'XPC_SERVICE_NAME': '0', 02:27:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:27:54 INFO - Cleaning up... 02:27:54 INFO - Return code: 0 02:27:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27: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', '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 0x10ad6cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd092cae810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ad30>, '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 0x10aa71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab3fbe8>, '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.VE548wLRkk/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.kmzVI7bKj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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:27: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:27: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 blobuploader==1.2.4 02:27:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VE548wLRkk/Render', 02:27:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:54 INFO - 'HOME': '/Users/cltbld', 02:27:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:54 INFO - 'LOGNAME': 'cltbld', 02:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:54 INFO - 'MOZ_NO_REMOTE': '1', 02:27:54 INFO - 'NO_EM_RESTART': '1', 02:27:54 INFO - 'PAGER': '/bin/cat', 02:27:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:54 INFO - 'PWD': '/builds/slave/test', 02:27:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:54 INFO - 'SHELL': '/bin/bash', 02:27:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmzVI7bKj2/Listeners', 02:27:54 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 02:27:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:54 INFO - 'USER': 'cltbld', 02:27:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:54 INFO - 'XPC_FLAGS': '0x0', 02:27:54 INFO - 'XPC_SERVICE_NAME': '0', 02:27:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:54 INFO - Downloading/unpacking blobuploader==1.2.4 02:27: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:27: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:27: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:27: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:27: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:27: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:27:57 INFO - Downloading blobuploader-1.2.4.tar.gz 02:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:27:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:27: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:27: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:27: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:27: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:27: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:27: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:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:27:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:27: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:27: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:27: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:27: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:27: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:27: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:27:58 INFO - Downloading docopt-0.6.1.tar.gz 02:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:27:58 INFO - Installing collected packages: blobuploader, requests, docopt 02:27:58 INFO - Running setup.py install for blobuploader 02:27:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:27:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:27:58 INFO - Running setup.py install for requests 02:27:59 INFO - Running setup.py install for docopt 02:27:59 INFO - Successfully installed blobuploader requests docopt 02:27:59 INFO - Cleaning up... 02:27:59 INFO - Return code: 0 02:27:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:27:59 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:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:27:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:59 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:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:27:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:27:59 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 0x10ad6cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd092cae810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ad30>, '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 0x10aa71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab3fbe8>, '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.VE548wLRkk/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.kmzVI7bKj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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:27:59 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:27:59 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:27:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VE548wLRkk/Render', 02:27:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:27:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:27:59 INFO - 'HOME': '/Users/cltbld', 02:27:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:27:59 INFO - 'LOGNAME': 'cltbld', 02:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:59 INFO - 'MOZ_NO_REMOTE': '1', 02:27:59 INFO - 'NO_EM_RESTART': '1', 02:27:59 INFO - 'PAGER': '/bin/cat', 02:27:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:59 INFO - 'PWD': '/builds/slave/test', 02:27:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:27:59 INFO - 'SHELL': '/bin/bash', 02:27:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmzVI7bKj2/Listeners', 02:27:59 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 02:27:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:27:59 INFO - 'USER': 'cltbld', 02:27:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:27:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:59 INFO - 'XPC_FLAGS': '0x0', 02:27:59 INFO - 'XPC_SERVICE_NAME': '0', 02:27:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:27:59 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-EtWIDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:27:59 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-32rs7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:27:59 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-uLwxYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:27:59 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-VlZGKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:27:59 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-ZuU4rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:00 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-uRuobX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:00 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-sPeetH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:00 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-9AC0lQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:00 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-nzvkaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:00 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-9ob5nt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:00 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-mNWidZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:00 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-7OOnKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:00 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-nJqvsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:00 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-EikFEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-wvn4p1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-2MT1nI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-gG7UlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:28:01 INFO - Running setup.py install for manifestparser 02:28:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:28:01 INFO - Running setup.py install for mozcrash 02:28:01 INFO - Running setup.py install for mozdebug 02:28:01 INFO - Running setup.py install for mozdevice 02:28:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:28:01 INFO - Running setup.py install for mozfile 02:28:02 INFO - Running setup.py install for mozhttpd 02:28:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:28:02 INFO - Running setup.py install for mozinfo 02:28:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:28:02 INFO - Running setup.py install for mozInstall 02:28:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:28:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:28:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:28:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:28:02 INFO - Running setup.py install for mozleak 02:28:02 INFO - Running setup.py install for mozlog 02:28:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:28:02 INFO - Running setup.py install for moznetwork 02:28:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:28:02 INFO - Running setup.py install for mozprocess 02:28:03 INFO - Running setup.py install for mozprofile 02:28:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:28:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:28:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:28:03 INFO - Running setup.py install for mozrunner 02:28:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:28:03 INFO - Running setup.py install for mozscreenshot 02:28:03 INFO - Running setup.py install for moztest 02:28:03 INFO - Running setup.py install for mozversion 02:28:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:28:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:28:03 INFO - Cleaning up... 02:28:03 INFO - Return code: 0 02:28:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28: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:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28: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:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:03 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 0x10ad6cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd092cae810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ad30>, '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 0x10aa71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab3fbe8>, '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.VE548wLRkk/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.kmzVI7bKj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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:28:03 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:28:03 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:28:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VE548wLRkk/Render', 02:28:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:03 INFO - 'HOME': '/Users/cltbld', 02:28:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:03 INFO - 'LOGNAME': 'cltbld', 02:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:03 INFO - 'MOZ_NO_REMOTE': '1', 02:28:03 INFO - 'NO_EM_RESTART': '1', 02:28:03 INFO - 'PAGER': '/bin/cat', 02:28:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:03 INFO - 'PWD': '/builds/slave/test', 02:28:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:03 INFO - 'SHELL': '/bin/bash', 02:28:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmzVI7bKj2/Listeners', 02:28:03 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 02:28:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:03 INFO - 'USER': 'cltbld', 02:28:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:03 INFO - 'XPC_FLAGS': '0x0', 02:28:03 INFO - 'XPC_SERVICE_NAME': '0', 02:28:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-ww_dvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-o0Njy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-V3N7eQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-syigzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-UQlDGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-DhPKiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-GJEFab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:04 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:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-hWW9Vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-eJdh_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-kdYzp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-MXqoKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-2IwwC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-dvXAcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-4T70Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-QDNRi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-mNQWtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:05 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:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-FFXMUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:06 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:28:06 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:28:06 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:28:06 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:28:06 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:28:06 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:28:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:28:06 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:28:06 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:28:06 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:28:06 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:28:06 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:28:06 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:28:08 INFO - Downloading blessings-1.6.tar.gz 02:28:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:28:08 INFO - Installing collected packages: blessings 02:28:08 INFO - Running setup.py install for blessings 02:28:08 INFO - Successfully installed blessings 02:28:08 INFO - Cleaning up... 02:28:09 INFO - Return code: 0 02:28:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:28:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:09 INFO - Reading from file tmpfile_stdout 02:28:09 INFO - Current package versions: 02:28:09 INFO - blessings == 1.6 02:28:09 INFO - blobuploader == 1.2.4 02:28:09 INFO - docopt == 0.6.1 02:28:09 INFO - functools32 == 3.2.3-2 02:28:09 INFO - jsonschema == 2.5.1 02:28:09 INFO - manifestparser == 1.1 02:28:09 INFO - mozInstall == 1.12 02:28:09 INFO - mozcrash == 1.0 02:28:09 INFO - mozdebug == 0.1 02:28:09 INFO - mozdevice == 0.50 02:28:09 INFO - mozfile == 1.2 02:28:09 INFO - mozhttpd == 0.7 02:28:09 INFO - mozinfo == 0.9 02:28:09 INFO - mozleak == 0.1 02:28:09 INFO - mozlog == 3.4 02:28:09 INFO - moznetwork == 0.27 02:28:09 INFO - mozprocess == 0.25 02:28:09 INFO - mozprofile == 0.28 02:28:09 INFO - mozrunner == 6.13 02:28:09 INFO - mozscreenshot == 0.1 02:28:09 INFO - mozsystemmonitor == 0.3 02:28:09 INFO - moztest == 0.8 02:28:09 INFO - mozversion == 1.4 02:28:09 INFO - psutil == 3.1.1 02:28:09 INFO - requests == 1.2.3 02:28:09 INFO - wsgiref == 0.1.2 02:28:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:09 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:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:09 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:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:09 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 0x10ad6cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd092cae810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ad30>, '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 0x10aa71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab3fbe8>, '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.VE548wLRkk/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.kmzVI7bKj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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:28:09 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:28:09 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:28:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VE548wLRkk/Render', 02:28:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:09 INFO - 'HOME': '/Users/cltbld', 02:28:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:09 INFO - 'LOGNAME': 'cltbld', 02:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:09 INFO - 'MOZ_NO_REMOTE': '1', 02:28:09 INFO - 'NO_EM_RESTART': '1', 02:28:09 INFO - 'PAGER': '/bin/cat', 02:28:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:09 INFO - 'PWD': '/builds/slave/test', 02:28:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:09 INFO - 'SHELL': '/bin/bash', 02:28:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmzVI7bKj2/Listeners', 02:28:09 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 02:28:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:09 INFO - 'USER': 'cltbld', 02:28:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:09 INFO - 'XPC_FLAGS': '0x0', 02:28:09 INFO - 'XPC_SERVICE_NAME': '0', 02:28:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:09 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:28:09 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:28:09 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:28:09 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:28:09 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:28:09 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:28:09 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:28:09 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:28:09 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:28:09 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:28:09 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:28:09 INFO - Cleaning up... 02:28:09 INFO - Return code: 0 02:28:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:28:09 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:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:09 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:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:09 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 0x10ad6cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad6f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd092cae810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ad30>, '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 0x10aa71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab3fbe8>, '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.VE548wLRkk/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.kmzVI7bKj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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:28:09 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:28:09 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:28:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VE548wLRkk/Render', 02:28:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:09 INFO - 'HOME': '/Users/cltbld', 02:28:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:09 INFO - 'LOGNAME': 'cltbld', 02:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:09 INFO - 'MOZ_NO_REMOTE': '1', 02:28:09 INFO - 'NO_EM_RESTART': '1', 02:28:09 INFO - 'PAGER': '/bin/cat', 02:28:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:09 INFO - 'PWD': '/builds/slave/test', 02:28:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:09 INFO - 'SHELL': '/bin/bash', 02:28:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmzVI7bKj2/Listeners', 02:28:09 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 02:28:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:09 INFO - 'USER': 'cltbld', 02:28:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:09 INFO - 'XPC_FLAGS': '0x0', 02:28:09 INFO - 'XPC_SERVICE_NAME': '0', 02:28:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:28:09 INFO - Cleaning up... 02:28:09 INFO - Return code: 0 02:28:09 INFO - Running post-action listener: _resource_record_post_action 02:28:09 INFO - Running post-action listener: _start_resource_monitoring 02:28:09 INFO - Starting resource monitoring. 02:28:09 INFO - [mozharness: 2017-04-13 09:28:09.933988Z] Finished create-virtualenv step (success) 02:28:09 INFO - [mozharness: 2017-04-13 09:28:09.935024Z] Running install step. 02:28:09 INFO - Running pre-action listener: _resource_record_pre_action 02:28:09 INFO - Running main action method: install 02:28:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:10 INFO - Reading from file tmpfile_stdout 02:28:10 INFO - Detecting whether we're running mozinstall >=1.0... 02:28:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:28:10 INFO - Reading from file tmpfile_stdout 02:28:10 INFO - Output received: 02:28:10 INFO - Usage: mozinstall [options] installer 02:28:10 INFO - Options: 02:28:10 INFO - -h, --help show this help message and exit 02:28:10 INFO - -d DEST, --destination=DEST 02:28:10 INFO - Directory to install application into. [default: 02:28:10 INFO - "/builds/slave/test"] 02:28:10 INFO - --app=APP Application being installed. [default: firefox] 02:28:10 INFO - mkdir: /builds/slave/test/build/application 02:28:10 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:28:10 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:28:30 INFO - Reading from file tmpfile_stdout 02:28:30 INFO - Output received: 02:28:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:28:30 INFO - Running post-action listener: _resource_record_post_action 02:28:30 INFO - [mozharness: 2017-04-13 09:28:30.111930Z] Finished install step (success) 02:28:30 INFO - [mozharness: 2017-04-13 09:28:30.112048Z] Running run-tests step. 02:28:30 INFO - Running pre-action listener: _resource_record_pre_action 02:28:30 INFO - Running main action method: run_tests 02:28:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:28:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:28:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:28:30 INFO - 2017-04-13 02:28:30.126 screenresolution[1622:7496] starting screenresolution argv=screenresolution get 02:28:30 INFO - 2017-04-13 02:28:30.129 screenresolution[1622:7496] Display 0: 1600x1200x32@60 02:28:30 INFO - 2017-04-13 02:28:30.135 screenresolution[1623:7499] starting screenresolution argv=screenresolution list 02:28:30 INFO - Available Modes on Display 0 02:28:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:28:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:28:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:28:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:28:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:28:30 INFO - Intel Iris: 02:28:30 INFO - Chipset Model: Intel Iris 02:28:30 INFO - Type: GPU 02:28:30 INFO - Bus: Built-In 02:28:30 INFO - VRAM (Dynamic, Max): 1536 MB 02:28:30 INFO - Vendor: Intel (0x8086) 02:28:30 INFO - Device ID: 0x0a2e 02:28:30 INFO - Revision ID: 0x0009 02:28:30 INFO - Displays: 02:28:30 INFO - Display: 02:28:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:28:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:28:30 INFO - Display Serial Number: 1600x1200 60 02:28:30 INFO - Main Display: Yes 02:28:30 INFO - Mirror: Off 02:28:30 INFO - Online: Yes 02:28:30 INFO - Rotation: Supported 02:28:30 INFO - Return code: 0 02:28:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:28:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:28:30 INFO - Python 2.7.3 02:28:30 INFO - Return code: 0 02:28:30 INFO - grabbing minidump binary from tooltool 02:28:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:30 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 0x10ab4ad30>, '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 0x10aa71db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab3fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:28:30 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:28:30 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:28:30 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:28:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:28:30 INFO - Return code: 0 02:28:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:28:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:28:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:28:30 INFO - ENV: RUST_BACKTRACE is now 1 02:28:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:28:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:28:30 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/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0101', '--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:28:30 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/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0101 --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:28:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VE548wLRkk/Render', 02:28:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:30 INFO - 'HOME': '/Users/cltbld', 02:28:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:30 INFO - 'LOGNAME': 'cltbld', 02:28:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:28:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:28:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:30 INFO - 'MOZ_NO_REMOTE': '1', 02:28:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:28:30 INFO - 'NO_EM_RESTART': '1', 02:28:30 INFO - 'PAGER': '/bin/cat', 02:28:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:30 INFO - 'PWD': '/builds/slave/test', 02:28:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:28:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:30 INFO - 'RUST_BACKTRACE': '1', 02:28:30 INFO - 'SHELL': '/bin/bash', 02:28:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmzVI7bKj2/Listeners', 02:28:30 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 02:28:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:30 INFO - 'USER': 'cltbld', 02:28:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:30 INFO - 'XPC_FLAGS': '0x0', 02:28:30 INFO - 'XPC_SERVICE_NAME': '0', 02:28:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:30 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/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0101', '--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:28:30 INFO - mozversion application_buildid: 20170413004025 02:28:30 INFO - mozversion application_changeset: 1f1c921f172cfb6f299e9ed810b748de6bede180 02:28:30 INFO - mozversion application_display_name: Nightly 02:28:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:28:30 INFO - mozversion application_name: Firefox 02:28:30 INFO - mozversion application_remotingname: firefox 02:28:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:28:30 INFO - mozversion application_vendor: Mozilla 02:28:30 INFO - mozversion application_version: 55.0a1 02:28:30 INFO - mozversion platform_buildid: 20170413004025 02:28:30 INFO - mozversion platform_changeset: 1f1c921f172cfb6f299e9ed810b748de6bede180 02:28:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:28:30 INFO - mozversion platform_version: 55.0a1 02:28:30 INFO - using testdate: 1492075710 02:28:30 INFO - actual date: 1492075710 02:28:30 INFO - starting webserver on 'localhost:49241' 02:28:30 INFO - SUITE-START | Running 2 tests 02:28:30 INFO - TEST-START | dromaeo_css 02:28:30 INFO - operating with platform_type : mac_ 02:28:30 INFO - Initialising browser for dromaeo_css test... 02:28:30 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpuqDBj3/profile http://localhost:49241/getInfo.html) 02:28:33 INFO - PID 1633 | 2017-04-13 02:28:33.645 plugin-container[1635:7683] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 02:28:33 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:33 INFO - PID 1633 | 2017-04-13 02:28:33.649 plugin-container[1635:7683] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 02:28:33 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:33 INFO - PID 1633 | 2017-04-13 02:28:33.649 plugin-container[1635:7683] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:33 INFO - PID 1633 | __metrics Screen width/height:1600/1200 02:28:33 INFO - PID 1633 | colorDepth:24 02:28:33 INFO - PID 1633 | Browser inner width/height: 1024/689 02:28:33 INFO - PID 1633 | __metrics 02:28:33 ERROR - PID 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 02:28:34 INFO - TEST-INFO | 1633: exit 0 02:28:34 INFO - Browser initialized. 02:28:34 INFO - Running cycle 1/1 for dromaeo_css test... 02:28:34 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpuqDBj3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:28:37 INFO - PID 1636 | 2017-04-13 02:28:37.290 plugin-container[1637:7814] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 02:28:37 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:37 INFO - PID 1636 | 2017-04-13 02:28:37.291 plugin-container[1637:7814] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 02:28:37 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:37 INFO - PID 1636 | 2017-04-13 02:28:37.291 plugin-container[1637:7814] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:47 INFO - PID 1636 | RSS: Main: 235208704 02:28:47 INFO - PID 1636 | 02:31:52 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 02:31:53 INFO - PID 1636 | RSS: Main: 253632512 02:31:53 INFO - PID 1636 | 02:33:35 INFO - PID 1636 | ************************* 02:33:35 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 02:33:35 INFO - PID 1636 | 02:33:35 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:33:35 INFO - PID 1636 | Full stack: 02:33:35 INFO - PID 1636 | ************************* 02:33:35 INFO - PID 1636 | ************************* 02:33:35 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 02:33:35 INFO - PID 1636 | 02:33:35 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:33:35 INFO - PID 1636 | Full stack: 02:33:35 INFO - PID 1636 | ************************* 02:33:35 INFO - PID 1636 | ************************* 02:33:35 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 02:33:35 INFO - PID 1636 | 02:33:35 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:33:35 INFO - PID 1636 | Full stack: 02:33:35 INFO - PID 1636 | ************************* 02:33:35 INFO - PID 1636 | ************************* 02:33:35 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 02:33:35 INFO - PID 1636 | 02:33:35 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:33:35 INFO - PID 1636 | Full stack: 02:33:35 INFO - PID 1636 | ************************* 02:34:55 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 02:34:56 INFO - PID 1636 | RSS: Main: 261345280 02:34:56 INFO - PID 1636 | 02:37:57 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 02:37:58 INFO - PID 1636 | RSS: Main: 273563648 02:37:58 INFO - PID 1636 | 02:41:00 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 02:41:00 INFO - PID 1636 | RSS: Main: 265965568 02:41:00 INFO - PID 1636 | 02:44:03 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 02:44:04 INFO - PID 1636 | RSS: Main: 276160512 02:44:04 INFO - PID 1636 | 02:47:06 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 02:47:06 INFO - PID 1636 | RSS: Main: 269996032 02:47:06 INFO - PID 1636 | 02:47:06 INFO - PID 1636 | __start_tp_report 02:47:06 INFO - PID 1636 | _x_x_mozilla_page_load 02:47:06 INFO - PID 1636 | _x_x_mozilla_page_load_details 02:47:06 INFO - PID 1636 | |i|pagename|runs| 02:47:06 INFO - PID 1636 | |0;dojo.html;3328.18459477428;3575.964240357597;3586.4082426399646;3638.1268495561067;3646.124930016797;5469.371022332428;6041.852048110862;6151.846203844895;6229.968850155743;6276.435120839124;6152.138700581911;6225.439710426061;6252.593581417216;6273.529485288599;6329.968350158243;5418.512333889949;5474.233607294982;5506.752196151183;5542;5615.989121958046;5405.972970135143;5508.614396992212;5570.610057295991;5596.664200147984;5614.691191984439;5382.381026182;5432.619716619838;5494.09347457669;5502.697351645658;5519.282493275889;5426.945730542683;5429.728513574345;5434.429384914567;5457.372402173722;5463.9180412293845;5331.587129410709;5358.866028349284;5377.892442151174;5382.650127741685;5408.4050754416985;4659.4175728034;6048.667323297217;6069.393060693896;6085.604435711664;6106.885399019245;6690.765823196213;6699.162604674416;6711.899321510181;6723.428508576777;6732;6820.420264277543;6826.24911259761;6832.487563432763;6843.452523798084;6860.279670634562;6139.584134579375;6238.157848690459;6324.2330706143985;6325.652089135142;6366.6180029198395;6951.443884489228;6961.895571566431;7001.964990175016;7002.509824312351;7035.683394247271;6358.77744278948;6401.167848179707;6405.935940640534;6436.601410952233;6618.63597502142;5965.880682386328;6499.54503184782;6762.019507171432;6785.185777706707;6799.150106236721;6360.586561873556;6425.871482570323;6441.452476539546;6620.768273110418;6652.368025037614;6389.840253993594;6512.544121911421;6591.802245932629;6625.370589793962;6669.566478178903;5352.411234764173;5356.892862142735;5360.356757189163;5375.81184658535;5401.02781499326;4670.906581868344;4735.739534325645;4762.594599994991;4773.451053128865;4794.089123066607;5021.447640759514;6307.432331090224;6336.017917222745;6359.427651511386;6365.681715914186;6240.188775459252;6349.333320001426;6808.659567021629;6830.487713421513;6841.555298905655;6332.303446620868;6351.142595749607;6365;6375.266844312963;6392.520560957853;5780.682062486603;5825.38833422493;5826.038703613941;5842.474177324061;5928.258967629046;6943.097397338313;6995.825104372431;7010.298970102949;7020.964895175492;7030.2969703029285;6707.396334329934;6803.115594972622;6805.217399999064;6807.557508762113;6807.9319206807295;5904.61619994699;6772.52592318533;6890.8621827564175;6981.3367730065565;7022.052022976833;5665.433456654301;6441.291457939529;6595.340465953366;6713.832154196184;6729.259781424137;6391.392817682313;6698.5980841149685;6716.865662686817;6770.390664840188;6772.018057381751;5360.839174824761;6380.234371875405;6476.093346931528;6569.9343006569325;6595.868082638224;6244.750209991549;6532.673366331569;6538.574992625654;6549.148610680581;6561.3438656134;6287.748490060347;6333.524985626004;6352.396522330371;6358.364163583605;6386.297507274289;6053.60651557665;6282.968585157045;6551.475881929339;6564.5076619252795;6581.572197807319;6412.743490260337;6415.839604009937;6434.388733070351;6434.58175218628;6446.258680251643 02:47:06 INFO - PID 1636 | |1;ext.html;596.8358701357126;637.8277864976457;655.6492276632002;1287.7360141171062;1289.7742894993373;37606.43590346147;38146.23707525855;38190;38209.808950955245;38222.23555528888;37718.811405943;37987;37994.810025949904;38139.66511172109;38262;8290.046644635868;8469.153084691543;8540.188682075193;8547.615357308936;8558.657653693876;8556.572171391437;8661.437006594551;8667.739967800977;8677.869831952527;8680.348973826956;8604.956975215115;8623.568821558896;8669.913300866974;8678.132186781302;8688.218060374566;8547.102554231778;8601.13988601138;8635.52504612246;8640.567971601458;8650.524221167832;8450.19723126302;8466.492010479391;8468.32253419725;8535.95732021336;8563;36813.81593092044;36952.26095478075;37179.4423083654;37242.06894827624;37257.068573285615;3568.553930758655;3589.712822974182;3594;3615.7468977171607;3618.764780289273;30659.926902558527;30716.53925191124;30737.692623073708;30774.38451230963;30857.69142308593;3084.64526579444;3111.2688518198206;3120.2979329650875;3126.437241296566;3128.577642018344;245984;250469;295433;295817.1483420073;299713;47480.57558273257;48097.519024810004;48384.516154838704;48403.757981210576;48403.78990525265;51561.48438515642;51710;51972;51974.22038669423;52142.73928630409;14393.136411815325;14459.710805783825;14511.419543218364;14520.83833293334;14521.92739036298;11818.940905295418;11916.94041529804;11917.52329906794;11918.225315354473;11920.582779602672;13359.332033398292;13375.53185638498;13400.798988015187;13402.865971340358;13448.731025379633;4603.976980115078;5035.697858128524;5466.9179962300595;5948.375420580864;6034.63792172471;850.6172222499899;1044.472541366605;1068.2789117345915;1074.4466599701161;1077.8814330423804;17491.03799291051;20560.38318850437;21319;24693.38266543315;24714.382140446633;983.4885859353096;1147.4951021550492;1173.524722487394;1183.4556104191995;1186.2052424875353;12276.263424194398;12624.747505049847;16268.67462650764;16421.343146274256;16570.050480084927;392403;393658;395601;396236;396607.01696491335;55929.44070559324;56276.43723562794;56316.71841640765;56396.87206255852;56421.58946026382;1519.7340465418729;1551.0538571471393;1557.5716677913665;1559.0411896683459;1578.6921550297582;23055.538889221785;28094.87620495239;29917.073996276515;30110.69889301079;30111.795528178627;38470;38564.42153367758;38684.41973370346;38809.61190388173;39663.603363967144;351030.44847756997;351124;352680;353134;353486;34559.30881382308;36520.81739591285;37399.252014959005;37417;38074.23851523009;48428.51571484381;48761;48917.510824891295;48968;49113.26330105123;31797.20506987344;32445.675543244266;33019.66980330166;33075.83462082771;33422.33155336831;23221.76778232196;31836.204094897814;32518;32802.343953120326;32919;37401;38550.036249093995;38605.613943861325;38903.61096389;39023.80488097541;23838.293205722824;32247.838760806048;33463.66536334703;33729.83135084309;34830.60677572869;23449;25403.36491587725;25460.872695637143;25534.23397298084;26003 02:47:06 INFO - PID 1636 | |2;jquery.html;383.7198844843264;419.8194776246217;421.1997205309911;443.0695539367329;447.84549330480974;17948;19438.319658811943;21022.05400756966;21162.576748465042;21793.782062179373;21842.235521756742;21972.230971915986;22153.44616384587;22944.082236710554;23040.884795576;5400.702961337125;5976.5517586180995;6214.875702485948;6239.220097487814;6268.467180289681;4935.35840340757;5218.034663587229;5873;6050.2134722486235;6193.380661933793;5891.440313170242;6140.140380346755;6158.722857471402;6160.445559899608;6164.321924588292;5975.85060373492;6049;6112.449879510866;6115.908261376083;6118.3269840317525;5357.116075847481;5366.597505187126;5373.623846330758;5417.539509141728;5418.83743487696;24935.877885495196;26681.93272269107;27053.1884043479;27324.72675273242;27467.587986180224;2142.678598210265;2176.0425412818468;2176.270949232009;2210.80102790748;2216.9224077157223;17056.91471542647;17377.47867563975;17605.207765650448;17641.294348226056;17917;2149.881756503392;2166.6316726156433;2175.445261458318;2183.5960347335636;2217.634090375086;135871.32064339714;136126;136509;136662.3166884169;138308;11879.287242765462;12352.320622365856;12494.687632809253;12542.428802095548;12554.435050422753;15825.366985320457;16113.355465781471;16129.435469758502;16155.676886462204;16162.919185403998;7770.640137975832;8554.700585479479;8819.955900220459;8828.51443170619;8933.062028486915;8032.919670803334;8314.376421768391;8331.208535189147;8494.193051660082;8498.70254541088;7578.3179513842915;7646.732364367221;7760.728374506865;7771.689132434639;7783.883241751379;1929.2861641192849;2074.543600407908;2177.5536015116863;2179.4115588791256;2219.82241420686;984.8128855517429;1016.3393794033938;1022.2741853284103;1055.646358469921;1064.5528877871313;19851.908145052163;21191.83444910514;21205.575888482454;21390.465238369165;21433.67849482259;875.627858160292;911.334725650279;1015.1168483419473;1057.582255009267;1070.3310430980637;10695.358278503314;10864.837027444595;10870.402127883042;10934.4532773361;11149.609763658395;731242.0627381187;737185;741301;747588.4067535592;754516;116273.3490660364;130338.87627866148;130537.34731326283;157081;160132.19933900255;1194.5341316886422;1199.7120691034088;1226.9509219631293;1228.7419641875128;1231.956881509143;17431;17834.10829458496;17886.5528361792;18043.729344060113;18077.276908923497;59021.40978590159;59520;59748.70125649518;59778.50553736191;60257.09614355876;75916;81698;82102;82168;85323.2935341302;7208.603526805866;8833.558322083998;8899.466032037839;9174.128457796498;9321.580528876082;14132.364043617856;14308.713825723218;14338.784918226444;14708.485203018272;14742.189179595225;11353.318800871974;11641.301521908712;12110.636680899586;12139.817902731642;12231.75536489283;11358.204925655455;11493.310401375944;11502.597409090642;11546.59586914454;11772.705682357668;11102.666919992413;11445.313281202822;12069.698257543632;12334.198277111958;12384.876151238372;8039.477433966774;8753.60608772594;8782.736517904474;9140.177384035203;9285.303602229726;10344.741381465523;12026.75946481083;12191.939040304742;12280.01759859227;12388.070894682754 02:47:06 INFO - PID 1636 | |3;mootools.html;1337.2043634037743;1351.452661672023;1358.2529608715204;1463.836250181106;1505.5934897577654;11313.094952403808;11703.531858725652;11756.882431175707;11841.585544505944;12068.456919438624;11795.882041179586;11844.637866645338;11970.34163121028;12120.81818772719;12124.81812772807;2488.340589743719;2510.899564017437;2518.357818756224;2523.205190365038;2527.633493143493;2410.144398738449;2450.9754902451014;2494.725580186178;2495.8752062396898;2497.5629264878667;2446.584080706284;2454.018392642939;2477.1949116537107;2482.565551028563;2483.267436106344;2034.369345502884;2420.6006009008493;2435.9391015224583;2448.8163387745903;2473.579491486452;2391.234804862445;2449.461118553915;2462.852228866274;2467.321486591202;2514.962275565868;12049.638510844688;12053.035757139407;12071;12094.637160885188;12118.575849845301;6537.248216455121;6740.696668649923;6783.0164626128935;6787.389134977876;6813.11429514165;3560.3413368526903;3686.299603075407;3688.2623475305045;4418.779061046935;4484.080763443519;1233.5929143382662;1246.2647038247478;1278.8081787731821;1284.319310765296;1285.8649841766573;12828.294443805587;14335.283235838271;16745.916270418693;16856.915715421466;16887.071211083385;5100.69395836251;5885.676287804126;6018.307894592091;6199.3490683478485;6211.844703882439;13928.791068133985;13961.511347102803;14004.43982240699;14026.438942442188;14057.85942140586;6772.966135169391;6841.486888483284;6841.863162736718;6856.965715171492;6858.794236172923;6094.786682466092;6127.816165515042;6146.231721034871;6203.844903877439;6228.34602366745;4651.139539185261;4768.833090841804;4835.153848076643;4865.537773911473;4949.554540091337;1463.5755630867143;1496.9850301497063;1504.2403586188905;1525.5347119128771;1533.1644253881661;792.0930534537922;811.1158836867843;819.6967122164838;826.5247482697473;853.0786750309651;6746.628935408501;6820.35206655367;7011.579305241702;7015.543989640657;7016.789496315118;585.4379795396381;587.4125874125874;600.1118344849647;603.1465476351002;612.2499937576433;4193.119444916601;4263.488381394252;4292.806823692941;4402.427684401007;4450.710703804242;14672.193029383487;15191.468298609496;15209.695806083817;15314.463993760164;15320.846791531942;12564.371781411075;15199.696006079817;15200.391984320504;15228.771568426482;15251.38994440232;9293.72118836436;9622.326437149472;10644.09525190352;10681.252312338062;10812.297200681931;6702.296258892941;7014.438844892295;7249.05762250904;7313.414926805949;7372.857207132903;11406.604613031357;11800.586979455678;12975.480980760663;12982.675433114247;12984.67538311512;10807.405592692163;13068.869311306766;13132.606021819362;13227;13245.006624503008;9423.811523769624;9532.285078618992;9643.517824108907;9651.855222171813;9654.903450965681;8991.730248092568;10641.733956651145;10749.838752418564;10786;10922.890771092187;9094.818103637757;9118.726438206864;9182.632694692405;9218.262538996998;9394.107559781809;8820.426672266281;8888.777780555538;8970.37207395476;8971.372003959661;8981.820363592822;8171.83656326882;8314.043884953307;8325.458845175044;8335.833283334421;8347.123552027193;6076.453119219292;6095.451409373178;6174.919389106799;6243.500519958302;6281.309056003744;6136.110264011604;6194.5973511721595;6237.906431403623;6293.685315734287;6298.0552917062805 02:47:06 INFO - PID 1636 | |4;prototype.html;377.79221428214476;393.1625637392354;396.24910794045286;410.41720756525723;411.8723195809299;4738.28925661151;4881.389826271716;6079.814436184945;6116.449519543235;6199.225096862892;5989.940100598993;6100.176476175715;6102.816915492531;6127.142200091991;6127.16185144456;3703;4303.35449682547;4488.775561221943;4514.616257618107;4525.886852828673;4236.512801027875;4380.08018316154;4472.262076757347;4504.797284122206;4532.229520981425;3883.708721845873;4393.846215382462;4433.778311084449;4459.955400446002;4484.833943174782;3700.889733080065;4286.249906266422;4462.509123996358;4463.933041004388;4516.9096618067615;3827.162961778334;3828.7894165820867;3833.233353329317;3856.2865869814173;3900.4539364488996;5941.613795103304;5942.168096466498;5958.821235362946;5965;5986.013986013986;4632.559906808848;4633.814647414099;5057.69653820772;5332.733363331857;5416.647917885325;5354.5180933715765;6165.537584681122;6506.056621789814;6521.021846722982;6545.869082618321;4202.0880499372715;4388.442103053402;4397.428334316551;4408.691391602589;4410.2723050696595;6719.462443004548;6948.9576563515375;6953.89569156463;6965.930340696579;6986.192525073847;3730.459083432887;3759.6240375962734;4441.800118994653;4454.665900057508;4518.299663552155;3800.9429858552144;3807.8957102440536;3925.862594809168;4488.832903445128;4539.954600453953;4733.502982186821;5198.518422249641;5340.8664783379945;5342.91985620216;5430.42980487051;5605.467480589337;5998;6517.446017088506;6613.603183808987;6614.735410583523;1417.9432822686977;1479.2381923309504;1537.5464238049801;1537.6309685675358;1554.4326320892792;835.7910522369408;864.7665130414753;999.1307562420741;1007.4005966449951;1040.2302296300684;5428.70142142186;6272.529560282997;6274.964630835861;6290.654014029181;6320.27316858558;5695.889301586234;5879.323877754027;6668.154429814735;6782.423494003065;6839.366843765186;5768.653880767083;5929.911051334234;6267.905981410283;6289.7484100635465;6325.335839736761;2952;2991.4914464540643;3247.8700851966128;3579.266250418658;3593.838277277529;6696.397324240842;6863.485238607124;6975.79072627822;6986.685599148051;6989.266127056688;5818.854528636818;6371.044343348442;6877.724891004404;6981.616011119336;6996.6851491683;6202.782902598387;6828.453723702092;6979.581225126509;6981.359380545568;7023.12210973618;5537.916931246115;6579.703913324008;6676.544760521665;6716.597004179765;6808;5345.599080069088;6273.435390814849;6650.703112892939;6697.966510167418;6709.329067093252;6748.291429399842;7036.788896333118;7038.648067596702;7046.436285097282;7058.294170583106;4226.260404429151;4387.188370151533;4420.557944205554;4428.380026796187;4433.357163211252;5728.713564321851;6783;6788.830279243059;6830.043793868763;6838;5858.736356863866;6468.838279043061;6581.736730530725;6585.967070164618;6644.840426861386;6038.75844966214;6497.4477169441125;6546;6556.868862622816;6557.868842623217;5076.847694569169;5162.561182299396;6135.754569817157;6429.807105786834;6452.838679033061;5999.370066143167;6088.934436473688;6429.935700642934;6551.639659818756;6598.538102332791;5590.580706446952;5663.575231857545;6035.969820150871;6421.967890160706;6426.518011149089 02:47:06 INFO - PID 1636 | |5;yui.html;830.2983978538133;850.188070392775;854.8546747053;867.9305655547557;874.0254616103045;13890.791638125436;14504.274786260698;15759.290831912564;16332.755008674865;16390.426335078275;13015.479380824756;13270.601881943558;14418.855811441908;17270.395536156237;17304.740428893576;960.8030353777476;996.9700908972742;1003.372891942536;1008.8890222075565;1009.2632378363799;805.267206841774;999.0409207161134;999.8050380175869;1016.0347669713765;1019.1133713669118;826.450410477032;985.6303886042734;1038.9688309350731;1051.9316244444126;1052.4316868890828;1021.2902033087007;1035.9326643768131;1036.973396337628;1037.901399367059;1038.0553696136517;763.152900280688;766.4443278622954;768.8961990131316;769.1078349114999;771.7106085218044;13596.864031359659;16976.490705278862;17021.744673829904;17146.742798858028;17770.466885993443;17003;17016.659666806594;17365.392211272672;17367.65264694699;17449.912750436168;13720.519781807689;13823.170609763345;13870.4451821927;13894.23581703006;14104.365303561364;3446.293509830479;3457.913552161191;3462.6191118976894;3469.5663042119736;3471.062813040465;95614.52192738991;102253.48873255661;112568;120613;123338.38330808378;12457.439415226336;12637.87362126367;12657.936710316573;12757.872421275853;12874.420651070724;23341.88329058344;23858.52282954331;24271.393215169763;24544.631830522558;24546.649934253786;8627.784305392415;9001.414908030956;9131.634734610541;9135.862962055575;9155.496447695441;4784.114938736345;4848.75756212195;4869.756512174377;4870.001649661811;4915.803367865316;4661.883452913704;4815.3258543803895;4820.276958456259;4837.56461918422;4866.197077482247;894.8277756139348;940.7977284883783;950.9809803803885;978.4080631218011;1001.8447140693204;599.4275466929072;643.2024289880515;662.4170729757782;680.5911762651265;696.979090627282;10366.378017318833;11276.379799111128;11700.239484433485;11742.823857642143;12096.455659495345;634.0932466572846;636.2651137935692;639.4117411980989;671.6876652356677;681.1315572644818;4912.72979986097;5508.14623733322;5716.57125715573;5739.339975902824;5806.1581070744505;73227;105515;110348;112915;114985;96096.03903961011;97260.51369743106;99039;99648;100839;79369.0157746061;79593.60203199062;79650.6017469909;80036;80514.59742701249;12196.817047744113;12703.301318427575;12752.936235318764;12783.23300601967;12824.743505130033;33694.15764605806;39276.60723392844;39283.017924552114;40481;40715;36160.73437429677;50419.49580504148;50829.745851270505;50976.980460389845;51278;14681.92659036698;15935.920320398323;16082.195890205678;16123.67752644964;16287.918560407123;17345.566360840574;17673.469795906145;17924;17933.282668693108;18098.457046288633;16247.51257462278;16292.674146516767;16864.747028794824;17055;17092.658146837242;16426.83573164253;16735.246913889147;16815.24331405065;17174;17282.13589320504;16354.591135221715;16863.072531010912;16897.91551042237;16902.40841570539;16910;11826.290422574673;11838.585649502225;11946.641600751991;12140.696482588006;12466.439010244741;11736.178467507534;13106.082574219714;13513.932430337785;13625.863741362857;13746.106503077268 02:47:06 INFO - PID 1636 | __end_tp_report 02:47:06 INFO - PID 1636 | __start_cc_report 02:47:06 INFO - PID 1636 | _x_x_mozilla_cycle_collect,432 02:47:06 INFO - PID 1636 | __end_cc_report 02:47:06 INFO - PID 1636 | __startTimestamp1492076826570__endTimestamp 02:47:06 INFO - PID 1636 | 02:47:06 INFO - PID 1636 | ------- Summary: start ------- 02:47:06 INFO - PID 1636 | Number of tests: 6 02:47:06 INFO - PID 1636 | 02:47:06 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6133.22 Median:6358.78 stddev:738.41 (11.6%) stddev-sans-first:707.13 02:47:06 INFO - PID 1636 | Values: 3328.2 3576.0 3586.4 3638.1 3646.1 5469.4 6041.9 6151.8 6230.0 6276.4 6152.1 6225.4 6252.6 6273.5 6330.0 5418.5 5474.2 5506.8 5542.0 5616.0 5406.0 5508.6 5570.6 5596.7 5614.7 5382.4 5432.6 5494.1 5502.7 5519.3 5426.9 5429.7 5434.4 5457.4 5463.9 5331.6 5358.9 5377.9 5382.7 5408.4 4659.4 6048.7 6069.4 6085.6 6106.9 6690.8 6699.2 6711.9 6723.4 6732.0 6820.4 6826.2 6832.5 6843.5 6860.3 6139.6 6238.2 6324.2 6325.7 6366.6 6951.4 6961.9 7002.0 7002.5 7035.7 6358.8 6401.2 6405.9 6436.6 6618.6 5965.9 6499.5 6762.0 6785.2 6799.2 6360.6 6425.9 6441.5 6620.8 6652.4 6389.8 6512.5 6591.8 6625.4 6669.6 5352.4 5356.9 5360.4 5375.8 5401.0 4670.9 4735.7 4762.6 4773.5 4794.1 5021.4 6307.4 6336.0 6359.4 6365.7 6240.2 6349.3 6808.7 6830.5 6841.6 6332.3 6351.1 6365.0 6375.3 6392.5 5780.7 5825.4 5826.0 5842.5 5928.3 6943.1 6995.8 7010.3 7021.0 7030.3 6707.4 6803.1 6805.2 6807.6 6807.9 5904.6 6772.5 6890.9 6981.3 7022.1 5665.4 6441.3 6595.3 6713.8 6729.3 6391.4 6698.6 6716.9 6770.4 6772.0 5360.8 6380.2 6476.1 6569.9 6595.9 6244.8 6532.7 6538.6 6549.1 6561.3 6287.7 6333.5 6352.4 6358.4 6386.3 6053.6 6283.0 6551.5 6564.5 6581.6 6412.7 6415.8 6434.4 6434.6 6446.3 02:47:06 INFO - PID 1636 | 02:47:06 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:49231.27 Median:20069.79 stddev:91028.46 (453.6%) stddev-sans-first:91210.58 02:47:06 INFO - PID 1636 | Values: 596.8 637.8 655.6 1287.7 1289.8 37606.4 38146.2 38190.0 38209.8 38222.2 37718.8 37987.0 37994.8 38139.7 38262.0 8290.0 8469.2 8540.2 8547.6 8558.7 8556.6 8661.4 8667.7 8677.9 8680.3 8605.0 8623.6 8669.9 8678.1 8688.2 8547.1 8601.1 8635.5 8640.6 8650.5 8450.2 8466.5 8468.3 8536.0 8563.0 36813.8 36952.3 37179.4 37242.1 37257.1 3568.6 3589.7 3594.0 3615.7 3618.8 30659.9 30716.5 30737.7 30774.4 30857.7 3084.6 3111.3 3120.3 3126.4 3128.6 245984.0 250469.0 295433.0 295817.1 299713.0 47480.6 48097.5 48384.5 48403.8 48403.8 51561.5 51710.0 51972.0 51974.2 52142.7 14393.1 14459.7 14511.4 14520.8 14521.9 11818.9 11916.9 11917.5 11918.2 11920.6 13359.3 13375.5 13400.8 13402.9 13448.7 4604.0 5035.7 5466.9 5948.4 6034.6 850.6 1044.5 1068.3 1074.4 1077.9 17491.0 20560.4 21319.0 24693.4 24714.4 983.5 1147.5 1173.5 1183.5 1186.2 12276.3 12624.7 16268.7 16421.3 16570.1 392403.0 393658.0 395601.0 396236.0 396607.0 55929.4 56276.4 56316.7 56396.9 56421.6 1519.7 1551.1 1557.6 1559.0 1578.7 23055.5 28094.9 29917.1 30110.7 30111.8 38470.0 38564.4 38684.4 38809.6 39663.6 351030.4 351124.0 352680.0 353134.0 353486.0 34559.3 36520.8 37399.3 37417.0 38074.2 48428.5 48761.0 48917.5 48968.0 49113.3 31797.2 32445.7 33019.7 33075.8 33422.3 23221.8 31836.2 32518.0 32802.3 32919.0 37401.0 38550.0 38605.6 38903.6 39023.8 23838.3 32247.8 33463.7 33729.8 34830.6 23449.0 25403.4 25460.9 25534.2 26003.0 02:47:06 INFO - PID 1636 | 02:47:06 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:40755.66 Median:11986.60 stddev:123312.65 (1028.8%) stddev-sans-first:123621.31 02:47:06 INFO - PID 1636 | Values: 383.7 419.8 421.2 443.1 447.8 17948.0 19438.3 21022.1 21162.6 21793.8 21842.2 21972.2 22153.4 22944.1 23040.9 5400.7 5976.6 6214.9 6239.2 6268.5 4935.4 5218.0 5873.0 6050.2 6193.4 5891.4 6140.1 6158.7 6160.4 6164.3 5975.9 6049.0 6112.4 6115.9 6118.3 5357.1 5366.6 5373.6 5417.5 5418.8 24935.9 26681.9 27053.2 27324.7 27467.6 2142.7 2176.0 2176.3 2210.8 2216.9 17056.9 17377.5 17605.2 17641.3 17917.0 2149.9 2166.6 2175.4 2183.6 2217.6 135871.3 136126.0 136509.0 136662.3 138308.0 11879.3 12352.3 12494.7 12542.4 12554.4 15825.4 16113.4 16129.4 16155.7 16162.9 7770.6 8554.7 8820.0 8828.5 8933.1 8032.9 8314.4 8331.2 8494.2 8498.7 7578.3 7646.7 7760.7 7771.7 7783.9 1929.3 2074.5 2177.6 2179.4 2219.8 984.8 1016.3 1022.3 1055.6 1064.6 19851.9 21191.8 21205.6 21390.5 21433.7 875.6 911.3 1015.1 1057.6 1070.3 10695.4 10864.8 10870.4 10934.5 11149.6 731242.1 737185.0 741301.0 747588.4 754516.0 116273.3 130338.9 130537.3 157081.0 160132.2 1194.5 1199.7 1227.0 1228.7 1232.0 17431.0 17834.1 17886.6 18043.7 18077.3 59021.4 59520.0 59748.7 59778.5 60257.1 75916.0 81698.0 82102.0 82168.0 85323.3 7208.6 8833.6 8899.5 9174.1 9321.6 14132.4 14308.7 14338.8 14708.5 14742.2 11353.3 11641.3 12110.6 12139.8 12231.8 11358.2 11493.3 11502.6 11546.6 11772.7 11102.7 11445.3 12069.7 12334.2 12384.9 8039.5 8753.6 8782.7 9140.2 9285.3 10344.7 12026.8 12191.9 12280.0 12388.1 02:47:06 INFO - PID 1636 | 02:47:06 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:7220.60 Median:2525.42 stddev:4507.44 (178.5%) stddev-sans-first:4498.40 02:47:06 INFO - PID 1636 | Values: 1337.2 1351.5 1358.3 1463.8 1505.6 11313.1 11703.5 11756.9 11841.6 12068.5 11795.9 11844.6 11970.3 12120.8 12124.8 2488.3 2510.9 2518.4 2523.2 2527.6 2410.1 2451.0 2494.7 2495.9 2497.6 2446.6 2454.0 2477.2 2482.6 2483.3 2034.4 2420.6 2435.9 2448.8 2473.6 2391.2 2449.5 2462.9 2467.3 2515.0 12049.6 12053.0 12071.0 12094.6 12118.6 6537.2 6740.7 6783.0 6787.4 6813.1 3560.3 3686.3 3688.3 4418.8 4484.1 1233.6 1246.3 1278.8 1284.3 1285.9 12828.3 14335.3 16745.9 16856.9 16887.1 5100.7 5885.7 6018.3 6199.3 6211.8 13928.8 13961.5 14004.4 14026.4 14057.9 6773.0 6841.5 6841.9 6857.0 6858.8 6094.8 6127.8 6146.2 6203.8 6228.3 4651.1 4768.8 4835.2 4865.5 4949.6 1463.6 1497.0 1504.2 1525.5 1533.2 792.1 811.1 819.7 826.5 853.1 6746.6 6820.4 7011.6 7015.5 7016.8 585.4 587.4 600.1 603.1 612.2 4193.1 4263.5 4292.8 4402.4 4450.7 14672.2 15191.5 15209.7 15314.5 15320.8 12564.4 15199.7 15200.4 15228.8 15251.4 9293.7 9622.3 10644.1 10681.3 10812.3 6702.3 7014.4 7249.1 7313.4 7372.9 11406.6 11800.6 12975.5 12982.7 12984.7 10807.4 13068.9 13132.6 13227.0 13245.0 9423.8 9532.3 9643.5 9651.9 9654.9 8991.7 10641.7 10749.8 10786.0 10922.9 9094.8 9118.7 9182.6 9218.3 9394.1 8820.4 8888.8 8970.4 8971.4 8981.8 8171.8 8314.0 8325.5 8335.8 8347.1 6076.5 6095.5 6174.9 6243.5 6281.3 6136.1 6194.6 6237.9 6293.7 6298.1 02:47:06 INFO - PID 1636 | 02:47:06 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5222.71 Median:5941.89 stddev:1681.29 (28.3%) stddev-sans-first:1646.21 02:47:06 INFO - PID 1636 | Values: 377.8 393.2 396.2 410.4 411.9 4738.3 4881.4 6079.8 6116.4 6199.2 5989.9 6100.2 6102.8 6127.1 6127.2 3703.0 4303.4 4488.8 4514.6 4525.9 4236.5 4380.1 4472.3 4504.8 4532.2 3883.7 4393.8 4433.8 4460.0 4484.8 3700.9 4286.2 4462.5 4463.9 4516.9 3827.2 3828.8 3833.2 3856.3 3900.5 5941.6 5942.2 5958.8 5965.0 5986.0 4632.6 4633.8 5057.7 5332.7 5416.6 5354.5 6165.5 6506.1 6521.0 6545.9 4202.1 4388.4 4397.4 4408.7 4410.3 6719.5 6949.0 6953.9 6965.9 6986.2 3730.5 3759.6 4441.8 4454.7 4518.3 3800.9 3807.9 3925.9 4488.8 4540.0 4733.5 5198.5 5340.9 5342.9 5430.4 5605.5 5998.0 6517.4 6613.6 6614.7 1417.9 1479.2 1537.5 1537.6 1554.4 835.8 864.8 999.1 1007.4 1040.2 5428.7 6272.5 6275.0 6290.7 6320.3 5695.9 5879.3 6668.2 6782.4 6839.4 5768.7 5929.9 6267.9 6289.7 6325.3 2952.0 2991.5 3247.9 3579.3 3593.8 6696.4 6863.5 6975.8 6986.7 6989.3 5818.9 6371.0 6877.7 6981.6 6996.7 6202.8 6828.5 6979.6 6981.4 7023.1 5537.9 6579.7 6676.5 6716.6 6808.0 5345.6 6273.4 6650.7 6698.0 6709.3 6748.3 7036.8 7038.6 7046.4 7058.3 4226.3 4387.2 4420.6 4428.4 4433.4 5728.7 6783.0 6788.8 6830.0 6838.0 5858.7 6468.8 6581.7 6586.0 6644.8 6038.8 6497.4 6546.0 6556.9 6557.9 5076.8 5162.6 6135.8 6429.8 6452.8 5999.4 6088.9 6429.9 6551.6 6598.5 5590.6 5663.6 6036.0 6422.0 6426.5 02:47:06 INFO - PID 1636 | 02:47:06 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:21193.71 Median:17366.52 stddev:29554.23 (170.2%) stddev-sans-first:29597.59 02:47:06 INFO - PID 1636 | Values: 830.3 850.2 854.9 867.9 874.0 13890.8 14504.3 15759.3 16332.8 16390.4 13015.5 13270.6 14418.9 17270.4 17304.7 960.8 997.0 1003.4 1008.9 1009.3 805.3 999.0 999.8 1016.0 1019.1 826.5 985.6 1039.0 1051.9 1052.4 1021.3 1035.9 1037.0 1037.9 1038.1 763.2 766.4 768.9 769.1 771.7 13596.9 16976.5 17021.7 17146.7 17770.5 17003.0 17016.7 17365.4 17367.7 17449.9 13720.5 13823.2 13870.4 13894.2 14104.4 3446.3 3457.9 3462.6 3469.6 3471.1 95614.5 102253.5 112568.0 120613.0 123338.4 12457.4 12637.9 12657.9 12757.9 12874.4 23341.9 23858.5 24271.4 24544.6 24546.6 8627.8 9001.4 9131.6 9135.9 9155.5 4784.1 4848.8 4869.8 4870.0 4915.8 4661.9 4815.3 4820.3 4837.6 4866.2 894.8 940.8 951.0 978.4 1001.8 599.4 643.2 662.4 680.6 697.0 10366.4 11276.4 11700.2 11742.8 12096.5 634.1 636.3 639.4 671.7 681.1 4912.7 5508.1 5716.6 5739.3 5806.2 73227.0 105515.0 110348.0 112915.0 114985.0 96096.0 97260.5 99039.0 99648.0 100839.0 79369.0 79593.6 79650.6 80036.0 80514.6 12196.8 12703.3 12752.9 12783.2 12824.7 33694.2 39276.6 39283.0 40481.0 40715.0 36160.7 50419.5 50829.7 50977.0 51278.0 14681.9 15935.9 16082.2 16123.7 16287.9 17345.6 17673.5 17924.0 17933.3 18098.5 16247.5 16292.7 16864.7 17055.0 17092.7 16426.8 16735.2 16815.2 17174.0 17282.1 16354.6 16863.1 16897.9 16902.4 16910.0 11826.3 11838.6 11946.6 12140.7 12466.4 11736.2 13106.1 13513.9 13625.9 13746.1 02:47:06 INFO - PID 1636 | -------- Summary: end -------- 02:47:06 INFO - PID 1636 | 02:47:07 INFO - TEST-INFO | 1636: exit 0 02:47:07 INFO - TEST-OK | dromaeo_css | took 1116518ms 02:47:07 INFO - TEST-START | kraken 02:47:07 INFO - operating with platform_type : mac_ 02:47:07 INFO - Initialising browser for kraken test... 02:47:07 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpoh_0UN/profile http://localhost:49241/getInfo.html) 02:47:10 INFO - PID 1649 | 2017-04-13 02:47:10.316 plugin-container[1650:10222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 02:47:10 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:47:10 INFO - PID 1649 | 2017-04-13 02:47:10.319 plugin-container[1650:10222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 02:47:10 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:47:10 INFO - PID 1649 | 2017-04-13 02:47:10.319 plugin-container[1650:10222] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:47:10 INFO - PID 1649 | __metrics Screen width/height:1600/1200 02:47:10 INFO - PID 1649 | colorDepth:24 02:47:10 INFO - PID 1649 | Browser inner width/height: 1024/689 02:47:10 INFO - PID 1649 | __metrics 02:47:10 ERROR - PID 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 02:47:11 INFO - TEST-INFO | 1649: exit 0 02:47:11 INFO - Browser initialized. 02:47:11 INFO - Running cycle 1/1 for kraken test... 02:47:11 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpoh_0UN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:47:13 INFO - PID 1651 | 2017-04-13 02:47:13.778 plugin-container[1652:10346] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 02:47:13 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:47:13 INFO - PID 1651 | 2017-04-13 02:47:13.779 plugin-container[1652:10346] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 02:47:13 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:47:13 INFO - PID 1651 | 2017-04-13 02:47:13.779 plugin-container[1652:10346] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:47:23 INFO - PID 1651 | RSS: Main: 234696704 02:47:23 INFO - PID 1651 | 02:47:26 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 02:47:27 INFO - PID 1651 | RSS: Main: 248881152 02:47:27 INFO - PID 1651 | 02:47:29 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 02:47:30 INFO - PID 1651 | RSS: Main: 257372160 02:47:30 INFO - PID 1651 | 02:47:33 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 02:47:34 INFO - PID 1651 | RSS: Main: 259928064 02:47:34 INFO - PID 1651 | 02:47:36 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 02:47:37 INFO - PID 1651 | RSS: Main: 261251072 02:47:37 INFO - PID 1651 | 02:47:39 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:47:39 INFO - PID 1651 | RSS: Main: 261693440 02:47:39 INFO - PID 1651 | 02:47:42 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 02:47:43 INFO - PID 1651 | RSS: Main: 268709888 02:47:43 INFO - PID 1651 | 02:47:46 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 02:47:47 INFO - PID 1651 | RSS: Main: 269131776 02:47:47 INFO - PID 1651 | 02:47:50 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 02:47:51 INFO - PID 1651 | RSS: Main: 269168640 02:47:51 INFO - PID 1651 | 02:47:52 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:47:53 INFO - PID 1651 | RSS: Main: 268828672 02:47:53 INFO - PID 1651 | 02:47:55 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:47:56 INFO - PID 1651 | RSS: Main: 268906496 02:47:56 INFO - PID 1651 | 02:47:58 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:47:58 INFO - PID 1651 | RSS: Main: 268910592 02:47:58 INFO - PID 1651 | 02:48:01 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:48:02 INFO - PID 1651 | RSS: Main: 269111296 02:48:02 INFO - PID 1651 | 02:48:05 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:48:06 INFO - PID 1651 | RSS: Main: 269312000 02:48:06 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 02:48:08 INFO - PID 1651 | RSS: Main: 271101952 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | __start_tp_report 02:48:08 INFO - PID 1651 | _x_x_mozilla_page_load 02:48:08 INFO - PID 1651 | _x_x_mozilla_page_load_details 02:48:08 INFO - PID 1651 | |i|pagename|runs| 02:48:08 INFO - PID 1651 | |0;ai-astar;104;116;114;113;116;116;114;115;116;116 02:48:08 INFO - PID 1651 | |1;audio-beat-detection;147;110;123;191;151;108;152;132;168;101 02:48:08 INFO - PID 1651 | |2;audio-dft;199;198;207;217;247;247;196;200;203;194 02:48:08 INFO - PID 1651 | |3;audio-fft;78;62;74;73;48;65;83;88;93;86 02:48:08 INFO - PID 1651 | |4;audio-oscillator;88;70;82;72;71;70;88;70;73;91 02:48:08 INFO - PID 1651 | |5;imaging-gaussian-blur;92;91;91;91;91;91;108;91;91;85 02:48:08 INFO - PID 1651 | |6;imaging-darkroom;103;154;115;156;138;98;141;143;98;100 02:48:08 INFO - PID 1651 | |7;imaging-desaturate;126;141;125;91;134;148;92;92;84;148 02:48:08 INFO - PID 1651 | |8;json-parse-financial;53;53;51;54;46;47;74;83;83;75 02:48:08 INFO - PID 1651 | |9;json-stringify-tinderbox;64;59;59;63;73;65;59;56;58;77 02:48:08 INFO - PID 1651 | |10;stanford-crypto-aes;87;85;87;88;86;85;88;87;85;106 02:48:08 INFO - PID 1651 | |11;stanford-crypto-ccm;144;145;165;157;158;173;165;138;202;179 02:48:08 INFO - PID 1651 | |12;stanford-crypto-pbkdf2;190;189;192;194;198;189;188;192;192;190 02:48:08 INFO - PID 1651 | |13;stanford-crypto-sha256-iterative;70;68;70;71;68;68;73;70;70;69 02:48:08 INFO - PID 1651 | __end_tp_report 02:48:08 INFO - PID 1651 | __start_cc_report 02:48:08 INFO - PID 1651 | _x_x_mozilla_cycle_collect,1019 02:48:08 INFO - PID 1651 | __end_cc_report 02:48:08 INFO - PID 1651 | __startTimestamp1492076888573__endTimestamp 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | ------- Summary: start ------- 02:48:08 INFO - PID 1651 | Number of tests: 14 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#0] ai-astar Cycles:10 Average:114.00 Median:116.00 stddev:3.68 (3.2%) stddev-sans-first:1.17 02:48:08 INFO - PID 1651 | Values: 104.0 116.0 114.0 113.0 116.0 116.0 114.0 115.0 116.0 116.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:149.00 stddev:28.81 (19.3%) stddev-sans-first:30.38 02:48:08 INFO - PID 1651 | Values: 147.0 110.0 123.0 191.0 151.0 108.0 152.0 132.0 168.0 101.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#2] audio-dft Cycles:10 Average:210.80 Median:205.00 stddev:20.15 (9.8%) stddev-sans-first:20.92 02:48:08 INFO - PID 1651 | Values: 199.0 198.0 207.0 217.0 247.0 247.0 196.0 200.0 203.0 194.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#3] audio-fft Cycles:10 Average:75.00 Median:80.50 stddev:13.70 (17.0%) stddev-sans-first:14.49 02:48:08 INFO - PID 1651 | Values: 78.0 62.0 74.0 73.0 48.0 65.0 83.0 88.0 93.0 86.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:77.50 stddev:8.72 (11.3%) stddev-sans-first:8.38 02:48:08 INFO - PID 1651 | Values: 88.0 70.0 82.0 72.0 71.0 70.0 88.0 70.0 73.0 91.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:91.00 stddev:5.88 (6.5%) stddev-sans-first:6.24 02:48:08 INFO - PID 1651 | Values: 92.0 91.0 91.0 91.0 91.0 91.0 108.0 91.0 91.0 85.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#6] imaging-darkroom Cycles:10 Average:124.60 Median:148.50 stddev:24.07 (16.2%) stddev-sans-first:24.23 02:48:08 INFO - PID 1651 | Values: 103.0 154.0 115.0 156.0 138.0 98.0 141.0 143.0 98.0 100.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#7] imaging-desaturate Cycles:10 Average:118.10 Median:116.00 stddev:25.68 (22.1%) stddev-sans-first:27.08 02:48:08 INFO - PID 1651 | Values: 126.0 141.0 125.0 91.0 134.0 148.0 92.0 92.0 84.0 148.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:64.00 stddev:14.99 (23.4%) stddev-sans-first:15.55 02:48:08 INFO - PID 1651 | Values: 53.0 53.0 51.0 54.0 46.0 47.0 74.0 83.0 83.0 75.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:63.50 stddev:6.85 (10.8%) stddev-sans-first:7.26 02:48:08 INFO - PID 1651 | Values: 64.0 59.0 59.0 63.0 73.0 65.0 59.0 56.0 58.0 77.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:87.00 stddev:6.29 (7.2%) stddev-sans-first:6.65 02:48:08 INFO - PID 1651 | Values: 87.0 85.0 87.0 88.0 86.0 85.0 88.0 87.0 85.0 106.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:162.60 Median:165.00 stddev:19.02 (11.5%) stddev-sans-first:18.94 02:48:08 INFO - PID 1651 | Values: 144.0 145.0 165.0 157.0 158.0 173.0 165.0 138.0 202.0 179.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.40 Median:192.00 stddev:2.95 (1.5%) stddev-sans-first:3.09 02:48:08 INFO - PID 1651 | Values: 190.0 189.0 192.0 194.0 198.0 189.0 188.0 192.0 192.0 190.0 02:48:08 INFO - PID 1651 | 02:48:08 INFO - PID 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:70.00 stddev:1.57 (2.2%) stddev-sans-first:1.66 02:48:08 INFO - PID 1651 | Values: 70.0 68.0 70.0 71.0 68.0 68.0 73.0 70.0 70.0 69.0 02:48:08 INFO - PID 1651 | -------- Summary: end -------- 02:48:08 INFO - PID 1651 | 02:48:09 INFO - TEST-INFO | 1651: exit 0 02:48:09 INFO - TEST-OK | kraken | took 62536ms 02:48:09 INFO - SUITE-END | took 1179s 02:48:10 INFO - Completed test suite (00:19:39) 02:48:10 INFO - javascript benchmark 02:48:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7910.763515188638, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3328.18459477428, 3575.964240357597, 3586.4082426399646, 3638.1268495561067, 3646.124930016797, 5469.371022332428, 6041.852048110862, 6151.846203844895, 6229.968850155743, 6276.435120839124, 6152.138700581911, 6225.439710426061, 6252.593581417216, 6273.529485288599, 6329.968350158243, 5418.512333889949, 5474.233607294982, 5506.752196151183, 5542.0, 5615.989121958046, 5405.972970135143, 5508.614396992212, 5570.610057295991, 5596.664200147984, 5614.691191984439, 5382.381026182, 5432.619716619838, 5494.09347457669, 5502.697351645658, 5519.282493275889, 5426.945730542683, 5429.728513574345, 5434.429384914567, 5457.372402173722, 5463.9180412293845, 5331.587129410709, 5358.866028349284, 5377.892442151174, 5382.650127741685, 5408.4050754416985, 4659.4175728034, 6048.667323297217, 6069.393060693896, 6085.604435711664, 6106.885399019245, 6690.765823196213, 6699.162604674416, 6711.899321510181, 6723.428508576777, 6732.0, 6820.420264277543, 6826.24911259761, 6832.487563432763, 6843.452523798084, 6860.279670634562, 6139.584134579375, 6238.157848690459, 6324.2330706143985, 6325.652089135142, 6366.6180029198395, 6951.443884489228, 6961.895571566431, 7001.964990175016, 7002.509824312351, 7035.683394247271, 6358.77744278948, 6401.167848179707, 6405.935940640534, 6436.601410952233, 6618.63597502142, 5965.880682386328, 6499.54503184782, 6762.019507171432, 6785.185777706707, 6799.150106236721, 6360.586561873556, 6425.871482570323, 6441.452476539546, 6620.768273110418, 6652.368025037614, 6389.840253993594, 6512.544121911421, 6591.802245932629, 6625.370589793962, 6669.566478178903, 5352.411234764173, 5356.892862142735, 5360.356757189163, 5375.81184658535, 5401.02781499326, 4670.906581868344, 4735.739534325645, 4762.594599994991, 4773.451053128865, 4794.089123066607, 5021.447640759514, 6307.432331090224, 6336.017917222745, 6359.427651511386, 6365.681715914186, 6240.188775459252, 6349.333320001426, 6808.659567021629, 6830.487713421513, 6841.555298905655, 6332.303446620868, 6351.142595749607, 6365.0, 6375.266844312963, 6392.520560957853, 5780.682062486603, 5825.38833422493, 5826.038703613941, 5842.474177324061, 5928.258967629046, 6943.097397338313, 6995.825104372431, 7010.298970102949, 7020.964895175492, 7030.2969703029285, 6707.396334329934, 6803.115594972622, 6805.217399999064, 6807.557508762113, 6807.9319206807295, 5904.61619994699, 6772.52592318533, 6890.8621827564175, 6981.3367730065565, 7022.052022976833, 5665.433456654301, 6441.291457939529, 6595.340465953366, 6713.832154196184, 6729.259781424137, 6391.392817682313, 6698.5980841149685, 6716.865662686817, 6770.390664840188, 6772.018057381751, 5360.839174824761, 6380.234371875405, 6476.093346931528, 6569.9343006569325, 6595.868082638224, 6244.750209991549, 6532.673366331569, 6538.574992625654, 6549.148610680581, 6561.3438656134, 6287.748490060347, 6333.524985626004, 6352.396522330371, 6358.364163583605, 6386.297507274289, 6053.60651557665, 6282.968585157045, 6551.475881929339, 6564.5076619252795, 6581.572197807319, 6412.743490260337, 6415.839604009937, 6434.388733070351, 6434.58175218628, 6446.258680251643], "value": 6085.54604872321, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.8358701357126, 637.8277864976457, 655.6492276632002, 1287.7360141171062, 1289.7742894993373, 37606.43590346147, 38146.23707525855, 38190.0, 38209.808950955245, 38222.23555528888, 37718.811405943, 37987.0, 37994.810025949904, 38139.66511172109, 38262.0, 8290.046644635868, 8469.153084691543, 8540.188682075193, 8547.615357308936, 8558.657653693876, 8556.572171391437, 8661.437006594551, 8667.739967800977, 8677.869831952527, 8680.348973826956, 8604.956975215115, 8623.568821558896, 8669.913300866974, 8678.132186781302, 8688.218060374566, 8547.102554231778, 8601.13988601138, 8635.52504612246, 8640.567971601458, 8650.524221167832, 8450.19723126302, 8466.492010479391, 8468.32253419725, 8535.95732021336, 8563.0, 36813.81593092044, 36952.26095478075, 37179.4423083654, 37242.06894827624, 37257.068573285615, 3568.553930758655, 3589.712822974182, 3594.0, 3615.7468977171607, 3618.764780289273, 30659.926902558527, 30716.53925191124, 30737.692623073708, 30774.38451230963, 30857.69142308593, 3084.64526579444, 3111.2688518198206, 3120.2979329650875, 3126.437241296566, 3128.577642018344, 245984.0, 250469.0, 295433.0, 295817.1483420073, 299713.0, 47480.57558273257, 48097.519024810004, 48384.516154838704, 48403.757981210576, 48403.78990525265, 51561.48438515642, 51710.0, 51972.0, 51974.22038669423, 52142.73928630409, 14393.136411815325, 14459.710805783825, 14511.419543218364, 14520.83833293334, 14521.92739036298, 11818.940905295418, 11916.94041529804, 11917.52329906794, 11918.225315354473, 11920.582779602672, 13359.332033398292, 13375.53185638498, 13400.798988015187, 13402.865971340358, 13448.731025379633, 4603.976980115078, 5035.697858128524, 5466.9179962300595, 5948.375420580864, 6034.63792172471, 850.6172222499899, 1044.472541366605, 1068.2789117345915, 1074.4466599701161, 1077.8814330423804, 17491.03799291051, 20560.38318850437, 21319.0, 24693.38266543315, 24714.382140446633, 983.4885859353096, 1147.4951021550492, 1173.524722487394, 1183.4556104191995, 1186.2052424875353, 12276.263424194398, 12624.747505049847, 16268.67462650764, 16421.343146274256, 16570.050480084927, 392403.0, 393658.0, 395601.0, 396236.0, 396607.01696491335, 55929.44070559324, 56276.43723562794, 56316.71841640765, 56396.87206255852, 56421.58946026382, 1519.7340465418729, 1551.0538571471393, 1557.5716677913665, 1559.0411896683459, 1578.6921550297582, 23055.538889221785, 28094.87620495239, 29917.073996276515, 30110.69889301079, 30111.795528178627, 38470.0, 38564.42153367758, 38684.41973370346, 38809.61190388173, 39663.603363967144, 351030.44847756997, 351124.0, 352680.0, 353134.0, 353486.0, 34559.30881382308, 36520.81739591285, 37399.252014959005, 37417.0, 38074.23851523009, 48428.51571484381, 48761.0, 48917.510824891295, 48968.0, 49113.26330105123, 31797.20506987344, 32445.675543244266, 33019.66980330166, 33075.83462082771, 33422.33155336831, 23221.76778232196, 31836.204094897814, 32518.0, 32802.343953120326, 32919.0, 37401.0, 38550.036249093995, 38605.613943861325, 38903.61096389, 39023.80488097541, 23838.293205722824, 32247.838760806048, 33463.66536334703, 33729.83135084309, 34830.60677572869, 23449.0, 25403.36491587725, 25460.872695637143, 25534.23397298084, 26003.0], "value": 18088.455567765337, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.7198844843264, 419.8194776246217, 421.1997205309911, 443.0695539367329, 447.84549330480974, 17948.0, 19438.319658811943, 21022.05400756966, 21162.576748465042, 21793.782062179373, 21842.235521756742, 21972.230971915986, 22153.44616384587, 22944.082236710554, 23040.884795576, 5400.702961337125, 5976.5517586180995, 6214.875702485948, 6239.220097487814, 6268.467180289681, 4935.35840340757, 5218.034663587229, 5873.0, 6050.2134722486235, 6193.380661933793, 5891.440313170242, 6140.140380346755, 6158.722857471402, 6160.445559899608, 6164.321924588292, 5975.85060373492, 6049.0, 6112.449879510866, 6115.908261376083, 6118.3269840317525, 5357.116075847481, 5366.597505187126, 5373.623846330758, 5417.539509141728, 5418.83743487696, 24935.877885495196, 26681.93272269107, 27053.1884043479, 27324.72675273242, 27467.587986180224, 2142.678598210265, 2176.0425412818468, 2176.270949232009, 2210.80102790748, 2216.9224077157223, 17056.91471542647, 17377.47867563975, 17605.207765650448, 17641.294348226056, 17917.0, 2149.881756503392, 2166.6316726156433, 2175.445261458318, 2183.5960347335636, 2217.634090375086, 135871.32064339714, 136126.0, 136509.0, 136662.3166884169, 138308.0, 11879.287242765462, 12352.320622365856, 12494.687632809253, 12542.428802095548, 12554.435050422753, 15825.366985320457, 16113.355465781471, 16129.435469758502, 16155.676886462204, 16162.919185403998, 7770.640137975832, 8554.700585479479, 8819.955900220459, 8828.51443170619, 8933.062028486915, 8032.919670803334, 8314.376421768391, 8331.208535189147, 8494.193051660082, 8498.70254541088, 7578.3179513842915, 7646.732364367221, 7760.728374506865, 7771.689132434639, 7783.883241751379, 1929.2861641192849, 2074.543600407908, 2177.5536015116863, 2179.4115588791256, 2219.82241420686, 984.8128855517429, 1016.3393794033938, 1022.2741853284103, 1055.646358469921, 1064.5528877871313, 19851.908145052163, 21191.83444910514, 21205.575888482454, 21390.465238369165, 21433.67849482259, 875.627858160292, 911.334725650279, 1015.1168483419473, 1057.582255009267, 1070.3310430980637, 10695.358278503314, 10864.837027444595, 10870.402127883042, 10934.4532773361, 11149.609763658395, 731242.0627381187, 737185.0, 741301.0, 747588.4067535592, 754516.0, 116273.3490660364, 130338.87627866148, 130537.34731326283, 157081.0, 160132.19933900255, 1194.5341316886422, 1199.7120691034088, 1226.9509219631293, 1228.7419641875128, 1231.956881509143, 17431.0, 17834.10829458496, 17886.5528361792, 18043.729344060113, 18077.276908923497, 59021.40978590159, 59520.0, 59748.70125649518, 59778.50553736191, 60257.09614355876, 75916.0, 81698.0, 82102.0, 82168.0, 85323.2935341302, 7208.603526805866, 8833.558322083998, 8899.466032037839, 9174.128457796498, 9321.580528876082, 14132.364043617856, 14308.713825723218, 14338.784918226444, 14708.485203018272, 14742.189179595225, 11353.318800871974, 11641.301521908712, 12110.636680899586, 12139.817902731642, 12231.75536489283, 11358.204925655455, 11493.310401375944, 11502.597409090642, 11546.59586914454, 11772.705682357668, 11102.666919992413, 11445.313281202822, 12069.698257543632, 12334.198277111958, 12384.876151238372, 8039.477433966774, 8753.60608772594, 8782.736517904474, 9140.177384035203, 9285.303602229726, 10344.741381465523, 12026.75946481083, 12191.939040304742, 12280.01759859227, 12388.070894682754], "value": 10456.585444604845, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.2043634037743, 1351.452661672023, 1358.2529608715204, 1463.836250181106, 1505.5934897577654, 11313.094952403808, 11703.531858725652, 11756.882431175707, 11841.585544505944, 12068.456919438624, 11795.882041179586, 11844.637866645338, 11970.34163121028, 12120.81818772719, 12124.81812772807, 2488.340589743719, 2510.899564017437, 2518.357818756224, 2523.205190365038, 2527.633493143493, 2410.144398738449, 2450.9754902451014, 2494.725580186178, 2495.8752062396898, 2497.5629264878667, 2446.584080706284, 2454.018392642939, 2477.1949116537107, 2482.565551028563, 2483.267436106344, 2034.369345502884, 2420.6006009008493, 2435.9391015224583, 2448.8163387745903, 2473.579491486452, 2391.234804862445, 2449.461118553915, 2462.852228866274, 2467.321486591202, 2514.962275565868, 12049.638510844688, 12053.035757139407, 12071.0, 12094.637160885188, 12118.575849845301, 6537.248216455121, 6740.696668649923, 6783.0164626128935, 6787.389134977876, 6813.11429514165, 3560.3413368526903, 3686.299603075407, 3688.2623475305045, 4418.779061046935, 4484.080763443519, 1233.5929143382662, 1246.2647038247478, 1278.8081787731821, 1284.319310765296, 1285.8649841766573, 12828.294443805587, 14335.283235838271, 16745.916270418693, 16856.915715421466, 16887.071211083385, 5100.69395836251, 5885.676287804126, 6018.307894592091, 6199.3490683478485, 6211.844703882439, 13928.791068133985, 13961.511347102803, 14004.43982240699, 14026.438942442188, 14057.85942140586, 6772.966135169391, 6841.486888483284, 6841.863162736718, 6856.965715171492, 6858.794236172923, 6094.786682466092, 6127.816165515042, 6146.231721034871, 6203.844903877439, 6228.34602366745, 4651.139539185261, 4768.833090841804, 4835.153848076643, 4865.537773911473, 4949.554540091337, 1463.5755630867143, 1496.9850301497063, 1504.2403586188905, 1525.5347119128771, 1533.1644253881661, 792.0930534537922, 811.1158836867843, 819.6967122164838, 826.5247482697473, 853.0786750309651, 6746.628935408501, 6820.35206655367, 7011.579305241702, 7015.543989640657, 7016.789496315118, 585.4379795396381, 587.4125874125874, 600.1118344849647, 603.1465476351002, 612.2499937576433, 4193.119444916601, 4263.488381394252, 4292.806823692941, 4402.427684401007, 4450.710703804242, 14672.193029383487, 15191.468298609496, 15209.695806083817, 15314.463993760164, 15320.846791531942, 12564.371781411075, 15199.696006079817, 15200.391984320504, 15228.771568426482, 15251.38994440232, 9293.72118836436, 9622.326437149472, 10644.09525190352, 10681.252312338062, 10812.297200681931, 6702.296258892941, 7014.438844892295, 7249.05762250904, 7313.414926805949, 7372.857207132903, 11406.604613031357, 11800.586979455678, 12975.480980760663, 12982.675433114247, 12984.67538311512, 10807.405592692163, 13068.869311306766, 13132.606021819362, 13227.0, 13245.006624503008, 9423.811523769624, 9532.285078618992, 9643.517824108907, 9651.855222171813, 9654.903450965681, 8991.730248092568, 10641.733956651145, 10749.838752418564, 10786.0, 10922.890771092187, 9094.818103637757, 9118.726438206864, 9182.632694692405, 9218.262538996998, 9394.107559781809, 8820.426672266281, 8888.777780555538, 8970.37207395476, 8971.372003959661, 8981.820363592822, 8171.83656326882, 8314.043884953307, 8325.458845175044, 8335.833283334421, 8347.123552027193, 6076.453119219292, 6095.451409373178, 6174.919389106799, 6243.500519958302, 6281.309056003744, 6136.110264011604, 6194.5973511721595, 6237.906431403623, 6293.685315734287, 6298.0552917062805], "value": 5425.426502855391, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.79221428214476, 393.1625637392354, 396.24910794045286, 410.41720756525723, 411.8723195809299, 4738.28925661151, 4881.389826271716, 6079.814436184945, 6116.449519543235, 6199.225096862892, 5989.940100598993, 6100.176476175715, 6102.816915492531, 6127.142200091991, 6127.16185144456, 3703.0, 4303.35449682547, 4488.775561221943, 4514.616257618107, 4525.886852828673, 4236.512801027875, 4380.08018316154, 4472.262076757347, 4504.797284122206, 4532.229520981425, 3883.708721845873, 4393.846215382462, 4433.778311084449, 4459.955400446002, 4484.833943174782, 3700.889733080065, 4286.249906266422, 4462.509123996358, 4463.933041004388, 4516.9096618067615, 3827.162961778334, 3828.7894165820867, 3833.233353329317, 3856.2865869814173, 3900.4539364488996, 5941.613795103304, 5942.168096466498, 5958.821235362946, 5965.0, 5986.013986013986, 4632.559906808848, 4633.814647414099, 5057.69653820772, 5332.733363331857, 5416.647917885325, 5354.5180933715765, 6165.537584681122, 6506.056621789814, 6521.021846722982, 6545.869082618321, 4202.0880499372715, 4388.442103053402, 4397.428334316551, 4408.691391602589, 4410.2723050696595, 6719.462443004548, 6948.9576563515375, 6953.89569156463, 6965.930340696579, 6986.192525073847, 3730.459083432887, 3759.6240375962734, 4441.800118994653, 4454.665900057508, 4518.299663552155, 3800.9429858552144, 3807.8957102440536, 3925.862594809168, 4488.832903445128, 4539.954600453953, 4733.502982186821, 5198.518422249641, 5340.8664783379945, 5342.91985620216, 5430.42980487051, 5605.467480589337, 5998.0, 6517.446017088506, 6613.603183808987, 6614.735410583523, 1417.9432822686977, 1479.2381923309504, 1537.5464238049801, 1537.6309685675358, 1554.4326320892792, 835.7910522369408, 864.7665130414753, 999.1307562420741, 1007.4005966449951, 1040.2302296300684, 5428.70142142186, 6272.529560282997, 6274.964630835861, 6290.654014029181, 6320.27316858558, 5695.889301586234, 5879.323877754027, 6668.154429814735, 6782.423494003065, 6839.366843765186, 5768.653880767083, 5929.911051334234, 6267.905981410283, 6289.7484100635465, 6325.335839736761, 2952.0, 2991.4914464540643, 3247.8700851966128, 3579.266250418658, 3593.838277277529, 6696.397324240842, 6863.485238607124, 6975.79072627822, 6986.685599148051, 6989.266127056688, 5818.854528636818, 6371.044343348442, 6877.724891004404, 6981.616011119336, 6996.6851491683, 6202.782902598387, 6828.453723702092, 6979.581225126509, 6981.359380545568, 7023.12210973618, 5537.916931246115, 6579.703913324008, 6676.544760521665, 6716.597004179765, 6808.0, 5345.599080069088, 6273.435390814849, 6650.703112892939, 6697.966510167418, 6709.329067093252, 6748.291429399842, 7036.788896333118, 7038.648067596702, 7046.436285097282, 7058.294170583106, 4226.260404429151, 4387.188370151533, 4420.557944205554, 4428.380026796187, 4433.357163211252, 5728.713564321851, 6783.0, 6788.830279243059, 6830.043793868763, 6838.0, 5858.736356863866, 6468.838279043061, 6581.736730530725, 6585.967070164618, 6644.840426861386, 6038.75844966214, 6497.4477169441125, 6546.0, 6556.868862622816, 6557.868842623217, 5076.847694569169, 5162.561182299396, 6135.754569817157, 6429.807105786834, 6452.838679033061, 5999.370066143167, 6088.934436473688, 6429.935700642934, 6551.639659818756, 6598.538102332791, 5590.580706446952, 5663.575231857545, 6035.969820150871, 6421.967890160706, 6426.518011149089], "value": 4697.83973144484, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.2983978538133, 850.188070392775, 854.8546747053, 867.9305655547557, 874.0254616103045, 13890.791638125436, 14504.274786260698, 15759.290831912564, 16332.755008674865, 16390.426335078275, 13015.479380824756, 13270.601881943558, 14418.855811441908, 17270.395536156237, 17304.740428893576, 960.8030353777476, 996.9700908972742, 1003.372891942536, 1008.8890222075565, 1009.2632378363799, 805.267206841774, 999.0409207161134, 999.8050380175869, 1016.0347669713765, 1019.1133713669118, 826.450410477032, 985.6303886042734, 1038.9688309350731, 1051.9316244444126, 1052.4316868890828, 1021.2902033087007, 1035.9326643768131, 1036.973396337628, 1037.901399367059, 1038.0553696136517, 763.152900280688, 766.4443278622954, 768.8961990131316, 769.1078349114999, 771.7106085218044, 13596.864031359659, 16976.490705278862, 17021.744673829904, 17146.742798858028, 17770.466885993443, 17003.0, 17016.659666806594, 17365.392211272672, 17367.65264694699, 17449.912750436168, 13720.519781807689, 13823.170609763345, 13870.4451821927, 13894.23581703006, 14104.365303561364, 3446.293509830479, 3457.913552161191, 3462.6191118976894, 3469.5663042119736, 3471.062813040465, 95614.52192738991, 102253.48873255661, 112568.0, 120613.0, 123338.38330808378, 12457.439415226336, 12637.87362126367, 12657.936710316573, 12757.872421275853, 12874.420651070724, 23341.88329058344, 23858.52282954331, 24271.393215169763, 24544.631830522558, 24546.649934253786, 8627.784305392415, 9001.414908030956, 9131.634734610541, 9135.862962055575, 9155.496447695441, 4784.114938736345, 4848.75756212195, 4869.756512174377, 4870.001649661811, 4915.803367865316, 4661.883452913704, 4815.3258543803895, 4820.276958456259, 4837.56461918422, 4866.197077482247, 894.8277756139348, 940.7977284883783, 950.9809803803885, 978.4080631218011, 1001.8447140693204, 599.4275466929072, 643.2024289880515, 662.4170729757782, 680.5911762651265, 696.979090627282, 10366.378017318833, 11276.379799111128, 11700.239484433485, 11742.823857642143, 12096.455659495345, 634.0932466572846, 636.2651137935692, 639.4117411980989, 671.6876652356677, 681.1315572644818, 4912.72979986097, 5508.14623733322, 5716.57125715573, 5739.339975902824, 5806.1581070744505, 73227.0, 105515.0, 110348.0, 112915.0, 114985.0, 96096.03903961011, 97260.51369743106, 99039.0, 99648.0, 100839.0, 79369.0157746061, 79593.60203199062, 79650.6017469909, 80036.0, 80514.59742701249, 12196.817047744113, 12703.301318427575, 12752.936235318764, 12783.23300601967, 12824.743505130033, 33694.15764605806, 39276.60723392844, 39283.017924552114, 40481.0, 40715.0, 36160.73437429677, 50419.49580504148, 50829.745851270505, 50976.980460389845, 51278.0, 14681.92659036698, 15935.920320398323, 16082.195890205678, 16123.67752644964, 16287.918560407123, 17345.566360840574, 17673.469795906145, 17924.0, 17933.282668693108, 18098.457046288633, 16247.51257462278, 16292.674146516767, 16864.747028794824, 17055.0, 17092.658146837242, 16426.83573164253, 16735.246913889147, 16815.24331405065, 17174.0, 17282.13589320504, 16354.591135221715, 16863.072531010912, 16897.91551042237, 16902.40841570539, 16910.0, 11826.290422574673, 11838.585649502225, 11946.641600751991, 12140.696482588006, 12466.439010244741, 11736.178467507534, 13106.082574219714, 13513.932430337785, 13625.863741362857, 13746.106503077268], "value": 8353.248667761278, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1587.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 114.0, 113.0, 116.0, 116.0, 114.0, 115.0, 116.0, 116.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 123.0, 191.0, 151.0, 108.0, 152.0, 132.0, 168.0, 101.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 198.0, 207.0, 217.0, 247.0, 247.0, 196.0, 200.0, 203.0, 194.0], "value": 210.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 62.0, 74.0, 73.0, 48.0, 65.0, 83.0, 88.0, 93.0, 86.0], "value": 75.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 82.0, 72.0, 71.0, 70.0, 88.0, 70.0, 73.0, 91.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 91.0, 91.0, 91.0, 91.0, 108.0, 91.0, 91.0, 85.0], "value": 92.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 154.0, 115.0, 156.0, 138.0, 98.0, 141.0, 143.0, 98.0, 100.0], "value": 124.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 141.0, 125.0, 91.0, 134.0, 148.0, 92.0, 92.0, 84.0, 148.0], "value": 118.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 51.0, 54.0, 46.0, 47.0, 74.0, 83.0, 83.0, 75.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 59.0, 59.0, 63.0, 73.0, 65.0, 59.0, 56.0, 58.0, 77.0], "value": 63.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 88.0, 86.0, 85.0, 88.0, 87.0, 85.0, 106.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 145.0, 165.0, 157.0, 158.0, 173.0, 165.0, 138.0, 202.0, 179.0], "value": 162.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 189.0, 192.0, 194.0, 198.0, 189.0, 188.0, 192.0, 192.0, 190.0], "value": 191.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 70.0, 71.0, 68.0, 68.0, 73.0, 70.0, 70.0, 69.0], "value": 69.7, "unit": "score"}]}]} 02:48:10 INFO - Return code: 0 02:48:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:48:10 ERROR - # TBPL SUCCESS # 02:48:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:48:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:48:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:48:10 INFO - 2017-04-13 02:48:10.408 screenresolution[1656:10743] starting screenresolution argv=screenresolution get 02:48:10 INFO - 2017-04-13 02:48:10.411 screenresolution[1656:10743] Display 0: 1600x1200x32@60 02:48:10 INFO - 2017-04-13 02:48:10.417 screenresolution[1657:10746] starting screenresolution argv=screenresolution list 02:48:10 INFO - Available Modes on Display 0 02:48:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:48:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:48:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:48:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:48:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:48:10 INFO - Intel Iris: 02:48:10 INFO - Chipset Model: Intel Iris 02:48:10 INFO - Type: GPU 02:48:10 INFO - Bus: Built-In 02:48:10 INFO - VRAM (Dynamic, Max): 1536 MB 02:48:10 INFO - Vendor: Intel (0x8086) 02:48:10 INFO - Device ID: 0x0a2e 02:48:10 INFO - Revision ID: 0x0009 02:48:10 INFO - Displays: 02:48:10 INFO - Display: 02:48:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:48:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:48:10 INFO - Display Serial Number: 1600x1200 60 02:48:10 INFO - Main Display: Yes 02:48:10 INFO - Mirror: Off 02:48:10 INFO - Online: Yes 02:48:10 INFO - Rotation: Supported 02:48:10 INFO - Return code: 0 02:48:10 INFO - Running post-action listener: _resource_record_post_action 02:48:10 INFO - [mozharness: 2017-04-13 09:48:10.654097Z] Finished run-tests step (success) 02:48:10 INFO - Running post-run listener: _resource_record_post_run 02:48:10 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 39078400; Write bytes: 519246336; Read time: 440; Write time: 1920 02:48:10 INFO - TinderboxPrint: CPU usage
54.1% 02:48:10 INFO - TinderboxPrint: I/O read bytes / time
39,078,400 / 440 02:48:10 INFO - TinderboxPrint: I/O write bytes / time
519,246,336 / 1,920 02:48:10 INFO - TinderboxPrint: CPU idle
2,202.7 (45.9%) 02:48:10 INFO - TinderboxPrint: CPU system
962.4 (20.0%) 02:48:10 INFO - TinderboxPrint: CPU user
1,636.5 (34.1%) 02:48:10 INFO - TinderboxPrint: Swap in / out
247,193,600 / 0 02:48:10 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207561216; Write bytes: 204740096; Read time: 15918; Write time: 443 02:48:10 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35375616; Write bytes: 307297280; Read time: 408; Write time: 1447 02:48:11 INFO - Running post-run listener: _upload_blobber_files 02:48:11 INFO - Blob upload gear active. 02:48:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:48:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:48:11 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'] 02:48:11 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 02:48:11 INFO - (blobuploader) - INFO - Open directory for files ... 02:48:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:48:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:48:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:12 INFO - (blobuploader) - INFO - Done attempting. 02:48:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:48:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:48:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:13 INFO - (blobuploader) - INFO - Done attempting. 02:48:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:48:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:48:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:14 INFO - (blobuploader) - INFO - Done attempting. 02:48:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:48:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:48:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:17 INFO - (blobuploader) - INFO - Done attempting. 02:48:17 INFO - (blobuploader) - INFO - Iteration through files over. 02:48:17 INFO - Return code: 0 02:48:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:48:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:48:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44d8f2a67411459c1b444a58dc7da582a2559bf310953176e0cb1d3478605f0b3e5fa7bd6cb911c92814d6e63fb2d2c510df1b793f42d8c68280d039b7f3aef1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/574c516e193704db822543605958dd2113571a015640a6a3eea711215898676db7e027c4ec251deb7780f34bb2ee7fa4fd0b794b218479dcfc87ca1f779bf3e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/803e43fc08613ac0eac855663f2cba92a05e1daf94b2d9f2b9a8ed9206f15ceccb4cdcbf4864b421edcd80964ac0c7ac7803ca064b9a35e7e57d2df719433a57", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9765f35218f7d4fa687ac12d66262c45910363f1468564a5d7afb9714848b6a44888caae5e7911978b629908b96e62fbd92e0fcb059e7f75fbe4d76fba7c0e91"} 02:48:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:48:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:48:17 INFO - Contents: 02:48:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44d8f2a67411459c1b444a58dc7da582a2559bf310953176e0cb1d3478605f0b3e5fa7bd6cb911c92814d6e63fb2d2c510df1b793f42d8c68280d039b7f3aef1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/574c516e193704db822543605958dd2113571a015640a6a3eea711215898676db7e027c4ec251deb7780f34bb2ee7fa4fd0b794b218479dcfc87ca1f779bf3e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/803e43fc08613ac0eac855663f2cba92a05e1daf94b2d9f2b9a8ed9206f15ceccb4cdcbf4864b421edcd80964ac0c7ac7803ca064b9a35e7e57d2df719433a57", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9765f35218f7d4fa687ac12d66262c45910363f1468564a5d7afb9714848b6a44888caae5e7911978b629908b96e62fbd92e0fcb059e7f75fbe4d76fba7c0e91"} 02:48:17 INFO - Running post-run listener: copy_logs_to_upload_dir 02:48:17 INFO - Copying logs to upload dir... 02:48:17 INFO - mkdir: /builds/slave/test/build/upload/logs 02:48:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.963901 ========= master_lag: 2.29 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-13 02:48:19.486348) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 02:48:19.490409) ========= 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.VE548wLRkk/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.kmzVI7bKj2/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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/44d8f2a67411459c1b444a58dc7da582a2559bf310953176e0cb1d3478605f0b3e5fa7bd6cb911c92814d6e63fb2d2c510df1b793f42d8c68280d039b7f3aef1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/574c516e193704db822543605958dd2113571a015640a6a3eea711215898676db7e027c4ec251deb7780f34bb2ee7fa4fd0b794b218479dcfc87ca1f779bf3e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/803e43fc08613ac0eac855663f2cba92a05e1daf94b2d9f2b9a8ed9206f15ceccb4cdcbf4864b421edcd80964ac0c7ac7803ca064b9a35e7e57d2df719433a57", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9765f35218f7d4fa687ac12d66262c45910363f1468564a5d7afb9714848b6a44888caae5e7911978b629908b96e62fbd92e0fcb059e7f75fbe4d76fba7c0e91"} build_url:https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011208 build_url: 'https://queue.taskcluster.net/v1/task/dDmjNvKLRx-mGsyb_fJV8Q/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/44d8f2a67411459c1b444a58dc7da582a2559bf310953176e0cb1d3478605f0b3e5fa7bd6cb911c92814d6e63fb2d2c510df1b793f42d8c68280d039b7f3aef1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/574c516e193704db822543605958dd2113571a015640a6a3eea711215898676db7e027c4ec251deb7780f34bb2ee7fa4fd0b794b218479dcfc87ca1f779bf3e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/803e43fc08613ac0eac855663f2cba92a05e1daf94b2d9f2b9a8ed9206f15ceccb4cdcbf4864b421edcd80964ac0c7ac7803ca064b9a35e7e57d2df719433a57", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9765f35218f7d4fa687ac12d66262c45910363f1468564a5d7afb9714848b6a44888caae5e7911978b629908b96e62fbd92e0fcb059e7f75fbe4d76fba7c0e91"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 02:48:19.525959) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:48:19.526315) ========= 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.VE548wLRkk/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.kmzVI7bKj2/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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.005122 ========= master_lag: 0.16 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 02:48:19.689844) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 02:48:19.690170) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 02:48:19.690690) ========= ========= Total master_lag: 2.55 =========