builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0123 starttime: 1491929173.41 results: success (0) buildid: 20170411085820 builduid: 832633289301447bb8ea8fa09cafe3f7 revision: abf145ebd05fe105efbc78b761858c34f7690154 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 09:46:13.413526) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 09:46:13.414004) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 09:46:13.414276) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 09:46:13.440515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 09:46:13.441096) ========= 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.rN9YM5TCoI/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.YAqeywrGpm/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-11 09:46:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.3M=0.001s 2017-04-11 09:46:13 (12.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080501 ========= master_lag: 0.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 09:46:13.727815) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 09:46:13.728128) ========= 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.rN9YM5TCoI/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.YAqeywrGpm/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.020838 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 09:46:13.780377) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-11 09:46:13.783250) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev abf145ebd05fe105efbc78b761858c34f7690154 --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 abf145ebd05fe105efbc78b761858c34f7690154 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rN9YM5TCoI/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.YAqeywrGpm/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-11 09:46:13,876 truncating revision to first 12 chars 2017-04-11 09:46:13,876 Setting DEBUG logging. 2017-04-11 09:46:13,876 attempt 1/10 2017-04-11 09:46:13,876 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/abf145ebd05f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 09:46:15,449 unpacking tar archive at: mozilla-central-abf145ebd05f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.881288 ========= master_lag: 0.60 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-11 09:46:16.261952) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 09:46:16.262290) ========= script_repo_revision: abf145ebd05fe105efbc78b761858c34f7690154 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 09:46:16.262689) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 09:46:16.263094) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 09:46:16.284658) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-11 09:46:16.285046) ========= /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.rN9YM5TCoI/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.YAqeywrGpm/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:46:16 INFO - MultiFileLogger online at 20170411 09:46:16 in /builds/slave/test 09:46:16 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 09:46:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:46:16 INFO - {'append_to_log': False, 09:46:16 INFO - 'base_work_dir': '/builds/slave/test', 09:46:16 INFO - 'blob_upload_branch': 'Firefox', 09:46:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:46:16 INFO - 'branch': 'Firefox', 09:46:16 INFO - 'buildbot_json_path': 'buildprops.json', 09:46:16 INFO - 'config_files': ('talos/mac_config.py',), 09:46:16 INFO - 'default_actions': ('clobber', 09:46:16 INFO - 'read-buildbot-config', 09:46:16 INFO - 'download-and-extract', 09:46:16 INFO - 'populate-webroot', 09:46:16 INFO - 'create-virtualenv', 09:46:16 INFO - 'install', 09:46:16 INFO - 'run-tests'), 09:46:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:46:16 INFO - 'download_minidump_stackwalk': True, 09:46:16 INFO - 'download_symbols': 'ondemand', 09:46:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:46:16 INFO - 'tooltool.py': '/tools/tooltool.py', 09:46:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:46:16 INFO - '/tools/misc-python/virtualenv.py')}, 09:46:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:46:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:46:16 INFO - 'gecko_profile': False, 09:46:16 INFO - 'gecko_profile_interval': 0, 09:46:16 INFO - 'installer_path': 'installer.exe', 09:46:16 INFO - 'log_level': 'info', 09:46:16 INFO - 'log_name': 'talos', 09:46:16 INFO - 'log_to_console': True, 09:46:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:46:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:46:16 INFO - 'opt_config_files': (), 09:46:16 INFO - 'pip_index': False, 09:46:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:46:16 INFO - 'cmd': ('bash', 09:46:16 INFO - '-c', 09:46:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:46:16 INFO - 'enabled': True, 09:46:16 INFO - 'halt_on_failure': False, 09:46:16 INFO - 'name': 'check_screen_resolution'},), 09:46:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:46:16 INFO - 'cmd': ('bash', 09:46:16 INFO - '-c', 09:46:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:46:16 INFO - 'enabled': True, 09:46:16 INFO - 'halt_on_failure': False, 09:46:16 INFO - 'name': 'check_screen_resolution'},), 09:46:16 INFO - 'run_cmd_checks_enabled': True, 09:46:16 INFO - 'suite': 'dromaeojs-e10s', 09:46:16 INFO - 'system_bits': '32', 09:46:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:46:16 INFO - 'title': 't-yosemite-r7-0123', 09:46:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:46:16 INFO - 'use_talos_json': True, 09:46:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:46:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:46:16 INFO - 'work_dir': 'build'} 09:46:16 INFO - [mozharness: 2017-04-11 16:46:16.504216Z] Running clobber step. 09:46:16 INFO - Running pre-action listener: _resource_record_pre_action 09:46:16 INFO - Running main action method: clobber 09:46:16 INFO - rmtree: /builds/slave/test/build 09:46:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:46:18 INFO - Running post-action listener: _resource_record_post_action 09:46:18 INFO - [mozharness: 2017-04-11 16:46:18.022870Z] Finished clobber step (success) 09:46:18 INFO - [mozharness: 2017-04-11 16:46:18.022998Z] Running read-buildbot-config step. 09:46:18 INFO - Running pre-action listener: _resource_record_pre_action 09:46:18 INFO - Running main action method: read_buildbot_config 09:46:18 INFO - Using buildbot properties: 09:46:18 INFO - { 09:46:18 INFO - "project": "", 09:46:18 INFO - "product": "firefox", 09:46:18 INFO - "script_repo_revision": "production", 09:46:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:46:18 INFO - "repository": "", 09:46:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:46:18 INFO - "buildid": "20170411085820", 09:46:18 INFO - "pgo_build": "False", 09:46:18 INFO - "basedir": "/builds/slave/test", 09:46:18 INFO - "buildnumber": 118, 09:46:18 INFO - "slavename": "t-yosemite-r7-0123", 09:46:18 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:46:18 INFO - "platform": "yosemite_r7", 09:46:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:46:18 INFO - "branch": "mozilla-central", 09:46:18 INFO - "repo_path": "mozilla-central", 09:46:18 INFO - "revision": "abf145ebd05fe105efbc78b761858c34f7690154", 09:46:18 INFO - "stage_platform": "macosx64", 09:46:18 INFO - "builduid": "832633289301447bb8ea8fa09cafe3f7", 09:46:18 INFO - "slavebuilddir": "test" 09:46:18 INFO - } 09:46:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:46:18 INFO - Running post-action listener: _resource_record_post_action 09:46:18 INFO - [mozharness: 2017-04-11 16:46:18.026511Z] Finished read-buildbot-config step (success) 09:46:18 INFO - [mozharness: 2017-04-11 16:46:18.026632Z] Running download-and-extract step. 09:46:18 INFO - Running pre-action listener: _resource_record_pre_action 09:46:18 INFO - Running main action method: download_and_extract 09:46:18 INFO - mkdir: /builds/slave/test/build/tests 09:46:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:18 INFO - trying https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:46:18 INFO - Downloading https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/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 09:46:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/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 09:46:18 INFO - Downloaded 1325 bytes. 09:46:18 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:46:18 INFO - Using the following test package requirements: 09:46:18 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:46:18 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:46:18 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:46:18 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:46:18 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:46:18 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:46:18 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:46:18 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:46:18 INFO - u'jsshell-mac.zip'], 09:46:18 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:46:18 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:46:18 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:46:18 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:46:18 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:46:18 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:46:18 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:46:18 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:46:18 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:46:18 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:46:18 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:46:18 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:46:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:46:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:46:18 INFO - Fetch https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:46:20 INFO - Expected file size: 52582296 09:46:20 INFO - Obtained file size: 52582296 09:46:36 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 09:46:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:46:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:46:36 INFO - Fetch https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:46:37 INFO - Expected file size: 52582296 09:46:37 INFO - Obtained file size: 52582296 09:46:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:46:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:46:53 INFO - Fetch https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:46:54 INFO - Expected file size: 14405596 09:46:54 INFO - Obtained file size: 14405596 09:46: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')]} 09:46:54 INFO - trying https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:46:54 INFO - Downloading https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:46:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/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 09:46:56 INFO - Downloaded 70916348 bytes. 09:46:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:46:56 INFO - mkdir: /builds/slave/test/properties 09:46:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:46:56 INFO - Writing to file /builds/slave/test/properties/build_url 09:46:56 INFO - Contents: 09:46:56 INFO - build_url:https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:46:57 INFO - Running post-action listener: _resource_record_post_action 09:46:57 INFO - Running post-action listener: set_extra_try_arguments 09:46:57 INFO - [mozharness: 2017-04-11 16:46:57.626677Z] Finished download-and-extract step (success) 09:46:57 INFO - [mozharness: 2017-04-11 16:46:57.626827Z] Running populate-webroot step. 09:46:57 INFO - Running pre-action listener: _resource_record_pre_action 09:46:57 INFO - Running main action method: populate_webroot 09:46:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:46:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:46:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:46:57 INFO - '--tppagecycles', 09:46:57 INFO - '7'], 09:46:57 INFO - 'tests': ['tsvgm']}}, 09:46:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:46:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:46:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:46:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:46:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:46:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:46:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:46:57 INFO - 'talos_options': ['--disable-e10s'], 09:46:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:46:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:46:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:46:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:46:57 INFO - 'talos_options': ['--disable-e10s'], 09:46:57 INFO - 'tests': ['damp', 'tps']}, 09:46:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:46:57 INFO - 'tests': ['damp', 'tps']}, 09:46:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:46:57 INFO - 'tests': ['dromaeo_dom']}, 09:46:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:46:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:46:57 INFO - 'tests': ['basic_compositor_video']}, 09:46:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:46:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:46:57 INFO - 'tests': ['a11yr', 09:46:57 INFO - 'ts_paint', 09:46:57 INFO - 'tpaint', 09:46:57 INFO - 'sessionrestore', 09:46:57 INFO - 'sessionrestore_no_auto_restore', 09:46:57 INFO - 'tabpaint']}, 09:46:57 INFO - 'other-e10s': {'tests': ['a11yr', 09:46:57 INFO - 'ts_paint', 09:46:57 INFO - 'tpaint', 09:46:57 INFO - 'sessionrestore', 09:46:57 INFO - 'sessionrestore_no_auto_restore', 09:46:57 INFO - 'tabpaint']}, 09:46:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:46:57 INFO - 'ts_paint', 09:46:57 INFO - 'tpaint', 09:46:57 INFO - 'sessionrestore', 09:46:57 INFO - 'sessionrestore_no_auto_restore', 09:46:57 INFO - 'tabpaint']}, 09:46:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:46:57 INFO - 'ts_paint', 09:46:57 INFO - 'tpaint', 09:46:57 INFO - 'sessionrestore', 09:46:57 INFO - 'sessionrestore_no_auto_restore', 09:46:57 INFO - 'tabpaint']}, 09:46:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:46:57 INFO - 'tests': ['a11yr', 09:46:57 INFO - 'ts_paint', 09:46:57 INFO - 'tpaint', 09:46:57 INFO - 'sessionrestore', 09:46:57 INFO - 'sessionrestore_no_auto_restore', 09:46:57 INFO - 'tabpaint']}, 09:46:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:46:57 INFO - 'tests': ['a11yr', 09:46:57 INFO - 'ts_paint', 09:46:57 INFO - 'tpaint', 09:46:57 INFO - 'sessionrestore', 09:46:57 INFO - 'sessionrestore_no_auto_restore', 09:46:57 INFO - 'tabpaint']}, 09:46:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:46:57 INFO - 'tests': ['tsvgx', 09:46:57 INFO - 'tsvgr_opacity', 09:46:57 INFO - 'tart', 09:46:57 INFO - 'tscrollx', 09:46:57 INFO - 'cart', 09:46:57 INFO - 'tsvg_static']}, 09:46:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:46:57 INFO - 'tsvgr_opacity', 09:46:57 INFO - 'tart', 09:46:57 INFO - 'tscrollx', 09:46:57 INFO - 'cart', 09:46:57 INFO - 'tsvg_static']}, 09:46:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:46:57 INFO - 'talos_options': ['--disable-e10s'], 09:46:57 INFO - 'tests': ['tp5o']}, 09:46:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:46:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:46:57 INFO - 'talos_options': ['--disable-e10s', 09:46:57 INFO - '--xperf_path', 09:46:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:46:57 INFO - 'tests': ['tp5n']}, 09:46:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:46:57 INFO - 'talos_options': ['--xperf_path', 09:46:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:46:57 INFO - 'tests': ['tp5n']}}, 09:46:57 INFO - 'talos.zip': {'path': '', 09:46:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:46:57 INFO - Running post-action listener: _resource_record_post_action 09:46:57 INFO - [mozharness: 2017-04-11 16:46:57.645443Z] Finished populate-webroot step (success) 09:46:57 INFO - [mozharness: 2017-04-11 16:46:57.645582Z] Running create-virtualenv step. 09:46:57 INFO - Running pre-action listener: _resource_record_pre_action 09:46:57 INFO - Running main action method: create_virtualenv 09:46:57 INFO - Creating virtualenv /builds/slave/test/build/venv 09:46:57 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 09:46:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:46:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:46:57 INFO - Using real prefix '/tools/python27' 09:46:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:46:58 INFO - Installing distribute.............................................................................................................................................................................................done. 09:47:01 INFO - Installing pip.................done. 09:47:01 INFO - Return code: 0 09:47:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:47:01 INFO - Reading from file tmpfile_stdout 09:47:01 INFO - Output received: 09:47:01 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) 09:47:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ecb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fc2c5d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca6d30>, '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 0x101bcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c9bbe8>, '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.rN9YM5TCoI/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.YAqeywrGpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:47:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:47:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rN9YM5TCoI/Render', 09:47:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:01 INFO - 'HOME': '/Users/cltbld', 09:47:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:01 INFO - 'LOGNAME': 'cltbld', 09:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:01 INFO - 'MOZ_NO_REMOTE': '1', 09:47:01 INFO - 'NO_EM_RESTART': '1', 09:47:01 INFO - 'PAGER': '/bin/cat', 09:47:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:01 INFO - 'PWD': '/builds/slave/test', 09:47:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:01 INFO - 'SHELL': '/bin/bash', 09:47:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YAqeywrGpm/Listeners', 09:47:01 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:47:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:01 INFO - 'USER': 'cltbld', 09:47:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:01 INFO - 'XPC_FLAGS': '0x0', 09:47:01 INFO - 'XPC_SERVICE_NAME': '0', 09:47:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:01 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 09:47:01 INFO - Cleaning up... 09:47:01 INFO - Return code: 0 09:47:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101ec8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ecb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fc2c5d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca6d30>, '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 0x101bcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c9bbe8>, '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.rN9YM5TCoI/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.YAqeywrGpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:47:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:47:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rN9YM5TCoI/Render', 09:47:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:01 INFO - 'HOME': '/Users/cltbld', 09:47:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:01 INFO - 'LOGNAME': 'cltbld', 09:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:01 INFO - 'MOZ_NO_REMOTE': '1', 09:47:01 INFO - 'NO_EM_RESTART': '1', 09:47:01 INFO - 'PAGER': '/bin/cat', 09:47:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:01 INFO - 'PWD': '/builds/slave/test', 09:47:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:01 INFO - 'SHELL': '/bin/bash', 09:47:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YAqeywrGpm/Listeners', 09:47:01 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:47:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:01 INFO - 'USER': 'cltbld', 09:47:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:01 INFO - 'XPC_FLAGS': '0x0', 09:47:01 INFO - 'XPC_SERVICE_NAME': '0', 09:47:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:02 INFO - Downloading/unpacking psutil>=3.1.1 09:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:47:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:47:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:47:05 INFO - Installing collected packages: psutil 09:47:05 INFO - Running setup.py install for psutil 09:47:05 INFO - building 'psutil._psutil_osx' extension 09:47:05 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 09:47:06 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 09:47:06 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 09:47:06 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 09:47:06 INFO - building 'psutil._psutil_posix' extension 09:47:06 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 09:47:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:47:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:47:06 INFO - ^ 09:47:06 INFO - 1 warning generated. 09:47:06 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 09:47:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:47:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:47:06 INFO - Successfully installed psutil 09:47:06 INFO - Cleaning up... 09:47:06 INFO - Return code: 0 09:47:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:06 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 0x101ec8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ecb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fc2c5d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca6d30>, '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 0x101bcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c9bbe8>, '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.rN9YM5TCoI/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.YAqeywrGpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:47:06 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 09:47:06 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 09:47:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rN9YM5TCoI/Render', 09:47:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:06 INFO - 'HOME': '/Users/cltbld', 09:47:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:06 INFO - 'LOGNAME': 'cltbld', 09:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:06 INFO - 'MOZ_NO_REMOTE': '1', 09:47:06 INFO - 'NO_EM_RESTART': '1', 09:47:06 INFO - 'PAGER': '/bin/cat', 09:47:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:06 INFO - 'PWD': '/builds/slave/test', 09:47:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:06 INFO - 'SHELL': '/bin/bash', 09:47:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YAqeywrGpm/Listeners', 09:47:06 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:47:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:06 INFO - 'USER': 'cltbld', 09:47:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:06 INFO - 'XPC_FLAGS': '0x0', 09:47:06 INFO - 'XPC_SERVICE_NAME': '0', 09:47:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:47: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 09:47: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 09:47: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 09:47: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 09:47: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 09:47: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 09:47:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:47:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:47:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:47:09 INFO - Installing collected packages: mozsystemmonitor 09:47:09 INFO - Running setup.py install for mozsystemmonitor 09:47:09 INFO - Successfully installed mozsystemmonitor 09:47:09 INFO - Cleaning up... 09:47:09 INFO - Return code: 0 09:47:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:47: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')]} 09:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:10 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')]} 09:47:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:10 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 0x101ec8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ecb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fc2c5d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca6d30>, '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 0x101bcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c9bbe8>, '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.rN9YM5TCoI/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.YAqeywrGpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:47:10 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 09:47:10 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 09:47:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rN9YM5TCoI/Render', 09:47:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:10 INFO - 'HOME': '/Users/cltbld', 09:47:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:10 INFO - 'LOGNAME': 'cltbld', 09:47:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:10 INFO - 'MOZ_NO_REMOTE': '1', 09:47:10 INFO - 'NO_EM_RESTART': '1', 09:47:10 INFO - 'PAGER': '/bin/cat', 09:47:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:10 INFO - 'PWD': '/builds/slave/test', 09:47:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:10 INFO - 'SHELL': '/bin/bash', 09:47:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YAqeywrGpm/Listeners', 09:47:10 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:47:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:10 INFO - 'USER': 'cltbld', 09:47:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:10 INFO - 'XPC_FLAGS': '0x0', 09:47:10 INFO - 'XPC_SERVICE_NAME': '0', 09:47:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:10 INFO - Downloading/unpacking jsonschema==2.5.1 09:47:10 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 09:47:10 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 09:47:10 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 09:47:10 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 09:47:10 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 09:47:10 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 09:47:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:47:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:47:14 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 09:47:14 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 09:47:14 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 09:47:14 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 09:47:14 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 09:47:14 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 09:47:15 INFO - Downloading functools32-3.2.3-2.tar.gz 09:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:47:15 INFO - warning: no files found matching '*.txt' 09:47:15 INFO - no previously-included directories found matching 'build' 09:47:15 INFO - no previously-included directories found matching 'dist' 09:47:15 INFO - no previously-included directories found matching '.git*' 09:47:15 INFO - Installing collected packages: jsonschema, functools32 09:47:15 INFO - Running setup.py install for functools32 09:47:15 INFO - warning: no files found matching '*.txt' 09:47:15 INFO - no previously-included directories found matching 'build' 09:47:15 INFO - no previously-included directories found matching 'dist' 09:47:15 INFO - no previously-included directories found matching '.git*' 09:47:15 INFO - Successfully installed jsonschema functools32 09:47:15 INFO - Cleaning up... 09:47:15 INFO - Return code: 0 09:47:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:15 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 0x101ec8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ecb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fc2c5d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca6d30>, '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 0x101bcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c9bbe8>, '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.rN9YM5TCoI/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.YAqeywrGpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:47:15 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 09:47:15 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 09:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rN9YM5TCoI/Render', 09:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:15 INFO - 'HOME': '/Users/cltbld', 09:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:15 INFO - 'LOGNAME': 'cltbld', 09:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:15 INFO - 'MOZ_NO_REMOTE': '1', 09:47:15 INFO - 'NO_EM_RESTART': '1', 09:47:15 INFO - 'PAGER': '/bin/cat', 09:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:15 INFO - 'PWD': '/builds/slave/test', 09:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:15 INFO - 'SHELL': '/bin/bash', 09:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YAqeywrGpm/Listeners', 09:47:15 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:15 INFO - 'USER': 'cltbld', 09:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:15 INFO - 'XPC_FLAGS': '0x0', 09:47:15 INFO - 'XPC_SERVICE_NAME': '0', 09:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:47:15 INFO - Cleaning up... 09:47:15 INFO - Return code: 0 09:47:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:15 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 0x101ec8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ecb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fc2c5d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca6d30>, '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 0x101bcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c9bbe8>, '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.rN9YM5TCoI/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.YAqeywrGpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:47:15 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 09:47:15 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 09:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rN9YM5TCoI/Render', 09:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:15 INFO - 'HOME': '/Users/cltbld', 09:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:15 INFO - 'LOGNAME': 'cltbld', 09:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:15 INFO - 'MOZ_NO_REMOTE': '1', 09:47:15 INFO - 'NO_EM_RESTART': '1', 09:47:15 INFO - 'PAGER': '/bin/cat', 09:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:15 INFO - 'PWD': '/builds/slave/test', 09:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:15 INFO - 'SHELL': '/bin/bash', 09:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YAqeywrGpm/Listeners', 09:47:15 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:15 INFO - 'USER': 'cltbld', 09:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:15 INFO - 'XPC_FLAGS': '0x0', 09:47:15 INFO - 'XPC_SERVICE_NAME': '0', 09:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:16 INFO - Downloading/unpacking blobuploader==1.2.4 09:47:16 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 09:47:16 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 09:47:16 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 09:47:16 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 09:47:16 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 09:47:16 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 09:47:18 INFO - Downloading blobuploader-1.2.4.tar.gz 09:47:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:47:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:47:18 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 09:47:18 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 09:47:18 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 09:47:18 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 09:47:18 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 09:47:18 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 09:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:47:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:19 INFO - Downloading docopt-0.6.1.tar.gz 09:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:47:19 INFO - Installing collected packages: blobuploader, requests, docopt 09:47:19 INFO - Running setup.py install for blobuploader 09:47:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:47:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:47:20 INFO - Running setup.py install for requests 09:47:20 INFO - Running setup.py install for docopt 09:47:20 INFO - Successfully installed blobuploader requests docopt 09:47:20 INFO - Cleaning up... 09:47:20 INFO - Return code: 0 09:47:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:20 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 0x101ec8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ecb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fc2c5d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca6d30>, '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 0x101bcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c9bbe8>, '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.rN9YM5TCoI/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.YAqeywrGpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:47:20 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 09:47:20 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 09:47:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rN9YM5TCoI/Render', 09:47:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:20 INFO - 'HOME': '/Users/cltbld', 09:47:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:20 INFO - 'LOGNAME': 'cltbld', 09:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:20 INFO - 'MOZ_NO_REMOTE': '1', 09:47:20 INFO - 'NO_EM_RESTART': '1', 09:47:20 INFO - 'PAGER': '/bin/cat', 09:47:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:20 INFO - 'PWD': '/builds/slave/test', 09:47:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:20 INFO - 'SHELL': '/bin/bash', 09:47:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YAqeywrGpm/Listeners', 09:47:20 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:47:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:20 INFO - 'USER': 'cltbld', 09:47:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:20 INFO - 'XPC_FLAGS': '0x0', 09:47:20 INFO - 'XPC_SERVICE_NAME': '0', 09:47:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Lx8xHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-tE9Ouc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-gbLQG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-KjL2NX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-xZtamT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-MtGIIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-jF1xW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-vlQK88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:21 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-6QCjzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:22 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-MApgkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:22 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-wMtmcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:22 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-aQALJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:22 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-4qwnDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:22 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Pb1Kdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:22 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-X4v4KX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:22 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-OVcwXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:22 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Y4LMqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:47:22 INFO - Running setup.py install for manifestparser 09:47:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:47:23 INFO - Running setup.py install for mozcrash 09:47:23 INFO - Running setup.py install for mozdebug 09:47:23 INFO - Running setup.py install for mozdevice 09:47:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:47:23 INFO - Running setup.py install for mozfile 09:47:23 INFO - Running setup.py install for mozhttpd 09:47:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:47:23 INFO - Running setup.py install for mozinfo 09:47:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:47:23 INFO - Running setup.py install for mozInstall 09:47:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:47:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:47:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:47:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:47:24 INFO - Running setup.py install for mozleak 09:47:24 INFO - Running setup.py install for mozlog 09:47:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:47:24 INFO - Running setup.py install for moznetwork 09:47:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:47:24 INFO - Running setup.py install for mozprocess 09:47:24 INFO - Running setup.py install for mozprofile 09:47:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:47:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:47:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:47:24 INFO - Running setup.py install for mozrunner 09:47:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:47:25 INFO - Running setup.py install for mozscreenshot 09:47:25 INFO - Running setup.py install for moztest 09:47:25 INFO - Running setup.py install for mozversion 09:47:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:47:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:47:25 INFO - Cleaning up... 09:47:25 INFO - Return code: 0 09:47:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:25 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')]} 09:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:25 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')]} 09:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:25 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 0x101ec8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ecb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fc2c5d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca6d30>, '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 0x101bcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c9bbe8>, '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.rN9YM5TCoI/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.YAqeywrGpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:47:25 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 09:47:25 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 09:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rN9YM5TCoI/Render', 09:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:25 INFO - 'HOME': '/Users/cltbld', 09:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:25 INFO - 'LOGNAME': 'cltbld', 09:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:25 INFO - 'MOZ_NO_REMOTE': '1', 09:47:25 INFO - 'NO_EM_RESTART': '1', 09:47:25 INFO - 'PAGER': '/bin/cat', 09:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:25 INFO - 'PWD': '/builds/slave/test', 09:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:25 INFO - 'SHELL': '/bin/bash', 09:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YAqeywrGpm/Listeners', 09:47:25 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:25 INFO - 'USER': 'cltbld', 09:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:25 INFO - 'XPC_FLAGS': '0x0', 09:47:25 INFO - 'XPC_SERVICE_NAME': '0', 09:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-kGK4mK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:25 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)) 09:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-KarZHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:25 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)) 09:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:25 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-cRyduc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:26 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)) 09:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ZoXEEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:26 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)) 09:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-mcc08i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:26 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)) 09:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-rKzkEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:26 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)) 09:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ZeiNg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:26 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)) 09:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-1Lq9FS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:26 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)) 09:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Z1VC1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:26 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)) 09:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-7Ov2G1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:26 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)) 09:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-xkDZcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:26 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)) 09:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-wC6WPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:26 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)) 09:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:26 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-lSEgDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:27 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)) 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:27 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-vGrFqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:27 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)) 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:27 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-6N8Fop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:27 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)) 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:27 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-7WD70s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:27 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)) 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:27 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-dijhKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:27 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)) 09:47:27 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)) 09:47:27 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)) 09:47:27 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)) 09:47:27 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)) 09:47:27 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)) 09:47:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:30 INFO - Downloading blessings-1.6.tar.gz 09:47:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:47:30 INFO - Installing collected packages: blessings 09:47:30 INFO - Running setup.py install for blessings 09:47:30 INFO - Successfully installed blessings 09:47:30 INFO - Cleaning up... 09:47:30 INFO - Return code: 0 09:47:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:47:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:30 INFO - Reading from file tmpfile_stdout 09:47:30 INFO - Current package versions: 09:47:30 INFO - blessings == 1.6 09:47:30 INFO - blobuploader == 1.2.4 09:47:30 INFO - docopt == 0.6.1 09:47:30 INFO - functools32 == 3.2.3-2 09:47:30 INFO - jsonschema == 2.5.1 09:47:30 INFO - manifestparser == 1.1 09:47:30 INFO - mozInstall == 1.12 09:47:30 INFO - mozcrash == 1.0 09:47:30 INFO - mozdebug == 0.1 09:47:30 INFO - mozdevice == 0.50 09:47:30 INFO - mozfile == 1.2 09:47:30 INFO - mozhttpd == 0.7 09:47:30 INFO - mozinfo == 0.9 09:47:30 INFO - mozleak == 0.1 09:47:30 INFO - mozlog == 3.4 09:47:30 INFO - moznetwork == 0.27 09:47:30 INFO - mozprocess == 0.25 09:47:30 INFO - mozprofile == 0.28 09:47:30 INFO - mozrunner == 6.13 09:47:30 INFO - mozscreenshot == 0.1 09:47:30 INFO - mozsystemmonitor == 0.3 09:47:30 INFO - moztest == 0.8 09:47:30 INFO - mozversion == 1.4 09:47:30 INFO - psutil == 3.1.1 09:47:30 INFO - requests == 1.2.3 09:47:30 INFO - wsgiref == 0.1.2 09:47:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47: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')]} 09:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47: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')]} 09:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:30 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 0x101ec8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ecb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fc2c5d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca6d30>, '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 0x101bcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c9bbe8>, '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.rN9YM5TCoI/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.YAqeywrGpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:47:30 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 09:47:30 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 09:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rN9YM5TCoI/Render', 09:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:30 INFO - 'HOME': '/Users/cltbld', 09:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:30 INFO - 'LOGNAME': 'cltbld', 09:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:30 INFO - 'MOZ_NO_REMOTE': '1', 09:47:30 INFO - 'NO_EM_RESTART': '1', 09:47:30 INFO - 'PAGER': '/bin/cat', 09:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:30 INFO - 'PWD': '/builds/slave/test', 09:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:30 INFO - 'SHELL': '/bin/bash', 09:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YAqeywrGpm/Listeners', 09:47:30 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:30 INFO - 'USER': 'cltbld', 09:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:30 INFO - 'XPC_FLAGS': '0x0', 09:47:30 INFO - 'XPC_SERVICE_NAME': '0', 09:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:30 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)) 09:47:30 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)) 09:47:30 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)) 09:47:30 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)) 09:47:30 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)) 09:47:30 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)) 09:47:30 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)) 09:47:30 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)) 09:47:30 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)) 09:47:30 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)) 09:47:30 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)) 09:47:30 INFO - Cleaning up... 09:47:31 INFO - Return code: 0 09:47:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ecb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fc2c5d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca6d30>, '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 0x101bcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c9bbe8>, '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.rN9YM5TCoI/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.YAqeywrGpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:47:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:47:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:47:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rN9YM5TCoI/Render', 09:47:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:31 INFO - 'HOME': '/Users/cltbld', 09:47:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:31 INFO - 'LOGNAME': 'cltbld', 09:47:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:31 INFO - 'MOZ_NO_REMOTE': '1', 09:47:31 INFO - 'NO_EM_RESTART': '1', 09:47:31 INFO - 'PAGER': '/bin/cat', 09:47:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:31 INFO - 'PWD': '/builds/slave/test', 09:47:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:31 INFO - 'SHELL': '/bin/bash', 09:47:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YAqeywrGpm/Listeners', 09:47:31 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:47:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:31 INFO - 'USER': 'cltbld', 09:47:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:31 INFO - 'XPC_FLAGS': '0x0', 09:47:31 INFO - 'XPC_SERVICE_NAME': '0', 09:47:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:47:31 INFO - Cleaning up... 09:47:31 INFO - Return code: 0 09:47:31 INFO - Running post-action listener: _resource_record_post_action 09:47:31 INFO - Running post-action listener: _start_resource_monitoring 09:47:31 INFO - Starting resource monitoring. 09:47:31 INFO - [mozharness: 2017-04-11 16:47:31.307674Z] Finished create-virtualenv step (success) 09:47:31 INFO - [mozharness: 2017-04-11 16:47:31.308650Z] Running install step. 09:47:31 INFO - Running pre-action listener: _resource_record_pre_action 09:47:31 INFO - Running main action method: install 09:47:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:31 INFO - Reading from file tmpfile_stdout 09:47:31 INFO - Detecting whether we're running mozinstall >=1.0... 09:47:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:47:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:47:31 INFO - Reading from file tmpfile_stdout 09:47:31 INFO - Output received: 09:47:31 INFO - Usage: mozinstall [options] installer 09:47:31 INFO - Options: 09:47:31 INFO - -h, --help show this help message and exit 09:47:31 INFO - -d DEST, --destination=DEST 09:47:31 INFO - Directory to install application into. [default: 09:47:31 INFO - "/builds/slave/test"] 09:47:31 INFO - --app=APP Application being installed. [default: firefox] 09:47:31 INFO - mkdir: /builds/slave/test/build/application 09:47:31 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'] 09:47:31 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 09:47:51 INFO - Reading from file tmpfile_stdout 09:47:51 INFO - Output received: 09:47:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:47:51 INFO - Running post-action listener: _resource_record_post_action 09:47:51 INFO - [mozharness: 2017-04-11 16:47:51.378711Z] Finished install step (success) 09:47:51 INFO - [mozharness: 2017-04-11 16:47:51.378842Z] Running run-tests step. 09:47:51 INFO - Running pre-action listener: _resource_record_pre_action 09:47:51 INFO - Running main action method: run_tests 09:47:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:51 INFO - 2017-04-11 09:47:51.393 screenresolution[1898:13371] starting screenresolution argv=screenresolution get 09:47:51 INFO - 2017-04-11 09:47:51.395 screenresolution[1898:13371] Display 0: 1600x1200x32@60 09:47:51 INFO - 2017-04-11 09:47:51.401 screenresolution[1899:13374] starting screenresolution argv=screenresolution list 09:47:51 INFO - Available Modes on Display 0 09:47:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:51 INFO - Intel Iris: 09:47:51 INFO - Chipset Model: Intel Iris 09:47:51 INFO - Type: GPU 09:47:51 INFO - Bus: Built-In 09:47:51 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:51 INFO - Vendor: Intel (0x8086) 09:47:51 INFO - Device ID: 0x0a2e 09:47:51 INFO - Revision ID: 0x0009 09:47:51 INFO - Displays: 09:47:51 INFO - Display: 09:47:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:51 INFO - Display Serial Number: 1600x1200 60 09:47:51 INFO - Main Display: Yes 09:47:51 INFO - Mirror: Off 09:47:51 INFO - Online: Yes 09:47:51 INFO - Rotation: Supported 09:47:51 INFO - Return code: 0 09:47:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:47:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:47:51 INFO - Python 2.7.3 09:47:51 INFO - Return code: 0 09:47:51 INFO - grabbing minidump binary from tooltool 09:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:51 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 0x101ca6d30>, '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 0x101bcddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c9bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:47:51 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 09:47:51 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 09:47:51 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 09:47:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:47:51 INFO - Return code: 0 09:47:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:47:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:47:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:47:51 INFO - ENV: RUST_BACKTRACE is now 1 09:47:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:47:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:47:51 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/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 09:47:51 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/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0123 --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 09:47:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rN9YM5TCoI/Render', 09:47:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:51 INFO - 'HOME': '/Users/cltbld', 09:47:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:51 INFO - 'LOGNAME': 'cltbld', 09:47:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:47:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:51 INFO - 'MOZ_NO_REMOTE': '1', 09:47:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:47:51 INFO - 'NO_EM_RESTART': '1', 09:47:51 INFO - 'PAGER': '/bin/cat', 09:47:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:51 INFO - 'PWD': '/builds/slave/test', 09:47:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:47:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:51 INFO - 'RUST_BACKTRACE': '1', 09:47:51 INFO - 'SHELL': '/bin/bash', 09:47:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YAqeywrGpm/Listeners', 09:47:51 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:47:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:51 INFO - 'USER': 'cltbld', 09:47:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:51 INFO - 'XPC_FLAGS': '0x0', 09:47:51 INFO - 'XPC_SERVICE_NAME': '0', 09:47:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:51 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/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 09:47:51 INFO - mozversion application_buildid: 20170411085820 09:47:51 INFO - mozversion application_changeset: abf145ebd05fe105efbc78b761858c34f7690154 09:47:51 INFO - mozversion application_display_name: Nightly 09:47:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:47:51 INFO - mozversion application_name: Firefox 09:47:51 INFO - mozversion application_remotingname: firefox 09:47:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:47:51 INFO - mozversion application_vendor: Mozilla 09:47:51 INFO - mozversion application_version: 55.0a1 09:47:51 INFO - mozversion platform_buildid: 20170411085820 09:47:51 INFO - mozversion platform_changeset: abf145ebd05fe105efbc78b761858c34f7690154 09:47:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:47:51 INFO - mozversion platform_version: 55.0a1 09:47:51 INFO - using testdate: 1491929271 09:47:51 INFO - actual date: 1491929271 09:47:51 INFO - starting webserver on 'localhost:51829' 09:47:51 INFO - SUITE-START | Running 2 tests 09:47:51 INFO - TEST-START | dromaeo_css 09:47:51 INFO - operating with platform_type : mac_ 09:47:51 INFO - Initialising browser for dromaeo_css test... 09:47:52 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpJkM9e_/profile http://localhost:51829/getInfo.html) 09:47:55 INFO - PID 1909 | 2017-04-11 09:47:55.039 plugin-container[1911:13535] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 09:47:55 INFO - PID 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:55 INFO - PID 1909 | 2017-04-11 09:47:55.042 plugin-container[1911:13535] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 09:47:55 INFO - PID 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:55 INFO - PID 1909 | 2017-04-11 09:47:55.042 plugin-container[1911:13535] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:55 INFO - PID 1909 | __metrics Screen width/height:1600/1200 09:47:55 INFO - PID 1909 | colorDepth:24 09:47:55 INFO - PID 1909 | Browser inner width/height: 1024/689 09:47:55 INFO - PID 1909 | __metrics 09:47:55 ERROR - PID 1909 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 09:47:56 INFO - TEST-INFO | 1909: exit 0 09:47:56 INFO - Browser initialized. 09:47:56 INFO - Running cycle 1/1 for dromaeo_css test... 09:47:56 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpJkM9e_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:47:58 INFO - PID 1912 | 2017-04-11 09:47:58.965 plugin-container[1913:13664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 09:47:58 INFO - PID 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:58 INFO - PID 1912 | 2017-04-11 09:47:58.966 plugin-container[1913:13664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9013, name = 'com.apple.CFPasteboardClient' 09:47:58 INFO - PID 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:58 INFO - PID 1912 | 2017-04-11 09:47:58.966 plugin-container[1913:13664] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:48:08 INFO - PID 1912 | RSS: Main: 235655168 09:48:08 INFO - PID 1912 | 09:51:12 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:51829/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51829/tests/dromaeo/cssquery-ext.html) 09:51:14 INFO - PID 1912 | RSS: Main: 253788160 09:51:14 INFO - PID 1912 | 09:52:56 INFO - PID 1912 | ************************* 09:52:56 INFO - PID 1912 | A coding exception was thrown and uncaught in a Task. 09:52:56 INFO - PID 1912 | 09:52:56 ERROR - PID 1912 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:52:56 INFO - PID 1912 | Full stack: 09:52:56 INFO - PID 1912 | ************************* 09:52:56 INFO - PID 1912 | ************************* 09:52:56 INFO - PID 1912 | A coding exception was thrown and uncaught in a Task. 09:52:56 INFO - PID 1912 | 09:52:56 ERROR - PID 1912 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:52:56 INFO - PID 1912 | Full stack: 09:52:56 INFO - PID 1912 | ************************* 09:52:56 INFO - PID 1912 | ************************* 09:52:56 INFO - PID 1912 | A coding exception was thrown and uncaught in a Task. 09:52:56 INFO - PID 1912 | 09:52:56 ERROR - PID 1912 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:52:56 INFO - PID 1912 | Full stack: 09:52:56 INFO - PID 1912 | ************************* 09:52:56 INFO - PID 1912 | ************************* 09:52:56 INFO - PID 1912 | A coding exception was thrown and uncaught in a Task. 09:52:56 INFO - PID 1912 | 09:52:56 ERROR - PID 1912 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:52:56 INFO - PID 1912 | Full stack: 09:52:56 INFO - PID 1912 | ************************* 09:54:16 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:51829/tests/dromaeo/cssquery-ext.html (next: http://localhost:51829/tests/dromaeo/cssquery-jquery.html) 09:54:17 INFO - PID 1912 | RSS: Main: 260542464 09:54:17 INFO - PID 1912 | 09:57:18 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:51829/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51829/tests/dromaeo/cssquery-mootools.html) 09:57:19 INFO - PID 1912 | RSS: Main: 273735680 09:57:19 INFO - PID 1912 | 10:00:21 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:51829/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51829/tests/dromaeo/cssquery-prototype.html) 10:00:21 INFO - PID 1912 | RSS: Main: 267067392 10:00:21 INFO - PID 1912 | 10:03:24 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:51829/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51829/tests/dromaeo/cssquery-yui.html) 10:03:25 INFO - PID 1912 | RSS: Main: 277491712 10:03:25 INFO - PID 1912 | 10:06:27 INFO - PID 1912 | Cycle 1(1): loaded http://localhost:51829/tests/dromaeo/cssquery-yui.html (next: http://localhost:51829/tests/dromaeo/cssquery-dojo.html) 10:06:27 INFO - PID 1912 | RSS: Main: 271249408 10:06:27 INFO - PID 1912 | 10:06:27 INFO - PID 1912 | __start_tp_report 10:06:27 INFO - PID 1912 | _x_x_mozilla_page_load 10:06:27 INFO - PID 1912 | _x_x_mozilla_page_load_details 10:06:27 INFO - PID 1912 | |i|pagename|runs| 10:06:27 INFO - PID 1912 | |0;dojo.html;2958.4526862530447;3526.6297038810926;3559.982200088999;3572.4462708280216;3579.2304654499276;4688.5077066908;5890.823275301743;6194.752209911604;6270.341614130519;6275.184226050618;5000.249962505626;6097.725602347894;6167.907481387782;6215.409536094064;6308.022256550231;4349.43457350544;4457.866264012085;5478.178273259013;5531.308586426697;5580.190872323511;5410.783568657249;5523.475269849358;5576.498115169634;5577.05190117681;5580.665160090387;4492.61812745917;5503.422140675251;5539.250011173837;5547.861303467425;5621.241132447109;5389.946100539023;5482.616216864821;5490.423505531901;5519.089350257203;5566.777328906839;5414.485623865727;5419.59353048519;5438.972805135949;5441.374241962146;5468.152436372368;5663.838913022821;6131.468419844951;6236.844078898019;6292.087647291117;6320.778772742932;5591.7483713233005;6485;6618.4705223582;6694;6702.564333318367;6789.796306110825;6790.796276111675;6800.65996700163;6809.421199198074;6850.862982740317;5162.535371816554;5238.423773384925;6357.459615932651;6364.331745166737;6366.26787919386;6277.623342599459;6358.586691865014;6662.866742665216;6875.140607424072;6899.137607799025;6369.904451433232;6501.8699626008165;6522.412982831568;6614.437772789271;6627.37039981201;6137.8673257933415;6593.505487088488;6603.537752357289;6661.666916654148;6696.2968888267005;6708.463322934153;6722.899156512656;6725.159355080615;6729.69716362755;6747.190337159573;6470.09418681385;6550.868982620321;6552.8361790955605;6738.494612904052;6758.05387245786;5306.575473962073;5337.62636615441;5339.893202135935;5353.625246232804;5379.950909572596;4763.976180119077;4783.6651434399255;4784.378030856036;4806.038792241566;4830.3479030331155;6262.874742505124;6348.619082855043;6349.9682501587195;6364.586301890455;6383.489320854414;6621.735130594722;6733.293004234582;6768.593884366954;6778.389944904884;6807.897881531781;6294.71673774672;6341.175647165931;6353.491720662336;6355.5551111421755;6371.617702937746;5939.07944268639;5941.792037278675;5960.910586341209;5965;5966.582339236212;5305.761240744098;6769.526133170631;6844.828879278058;6894.758683446055;6942.826429339307;6778.627175505395;6819.624920629413;6824.283450237852;6830.8633827324165;6834.931650683429;6818.795436136924;6911.101556797584;6934.826629334307;6936.7572134975035;6954.652267386711;6433.356664333529;6527.216733991761;6606.504512161511;6645.634490103091;6704.363085506869;6769.864602707819;6808;6808.114945057111;6811.863762724817;6814.795556133324;6291.496680265659;6528.804135875931;6561.96719016421;6568.638724870178;6588.670566471753;6435.967820160869;6501.609903405811;6544.934550654623;6590.7693230736695;6591.472682185509;4987.850364489072;6338.859005379076;6388.968055159695;6419.037144428186;6448.774292899726;5746.310442746897;6524.184476940382;6550.246721627074;6574.506911981716;6577.736890524326;6240.656763878031;6297.52768542352;6324.841878953063;6326.746930122744;6332.335104813928 10:06:27 INFO - PID 1912 | |1;ext.html;730.6785014593579;732.3445516262944;825.4428260923877;1036.6838114375112;1263.7346157306965;36810.815945920265;37489.81255093728;37580.43629345558;37686.623133768655;37913.810430947844;37060.25879482409;37483.875483735465;37542.8122859386;37569.62430375695;37590.24819503608;7931.127575966639;8394.412391132622;8621.13788621136;8646.351523635722;8655.221030107288;8588.785280367978;8683.175098365646;8683.913160868406;8685.652573897036;8688.174623410798;8645.740627781146;8700.30397568193;8706.085860984569;8719.04090550039;8769.649214031431;6907.896381554281;8485.193906578927;8625.26685231756;8631.95684021579;8694.478331300143;6936.861262774716;7174.605396703179;8407.747767566983;8489.066202717697;8491.702790402369;36471;36757.44863827045;36806.44790328147;36872.52509899601;36873.81563092194;3611.693006094485;3620.529331186955;3644.4715516249994;3651.415773476231;3659.4693769403552;29705.70294297051;30119;30198.54702179469;30199;30219.697803021907;2657.627932089509;3119.0954623159255;3120.2667373167287;3164.889228877001;3176.2059485128716;168186;212533;237917;278372.21627783665;278496;46866.76566617145;47198.5280147201;47243.818904526976;47253.5274647256;47343.763281183376;44502.77748611236;45017.649470515935;48535.029299413814;48927.51072489301;49205.27781527701;12270.570530031406;14085.15489070659;14392.06451580644;14527.418903243963;14543.636409089646;11888.167828252112;12050.39748012596;12073.698157546132;12086.87913120875;12136.878631213574;13211.075224734177;13221.73556528864;13245.271510066847;13304;13340.132891362031;5086.618503612244;5917.644941303535;5976.760929562855;5977.641341519523;6000.459958603747;957.6265256549951;969.8604140135453;972.644984580623;989.8762654668167;1005.0201053972319;19116.713249301272;21895.452613684465;21995.890020549796;22030.339089827332;22083.227087051877;1065.7016035510069;1093.7156339351825;1095.646876108006;1103.8399432082304;1107.8061339265596;10581.735456613646;11341.886581134084;11409.600663976888;11460.828087578693;11807.527698891949;283631;345650.27174863964;345693;346100;346232;53766;53906.460935390925;54202.728986354814;54204.45795542073;54272.728636356565;1474.808274924253;1489.101395720465;1535.9155246461328;1536.8309485956536;1554.8678362339328;21533.353999380044;21768.67346989828;21805.672914905972;21876.01557929929;22031.779682203614;28002.579961301006;37383.626163738016;37444.06389840167;37486.2502749938;37520.24959500849;307610.92389076715;309865;310217;310445;310768;30721.53917691281;33032.66967330296;34090.82954585211;34097.48853767245;34161.31677366488;44130.77934610307;44714.77642611766;44970.77514612406;44983.77508112569;45386;30229.395412092075;30345;30823.691763082083;30953;31277.748890044142;22162.33512994613;24144.63783043291;30847.84576077105;30981.53527697041;31061.068167954996;30210.84894575513;36241.27517449584;36814.26371472609;36916.81541592275;37171;30203.848980754956;30248;31267.53098703567;31356.843215783774;31433.68566314399;22546.774532255124;23978.160764373162;24167.637485438085;24194.637080443455;24460.755392445848 10:06:27 INFO - PID 1912 | |2;jquery.html;422.51832910482034;442.2459706200927;442.75205884704565;444.89100170458244;454.0804870137972;16979.57551061224;17068.146592670364;21574.22451071062;22181.334559963187;22422.439439014033;21922.780772192273;23068.19261325854;23687.881560592174;23859.16492922748;23931.401714957134;6039.305479869805;6206.875862482748;6267.310595834455;6271.874562508747;6273.623582585036;5536.446355364452;5908.350081491032;6024.9698751506185;6036.879262414749;6135.478484328815;4663.183942810012;6119.90820137696;6133.631982081067;6152.384761523835;6163.753449862;5873.676947767871;6161.199044124258;6163.907541386838;6182.474489668383;6187.381261873822;5434.592405569598;5438.7280635968045;5492.780288788444;5495.917561236584;5504.669719816784;27673;27891.442171156465;27973.86013069942;28196.295092622644;28269.85865070682;2191.2878314547834;2261.3894248552965;2266.3994041578994;2269.4326418395403;2270.8069814065825;17849.7322540162;18006;18230.453086407295;18533.351332703292;18644.25422983079;2170.848040637156;2188.2013065231235;2239.4177513846353;2244.382794731462;2245.3937436891947;157559;157718;157765;159351.2194634163;161226;11975;12624.116311858266;12652;12671.809922851164;12672.303023333805;16402.67194656124;16539.173041347884;16570;16580.088095154882;16602.91698541524;8931.776705582282;9009;9119.452832830051;9193.310501712265;9264.397814142194;8800.427972181787;8804.735857924272;8807.163319484638;8967.865482017774;8985.820283594423;7696.615169241515;7804.97070761606;7844.843103137905;7857.017872765904;7870.763877083697;2132.978670213309;2151.4943988162768;2154.8491605587774;2155.1379448220837;2161.8378621603442;944.5749412764284;1012.1649639047815;1012.291396022787;1027.1474676018886;1063.9361638301573;20687.482812929495;21620.78379216219;21695.34913952584;21720.891395543236;21751.347459576238;959.8320293948531;985.5515740338129;994.9054839790208;1044.4203467075695;1075.7848430313968;10499.895001050045;11193.552257909592;11194.832077518844;11197.880211978736;11223.831642525369;647465;660210;667002;675227;675493;118557;149331;155943.44056559517;159132;169652;1211.8909298163207;1218.4760552962312;1255.3723138430785;1261.9936900315624;1265.7974724044109;19035.904820475807;21772.45568860791;22125.336239912827;22450;22595.322140335815;60577;64218;64609.03086453801;65840.6707966457;67000.6649966747;54828;65800.67099664471;82414;83648.58175709083;84097;8830.337724670804;8853.557322133996;8862.468251904906;8918.821623567623;9171.862422063541;11817.818218178114;13750.862491374959;13993.440262389391;14142.50501232452;14270.072445291024;9697.806043879224;11611.5355385787;11667.59163429276;12040.75918481643;12139.332336721565;10462.515477093792;11233.38216398106;11264.324140551593;11352.432378381212;11477.598284060017;11213;11821.940890295493;11935.940320298343;12098.395080245777;12221.511139554674;8813.42712723671;8877.467351958903;8877.913690282649;8968.192862642394;9146.31402644791;11707.239029463057;11761.941190293994;11952.223105498462;11954.043676506033;12015.219010764273 10:06:27 INFO - PID 1912 | |3;mootools.html;1221.2184202110648;1434.8063011493452;1436.6982933583947;1458.0449805377475;1485.4355344968913;11447.141464390163;11852.644420667393;11907.047436205105;11934.04527637789;12015.098867584922;11889.286642801439;11971.880281197185;12037.157398982054;12126.090543209251;12142.028637708983;2528.582783840664;2558.9744102559016;2561.9231423057245;2565.050931155475;2572.3311938895927;2467.0501856785177;2474.418511649761;2532.9240122796255;2535.492901419714;2539.5555777738914;2436.5370579589908;2487.129504673368;2506.8621225832662;2517.3832411059316;2519.4457219411793;2395.4849707312924;2477.479729256849;2494.977059405644;2495.513374891879;2500.374906273432;2433.3429973907123;2443.2914454808083;2473.542394656994;2481.5409149307434;2533.1640558615613;11877.88122118785;11952.880471195263;11974.880251197465;11978.281303121841;11981.46083426248;6600.570962887397;6854.931450685479;6869.072675188835;6871.587704737682;6871.828204294882;4054.331035379159;4268.338407546835;4341.696081274312;4369.978150109229;4407.140607581523;1213.1447329632588;1232.433080782841;1235.2650173147053;1279.8912092472058;1287.5043108403238;14973.475928342565;15361.231938403034;16631.334746610122;16763.16184190798;16777.077260750655;5567.66594004361;5600.439956004367;5764.481196692318;5804.94195058044;5846.94153058464;12211.26732396059;12317.260964342167;12437.62687119388;12449.688757781129;12467.625971220878;6595.736170553124;6667;6670.166229221348;6677.933220667828;6679.76620818269;6053.848653783691;6259.655718935503;6287.9056814147825;6311.147995020705;6332.905006424908;3847.3844184930276;4350.825966961349;4380.277254253013;4452.287633978613;4539.75031373271;1274.846264130963;1304.1979182802697;1329.6875234319928;1343.4559003603556;1348.5010546098006;691.6991108867558;693.230514129316;713.4399496395305;795.4829360915451;814.8329592433537;6085.604435711664;6150;6281.560290779693;6327.240731112204;6405.071264666597;587.3451102021301;601.2484394506866;612.3386742318286;614.8739508400768;617.6664601115348;3943.8957092014284;3956.248312820555;4145.606167414091;4337.804798784062;4396.648268138541;14987.775183372258;15483.92258038725;15518.146501942501;15523.146226957626;15539.378424863105;15320.15739134336;15361.692766144615;15394;15412.614684632974;15463.690726185638;9109.590068446936;10979.890201097887;11183.384913829817;11194.272372295774;11196.552137914392;6414.198225221848;6731.528792984444;6943.722251110101;7054.224035356004;7144.785656430316;12141.453634586289;12653.240805551697;13273.137246078973;13453.932730336286;13763.830074443384;13014.934925325691;13969.930150349182;13995.16029038261;14011.719765604834;14289.571312860631;8610.913890861011;9363.859542106738;9426.010268921938;9468.195203407511;9536.618535258789;10413.895861041292;10673;10687.625933092304;10688.679339619825;10697.946510267398;8847.46915185091;8983.416077954913;9196.632134714537;9201.907980920372;9340.53297335117;8917.910820891968;8953.641854325753;8959.283257339528;9044.231240344645;9090.000099988863;8053.395995300259;8066.153053929253;8180.509169449852;8180.509169449852;8206.425550211428;5328.733563321896;6011.519078473799;6084.421979911901;6140.078988151634;6205.503559715302;5579.16312553104;6079.148919151234;6162.722677479614;6179.5056395489155;6245.5628106033955 10:06:27 INFO - PID 1912 | |4;prototype.html;341.88717723151365;390.18840811112887;403.4231049599074;413.1963331320581;415.2836357283686;5471.206675032123;5937.109433584959;6150.446459818615;6209.410106039931;6409.8718025639455;6120.387961203888;6184.938150618493;6189.628622282655;6218.782342618009;6248.437890527368;3931.941020884689;4270.444842170514;4424.889377765549;4540.9318860217045;4568.817247310104;4512.323151527265;4542.750148741818;4545.409096817409;4548.7043342182815;4568.862934111982;3608.9819550902207;4003.958970667633;4051.8379264829373;4508.72947623142;4539.6368290536675;4316.309390497506;4467.731936083813;4523.434570678665;4536.115457485792;4561.315802629599;3016.9396612067853;3901.2977664020464;3905.297046531623;3905.379267603941;3922.8430862765454;4685.5314468553215;5171.482851714836;5586.910552442276;6074.8785024299705;6106.389361063858;4013.9965008747813;4733.195356789354;5107.693538387709;5385.461453854622;5468.179773034037;5140.845774626767;5387.4612538746205;5952.166696662471;6514.837129071811;6708.396244337984;4387.319965405367;4402.8426071225185;4457.509673935864;4462.709923854939;4471.329300604935;5119.846404607868;5811.79658711943;6725.39471447572;6971.8605627887155;6990.825229369256;4414.183376075436;4505.256632655575;4505.842295519641;4528.207563676409;4539.16025535277;4558.247889098262;4593.816247350069;4601.723896566216;4614.976925115353;4619.953800461953;4555.9772201138785;4575.931361029588;5202.24567437727;5392.676439413648;5411.675299482044;6641.435477984425;6641.601503909781;6666.699998500079;6693.732250709917;6712.362325579063;1501.5074975540551;1544.3668096080553;1568.8588027077617;1570.2776722707454;1571.5206861907227;1025.9586519682528;1026.343140390151;1032.9896701032894;1034.5809946971488;1039.9740006499746;6316.778912738032;6355.968220158869;6370;6377;6394.616323020634;5845.386234445407;6515.283318834924;6690.632015239208;6835.692393842289;6855.828604284833;6171.660558669317;6277.372262773686;6286.654234017173;6350.269718982377;6372.681365931685;3014.608100946863;3461.1866211440292;3591.479235510836;3632.182758879284;3636.781793092423;6842.897356539655;7064.399526040344;7080.752173673896;7142.607156606333;7161.301600779183;6192.659403732748;6912.792616221522;6958.7912362628185;7166.426685865118;7176.282371762782;5558.583106266965;6149.2313460817395;6611.702473388709;7065.6113913735235;7084.681189346595;6020.909686354796;6458.451031662361;6779.644086166564;6944.305569443015;6953;6360.363963603602;6683.6324002180345;6755.86488270222;6789.966050169717;6792.762253321308;5830;7079.433645308466;7120;7144.85710285781;7163.31948464895;3943.7042221832903;4017.8348278998765;4470.664700147437;4497.100579883971;4498.145352383037;6476.255230648536;6819.488538359742;6868.896966545406;6913.827154321182;6932.341427564374;6446.387593178641;6603.669816509251;6621.795160073292;6627.536072474881;6687.1975362956755;5803.82588522345;6497.512686548623;6510.414062734377;6524.303681042868;6622.238442578972;6450.419462248233;6465.288818230085;6508.934910651023;6511.837204069936;6578.868422631424;5435.374931882884;6463.64449955257;6604.735810567716;6615.503837212132;6618.106555614931;6386.489080873426;6425.807225783234;6437.356264373525;6460.25707043696;6486.902696459651 10:06:27 INFO - PID 1912 | |5;yui.html;817.7832874288318;826.6321486938315;845.6406027438339;866.0257210638032;868.1925808997631;14271.78592321116;14972.625684357896;16237.51287461375;16534.17329133543;16937.745933810987;13155.473781048771;13685.452581896712;17611.207495662693;17753.467395978143;17793;992.6674564021062;1033.612395351743;1038.9688309350731;1045.242199405429;1045.6288017753702;980.5097451274363;990.2770977186658;1011.3426272922586;1018.8522664213684;1036.7097212780434;1021.3208216535994;1046.8325067989124;1058.867641544807;1060.8355704865758;1065.4832406282947;1044.154235069596;1046.0376453662611;1055.4775386183887;1056.8467572202026;1057.4659796802566;779.8440311937578;782.5304817109745;790.6165509727834;790.7311514085238;796;12829.55096571625;15491.845081549152;15520.456784012506;15632;16676.66646667072;16463.34146634133;16593.087380194083;16780;16780.077095759727;17346.392876249272;13880.097793643381;14043.297835108306;14074;14163.433462661584;14205.928970355082;3491.7555771096036;3505.6494350564994;3518.5249991251108;3519.2961407718303;3523.612402635708;122463.38768306101;123219.38390307991;125542.11686824705;125668;126217;13048.54330098442;13241;13305.268210248529;13418.530351437652;13515.864841351657;25344.11295604645;25552.616710749353;25564.23307300784;25640.102596409037;26279.21162365132;7397.889031664529;8621.396502244783;8723.389362744674;8822.117788221127;8844.513551754715;4812.398450193726;4902.656814022984;4912.214045752662;4920.237363208708;4926.6551341406475;4303.096349766584;4364.258076127066;4386.649068074546;4393.428854248928;4401.559844015573;878.2885862451435;898.6495266845935;907.9319051071196;913.5432283858071;914.5107367558325;638.6678926957956;661.3518751623436;667.4526887951832;672.831792051987;678.4233401608692;9473.668421605349;11721.296722196696;11839.940800296115;11929.105317101252;11946.701332466759;631.2172905597026;645.0807599171256;665.3878431842717;668.9464842812661;680.3672584496466;5392.487713667196;5457.61796674229;5473;5491.121420572689;5498.917516237259;85179.57410212909;105328.94671053192;106860.46569767101;107985.46007269913;109995;72655.63672181606;93633.53183234176;94453.5831962521;94774;96144;83223.5838820802;83447;83797.16202837894;84024.57987710145;84039;9105.908940910505;10132.594696212069;10720.249582529148;10826.296290741077;11147.108231341312;42337.57662423336;42641;42738.57261427346;43180.78409607932;43224;55643;55859.72070139623;55959.44040559542;56439.920407163554;56545.43454565402;11186.440677966231;16068.517944461684;16933.49199524016;16981.235844386785;17085.48743537696;18095;18531.98074105937;18609;19210.039498025322;19279.903600481906;13306.534271300457;17514.47456576305;17885.28458861683;17996.37012704549;18228.544286392946;16696;17636.470905872844;18267.634647307244;18285.451436456926;18370.540736481697;15022.399104036152;16171.757423638419;16470.75293870569;17002;17257.0508622027;10099.848502272325;11402.714932126431;11579.305241685526;11642.6507204784;12120.575779848015;11455.770884582429;13189.802152967904;13487.730245394841;13874.514391996232;13884.861151388357 10:06:27 INFO - PID 1912 | __end_tp_report 10:06:27 INFO - PID 1912 | __start_cc_report 10:06:27 INFO - PID 1912 | _x_x_mozilla_cycle_collect,425 10:06:27 INFO - PID 1912 | __end_cc_report 10:06:27 INFO - PID 1912 | __startTimestamp1491930387563__endTimestamp 10:06:27 INFO - PID 1912 | 10:06:27 INFO - PID 1912 | ------- Summary: start ------- 10:06:27 INFO - PID 1912 | Number of tests: 6 10:06:27 INFO - PID 1912 | 10:06:27 INFO - PID 1912 | [#0] dojo.html Cycles:165 Average:6104.79 Median:6357.46 stddev:780.08 (12.3%) stddev-sans-first:742.40 10:06:27 INFO - PID 1912 | Values: 2958.5 3526.6 3560.0 3572.4 3579.2 4688.5 5890.8 6194.8 6270.3 6275.2 5000.2 6097.7 6167.9 6215.4 6308.0 4349.4 4457.9 5478.2 5531.3 5580.2 5410.8 5523.5 5576.5 5577.1 5580.7 4492.6 5503.4 5539.3 5547.9 5621.2 5389.9 5482.6 5490.4 5519.1 5566.8 5414.5 5419.6 5439.0 5441.4 5468.2 5663.8 6131.5 6236.8 6292.1 6320.8 5591.7 6485.0 6618.5 6694.0 6702.6 6789.8 6790.8 6800.7 6809.4 6850.9 5162.5 5238.4 6357.5 6364.3 6366.3 6277.6 6358.6 6662.9 6875.1 6899.1 6369.9 6501.9 6522.4 6614.4 6627.4 6137.9 6593.5 6603.5 6661.7 6696.3 6708.5 6722.9 6725.2 6729.7 6747.2 6470.1 6550.9 6552.8 6738.5 6758.1 5306.6 5337.6 5339.9 5353.6 5380.0 4764.0 4783.7 4784.4 4806.0 4830.3 6262.9 6348.6 6350.0 6364.6 6383.5 6621.7 6733.3 6768.6 6778.4 6807.9 6294.7 6341.2 6353.5 6355.6 6371.6 5939.1 5941.8 5960.9 5965.0 5966.6 5305.8 6769.5 6844.8 6894.8 6942.8 6778.6 6819.6 6824.3 6830.9 6834.9 6818.8 6911.1 6934.8 6936.8 6954.7 6433.4 6527.2 6606.5 6645.6 6704.4 6769.9 6808.0 6808.1 6811.9 6814.8 6291.5 6528.8 6562.0 6568.6 6588.7 6436.0 6501.6 6544.9 6590.8 6591.5 4987.9 6338.9 6389.0 6419.0 6448.8 5746.3 6524.2 6550.2 6574.5 6577.7 6240.7 6297.5 6324.8 6326.7 6332.3 10:06:27 INFO - PID 1912 | 10:06:27 INFO - PID 1912 | [#1] ext.html Cycles:180 Average:43996.03 Median:189905.79 stddev:78121.99 (41.1%) stddev-sans-first:78273.60 10:06:27 INFO - PID 1912 | Values: 730.7 732.3 825.4 1036.7 1263.7 36810.8 37489.8 37580.4 37686.6 37913.8 37060.3 37483.9 37542.8 37569.6 37590.2 7931.1 8394.4 8621.1 8646.4 8655.2 8588.8 8683.2 8683.9 8685.7 8688.2 8645.7 8700.3 8706.1 8719.0 8769.6 6907.9 8485.2 8625.3 8632.0 8694.5 6936.9 7174.6 8407.7 8489.1 8491.7 36471.0 36757.4 36806.4 36872.5 36873.8 3611.7 3620.5 3644.5 3651.4 3659.5 29705.7 30119.0 30198.5 30199.0 30219.7 2657.6 3119.1 3120.3 3164.9 3176.2 168186.0 212533.0 237917.0 278372.2 278496.0 46866.8 47198.5 47243.8 47253.5 47343.8 44502.8 45017.6 48535.0 48927.5 49205.3 12270.6 14085.2 14392.1 14527.4 14543.6 11888.2 12050.4 12073.7 12086.9 12136.9 13211.1 13221.7 13245.3 13304.0 13340.1 5086.6 5917.6 5976.8 5977.6 6000.5 957.6 969.9 972.6 989.9 1005.0 19116.7 21895.5 21995.9 22030.3 22083.2 1065.7 1093.7 1095.6 1103.8 1107.8 10581.7 11341.9 11409.6 11460.8 11807.5 283631.0 345650.3 345693.0 346100.0 346232.0 53766.0 53906.5 54202.7 54204.5 54272.7 1474.8 1489.1 1535.9 1536.8 1554.9 21533.4 21768.7 21805.7 21876.0 22031.8 28002.6 37383.6 37444.1 37486.3 37520.2 307610.9 309865.0 310217.0 310445.0 310768.0 30721.5 33032.7 34090.8 34097.5 34161.3 44130.8 44714.8 44970.8 44983.8 45386.0 30229.4 30345.0 30823.7 30953.0 31277.7 22162.3 24144.6 30847.8 30981.5 31061.1 30210.8 36241.3 36814.3 36916.8 37171.0 30203.8 30248.0 31267.5 31356.8 31433.7 22546.8 23978.2 24167.6 24194.6 24460.8 10:06:27 INFO - PID 1912 | 10:06:27 INFO - PID 1912 | [#2] jquery.html Cycles:180 Average:39700.44 Median:22522.66 stddev:111928.29 (497.0%) stddev-sans-first:112203.42 10:06:27 INFO - PID 1912 | Values: 422.5 442.2 442.8 444.9 454.1 16979.6 17068.1 21574.2 22181.3 22422.4 21922.8 23068.2 23687.9 23859.2 23931.4 6039.3 6206.9 6267.3 6271.9 6273.6 5536.4 5908.4 6025.0 6036.9 6135.5 4663.2 6119.9 6133.6 6152.4 6163.8 5873.7 6161.2 6163.9 6182.5 6187.4 5434.6 5438.7 5492.8 5495.9 5504.7 27673.0 27891.4 27973.9 28196.3 28269.9 2191.3 2261.4 2266.4 2269.4 2270.8 17849.7 18006.0 18230.5 18533.4 18644.3 2170.8 2188.2 2239.4 2244.4 2245.4 157559.0 157718.0 157765.0 159351.2 161226.0 11975.0 12624.1 12652.0 12671.8 12672.3 16402.7 16539.2 16570.0 16580.1 16602.9 8931.8 9009.0 9119.5 9193.3 9264.4 8800.4 8804.7 8807.2 8967.9 8985.8 7696.6 7805.0 7844.8 7857.0 7870.8 2133.0 2151.5 2154.8 2155.1 2161.8 944.6 1012.2 1012.3 1027.1 1063.9 20687.5 21620.8 21695.3 21720.9 21751.3 959.8 985.6 994.9 1044.4 1075.8 10499.9 11193.6 11194.8 11197.9 11223.8 647465.0 660210.0 667002.0 675227.0 675493.0 118557.0 149331.0 155943.4 159132.0 169652.0 1211.9 1218.5 1255.4 1262.0 1265.8 19035.9 21772.5 22125.3 22450.0 22595.3 60577.0 64218.0 64609.0 65840.7 67000.7 54828.0 65800.7 82414.0 83648.6 84097.0 8830.3 8853.6 8862.5 8918.8 9171.9 11817.8 13750.9 13993.4 14142.5 14270.1 9697.8 11611.5 11667.6 12040.8 12139.3 10462.5 11233.4 11264.3 11352.4 11477.6 11213.0 11821.9 11935.9 12098.4 12221.5 8813.4 8877.5 8877.9 8968.2 9146.3 11707.2 11761.9 11952.2 11954.0 12015.2 10:06:27 INFO - PID 1912 | 10:06:27 INFO - PID 1912 | [#3] mootools.html Cycles:180 Average:7223.31 Median:2560.45 stddev:4601.33 (179.7%) stddev-sans-first:4592.13 10:06:27 INFO - PID 1912 | Values: 1221.2 1434.8 1436.7 1458.0 1485.4 11447.1 11852.6 11907.0 11934.0 12015.1 11889.3 11971.9 12037.2 12126.1 12142.0 2528.6 2559.0 2561.9 2565.1 2572.3 2467.1 2474.4 2532.9 2535.5 2539.6 2436.5 2487.1 2506.9 2517.4 2519.4 2395.5 2477.5 2495.0 2495.5 2500.4 2433.3 2443.3 2473.5 2481.5 2533.2 11877.9 11952.9 11974.9 11978.3 11981.5 6600.6 6854.9 6869.1 6871.6 6871.8 4054.3 4268.3 4341.7 4370.0 4407.1 1213.1 1232.4 1235.3 1279.9 1287.5 14973.5 15361.2 16631.3 16763.2 16777.1 5567.7 5600.4 5764.5 5804.9 5846.9 12211.3 12317.3 12437.6 12449.7 12467.6 6595.7 6667.0 6670.2 6677.9 6679.8 6053.8 6259.7 6287.9 6311.1 6332.9 3847.4 4350.8 4380.3 4452.3 4539.8 1274.8 1304.2 1329.7 1343.5 1348.5 691.7 693.2 713.4 795.5 814.8 6085.6 6150.0 6281.6 6327.2 6405.1 587.3 601.2 612.3 614.9 617.7 3943.9 3956.2 4145.6 4337.8 4396.6 14987.8 15483.9 15518.1 15523.1 15539.4 15320.2 15361.7 15394.0 15412.6 15463.7 9109.6 10979.9 11183.4 11194.3 11196.6 6414.2 6731.5 6943.7 7054.2 7144.8 12141.5 12653.2 13273.1 13453.9 13763.8 13014.9 13969.9 13995.2 14011.7 14289.6 8610.9 9363.9 9426.0 9468.2 9536.6 10413.9 10673.0 10687.6 10688.7 10697.9 8847.5 8983.4 9196.6 9201.9 9340.5 8917.9 8953.6 8959.3 9044.2 9090.0 8053.4 8066.2 8180.5 8180.5 8206.4 5328.7 6011.5 6084.4 6140.1 6205.5 5579.2 6079.1 6162.7 6179.5 6245.6 10:06:27 INFO - PID 1912 | 10:06:27 INFO - PID 1912 | [#4] prototype.html Cycles:180 Average:5307.17 Median:6047.89 stddev:1695.00 (28.0%) stddev-sans-first:1658.28 10:06:27 INFO - PID 1912 | Values: 341.9 390.2 403.4 413.2 415.3 5471.2 5937.1 6150.4 6209.4 6409.9 6120.4 6184.9 6189.6 6218.8 6248.4 3931.9 4270.4 4424.9 4540.9 4568.8 4512.3 4542.8 4545.4 4548.7 4568.9 3609.0 4004.0 4051.8 4508.7 4539.6 4316.3 4467.7 4523.4 4536.1 4561.3 3016.9 3901.3 3905.3 3905.4 3922.8 4685.5 5171.5 5586.9 6074.9 6106.4 4014.0 4733.2 5107.7 5385.5 5468.2 5140.8 5387.5 5952.2 6514.8 6708.4 4387.3 4402.8 4457.5 4462.7 4471.3 5119.8 5811.8 6725.4 6971.9 6990.8 4414.2 4505.3 4505.8 4528.2 4539.2 4558.2 4593.8 4601.7 4615.0 4620.0 4556.0 4575.9 5202.2 5392.7 5411.7 6641.4 6641.6 6666.7 6693.7 6712.4 1501.5 1544.4 1568.9 1570.3 1571.5 1026.0 1026.3 1033.0 1034.6 1040.0 6316.8 6356.0 6370.0 6377.0 6394.6 5845.4 6515.3 6690.6 6835.7 6855.8 6171.7 6277.4 6286.7 6350.3 6372.7 3014.6 3461.2 3591.5 3632.2 3636.8 6842.9 7064.4 7080.8 7142.6 7161.3 6192.7 6912.8 6958.8 7166.4 7176.3 5558.6 6149.2 6611.7 7065.6 7084.7 6020.9 6458.5 6779.6 6944.3 6953.0 6360.4 6683.6 6755.9 6790.0 6792.8 5830.0 7079.4 7120.0 7144.9 7163.3 3943.7 4017.8 4470.7 4497.1 4498.1 6476.3 6819.5 6868.9 6913.8 6932.3 6446.4 6603.7 6621.8 6627.5 6687.2 5803.8 6497.5 6510.4 6524.3 6622.2 6450.4 6465.3 6508.9 6511.8 6578.9 5435.4 6463.6 6604.7 6615.5 6618.1 6386.5 6425.8 6437.4 6460.3 6486.9 10:06:27 INFO - PID 1912 | 10:06:27 INFO - PID 1912 | [#5] yui.html Cycles:180 Average:21782.41 Median:18161.77 stddev:30651.15 (168.8%) stddev-sans-first:30696.71 10:06:27 INFO - PID 1912 | Values: 817.8 826.6 845.6 866.0 868.2 14271.8 14972.6 16237.5 16534.2 16937.7 13155.5 13685.5 17611.2 17753.5 17793.0 992.7 1033.6 1039.0 1045.2 1045.6 980.5 990.3 1011.3 1018.9 1036.7 1021.3 1046.8 1058.9 1060.8 1065.5 1044.2 1046.0 1055.5 1056.8 1057.5 779.8 782.5 790.6 790.7 796.0 12829.6 15491.8 15520.5 15632.0 16676.7 16463.3 16593.1 16780.0 16780.1 17346.4 13880.1 14043.3 14074.0 14163.4 14205.9 3491.8 3505.6 3518.5 3519.3 3523.6 122463.4 123219.4 125542.1 125668.0 126217.0 13048.5 13241.0 13305.3 13418.5 13515.9 25344.1 25552.6 25564.2 25640.1 26279.2 7397.9 8621.4 8723.4 8822.1 8844.5 4812.4 4902.7 4912.2 4920.2 4926.7 4303.1 4364.3 4386.6 4393.4 4401.6 878.3 898.6 907.9 913.5 914.5 638.7 661.4 667.5 672.8 678.4 9473.7 11721.3 11839.9 11929.1 11946.7 631.2 645.1 665.4 668.9 680.4 5392.5 5457.6 5473.0 5491.1 5498.9 85179.6 105328.9 106860.5 107985.5 109995.0 72655.6 93633.5 94453.6 94774.0 96144.0 83223.6 83447.0 83797.2 84024.6 84039.0 9105.9 10132.6 10720.2 10826.3 11147.1 42337.6 42641.0 42738.6 43180.8 43224.0 55643.0 55859.7 55959.4 56439.9 56545.4 11186.4 16068.5 16933.5 16981.2 17085.5 18095.0 18532.0 18609.0 19210.0 19279.9 13306.5 17514.5 17885.3 17996.4 18228.5 16696.0 17636.5 18267.6 18285.5 18370.5 15022.4 16171.8 16470.8 17002.0 17257.1 10099.8 11402.7 11579.3 11642.7 12120.6 11455.8 13189.8 13487.7 13874.5 13884.9 10:06:27 INFO - PID 1912 | -------- Summary: end -------- 10:06:27 INFO - PID 1912 | 10:06:28 INFO - TEST-INFO | 1912: exit 0 10:06:28 INFO - TEST-OK | dromaeo_css | took 1116233ms 10:06:28 INFO - TEST-START | kraken 10:06:28 INFO - operating with platform_type : mac_ 10:06:28 INFO - Initialising browser for kraken test... 10:06:28 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpuQNlwx/profile http://localhost:51829/getInfo.html) 10:06:31 INFO - PID 1918 | 2017-04-11 10:06:31.340 plugin-container[1919:15788] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 10:06:31 INFO - PID 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:31 INFO - PID 1918 | 2017-04-11 10:06:31.345 plugin-container[1919:15788] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 10:06:31 INFO - PID 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:31 INFO - PID 1918 | 2017-04-11 10:06:31.345 plugin-container[1919:15788] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:31 INFO - PID 1918 | __metrics Screen width/height:1600/1200 10:06:31 INFO - PID 1918 | colorDepth:24 10:06:31 INFO - PID 1918 | Browser inner width/height: 1024/689 10:06:31 INFO - PID 1918 | __metrics 10:06:31 ERROR - PID 1918 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 10:06:32 INFO - TEST-INFO | 1918: exit 0 10:06:32 INFO - Browser initialized. 10:06:32 INFO - Running cycle 1/1 for kraken test... 10:06:32 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpuQNlwx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:35 INFO - PID 1920 | 2017-04-11 10:06:35.249 plugin-container[1921:15917] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 10:06:35 INFO - PID 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:35 INFO - PID 1920 | 2017-04-11 10:06:35.250 plugin-container[1921:15917] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 10:06:35 INFO - PID 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:35 INFO - PID 1920 | 2017-04-11 10:06:35.250 plugin-container[1921:15917] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:44 INFO - PID 1920 | RSS: Main: 236019712 10:06:44 INFO - PID 1920 | 10:06:47 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51829/tests/kraken/driver.html?testName=audio-beat-detection) 10:06:48 INFO - PID 1920 | RSS: Main: 249933824 10:06:48 INFO - PID 1920 | 10:06:51 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51829/tests/kraken/driver.html?testName=audio-dft) 10:06:52 INFO - PID 1920 | RSS: Main: 258551808 10:06:52 INFO - PID 1920 | 10:06:55 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51829/tests/kraken/driver.html?testName=audio-fft) 10:06:56 INFO - PID 1920 | RSS: Main: 260780032 10:06:56 INFO - PID 1920 | 10:06:58 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51829/tests/kraken/driver.html?testName=audio-oscillator) 10:06:59 INFO - PID 1920 | RSS: Main: 262774784 10:06:59 INFO - PID 1920 | 10:07:01 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51829/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:07:01 INFO - PID 1920 | RSS: Main: 263442432 10:07:01 INFO - PID 1920 | 10:07:05 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51829/tests/kraken/driver.html?testName=imaging-darkroom) 10:07:05 INFO - PID 1920 | RSS: Main: 268767232 10:07:05 INFO - PID 1920 | 10:07:09 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51829/tests/kraken/driver.html?testName=imaging-desaturate) 10:07:10 INFO - PID 1920 | RSS: Main: 266899456 10:07:10 INFO - PID 1920 | 10:07:13 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51829/tests/kraken/driver.html?testName=json-parse-financial) 10:07:14 INFO - PID 1920 | RSS: Main: 266735616 10:07:14 INFO - PID 1920 | 10:07:15 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51829/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:07:16 INFO - PID 1920 | RSS: Main: 267710464 10:07:16 INFO - PID 1920 | 10:07:18 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51829/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:07:19 INFO - PID 1920 | RSS: Main: 268087296 10:07:19 INFO - PID 1920 | 10:07:21 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51829/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:07:21 INFO - PID 1920 | RSS: Main: 268189696 10:07:21 INFO - PID 1920 | 10:07:24 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51829/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:07:25 INFO - PID 1920 | RSS: Main: 268558336 10:07:25 INFO - PID 1920 | 10:07:29 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51829/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:07:29 INFO - PID 1920 | RSS: Main: 266596352 10:07:29 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | Cycle 1(1): loaded http://localhost:51829/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51829/tests/kraken/driver.html?testName=ai-astar) 10:07:31 INFO - PID 1920 | RSS: Main: 270028800 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | __start_tp_report 10:07:31 INFO - PID 1920 | _x_x_mozilla_page_load 10:07:31 INFO - PID 1920 | _x_x_mozilla_page_load_details 10:07:31 INFO - PID 1920 | |i|pagename|runs| 10:07:31 INFO - PID 1920 | |0;ai-astar;115;119;130;124;121;139;165;120;121;166 10:07:31 INFO - PID 1920 | |1;audio-beat-detection;151;118;124;151;129;146;102;99;180;153 10:07:31 INFO - PID 1920 | |2;audio-dft;223;248;308;232;220;215;215;210;202;296 10:07:31 INFO - PID 1920 | |3;audio-fft;92;90;87;97;60;59;59;59;77;86 10:07:31 INFO - PID 1920 | |4;audio-oscillator;89;71;90;72;73;72;89;76;108;86 10:07:31 INFO - PID 1920 | |5;imaging-gaussian-blur;92;123;135;92;128;93;97;141;93;98 10:07:31 INFO - PID 1920 | |6;imaging-darkroom;101;124;112;161;158;102;102;163;121;96 10:07:31 INFO - PID 1920 | |7;imaging-desaturate;149;103;103;105;115;125;120;159;122;157 10:07:31 INFO - PID 1920 | |8;json-parse-financial;52;51;50;53;53;55;61;74;85;82 10:07:31 INFO - PID 1920 | |9;json-stringify-tinderbox;58;66;64;65;66;53;78;91;90;90 10:07:31 INFO - PID 1920 | |10;stanford-crypto-aes;86;89;91;88;88;101;88;76;107;84 10:07:31 INFO - PID 1920 | |11;stanford-crypto-ccm;151;152;147;151;166;168;156;173;181;132 10:07:31 INFO - PID 1920 | |12;stanford-crypto-pbkdf2;187;231;210;226;217;206;209;260;200;207 10:07:31 INFO - PID 1920 | |13;stanford-crypto-sha256-iterative;73;74;75;94;75;75;73;98;63;94 10:07:31 INFO - PID 1920 | __end_tp_report 10:07:31 INFO - PID 1920 | __start_cc_report 10:07:31 INFO - PID 1920 | _x_x_mozilla_cycle_collect,1012 10:07:31 INFO - PID 1920 | __end_cc_report 10:07:31 INFO - PID 1920 | __startTimestamp1491930451908__endTimestamp 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | ------- Summary: start ------- 10:07:31 INFO - PID 1920 | Number of tests: 14 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#0] ai-astar Cycles:10 Average:132.00 Median:127.00 stddev:18.87 (14.9%) stddev-sans-first:18.99 10:07:31 INFO - PID 1920 | Values: 115.0 119.0 130.0 124.0 121.0 139.0 165.0 120.0 121.0 166.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#1] audio-beat-detection Cycles:10 Average:135.30 Median:151.00 stddev:25.41 (16.8%) stddev-sans-first:26.31 10:07:31 INFO - PID 1920 | Values: 151.0 118.0 124.0 151.0 129.0 146.0 102.0 99.0 180.0 153.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#2] audio-dft Cycles:10 Average:236.90 Median:227.50 stddev:36.63 (16.1%) stddev-sans-first:38.50 10:07:31 INFO - PID 1920 | Values: 223.0 248.0 308.0 232.0 220.0 215.0 215.0 210.0 202.0 296.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#3] audio-fft Cycles:10 Average:76.60 Median:86.50 stddev:15.76 (18.2%) stddev-sans-first:15.70 10:07:31 INFO - PID 1920 | Values: 92.0 90.0 87.0 97.0 60.0 59.0 59.0 59.0 77.0 86.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#4] audio-oscillator Cycles:10 Average:82.60 Median:81.00 stddev:11.96 (14.8%) stddev-sans-first:12.46 10:07:31 INFO - PID 1920 | Values: 89.0 71.0 90.0 72.0 73.0 72.0 89.0 76.0 108.0 86.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:92.50 stddev:20.03 (21.7%) stddev-sans-first:20.26 10:07:31 INFO - PID 1920 | Values: 92.0 123.0 135.0 92.0 128.0 93.0 97.0 141.0 93.0 98.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#6] imaging-darkroom Cycles:10 Average:124.00 Median:141.00 stddev:26.83 (19.0%) stddev-sans-first:27.14 10:07:31 INFO - PID 1920 | Values: 101.0 124.0 112.0 161.0 158.0 102.0 102.0 163.0 121.0 96.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#7] imaging-desaturate Cycles:10 Average:125.80 Median:123.50 stddev:21.73 (17.6%) stddev-sans-first:21.37 10:07:31 INFO - PID 1920 | Values: 149.0 103.0 103.0 105.0 115.0 125.0 120.0 159.0 122.0 157.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:58.00 stddev:13.53 (23.3%) stddev-sans-first:13.90 10:07:31 INFO - PID 1920 | Values: 52.0 51.0 50.0 53.0 53.0 55.0 61.0 74.0 85.0 82.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:72.00 stddev:14.09 (19.6%) stddev-sans-first:13.99 10:07:31 INFO - PID 1920 | Values: 58.0 66.0 64.0 65.0 66.0 53.0 78.0 91.0 90.0 90.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:88.00 stddev:8.64 (9.8%) stddev-sans-first:9.05 10:07:31 INFO - PID 1920 | Values: 86.0 89.0 91.0 88.0 88.0 101.0 88.0 76.0 107.0 84.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#11] stanford-crypto-ccm Cycles:10 Average:157.70 Median:161.00 stddev:14.35 (8.9%) stddev-sans-first:15.01 10:07:31 INFO - PID 1920 | Values: 151.0 152.0 147.0 151.0 166.0 168.0 156.0 173.0 181.0 132.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:215.30 Median:213.50 stddev:20.06 (9.4%) stddev-sans-first:18.47 10:07:31 INFO - PID 1920 | Values: 187.0 231.0 210.0 226.0 217.0 206.0 209.0 260.0 200.0 207.0 10:07:31 INFO - PID 1920 | 10:07:31 INFO - PID 1920 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.40 Median:75.00 stddev:11.60 (15.5%) stddev-sans-first:12.07 10:07:31 INFO - PID 1920 | Values: 73.0 74.0 75.0 94.0 75.0 75.0 73.0 98.0 63.0 94.0 10:07:31 INFO - PID 1920 | -------- Summary: end -------- 10:07:31 INFO - PID 1920 | 10:07:32 INFO - TEST-INFO | 1920: exit 0 10:07:32 INFO - TEST-OK | kraken | took 64749ms 10:07:32 INFO - SUITE-END | took 1180s 10:07:33 INFO - Completed test suite (00:19:41) 10:07:33 INFO - javascript benchmark 10:07:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7879.276849808305, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2958.4526862530447, 3526.6297038810926, 3559.982200088999, 3572.4462708280216, 3579.2304654499276, 4688.5077066908, 5890.823275301743, 6194.752209911604, 6270.341614130519, 6275.184226050618, 5000.249962505626, 6097.725602347894, 6167.907481387782, 6215.409536094064, 6308.022256550231, 4349.43457350544, 4457.866264012085, 5478.178273259013, 5531.308586426697, 5580.190872323511, 5410.783568657249, 5523.475269849358, 5576.498115169634, 5577.05190117681, 5580.665160090387, 4492.61812745917, 5503.422140675251, 5539.250011173837, 5547.861303467425, 5621.241132447109, 5389.946100539023, 5482.616216864821, 5490.423505531901, 5519.089350257203, 5566.777328906839, 5414.485623865727, 5419.59353048519, 5438.972805135949, 5441.374241962146, 5468.152436372368, 5663.838913022821, 6131.468419844951, 6236.844078898019, 6292.087647291117, 6320.778772742932, 5591.7483713233005, 6485.0, 6618.4705223582, 6694.0, 6702.564333318367, 6789.796306110825, 6790.796276111675, 6800.65996700163, 6809.421199198074, 6850.862982740317, 5162.535371816554, 5238.423773384925, 6357.459615932651, 6364.331745166737, 6366.26787919386, 6277.623342599459, 6358.586691865014, 6662.866742665216, 6875.140607424072, 6899.137607799025, 6369.904451433232, 6501.8699626008165, 6522.412982831568, 6614.437772789271, 6627.37039981201, 6137.8673257933415, 6593.505487088488, 6603.537752357289, 6661.666916654148, 6696.2968888267005, 6708.463322934153, 6722.899156512656, 6725.159355080615, 6729.69716362755, 6747.190337159573, 6470.09418681385, 6550.868982620321, 6552.8361790955605, 6738.494612904052, 6758.05387245786, 5306.575473962073, 5337.62636615441, 5339.893202135935, 5353.625246232804, 5379.950909572596, 4763.976180119077, 4783.6651434399255, 4784.378030856036, 4806.038792241566, 4830.3479030331155, 6262.874742505124, 6348.619082855043, 6349.9682501587195, 6364.586301890455, 6383.489320854414, 6621.735130594722, 6733.293004234582, 6768.593884366954, 6778.389944904884, 6807.897881531781, 6294.71673774672, 6341.175647165931, 6353.491720662336, 6355.5551111421755, 6371.617702937746, 5939.07944268639, 5941.792037278675, 5960.910586341209, 5965.0, 5966.582339236212, 5305.761240744098, 6769.526133170631, 6844.828879278058, 6894.758683446055, 6942.826429339307, 6778.627175505395, 6819.624920629413, 6824.283450237852, 6830.8633827324165, 6834.931650683429, 6818.795436136924, 6911.101556797584, 6934.826629334307, 6936.7572134975035, 6954.652267386711, 6433.356664333529, 6527.216733991761, 6606.504512161511, 6645.634490103091, 6704.363085506869, 6769.864602707819, 6808.0, 6808.114945057111, 6811.863762724817, 6814.795556133324, 6291.496680265659, 6528.804135875931, 6561.96719016421, 6568.638724870178, 6588.670566471753, 6435.967820160869, 6501.609903405811, 6544.934550654623, 6590.7693230736695, 6591.472682185509, 4987.850364489072, 6338.859005379076, 6388.968055159695, 6419.037144428186, 6448.774292899726, 5746.310442746897, 6524.184476940382, 6550.246721627074, 6574.506911981716, 6577.736890524326, 6240.656763878031, 6297.52768542352, 6324.841878953063, 6326.746930122744, 6332.335104813928], "value": 6053.61449071117, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.6785014593579, 732.3445516262944, 825.4428260923877, 1036.6838114375112, 1263.7346157306965, 36810.815945920265, 37489.81255093728, 37580.43629345558, 37686.623133768655, 37913.810430947844, 37060.25879482409, 37483.875483735465, 37542.8122859386, 37569.62430375695, 37590.24819503608, 7931.127575966639, 8394.412391132622, 8621.13788621136, 8646.351523635722, 8655.221030107288, 8588.785280367978, 8683.175098365646, 8683.913160868406, 8685.652573897036, 8688.174623410798, 8645.740627781146, 8700.30397568193, 8706.085860984569, 8719.04090550039, 8769.649214031431, 6907.896381554281, 8485.193906578927, 8625.26685231756, 8631.95684021579, 8694.478331300143, 6936.861262774716, 7174.605396703179, 8407.747767566983, 8489.066202717697, 8491.702790402369, 36471.0, 36757.44863827045, 36806.44790328147, 36872.52509899601, 36873.81563092194, 3611.693006094485, 3620.529331186955, 3644.4715516249994, 3651.415773476231, 3659.4693769403552, 29705.70294297051, 30119.0, 30198.54702179469, 30199.0, 30219.697803021907, 2657.627932089509, 3119.0954623159255, 3120.2667373167287, 3164.889228877001, 3176.2059485128716, 168186.0, 212533.0, 237917.0, 278372.21627783665, 278496.0, 46866.76566617145, 47198.5280147201, 47243.818904526976, 47253.5274647256, 47343.763281183376, 44502.77748611236, 45017.649470515935, 48535.029299413814, 48927.51072489301, 49205.27781527701, 12270.570530031406, 14085.15489070659, 14392.06451580644, 14527.418903243963, 14543.636409089646, 11888.167828252112, 12050.39748012596, 12073.698157546132, 12086.87913120875, 12136.878631213574, 13211.075224734177, 13221.73556528864, 13245.271510066847, 13304.0, 13340.132891362031, 5086.618503612244, 5917.644941303535, 5976.760929562855, 5977.641341519523, 6000.459958603747, 957.6265256549951, 969.8604140135453, 972.644984580623, 989.8762654668167, 1005.0201053972319, 19116.713249301272, 21895.452613684465, 21995.890020549796, 22030.339089827332, 22083.227087051877, 1065.7016035510069, 1093.7156339351825, 1095.646876108006, 1103.8399432082304, 1107.8061339265596, 10581.735456613646, 11341.886581134084, 11409.600663976888, 11460.828087578693, 11807.527698891949, 283631.0, 345650.27174863964, 345693.0, 346100.0, 346232.0, 53766.0, 53906.460935390925, 54202.728986354814, 54204.45795542073, 54272.728636356565, 1474.808274924253, 1489.101395720465, 1535.9155246461328, 1536.8309485956536, 1554.8678362339328, 21533.353999380044, 21768.67346989828, 21805.672914905972, 21876.01557929929, 22031.779682203614, 28002.579961301006, 37383.626163738016, 37444.06389840167, 37486.2502749938, 37520.24959500849, 307610.92389076715, 309865.0, 310217.0, 310445.0, 310768.0, 30721.53917691281, 33032.66967330296, 34090.82954585211, 34097.48853767245, 34161.31677366488, 44130.77934610307, 44714.77642611766, 44970.77514612406, 44983.77508112569, 45386.0, 30229.395412092075, 30345.0, 30823.691763082083, 30953.0, 31277.748890044142, 22162.33512994613, 24144.63783043291, 30847.84576077105, 30981.53527697041, 31061.068167954996, 30210.84894575513, 36241.27517449584, 36814.26371472609, 36916.81541592275, 37171.0, 30203.848980754956, 30248.0, 31267.53098703567, 31356.843215783774, 31433.68566314399, 22546.774532255124, 23978.160764373162, 24167.637485438085, 24194.637080443455, 24460.755392445848], "value": 17119.73229151412, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.51832910482034, 442.2459706200927, 442.75205884704565, 444.89100170458244, 454.0804870137972, 16979.57551061224, 17068.146592670364, 21574.22451071062, 22181.334559963187, 22422.439439014033, 21922.780772192273, 23068.19261325854, 23687.881560592174, 23859.16492922748, 23931.401714957134, 6039.305479869805, 6206.875862482748, 6267.310595834455, 6271.874562508747, 6273.623582585036, 5536.446355364452, 5908.350081491032, 6024.9698751506185, 6036.879262414749, 6135.478484328815, 4663.183942810012, 6119.90820137696, 6133.631982081067, 6152.384761523835, 6163.753449862, 5873.676947767871, 6161.199044124258, 6163.907541386838, 6182.474489668383, 6187.381261873822, 5434.592405569598, 5438.7280635968045, 5492.780288788444, 5495.917561236584, 5504.669719816784, 27673.0, 27891.442171156465, 27973.86013069942, 28196.295092622644, 28269.85865070682, 2191.2878314547834, 2261.3894248552965, 2266.3994041578994, 2269.4326418395403, 2270.8069814065825, 17849.7322540162, 18006.0, 18230.453086407295, 18533.351332703292, 18644.25422983079, 2170.848040637156, 2188.2013065231235, 2239.4177513846353, 2244.382794731462, 2245.3937436891947, 157559.0, 157718.0, 157765.0, 159351.2194634163, 161226.0, 11975.0, 12624.116311858266, 12652.0, 12671.809922851164, 12672.303023333805, 16402.67194656124, 16539.173041347884, 16570.0, 16580.088095154882, 16602.91698541524, 8931.776705582282, 9009.0, 9119.452832830051, 9193.310501712265, 9264.397814142194, 8800.427972181787, 8804.735857924272, 8807.163319484638, 8967.865482017774, 8985.820283594423, 7696.615169241515, 7804.97070761606, 7844.843103137905, 7857.017872765904, 7870.763877083697, 2132.978670213309, 2151.4943988162768, 2154.8491605587774, 2155.1379448220837, 2161.8378621603442, 944.5749412764284, 1012.1649639047815, 1012.291396022787, 1027.1474676018886, 1063.9361638301573, 20687.482812929495, 21620.78379216219, 21695.34913952584, 21720.891395543236, 21751.347459576238, 959.8320293948531, 985.5515740338129, 994.9054839790208, 1044.4203467075695, 1075.7848430313968, 10499.895001050045, 11193.552257909592, 11194.832077518844, 11197.880211978736, 11223.831642525369, 647465.0, 660210.0, 667002.0, 675227.0, 675493.0, 118557.0, 149331.0, 155943.44056559517, 159132.0, 169652.0, 1211.8909298163207, 1218.4760552962312, 1255.3723138430785, 1261.9936900315624, 1265.7974724044109, 19035.904820475807, 21772.45568860791, 22125.336239912827, 22450.0, 22595.322140335815, 60577.0, 64218.0, 64609.03086453801, 65840.6707966457, 67000.6649966747, 54828.0, 65800.67099664471, 82414.0, 83648.58175709083, 84097.0, 8830.337724670804, 8853.557322133996, 8862.468251904906, 8918.821623567623, 9171.862422063541, 11817.818218178114, 13750.862491374959, 13993.440262389391, 14142.50501232452, 14270.072445291024, 9697.806043879224, 11611.5355385787, 11667.59163429276, 12040.75918481643, 12139.332336721565, 10462.515477093792, 11233.38216398106, 11264.324140551593, 11352.432378381212, 11477.598284060017, 11213.0, 11821.940890295493, 11935.940320298343, 12098.395080245777, 12221.511139554674, 8813.42712723671, 8877.467351958903, 8877.913690282649, 8968.192862642394, 9146.31402644791, 11707.239029463057, 11761.941190293994, 11952.223105498462, 11954.043676506033, 12015.219010764273], "value": 10671.025775250473, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1221.2184202110648, 1434.8063011493452, 1436.6982933583947, 1458.0449805377475, 1485.4355344968913, 11447.141464390163, 11852.644420667393, 11907.047436205105, 11934.04527637789, 12015.098867584922, 11889.286642801439, 11971.880281197185, 12037.157398982054, 12126.090543209251, 12142.028637708983, 2528.582783840664, 2558.9744102559016, 2561.9231423057245, 2565.050931155475, 2572.3311938895927, 2467.0501856785177, 2474.418511649761, 2532.9240122796255, 2535.492901419714, 2539.5555777738914, 2436.5370579589908, 2487.129504673368, 2506.8621225832662, 2517.3832411059316, 2519.4457219411793, 2395.4849707312924, 2477.479729256849, 2494.977059405644, 2495.513374891879, 2500.374906273432, 2433.3429973907123, 2443.2914454808083, 2473.542394656994, 2481.5409149307434, 2533.1640558615613, 11877.88122118785, 11952.880471195263, 11974.880251197465, 11978.281303121841, 11981.46083426248, 6600.570962887397, 6854.931450685479, 6869.072675188835, 6871.587704737682, 6871.828204294882, 4054.331035379159, 4268.338407546835, 4341.696081274312, 4369.978150109229, 4407.140607581523, 1213.1447329632588, 1232.433080782841, 1235.2650173147053, 1279.8912092472058, 1287.5043108403238, 14973.475928342565, 15361.231938403034, 16631.334746610122, 16763.16184190798, 16777.077260750655, 5567.66594004361, 5600.439956004367, 5764.481196692318, 5804.94195058044, 5846.94153058464, 12211.26732396059, 12317.260964342167, 12437.62687119388, 12449.688757781129, 12467.625971220878, 6595.736170553124, 6667.0, 6670.166229221348, 6677.933220667828, 6679.76620818269, 6053.848653783691, 6259.655718935503, 6287.9056814147825, 6311.147995020705, 6332.905006424908, 3847.3844184930276, 4350.825966961349, 4380.277254253013, 4452.287633978613, 4539.75031373271, 1274.846264130963, 1304.1979182802697, 1329.6875234319928, 1343.4559003603556, 1348.5010546098006, 691.6991108867558, 693.230514129316, 713.4399496395305, 795.4829360915451, 814.8329592433537, 6085.604435711664, 6150.0, 6281.560290779693, 6327.240731112204, 6405.071264666597, 587.3451102021301, 601.2484394506866, 612.3386742318286, 614.8739508400768, 617.6664601115348, 3943.8957092014284, 3956.248312820555, 4145.606167414091, 4337.804798784062, 4396.648268138541, 14987.775183372258, 15483.92258038725, 15518.146501942501, 15523.146226957626, 15539.378424863105, 15320.15739134336, 15361.692766144615, 15394.0, 15412.614684632974, 15463.690726185638, 9109.590068446936, 10979.890201097887, 11183.384913829817, 11194.272372295774, 11196.552137914392, 6414.198225221848, 6731.528792984444, 6943.722251110101, 7054.224035356004, 7144.785656430316, 12141.453634586289, 12653.240805551697, 13273.137246078973, 13453.932730336286, 13763.830074443384, 13014.934925325691, 13969.930150349182, 13995.16029038261, 14011.719765604834, 14289.571312860631, 8610.913890861011, 9363.859542106738, 9426.010268921938, 9468.195203407511, 9536.618535258789, 10413.895861041292, 10673.0, 10687.625933092304, 10688.679339619825, 10697.946510267398, 8847.46915185091, 8983.416077954913, 9196.632134714537, 9201.907980920372, 9340.53297335117, 8917.910820891968, 8953.641854325753, 8959.283257339528, 9044.231240344645, 9090.000099988863, 8053.395995300259, 8066.153053929253, 8180.509169449852, 8180.509169449852, 8206.425550211428, 5328.733563321896, 6011.519078473799, 6084.421979911901, 6140.078988151634, 6205.503559715302, 5579.16312553104, 6079.148919151234, 6162.722677479614, 6179.5056395489155, 6245.5628106033955], "value": 5379.738396916222, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.88717723151365, 390.18840811112887, 403.4231049599074, 413.1963331320581, 415.2836357283686, 5471.206675032123, 5937.109433584959, 6150.446459818615, 6209.410106039931, 6409.8718025639455, 6120.387961203888, 6184.938150618493, 6189.628622282655, 6218.782342618009, 6248.437890527368, 3931.941020884689, 4270.444842170514, 4424.889377765549, 4540.9318860217045, 4568.817247310104, 4512.323151527265, 4542.750148741818, 4545.409096817409, 4548.7043342182815, 4568.862934111982, 3608.9819550902207, 4003.958970667633, 4051.8379264829373, 4508.72947623142, 4539.6368290536675, 4316.309390497506, 4467.731936083813, 4523.434570678665, 4536.115457485792, 4561.315802629599, 3016.9396612067853, 3901.2977664020464, 3905.297046531623, 3905.379267603941, 3922.8430862765454, 4685.5314468553215, 5171.482851714836, 5586.910552442276, 6074.8785024299705, 6106.389361063858, 4013.9965008747813, 4733.195356789354, 5107.693538387709, 5385.461453854622, 5468.179773034037, 5140.845774626767, 5387.4612538746205, 5952.166696662471, 6514.837129071811, 6708.396244337984, 4387.319965405367, 4402.8426071225185, 4457.509673935864, 4462.709923854939, 4471.329300604935, 5119.846404607868, 5811.79658711943, 6725.39471447572, 6971.8605627887155, 6990.825229369256, 4414.183376075436, 4505.256632655575, 4505.842295519641, 4528.207563676409, 4539.16025535277, 4558.247889098262, 4593.816247350069, 4601.723896566216, 4614.976925115353, 4619.953800461953, 4555.9772201138785, 4575.931361029588, 5202.24567437727, 5392.676439413648, 5411.675299482044, 6641.435477984425, 6641.601503909781, 6666.699998500079, 6693.732250709917, 6712.362325579063, 1501.5074975540551, 1544.3668096080553, 1568.8588027077617, 1570.2776722707454, 1571.5206861907227, 1025.9586519682528, 1026.343140390151, 1032.9896701032894, 1034.5809946971488, 1039.9740006499746, 6316.778912738032, 6355.968220158869, 6370.0, 6377.0, 6394.616323020634, 5845.386234445407, 6515.283318834924, 6690.632015239208, 6835.692393842289, 6855.828604284833, 6171.660558669317, 6277.372262773686, 6286.654234017173, 6350.269718982377, 6372.681365931685, 3014.608100946863, 3461.1866211440292, 3591.479235510836, 3632.182758879284, 3636.781793092423, 6842.897356539655, 7064.399526040344, 7080.752173673896, 7142.607156606333, 7161.301600779183, 6192.659403732748, 6912.792616221522, 6958.7912362628185, 7166.426685865118, 7176.282371762782, 5558.583106266965, 6149.2313460817395, 6611.702473388709, 7065.6113913735235, 7084.681189346595, 6020.909686354796, 6458.451031662361, 6779.644086166564, 6944.305569443015, 6953.0, 6360.363963603602, 6683.6324002180345, 6755.86488270222, 6789.966050169717, 6792.762253321308, 5830.0, 7079.433645308466, 7120.0, 7144.85710285781, 7163.31948464895, 3943.7042221832903, 4017.8348278998765, 4470.664700147437, 4497.100579883971, 4498.145352383037, 6476.255230648536, 6819.488538359742, 6868.896966545406, 6913.827154321182, 6932.341427564374, 6446.387593178641, 6603.669816509251, 6621.795160073292, 6627.536072474881, 6687.1975362956755, 5803.82588522345, 6497.512686548623, 6510.414062734377, 6524.303681042868, 6622.238442578972, 6450.419462248233, 6465.288818230085, 6508.934910651023, 6511.837204069936, 6578.868422631424, 5435.374931882884, 6463.64449955257, 6604.735810567716, 6615.503837212132, 6618.106555614931, 6386.489080873426, 6425.807225783234, 6437.356264373525, 6460.25707043696, 6486.902696459651], "value": 4780.834510153582, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.7832874288318, 826.6321486938315, 845.6406027438339, 866.0257210638032, 868.1925808997631, 14271.78592321116, 14972.625684357896, 16237.51287461375, 16534.17329133543, 16937.745933810987, 13155.473781048771, 13685.452581896712, 17611.207495662693, 17753.467395978143, 17793.0, 992.6674564021062, 1033.612395351743, 1038.9688309350731, 1045.242199405429, 1045.6288017753702, 980.5097451274363, 990.2770977186658, 1011.3426272922586, 1018.8522664213684, 1036.7097212780434, 1021.3208216535994, 1046.8325067989124, 1058.867641544807, 1060.8355704865758, 1065.4832406282947, 1044.154235069596, 1046.0376453662611, 1055.4775386183887, 1056.8467572202026, 1057.4659796802566, 779.8440311937578, 782.5304817109745, 790.6165509727834, 790.7311514085238, 796.0, 12829.55096571625, 15491.845081549152, 15520.456784012506, 15632.0, 16676.66646667072, 16463.34146634133, 16593.087380194083, 16780.0, 16780.077095759727, 17346.392876249272, 13880.097793643381, 14043.297835108306, 14074.0, 14163.433462661584, 14205.928970355082, 3491.7555771096036, 3505.6494350564994, 3518.5249991251108, 3519.2961407718303, 3523.612402635708, 122463.38768306101, 123219.38390307991, 125542.11686824705, 125668.0, 126217.0, 13048.54330098442, 13241.0, 13305.268210248529, 13418.530351437652, 13515.864841351657, 25344.11295604645, 25552.616710749353, 25564.23307300784, 25640.102596409037, 26279.21162365132, 7397.889031664529, 8621.396502244783, 8723.389362744674, 8822.117788221127, 8844.513551754715, 4812.398450193726, 4902.656814022984, 4912.214045752662, 4920.237363208708, 4926.6551341406475, 4303.096349766584, 4364.258076127066, 4386.649068074546, 4393.428854248928, 4401.559844015573, 878.2885862451435, 898.6495266845935, 907.9319051071196, 913.5432283858071, 914.5107367558325, 638.6678926957956, 661.3518751623436, 667.4526887951832, 672.831792051987, 678.4233401608692, 9473.668421605349, 11721.296722196696, 11839.940800296115, 11929.105317101252, 11946.701332466759, 631.2172905597026, 645.0807599171256, 665.3878431842717, 668.9464842812661, 680.3672584496466, 5392.487713667196, 5457.61796674229, 5473.0, 5491.121420572689, 5498.917516237259, 85179.57410212909, 105328.94671053192, 106860.46569767101, 107985.46007269913, 109995.0, 72655.63672181606, 93633.53183234176, 94453.5831962521, 94774.0, 96144.0, 83223.5838820802, 83447.0, 83797.16202837894, 84024.57987710145, 84039.0, 9105.908940910505, 10132.594696212069, 10720.249582529148, 10826.296290741077, 11147.108231341312, 42337.57662423336, 42641.0, 42738.57261427346, 43180.78409607932, 43224.0, 55643.0, 55859.72070139623, 55959.44040559542, 56439.920407163554, 56545.43454565402, 11186.440677966231, 16068.517944461684, 16933.49199524016, 16981.235844386785, 17085.48743537696, 18095.0, 18531.98074105937, 18609.0, 19210.039498025322, 19279.903600481906, 13306.534271300457, 17514.47456576305, 17885.28458861683, 17996.37012704549, 18228.544286392946, 16696.0, 17636.470905872844, 18267.634647307244, 18285.451436456926, 18370.540736481697, 15022.399104036152, 16171.757423638419, 16470.75293870569, 17002.0, 17257.0508622027, 10099.848502272325, 11402.714932126431, 11579.305241685526, 11642.6507204784, 12120.575779848015, 11455.770884582429, 13189.802152967904, 13487.730245394841, 13874.514391996232, 13884.861151388357], "value": 8412.11324099347, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1698.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 119.0, 130.0, 124.0, 121.0, 139.0, 165.0, 120.0, 121.0, 166.0], "value": 132.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 124.0, 151.0, 129.0, 146.0, 102.0, 99.0, 180.0, 153.0], "value": 135.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [223.0, 248.0, 308.0, 232.0, 220.0, 215.0, 215.0, 210.0, 202.0, 296.0], "value": 236.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 87.0, 97.0, 60.0, 59.0, 59.0, 59.0, 77.0, 86.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 90.0, 72.0, 73.0, 72.0, 89.0, 76.0, 108.0, 86.0], "value": 82.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 123.0, 135.0, 92.0, 128.0, 93.0, 97.0, 141.0, 93.0, 98.0], "value": 109.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 124.0, 112.0, 161.0, 158.0, 102.0, 102.0, 163.0, 121.0, 96.0], "value": 124.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 103.0, 103.0, 105.0, 115.0, 125.0, 120.0, 159.0, 122.0, 157.0], "value": 125.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 50.0, 53.0, 53.0, 55.0, 61.0, 74.0, 85.0, 82.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 66.0, 64.0, 65.0, 66.0, 53.0, 78.0, 91.0, 90.0, 90.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 91.0, 88.0, 88.0, 101.0, 88.0, 76.0, 107.0, 84.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 147.0, 151.0, 166.0, 168.0, 156.0, 173.0, 181.0, 132.0], "value": 157.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 231.0, 210.0, 226.0, 217.0, 206.0, 209.0, 260.0, 200.0, 207.0], "value": 215.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 94.0, 75.0, 75.0, 73.0, 98.0, 63.0, 94.0], "value": 79.4, "unit": "score"}]}]} 10:07:33 INFO - Return code: 0 10:07:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:07:33 ERROR - # TBPL SUCCESS # 10:07:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:07:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:07:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:07:33 INFO - 2017-04-11 10:07:33.224 screenresolution[1925:16308] starting screenresolution argv=screenresolution get 10:07:33 INFO - 2017-04-11 10:07:33.227 screenresolution[1925:16308] Display 0: 1600x1200x32@60 10:07:33 INFO - 2017-04-11 10:07:33.233 screenresolution[1926:16311] starting screenresolution argv=screenresolution list 10:07:33 INFO - Available Modes on Display 0 10:07:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:07:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:07:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:07:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:07:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:07:33 INFO - Intel Iris: 10:07:33 INFO - Chipset Model: Intel Iris 10:07:33 INFO - Type: GPU 10:07:33 INFO - Bus: Built-In 10:07:33 INFO - VRAM (Dynamic, Max): 1536 MB 10:07:33 INFO - Vendor: Intel (0x8086) 10:07:33 INFO - Device ID: 0x0a2e 10:07:33 INFO - Revision ID: 0x0009 10:07:33 INFO - Displays: 10:07:33 INFO - Display: 10:07:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:07:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:07:33 INFO - Display Serial Number: 1600x1200 60 10:07:33 INFO - Main Display: Yes 10:07:33 INFO - Mirror: Off 10:07:33 INFO - Online: Yes 10:07:33 INFO - Rotation: Supported 10:07:33 INFO - Return code: 0 10:07:33 INFO - Running post-action listener: _resource_record_post_action 10:07:33 INFO - [mozharness: 2017-04-11 17:07:33.481844Z] Finished run-tests step (success) 10:07:33 INFO - Running post-run listener: _resource_record_post_run 10:07:33 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 1553920; Write bytes: 518543360; Read time: 17; Write time: 1791 10:07:33 INFO - TinderboxPrint: CPU usage
54.2% 10:07:33 INFO - TinderboxPrint: I/O read bytes / time
1,553,920 / 17 10:07:33 INFO - TinderboxPrint: I/O write bytes / time
518,543,360 / 1,791 10:07:33 INFO - TinderboxPrint: CPU idle
2,199.2 (45.8%) 10:07:33 INFO - TinderboxPrint: CPU system
968.8 (20.2%) 10:07:33 INFO - TinderboxPrint: CPU user
1,637.8 (34.1%) 10:07:33 INFO - TinderboxPrint: Swap in / out
209,641,472 / 0 10:07:33 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208693760; Write bytes: 208670720; Read time: 16032; Write time: 414 10:07:33 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 1414656; Write bytes: 308594688; Read time: 16; Write time: 1371 10:07:33 INFO - Running post-run listener: _upload_blobber_files 10:07:33 INFO - Blob upload gear active. 10:07:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:07:33 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 10:07:33 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'] 10:07:33 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 10:07:34 INFO - (blobuploader) - INFO - Open directory for files ... 10:07:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:07:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:07:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:35 INFO - (blobuploader) - INFO - Done attempting. 10:07:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:07:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:07:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:36 INFO - (blobuploader) - INFO - Done attempting. 10:07:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:07:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:07:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:37 INFO - (blobuploader) - INFO - Done attempting. 10:07:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:07:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:07:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:38 INFO - (blobuploader) - INFO - Done attempting. 10:07:38 INFO - (blobuploader) - INFO - Iteration through files over. 10:07:38 INFO - Return code: 0 10:07:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:07:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:07:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8374f21655620df53d20b5a248f550de0da6b42856198c8cf249371d95f384eff422217a4521263d45f0644c359c804d42de6173e6f4a492b3fcd812988686c2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3247df8268f859011764e237198af1c234b107130d61f47edd77e041d7eeffe6cddb65d09e83441c99964737a4fc9b3286ce9625de5c21e88f394ac958523d70", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01769d7727b4efafd5c53a89742653c42578b68fee4a6a9de5401cc1b9a581c4950ae4226a6510a07c75664c793b61b4aa65b0f34669e5aabfa7c4107b627e95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01c835721f24bc45c6e0be68897f0fa04176e0fb2715823e14d19c11004c4c5914aba51b31f487d2b5acad79951ce2da35663dc1a58321e9e96610ae09d1b686"} 10:07:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:07:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:07:38 INFO - Contents: 10:07:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8374f21655620df53d20b5a248f550de0da6b42856198c8cf249371d95f384eff422217a4521263d45f0644c359c804d42de6173e6f4a492b3fcd812988686c2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3247df8268f859011764e237198af1c234b107130d61f47edd77e041d7eeffe6cddb65d09e83441c99964737a4fc9b3286ce9625de5c21e88f394ac958523d70", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01769d7727b4efafd5c53a89742653c42578b68fee4a6a9de5401cc1b9a581c4950ae4226a6510a07c75664c793b61b4aa65b0f34669e5aabfa7c4107b627e95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01c835721f24bc45c6e0be68897f0fa04176e0fb2715823e14d19c11004c4c5914aba51b31f487d2b5acad79951ce2da35663dc1a58321e9e96610ae09d1b686"} 10:07:38 INFO - Running post-run listener: copy_logs_to_upload_dir 10:07:38 INFO - Copying logs to upload dir... 10:07:38 INFO - mkdir: /builds/slave/test/build/upload/logs 10:07:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.691914 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-11 10:07:39.019378) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 10:07:39.022957) ========= 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.rN9YM5TCoI/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.YAqeywrGpm/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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/8374f21655620df53d20b5a248f550de0da6b42856198c8cf249371d95f384eff422217a4521263d45f0644c359c804d42de6173e6f4a492b3fcd812988686c2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3247df8268f859011764e237198af1c234b107130d61f47edd77e041d7eeffe6cddb65d09e83441c99964737a4fc9b3286ce9625de5c21e88f394ac958523d70", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01769d7727b4efafd5c53a89742653c42578b68fee4a6a9de5401cc1b9a581c4950ae4226a6510a07c75664c793b61b4aa65b0f34669e5aabfa7c4107b627e95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01c835721f24bc45c6e0be68897f0fa04176e0fb2715823e14d19c11004c4c5914aba51b31f487d2b5acad79951ce2da35663dc1a58321e9e96610ae09d1b686"} build_url:https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011177 build_url: 'https://queue.taskcluster.net/v1/task/aS1j6cFrTgyGi_ReNqDfpA/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/8374f21655620df53d20b5a248f550de0da6b42856198c8cf249371d95f384eff422217a4521263d45f0644c359c804d42de6173e6f4a492b3fcd812988686c2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3247df8268f859011764e237198af1c234b107130d61f47edd77e041d7eeffe6cddb65d09e83441c99964737a4fc9b3286ce9625de5c21e88f394ac958523d70", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01769d7727b4efafd5c53a89742653c42578b68fee4a6a9de5401cc1b9a581c4950ae4226a6510a07c75664c793b61b4aa65b0f34669e5aabfa7c4107b627e95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01c835721f24bc45c6e0be68897f0fa04176e0fb2715823e14d19c11004c4c5914aba51b31f487d2b5acad79951ce2da35663dc1a58321e9e96610ae09d1b686"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 10:07:39.057294) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:07:39.057621) ========= 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.rN9YM5TCoI/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.YAqeywrGpm/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.005170 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:07:39.108675) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 10:07:39.109364) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 10:07:39.109998) ========= ========= Total master_lag: 0.95 =========