builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0312 starttime: 1489027119.47 results: success (0) buildid: 20170308170829 builduid: 84f2b91ad51f4da6b19cc5945676ff11 revision: 19289cc8bf6ffce3b2067fbe91aebea5a356d008 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-08 18:38:39.473358) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-08 18:38:39.473777) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-08 18:38:39.474053) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-08 18:38:39.505521) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 18:38:39.505905) ========= 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.bI58cTMBUv/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.Td2PpYmCSB/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-08 18:38:39-- 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% 159M=0s 2017-03-08 18:38:39 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131665 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 18:38:39.661446) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 18:38:39.661753) ========= 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.bI58cTMBUv/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.Td2PpYmCSB/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.026082 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 18:38:39.713610) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-08 18:38:39.714900) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 19289cc8bf6ffce3b2067fbe91aebea5a356d008 --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 19289cc8bf6ffce3b2067fbe91aebea5a356d008 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bI58cTMBUv/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.Td2PpYmCSB/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-08 18:38:39,823 truncating revision to first 12 chars 2017-03-08 18:38:39,823 Setting DEBUG logging. 2017-03-08 18:38:39,823 attempt 1/10 2017-03-08 18:38:39,823 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/19289cc8bf6f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-08 18:38:40,853 unpacking tar archive at: mozilla-central-19289cc8bf6f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.331830 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-08 18:38:41.072499) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-08 18:38:41.073023) ========= script_repo_revision: 19289cc8bf6ffce3b2067fbe91aebea5a356d008 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-08 18:38:41.073825) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-08 18:38:41.074424) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-08 18:38:41.095215) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-08 18:38:41.095502) ========= /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.bI58cTMBUv/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.Td2PpYmCSB/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 18:38:41 INFO - MultiFileLogger online at 20170308 18:38:41 in /builds/slave/test 18:38:41 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 18:38:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:38:41 INFO - {'append_to_log': False, 18:38:41 INFO - 'base_work_dir': '/builds/slave/test', 18:38:41 INFO - 'blob_upload_branch': 'Firefox', 18:38:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:38:41 INFO - 'branch': 'Firefox', 18:38:41 INFO - 'buildbot_json_path': 'buildprops.json', 18:38:41 INFO - 'config_files': ('talos/mac_config.py',), 18:38:41 INFO - 'default_actions': ('clobber', 18:38:41 INFO - 'read-buildbot-config', 18:38:41 INFO - 'download-and-extract', 18:38:41 INFO - 'populate-webroot', 18:38:41 INFO - 'create-virtualenv', 18:38:41 INFO - 'install', 18:38:41 INFO - 'run-tests'), 18:38:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:38:41 INFO - 'download_minidump_stackwalk': True, 18:38:41 INFO - 'download_symbols': 'ondemand', 18:38:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:38:41 INFO - 'tooltool.py': '/tools/tooltool.py', 18:38:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:38:41 INFO - '/tools/misc-python/virtualenv.py')}, 18:38:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:38:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:38:41 INFO - 'gecko_profile': False, 18:38:41 INFO - 'gecko_profile_interval': 0, 18:38:41 INFO - 'installer_path': 'installer.exe', 18:38:41 INFO - 'log_level': 'info', 18:38:41 INFO - 'log_name': 'talos', 18:38:41 INFO - 'log_to_console': True, 18:38:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:38:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:38:41 INFO - 'opt_config_files': (), 18:38:41 INFO - 'pip_index': False, 18:38:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:38:41 INFO - 'cmd': ('bash', 18:38:41 INFO - '-c', 18:38:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:38:41 INFO - 'enabled': True, 18:38:41 INFO - 'halt_on_failure': False, 18:38:41 INFO - 'name': 'check_screen_resolution'},), 18:38:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:38:41 INFO - 'cmd': ('bash', 18:38:41 INFO - '-c', 18:38:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:38:41 INFO - 'enabled': True, 18:38:41 INFO - 'halt_on_failure': False, 18:38:41 INFO - 'name': 'check_screen_resolution'},), 18:38:41 INFO - 'run_cmd_checks_enabled': True, 18:38:41 INFO - 'suite': 'dromaeojs-e10s', 18:38:41 INFO - 'system_bits': '32', 18:38:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:38:41 INFO - 'title': 't-yosemite-r7-0312', 18:38:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:38:41 INFO - 'use_talos_json': True, 18:38:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:38:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:38:41 INFO - 'work_dir': 'build'} 18:38:41 INFO - [mozharness: 2017-03-09 02:38:41.310072Z] Running clobber step. 18:38:41 INFO - Running pre-action listener: _resource_record_pre_action 18:38:41 INFO - Running main action method: clobber 18:38:41 INFO - rmtree: /builds/slave/test/build 18:38:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:38:43 INFO - Running post-action listener: _resource_record_post_action 18:38:43 INFO - [mozharness: 2017-03-09 02:38:43.746372Z] Finished clobber step (success) 18:38:43 INFO - [mozharness: 2017-03-09 02:38:43.746453Z] Running read-buildbot-config step. 18:38:43 INFO - Running pre-action listener: _resource_record_pre_action 18:38:43 INFO - Running main action method: read_buildbot_config 18:38:43 INFO - Using buildbot properties: 18:38:43 INFO - { 18:38:43 INFO - "project": "", 18:38:43 INFO - "product": "firefox", 18:38:43 INFO - "script_repo_revision": "production", 18:38:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:38:43 INFO - "repository": "", 18:38:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:38:43 INFO - "buildid": "20170308170829", 18:38:43 INFO - "pgo_build": "False", 18:38:43 INFO - "basedir": "/builds/slave/test", 18:38:43 INFO - "buildnumber": 90, 18:38:43 INFO - "slavename": "t-yosemite-r7-0312", 18:38:43 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:38:43 INFO - "platform": "yosemite_r7", 18:38:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:38:43 INFO - "branch": "mozilla-central", 18:38:43 INFO - "repo_path": "mozilla-central", 18:38:43 INFO - "revision": "19289cc8bf6ffce3b2067fbe91aebea5a356d008", 18:38:43 INFO - "stage_platform": "macosx64", 18:38:43 INFO - "builduid": "84f2b91ad51f4da6b19cc5945676ff11", 18:38:43 INFO - "slavebuilddir": "test" 18:38:43 INFO - } 18:38:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:38:43 INFO - Running post-action listener: _resource_record_post_action 18:38:43 INFO - [mozharness: 2017-03-09 02:38:43.749856Z] Finished read-buildbot-config step (success) 18:38:43 INFO - [mozharness: 2017-03-09 02:38:43.749961Z] Running download-and-extract step. 18:38:43 INFO - Running pre-action listener: _resource_record_pre_action 18:38:43 INFO - Running main action method: download_and_extract 18:38:43 INFO - mkdir: /builds/slave/test/build/tests 18:38:43 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')]} 18:38:43 INFO - trying https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:38:43 INFO - Downloading https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/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 18:38:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/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 18:38:44 INFO - Downloaded 1199 bytes. 18:38:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:38:44 INFO - Using the following test package requirements: 18:38:44 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:38:44 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:38:44 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:38:44 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:38:44 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:38:44 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:38:44 INFO - u'jsshell-mac.zip'], 18:38:44 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:38:44 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:38:44 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:38:44 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:38:44 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:38:44 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:38:44 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:38:44 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:38:44 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:38:44 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:38:44 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:38:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:38:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:38:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:38:44 INFO - Fetch https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:38:46 INFO - Expected file size: 51786968 18:38:46 INFO - Obtained file size: 51786968 18:39:02 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 18:39:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:39:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:39:02 INFO - Fetch https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:39:04 INFO - Expected file size: 51786968 18:39:04 INFO - Obtained file size: 51786968 18:39:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:39:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:39:19 INFO - Fetch https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:39:21 INFO - Expected file size: 14404424 18:39:21 INFO - Obtained file size: 14404424 18:39:21 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')]} 18:39:21 INFO - trying https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:39:21 INFO - Downloading https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:39:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/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 18:39:23 INFO - Downloaded 74901587 bytes. 18:39:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:39:23 INFO - mkdir: /builds/slave/test/properties 18:39:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:39:23 INFO - Writing to file /builds/slave/test/properties/build_url 18:39:23 INFO - Contents: 18:39:23 INFO - build_url:https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:39:24 INFO - Running post-action listener: _resource_record_post_action 18:39:24 INFO - Running post-action listener: set_extra_try_arguments 18:39:24 INFO - [mozharness: 2017-03-09 02:39:24.571678Z] Finished download-and-extract step (success) 18:39:24 INFO - [mozharness: 2017-03-09 02:39:24.571799Z] Running populate-webroot step. 18:39:24 INFO - Running pre-action listener: _resource_record_pre_action 18:39:24 INFO - Running main action method: populate_webroot 18:39:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:39:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:39:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:39:24 INFO - '--tppagecycles', 18:39:24 INFO - '7'], 18:39:24 INFO - 'tests': ['tsvgm']}}, 18:39:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:39:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:39:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:39:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:39:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:39:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:39:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:39:24 INFO - 'talos_options': ['--disable-e10s'], 18:39:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:39:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:39:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:39:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:39:24 INFO - 'talos_options': ['--disable-e10s'], 18:39:24 INFO - 'tests': ['damp', 'tps']}, 18:39:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:39:24 INFO - 'tests': ['damp', 'tps']}, 18:39:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:39:24 INFO - 'tests': ['dromaeo_dom']}, 18:39:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:39:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:39:24 INFO - 'tests': ['basic_compositor_video']}, 18:39:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:39:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:39:24 INFO - 'tests': ['a11yr', 18:39:24 INFO - 'ts_paint', 18:39:24 INFO - 'tpaint', 18:39:24 INFO - 'sessionrestore', 18:39:24 INFO - 'sessionrestore_no_auto_restore', 18:39:24 INFO - 'tabpaint']}, 18:39:24 INFO - 'other-e10s': {'tests': ['a11yr', 18:39:24 INFO - 'ts_paint', 18:39:24 INFO - 'tpaint', 18:39:24 INFO - 'sessionrestore', 18:39:24 INFO - 'sessionrestore_no_auto_restore', 18:39:24 INFO - 'tabpaint']}, 18:39:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:39:24 INFO - 'ts_paint', 18:39:24 INFO - 'tpaint', 18:39:24 INFO - 'sessionrestore', 18:39:24 INFO - 'sessionrestore_no_auto_restore', 18:39:24 INFO - 'tabpaint']}, 18:39:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:39:24 INFO - 'ts_paint', 18:39:24 INFO - 'tpaint', 18:39:24 INFO - 'sessionrestore', 18:39:24 INFO - 'sessionrestore_no_auto_restore', 18:39:24 INFO - 'tabpaint']}, 18:39:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:39:24 INFO - 'tests': ['a11yr', 18:39:24 INFO - 'ts_paint', 18:39:24 INFO - 'tpaint', 18:39:24 INFO - 'sessionrestore', 18:39:24 INFO - 'sessionrestore_no_auto_restore', 18:39:24 INFO - 'tabpaint']}, 18:39:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:39:24 INFO - 'tests': ['a11yr', 18:39:24 INFO - 'ts_paint', 18:39:24 INFO - 'tpaint', 18:39:24 INFO - 'sessionrestore', 18:39:24 INFO - 'sessionrestore_no_auto_restore', 18:39:24 INFO - 'tabpaint']}, 18:39:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:39:24 INFO - 'tests': ['tsvgx', 18:39:24 INFO - 'tsvgr_opacity', 18:39:24 INFO - 'tart', 18:39:24 INFO - 'tscrollx', 18:39:24 INFO - 'cart', 18:39:24 INFO - 'tsvg_static']}, 18:39:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:39:24 INFO - 'tsvgr_opacity', 18:39:24 INFO - 'tart', 18:39:24 INFO - 'tscrollx', 18:39:24 INFO - 'cart', 18:39:24 INFO - 'tsvg_static']}, 18:39:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:39:24 INFO - 'talos_options': ['--disable-e10s'], 18:39:24 INFO - 'tests': ['tp5o']}, 18:39:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:39:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:39:24 INFO - 'talos_options': ['--disable-e10s', 18:39:24 INFO - '--xperf_path', 18:39:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:39:24 INFO - 'tests': ['tp5n']}, 18:39:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:39:24 INFO - 'talos_options': ['--xperf_path', 18:39:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:39:24 INFO - 'tests': ['tp5n']}}, 18:39:24 INFO - 'talos.zip': {'path': '', 18:39:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:39:24 INFO - Running post-action listener: _resource_record_post_action 18:39:24 INFO - [mozharness: 2017-03-09 02:39:24.590696Z] Finished populate-webroot step (success) 18:39:24 INFO - [mozharness: 2017-03-09 02:39:24.590802Z] Running create-virtualenv step. 18:39:24 INFO - Running pre-action listener: _resource_record_pre_action 18:39:24 INFO - Running main action method: create_virtualenv 18:39:24 INFO - Creating virtualenv /builds/slave/test/build/venv 18:39:24 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 18:39:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:39:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:39:24 INFO - Using real prefix '/tools/python27' 18:39:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:39:25 INFO - Installing distribute.............................................................................................................................................................................................done. 18:39:28 INFO - Installing pip.................done. 18:39:28 INFO - Return code: 0 18:39:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:39:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:39:28 INFO - Reading from file tmpfile_stdout 18:39:28 INFO - Output received: 18:39:28 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) 18:39:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e5cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf4bcbc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3a9d30>, '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 0x10e2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e39ebe8>, '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.bI58cTMBUv/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.Td2PpYmCSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 18:39:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:39:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:39:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bI58cTMBUv/Render', 18:39:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:28 INFO - 'HOME': '/Users/cltbld', 18:39:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:28 INFO - 'LOGNAME': 'cltbld', 18:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:28 INFO - 'MOZ_NO_REMOTE': '1', 18:39:28 INFO - 'NO_EM_RESTART': '1', 18:39:28 INFO - 'PAGER': '/bin/cat', 18:39:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:28 INFO - 'PWD': '/builds/slave/test', 18:39:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:28 INFO - 'SHELL': '/bin/bash', 18:39:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Td2PpYmCSB/Listeners', 18:39:28 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 18:39:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:28 INFO - 'USER': 'cltbld', 18:39:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:28 INFO - 'XPC_FLAGS': '0x0', 18:39:28 INFO - 'XPC_SERVICE_NAME': '0', 18:39:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:28 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 18:39:28 INFO - Cleaning up... 18:39:28 INFO - Return code: 0 18:39:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e5cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf4bcbc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3a9d30>, '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 0x10e2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e39ebe8>, '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.bI58cTMBUv/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.Td2PpYmCSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 18:39:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:39:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:39:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bI58cTMBUv/Render', 18:39:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:28 INFO - 'HOME': '/Users/cltbld', 18:39:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:28 INFO - 'LOGNAME': 'cltbld', 18:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:28 INFO - 'MOZ_NO_REMOTE': '1', 18:39:28 INFO - 'NO_EM_RESTART': '1', 18:39:28 INFO - 'PAGER': '/bin/cat', 18:39:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:28 INFO - 'PWD': '/builds/slave/test', 18:39:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:28 INFO - 'SHELL': '/bin/bash', 18:39:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Td2PpYmCSB/Listeners', 18:39:28 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 18:39:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:28 INFO - 'USER': 'cltbld', 18:39:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:28 INFO - 'XPC_FLAGS': '0x0', 18:39:28 INFO - 'XPC_SERVICE_NAME': '0', 18:39:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:29 INFO - Downloading/unpacking psutil>=3.1.1 18:39:29 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 18:39:29 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 18:39:29 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 18:39:29 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 18:39:29 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 18:39:29 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 18:39:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:39:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:39:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:39:32 INFO - Installing collected packages: psutil 18:39:32 INFO - Running setup.py install for psutil 18:39:32 INFO - building 'psutil._psutil_osx' extension 18:39:32 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 18:39:33 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 18:39:33 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 18:39:33 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 18:39:33 INFO - building 'psutil._psutil_posix' extension 18:39:33 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 18:39:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:39:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:39:33 INFO - ^ 18:39:33 INFO - 1 warning generated. 18:39:33 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 18:39:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:39:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:39:33 INFO - Successfully installed psutil 18:39:33 INFO - Cleaning up... 18:39:33 INFO - Return code: 0 18:39:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:34 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')]} 18:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:34 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 0x10e5cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf4bcbc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3a9d30>, '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 0x10e2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e39ebe8>, '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.bI58cTMBUv/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.Td2PpYmCSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 18:39:34 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 18:39:34 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 18:39:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bI58cTMBUv/Render', 18:39:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:34 INFO - 'HOME': '/Users/cltbld', 18:39:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:34 INFO - 'LOGNAME': 'cltbld', 18:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:34 INFO - 'MOZ_NO_REMOTE': '1', 18:39:34 INFO - 'NO_EM_RESTART': '1', 18:39:34 INFO - 'PAGER': '/bin/cat', 18:39:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:34 INFO - 'PWD': '/builds/slave/test', 18:39:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:34 INFO - 'SHELL': '/bin/bash', 18:39:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Td2PpYmCSB/Listeners', 18:39:34 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 18:39:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:34 INFO - 'USER': 'cltbld', 18:39:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:34 INFO - 'XPC_FLAGS': '0x0', 18:39:34 INFO - 'XPC_SERVICE_NAME': '0', 18:39:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:39:34 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 18:39:34 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 18:39:34 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 18:39:34 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 18:39:34 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 18:39:34 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 18:39:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:39:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:39:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:39:37 INFO - Installing collected packages: mozsystemmonitor 18:39:37 INFO - Running setup.py install for mozsystemmonitor 18:39:37 INFO - Successfully installed mozsystemmonitor 18:39:37 INFO - Cleaning up... 18:39:38 INFO - Return code: 0 18:39:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:39:38 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')]} 18:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:38 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')]} 18:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:38 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 0x10e5cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf4bcbc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3a9d30>, '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 0x10e2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e39ebe8>, '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.bI58cTMBUv/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.Td2PpYmCSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 18:39:38 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 18:39:38 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 18:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bI58cTMBUv/Render', 18:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:38 INFO - 'HOME': '/Users/cltbld', 18:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:38 INFO - 'LOGNAME': 'cltbld', 18:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:38 INFO - 'MOZ_NO_REMOTE': '1', 18:39:38 INFO - 'NO_EM_RESTART': '1', 18:39:38 INFO - 'PAGER': '/bin/cat', 18:39:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:38 INFO - 'PWD': '/builds/slave/test', 18:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:38 INFO - 'SHELL': '/bin/bash', 18:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Td2PpYmCSB/Listeners', 18:39:38 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 18:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:38 INFO - 'USER': 'cltbld', 18:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:38 INFO - 'XPC_FLAGS': '0x0', 18:39:38 INFO - 'XPC_SERVICE_NAME': '0', 18:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:38 INFO - Downloading/unpacking jsonschema==2.5.1 18:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:39:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:41 INFO - Downloading functools32-3.2.3-2.tar.gz 18:39:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:39:41 INFO - warning: no files found matching '*.txt' 18:39:41 INFO - no previously-included directories found matching 'build' 18:39:41 INFO - no previously-included directories found matching 'dist' 18:39:41 INFO - no previously-included directories found matching '.git*' 18:39:41 INFO - Installing collected packages: jsonschema, functools32 18:39:41 INFO - Running setup.py install for functools32 18:39:41 INFO - warning: no files found matching '*.txt' 18:39:41 INFO - no previously-included directories found matching 'build' 18:39:41 INFO - no previously-included directories found matching 'dist' 18:39:41 INFO - no previously-included directories found matching '.git*' 18:39:41 INFO - Successfully installed jsonschema functools32 18:39:41 INFO - Cleaning up... 18:39:41 INFO - Return code: 0 18:39:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:41 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 0x10e5cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf4bcbc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3a9d30>, '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 0x10e2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e39ebe8>, '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.bI58cTMBUv/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.Td2PpYmCSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 18:39:41 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 18:39:41 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 18:39:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bI58cTMBUv/Render', 18:39:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:41 INFO - 'HOME': '/Users/cltbld', 18:39:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:41 INFO - 'LOGNAME': 'cltbld', 18:39:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:41 INFO - 'MOZ_NO_REMOTE': '1', 18:39:41 INFO - 'NO_EM_RESTART': '1', 18:39:41 INFO - 'PAGER': '/bin/cat', 18:39:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:41 INFO - 'PWD': '/builds/slave/test', 18:39:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:41 INFO - 'SHELL': '/bin/bash', 18:39:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Td2PpYmCSB/Listeners', 18:39:41 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 18:39:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:41 INFO - 'USER': 'cltbld', 18:39:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:41 INFO - 'XPC_FLAGS': '0x0', 18:39:41 INFO - 'XPC_SERVICE_NAME': '0', 18:39:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:39:41 INFO - Cleaning up... 18:39:42 INFO - Return code: 0 18:39:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:39:42 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')]} 18:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:42 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')]} 18:39:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:42 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 0x10e5cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf4bcbc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3a9d30>, '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 0x10e2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e39ebe8>, '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.bI58cTMBUv/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.Td2PpYmCSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 18:39:42 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 18:39:42 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 18:39:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bI58cTMBUv/Render', 18:39:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:42 INFO - 'HOME': '/Users/cltbld', 18:39:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:42 INFO - 'LOGNAME': 'cltbld', 18:39:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:42 INFO - 'MOZ_NO_REMOTE': '1', 18:39:42 INFO - 'NO_EM_RESTART': '1', 18:39:42 INFO - 'PAGER': '/bin/cat', 18:39:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:42 INFO - 'PWD': '/builds/slave/test', 18:39:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:42 INFO - 'SHELL': '/bin/bash', 18:39:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Td2PpYmCSB/Listeners', 18:39:42 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 18:39:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:42 INFO - 'USER': 'cltbld', 18:39:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:42 INFO - 'XPC_FLAGS': '0x0', 18:39:42 INFO - 'XPC_SERVICE_NAME': '0', 18:39:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:42 INFO - Downloading/unpacking blobuploader==1.2.4 18:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:45 INFO - Downloading blobuploader-1.2.4.tar.gz 18:39:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:39:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:39:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:47 INFO - Downloading docopt-0.6.1.tar.gz 18:39:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:39:47 INFO - Installing collected packages: blobuploader, requests, docopt 18:39:47 INFO - Running setup.py install for blobuploader 18:39:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:39:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:39:47 INFO - Running setup.py install for requests 18:39:47 INFO - Running setup.py install for docopt 18:39:48 INFO - Successfully installed blobuploader requests docopt 18:39:48 INFO - Cleaning up... 18:39:48 INFO - Return code: 0 18:39:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:48 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 0x10e5cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf4bcbc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3a9d30>, '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 0x10e2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e39ebe8>, '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.bI58cTMBUv/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.Td2PpYmCSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 18:39:48 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 18:39:48 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 18:39:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bI58cTMBUv/Render', 18:39:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:48 INFO - 'HOME': '/Users/cltbld', 18:39:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:48 INFO - 'LOGNAME': 'cltbld', 18:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:48 INFO - 'MOZ_NO_REMOTE': '1', 18:39:48 INFO - 'NO_EM_RESTART': '1', 18:39:48 INFO - 'PAGER': '/bin/cat', 18:39:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:48 INFO - 'PWD': '/builds/slave/test', 18:39:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:48 INFO - 'SHELL': '/bin/bash', 18:39:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Td2PpYmCSB/Listeners', 18:39:48 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 18:39:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:48 INFO - 'USER': 'cltbld', 18:39:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:48 INFO - 'XPC_FLAGS': '0x0', 18:39:48 INFO - 'XPC_SERVICE_NAME': '0', 18:39:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:39:48 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-dktZeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:39:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:39:48 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-fInWRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:39:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:39:48 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-sGWMRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:39:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:39:48 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-AcN9id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:39:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:39:48 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-lW1Za6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:39:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:39:48 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-2FwHMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:39:49 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-iPRLxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:39:49 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-o21xQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:39:49 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-PMi_Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:39:49 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-KzMRP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:39:49 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-hn96J_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:39:49 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-RhQ_Xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:39:49 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-BMUObQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:39:49 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-vp5ZP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:49 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-MMM1Mt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:39:49 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-n7jv91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:39:50 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-HoJEoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:39:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:39:50 INFO - Running setup.py install for manifestparser 18:39:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:39:50 INFO - Running setup.py install for mozcrash 18:39:50 INFO - Running setup.py install for mozdebug 18:39:50 INFO - Running setup.py install for mozdevice 18:39:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:39:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:39:50 INFO - Running setup.py install for mozfile 18:39:50 INFO - Running setup.py install for mozhttpd 18:39:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:39:51 INFO - Running setup.py install for mozinfo 18:39:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:39:51 INFO - Running setup.py install for mozInstall 18:39:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:39:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:39:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:39:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:39:51 INFO - Running setup.py install for mozleak 18:39:51 INFO - Running setup.py install for mozlog 18:39:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:39:51 INFO - Running setup.py install for moznetwork 18:39:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:39:51 INFO - Running setup.py install for mozprocess 18:39:51 INFO - Running setup.py install for mozprofile 18:39:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:39:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:39:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:39:52 INFO - Running setup.py install for mozrunner 18:39:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:39:52 INFO - Running setup.py install for mozscreenshot 18:39:52 INFO - Running setup.py install for moztest 18:39:52 INFO - Running setup.py install for mozversion 18:39:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:39:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:39:52 INFO - Cleaning up... 18:39:52 INFO - Return code: 0 18:39:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:39:52 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')]} 18:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:52 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')]} 18:39:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:52 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 0x10e5cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf4bcbc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3a9d30>, '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 0x10e2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e39ebe8>, '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.bI58cTMBUv/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.Td2PpYmCSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 18:39:52 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 18:39:52 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 18:39:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bI58cTMBUv/Render', 18:39:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:52 INFO - 'HOME': '/Users/cltbld', 18:39:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:52 INFO - 'LOGNAME': 'cltbld', 18:39:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:52 INFO - 'MOZ_NO_REMOTE': '1', 18:39:52 INFO - 'NO_EM_RESTART': '1', 18:39:52 INFO - 'PAGER': '/bin/cat', 18:39:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:52 INFO - 'PWD': '/builds/slave/test', 18:39:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:52 INFO - 'SHELL': '/bin/bash', 18:39:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Td2PpYmCSB/Listeners', 18:39:52 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 18:39:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:52 INFO - 'USER': 'cltbld', 18:39:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:52 INFO - 'XPC_FLAGS': '0x0', 18:39:52 INFO - 'XPC_SERVICE_NAME': '0', 18:39:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:39:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-NB9BjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:39:53 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)) 18:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:39:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-LamrD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:39:53 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)) 18:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:39:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-uhYjjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:39:53 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)) 18:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:39:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-dSW3SN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:39:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:39:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-DNVQyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:39:53 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)) 18:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:39:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-qQPYXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:39:53 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)) 18:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:39:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-E89aWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:39:53 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)) 18:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:39:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Pc0VK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:39:53 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)) 18:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:39:53 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-W3Gf8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:39:54 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)) 18:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:39:54 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-8wFbPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:39:54 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)) 18:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:39:54 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-_Fgs4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:39:54 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)) 18:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:39:54 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Lyi39R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:39:54 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)) 18:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:39:54 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-9KZ7oT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:39:54 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)) 18:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:39:54 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-M1gwl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:39:54 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)) 18:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:54 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-EIsR9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:54 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)) 18:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:39:54 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-qtIvnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:39:54 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)) 18:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:39:54 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-djXn8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:39:54 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)) 18:39:54 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)) 18:39:54 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)) 18:39:54 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)) 18:39:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:39:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:39:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:39:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:39:57 INFO - Downloading blessings-1.6.tar.gz 18:39:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:39:57 INFO - Installing collected packages: blessings 18:39:57 INFO - Running setup.py install for blessings 18:39:57 INFO - Successfully installed blessings 18:39:57 INFO - Cleaning up... 18:39:57 INFO - Return code: 0 18:39:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:39:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:39:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:39:58 INFO - Reading from file tmpfile_stdout 18:39:58 INFO - Current package versions: 18:39:58 INFO - blessings == 1.6 18:39:58 INFO - blobuploader == 1.2.4 18:39:58 INFO - docopt == 0.6.1 18:39:58 INFO - functools32 == 3.2.3-2 18:39:58 INFO - jsonschema == 2.5.1 18:39:58 INFO - manifestparser == 1.1 18:39:58 INFO - mozInstall == 1.12 18:39:58 INFO - mozcrash == 1.0 18:39:58 INFO - mozdebug == 0.1 18:39:58 INFO - mozdevice == 0.49 18:39:58 INFO - mozfile == 1.2 18:39:58 INFO - mozhttpd == 0.7 18:39:58 INFO - mozinfo == 0.9 18:39:58 INFO - mozleak == 0.1 18:39:58 INFO - mozlog == 3.4 18:39:58 INFO - moznetwork == 0.27 18:39:58 INFO - mozprocess == 0.25 18:39:58 INFO - mozprofile == 0.28 18:39:58 INFO - mozrunner == 6.13 18:39:58 INFO - mozscreenshot == 0.1 18:39:58 INFO - mozsystemmonitor == 0.3 18:39:58 INFO - moztest == 0.8 18:39:58 INFO - mozversion == 1.4 18:39:58 INFO - psutil == 3.1.1 18:39:58 INFO - requests == 1.2.3 18:39:58 INFO - wsgiref == 0.1.2 18:39:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:39:58 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')]} 18:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:58 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')]} 18:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:58 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 0x10e5cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf4bcbc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3a9d30>, '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 0x10e2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e39ebe8>, '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.bI58cTMBUv/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.Td2PpYmCSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 18:39:58 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 18:39:58 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 18:39:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bI58cTMBUv/Render', 18:39:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:58 INFO - 'HOME': '/Users/cltbld', 18:39:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:58 INFO - 'LOGNAME': 'cltbld', 18:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:58 INFO - 'MOZ_NO_REMOTE': '1', 18:39:58 INFO - 'NO_EM_RESTART': '1', 18:39:58 INFO - 'PAGER': '/bin/cat', 18:39:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:58 INFO - 'PWD': '/builds/slave/test', 18:39:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:58 INFO - 'SHELL': '/bin/bash', 18:39:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Td2PpYmCSB/Listeners', 18:39:58 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 18:39:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:58 INFO - 'USER': 'cltbld', 18:39:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:58 INFO - 'XPC_FLAGS': '0x0', 18:39:58 INFO - 'XPC_SERVICE_NAME': '0', 18:39:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:58 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)) 18:39:58 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)) 18:39:58 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)) 18:39:58 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)) 18:39:58 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)) 18:39:58 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)) 18:39:58 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)) 18:39:58 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)) 18:39:58 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)) 18:39:58 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)) 18:39:58 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)) 18:39:58 INFO - Cleaning up... 18:39:58 INFO - Return code: 0 18:39:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:39:58 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')]} 18:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:58 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')]} 18:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:58 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 0x10e5cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf4bcbc280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3a9d30>, '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 0x10e2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e39ebe8>, '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.bI58cTMBUv/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.Td2PpYmCSB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 18:39:58 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 18:39:58 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 18:39:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bI58cTMBUv/Render', 18:39:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:58 INFO - 'HOME': '/Users/cltbld', 18:39:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:58 INFO - 'LOGNAME': 'cltbld', 18:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:58 INFO - 'MOZ_NO_REMOTE': '1', 18:39:58 INFO - 'NO_EM_RESTART': '1', 18:39:58 INFO - 'PAGER': '/bin/cat', 18:39:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:58 INFO - 'PWD': '/builds/slave/test', 18:39:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:58 INFO - 'SHELL': '/bin/bash', 18:39:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Td2PpYmCSB/Listeners', 18:39:58 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 18:39:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:58 INFO - 'USER': 'cltbld', 18:39:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:58 INFO - 'XPC_FLAGS': '0x0', 18:39:58 INFO - 'XPC_SERVICE_NAME': '0', 18:39:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:39:58 INFO - Cleaning up... 18:39:58 INFO - Return code: 0 18:39:58 INFO - Running post-action listener: _resource_record_post_action 18:39:58 INFO - Running post-action listener: _start_resource_monitoring 18:39:58 INFO - Starting resource monitoring. 18:39:58 INFO - [mozharness: 2017-03-09 02:39:58.652190Z] Finished create-virtualenv step (success) 18:39:58 INFO - [mozharness: 2017-03-09 02:39:58.653126Z] Running install step. 18:39:58 INFO - Running pre-action listener: _resource_record_pre_action 18:39:58 INFO - Running main action method: install 18:39:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:39:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:39:58 INFO - Reading from file tmpfile_stdout 18:39:58 INFO - Detecting whether we're running mozinstall >=1.0... 18:39:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:39:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:39:59 INFO - Reading from file tmpfile_stdout 18:39:59 INFO - Output received: 18:39:59 INFO - Usage: mozinstall [options] installer 18:39:59 INFO - Options: 18:39:59 INFO - -h, --help show this help message and exit 18:39:59 INFO - -d DEST, --destination=DEST 18:39:59 INFO - Directory to install application into. [default: 18:39:59 INFO - "/builds/slave/test"] 18:39:59 INFO - --app=APP Application being installed. [default: firefox] 18:39:59 INFO - mkdir: /builds/slave/test/build/application 18:39:59 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'] 18:39:59 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 18:40:19 INFO - Reading from file tmpfile_stdout 18:40:19 INFO - Output received: 18:40:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:40:19 INFO - Running post-action listener: _resource_record_post_action 18:40:19 INFO - [mozharness: 2017-03-09 02:40:19.446403Z] Finished install step (success) 18:40:19 INFO - [mozharness: 2017-03-09 02:40:19.446581Z] Running run-tests step. 18:40:19 INFO - Running pre-action listener: _resource_record_pre_action 18:40:19 INFO - Running main action method: run_tests 18:40:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:40:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:40:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:40:19 INFO - 2017-03-08 18:40:19.461 screenresolution[1626:7400] starting screenresolution argv=screenresolution get 18:40:19 INFO - 2017-03-08 18:40:19.463 screenresolution[1626:7400] Display 0: 1600x1200x32@60 18:40:19 INFO - 2017-03-08 18:40:19.469 screenresolution[1627:7403] starting screenresolution argv=screenresolution list 18:40:19 INFO - Available Modes on Display 0 18:40:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:40:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:40:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:40:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:40:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:40:19 INFO - Intel Iris: 18:40:19 INFO - Chipset Model: Intel Iris 18:40:19 INFO - Type: GPU 18:40:19 INFO - Bus: Built-In 18:40:19 INFO - VRAM (Dynamic, Max): 1536 MB 18:40:19 INFO - Vendor: Intel (0x8086) 18:40:19 INFO - Device ID: 0x0a2e 18:40:19 INFO - Revision ID: 0x0009 18:40:19 INFO - Displays: 18:40:19 INFO - Display: 18:40:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:40:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:40:19 INFO - Display Serial Number: 1600x1200 60 18:40:19 INFO - Main Display: Yes 18:40:19 INFO - Mirror: Off 18:40:19 INFO - Online: Yes 18:40:19 INFO - Rotation: Supported 18:40:19 INFO - Return code: 0 18:40:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:40:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:40:19 INFO - Python 2.7.3 18:40:19 INFO - Return code: 0 18:40:19 INFO - grabbing minidump binary from tooltool 18:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:19 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 0x10e3a9d30>, '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 0x10e2d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e39ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:40:19 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 18:40:19 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 18:40:19 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 18:40:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:40:19 INFO - Return code: 0 18:40:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:40:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:40:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:40:19 INFO - ENV: RUST_BACKTRACE is now 1 18:40:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:40:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:40:19 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/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 18:40:19 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/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0312 --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 18:40:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bI58cTMBUv/Render', 18:40:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:19 INFO - 'HOME': '/Users/cltbld', 18:40:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:19 INFO - 'LOGNAME': 'cltbld', 18:40:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:40:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:19 INFO - 'MOZ_NO_REMOTE': '1', 18:40:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:40:19 INFO - 'NO_EM_RESTART': '1', 18:40:19 INFO - 'PAGER': '/bin/cat', 18:40:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:19 INFO - 'PWD': '/builds/slave/test', 18:40:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:40:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:19 INFO - 'RUST_BACKTRACE': '1', 18:40:19 INFO - 'SHELL': '/bin/bash', 18:40:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Td2PpYmCSB/Listeners', 18:40:19 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 18:40:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:19 INFO - 'USER': 'cltbld', 18:40:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:19 INFO - 'XPC_FLAGS': '0x0', 18:40:19 INFO - 'XPC_SERVICE_NAME': '0', 18:40:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:19 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/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 18:40:20 INFO - mozversion application_buildid: 20170308170829 18:40:20 INFO - mozversion application_changeset: 19289cc8bf6ffce3b2067fbe91aebea5a356d008 18:40:20 INFO - mozversion application_display_name: Nightly 18:40:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:40:20 INFO - mozversion application_name: Firefox 18:40:20 INFO - mozversion application_remotingname: firefox 18:40:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:40:20 INFO - mozversion application_vendor: Mozilla 18:40:20 INFO - mozversion application_version: 55.0a1 18:40:20 INFO - mozversion platform_buildid: 20170308170829 18:40:20 INFO - mozversion platform_changeset: 19289cc8bf6ffce3b2067fbe91aebea5a356d008 18:40:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:40:20 INFO - mozversion platform_version: 55.0a1 18:40:20 INFO - using testdate: 1489027220 18:40:20 INFO - actual date: 1489027220 18:40:20 INFO - starting webserver on 'localhost:49241' 18:40:20 INFO - SUITE-START | Running 2 tests 18:40:20 INFO - TEST-START | dromaeo_css 18:40:20 INFO - operating with platform_type : mac_ 18:40:20 INFO - Initialising browser for dromaeo_css test... 18:40:20 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmp7ZHyiP/profile http://localhost:49241/getInfo.html) 18:40:22 INFO - PROCESS | 1637 | 2017-03-08 18:40:22.875 plugin-container[1639:7583] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 18:40:22 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:40:22 INFO - PROCESS | 1637 | 2017-03-08 18:40:22.879 plugin-container[1639:7583] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 18:40:22 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:40:22 INFO - PROCESS | 1637 | 2017-03-08 18:40:22.880 plugin-container[1639:7583] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:40:22 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 18:40:22 INFO - PROCESS | 1637 | colorDepth:24 18:40:22 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 18:40:22 INFO - PROCESS | 1637 | __metrics 18:40:23 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 18:40:23 INFO - TEST-INFO | 1637: exit 0 18:40:23 INFO - Browser initialized. 18:40:23 INFO - Running cycle 1/1 for dromaeo_css test... 18:40:23 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmp7ZHyiP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:40:26 INFO - PROCESS | 1640 | 2017-03-08 18:40:26.649 plugin-container[1641:7719] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8733, name = 'com.apple.tsm.portname' 18:40:26 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:40:26 INFO - PROCESS | 1640 | 2017-03-08 18:40:26.649 plugin-container[1641:7719] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a53, name = 'com.apple.CFPasteboardClient' 18:40:26 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:40:26 INFO - PROCESS | 1640 | 2017-03-08 18:40:26.649 plugin-container[1641:7719] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:40:36 INFO - PROCESS | 1640 | RSS: Main: 228007936 18:40:36 INFO - PROCESS | 1640 | 18:43:39 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 18:43:41 INFO - PROCESS | 1640 | RSS: Main: 250720256 18:43:41 INFO - PROCESS | 1640 | 18:45:24 INFO - PROCESS | 1640 | ************************* 18:45:24 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 18:45:24 INFO - PROCESS | 1640 | 18:45:24 ERROR - PROCESS | 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:45:24 INFO - PROCESS | 1640 | Full stack: 18:45:24 INFO - PROCESS | 1640 | ************************* 18:46:43 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 18:46:44 INFO - PROCESS | 1640 | RSS: Main: 258662400 18:46:44 INFO - PROCESS | 1640 | 18:49:46 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 18:49:47 INFO - PROCESS | 1640 | RSS: Main: 270774272 18:49:47 INFO - PROCESS | 1640 | 18:52:48 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 18:52:49 INFO - PROCESS | 1640 | RSS: Main: 263475200 18:52:49 INFO - PROCESS | 1640 | 18:55:52 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 18:55:53 INFO - PROCESS | 1640 | RSS: Main: 276287488 18:55:53 INFO - PROCESS | 1640 | 18:58:56 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 18:58:56 INFO - PROCESS | 1640 | RSS: Main: 270336000 18:58:56 INFO - PROCESS | 1640 | 18:58:56 INFO - PROCESS | 1640 | __start_tp_report 18:58:56 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 18:58:56 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 18:58:56 INFO - PROCESS | 1640 | |i|pagename|runs| 18:58:56 INFO - PROCESS | 1640 | |0;dojo.html;2993.8623323137217;3458.9654103458965;3488.145404375928;3492.8428220730066;3526.770759900606;4476.171908196981;4712.952870471294;6049.818505444839;6094.116353128794;6125.08123781433;5990.640561566309;6041.879162416749;6114.266288045441;6141.2937512186;6143.8771224575485;5402.108652072403;5405.972970135143;5409.918851217235;5414.756335964891;5544.695041772701;5548.500634942854;5548.750306236209;5563.221149039137;5567.192757050226;5574.275344205268;5423.945760542383;5462.9726851365485;5494.532964698005;5499.037668408013;5506.1190209566275;5466.508014278695;5477.808276710336;5479.1781232815;5489.862753431156;5555.666660000413;5408.8377348679605;5425.864353391197;5430.728463576806;5462.6995515246645;5514.062609356379;5789.07374820031;5792.102224155263;5895.086261629454;5916.112583112525;5923.763049478016;5717.627769335371;6114.814446323758;6256.374362563753;6268.968655156741;6421.711023003976;6472.3204063573585;6490.221173459168;6500.837479063014;6510.990796426514;6516.9674151629415;6133.509319254494;6146.0473626587955;6187.876242475215;6213.347598502138;6216.7824126155365;6273.592216506004;6308.211473565804;6459.741610335628;6469;6623.569467984566;6230.688465576703;6269.7178626961895;6290.779822706275;6290.8427289317215;6340.714667839959;6290.370962903673;6322.430981211713;6333.398327158912;6414.973604223302;6519.250286217051;5365.892682146413;6302.243730752339;6318.842028949313;6345.04824276353;6406.135171751847;6250.874982500415;6388.169537960035;6391.840203994844;6410.8076757697345;6411.711472983727;5148.613853960968;5162.9483705162465;5176.922346164811;5207.270982062514;5235.136202526541;4620.861374158781;4623.86128416148;4634.837780677727;4636.83771068011;4644.790984405709;5220.530152286313;6195.2255968004;6212.534059945522;6224.439800417984;6229.875402492015;6171.814845554641;6286.748530058747;6347.841303967438;6375.808725738235;6417.614943103429;6159.3224745277985;6173.59871608353;6196.287426945869;6204.286507051656;6207.937920620826;5755.1942728017275;5757;5761.53907687384;5772.682502462405;5790.594658373958;6334.556581039283;6412.39082287182;6500.674966251763;6537.836554086186;6734.124563806674;6462.483001359974;6468.870622587616;6476.4495017924;6480.837979050561;6483.805485835433;6570.671466426755;6592.67036648156;6615.4376877965915;6649.667516624053;6670.132882725215;6218.22272215973;6299.811005669837;6302.432781049728;6383.553151279366;6389.2971773105855;6477.546571740121;6503.089567460654;6513.837154071186;6522.249941256627;6533.8693226136165;6197.845053873689;6316.778912738032;6319.6208227506495;6336.809895703136;6341.334159913143;6194.442500175006;6218.098375735583;6223.906641400473;6224.782132625336;6268.091126786499;6101.038199791437;6149.831532008904;6273.749050037947;6284.528660350401;6293.905591416041;5571.860703482445;6284.811455656155;6300.086487459384;6326.9683651581445;6344.365563443619;5656.5757568181725;6087.178230938944;6113.5979309659515;6141.07883817431;6282.120503129474 18:58:56 INFO - PROCESS | 1640 | |1;ext.html;694.517310469224;712.9073220481337;802.1697543042952;925.1627277314033;931.9254459643225;38181.42727859081;39155.80422097889;41152;41253.17493650125;41435.58564414359;32911.51898164582;39986.80006599963;40157.19685606286;40252.39621405674;40268.39597406041;7720.4209684273765;7896.775999720053;9804.264680148981;11115.221934464591;11349.659510214666;11035.227534072617;11113.499892504857;11123.388213648246;11127.332360058423;11140.777184456307;11057.336559806437;11087;11127.72180695481;11133.777324453506;11139.832902506429;11065.612703555376;11098.61154859576;11098.778024439465;11100.777984440307;11100.833487497694;10986.615468458565;10994.835077473765;11015.669529914116;11071.612493562767;11077.504536887498;39316.80341598302;39595.60404395948;39595.80202098971;39717.40423893644;39784.20431591381;3762.5596101754595;3790.0105084391166;3801.3347664158937;3806.7335286529956;3808.771473711586;27828;28008.43983120326;28264.85867570649;28453.589693597525;29132.41735165306;3115.252339438541;3154.858031388593;3163.683631636841;3204.2630195055035;3266.069170286457;333297;340074;343514;345228;393987;47879.28181077287;47886.28170577444;48126.518734812904;48199;48280.517194827604;51840.48159518432;51852.70368240749;51973;52036.47963520316;52057.21914171215;14438.917081218951;14462.27688615586;14491.92754036223;14504.274786260645;14532.200728960008;11726.765464690658;11842.28946263227;11927.701807454709;11940.52237910474;11970.700732481584;13795.517156899661;13875.514356997457;13911.582652520441;13912.44350226;13970.720585588231;5405.162199859028;5884.911726324109;5887.882242355129;5893.793717219876;5940.6435613863305;1035.3011717091083;1090.6346373964811;1112.6161474291357;1121.6915348279288;1169.216624861345;25814.870925645628;26102.477950440883;26608.06871759518;26618;26651.733482664924;1195.7548702516137;1210.9818352724717;1218.9024878009739;1266.1390254626942;1272.0586765793432;13217.273049982152;13508.459661613426;13897.374618142208;13918.652033699036;13919.791203131961;374753;375359;377561;378729.10635446646;378968.051597419;55596.72201639047;55811.72094139585;56061;56116.71941640347;56220.43779562152;1369.301656155368;1415.0236336927487;1421.6516953346497;1422.587449639593;1431.1556181852757;21248.681269781275;23324.3002709919;24881.377965551004;25083.623745643465;26738;46198;46357;46358.30462543132;46440.767796160806;46527.53472465232;374236;375288.1235593805;376289;376295;376693;28291.858540707166;28940.84236630511;32454;34015;34468.82765586156;44591.33113003243;44642.77678611716;44820.55179448164;45945.31082033839;46165.46014478284;32862.34275314528;34624.653753462146;35502.8224858874;36314;36457.81771091127;32237.838810805795;35654;36181.81909090438;37154.88535343944;37192.07019824526;35870.64129358673;36404.63595364012;36705.816470917474;36823.07942301464;37207.81396093002;30553.93061242846;31043;32586.02241932746;32714.50928236126;32841.50737738983;19690.704639430707;24129.396765081012;24146.517069658155;24352.391190219678;24863.64886144881 18:58:56 INFO - PROCESS | 1640 | |2;jquery.html;363.8708258568208;390.73234834138634;391.2761391425861;397.4396101496888;436.45443196004993;21001.47496312593;21449.46376340592;21607.243746468877;22303.219387321446;22350.441238969015;21427.892860535714;21923.342299730994;22074.88962555189;22502.099916003383;22659.77340226597;6321.77873774418;6709.563878347916;6735.360140786642;6738.764143254987;6748.932510674904;5236.8690782730355;5437.531866396071;5714.428557144294;6038.792241551685;6326.715297811609;5634.521065709419;6519.119918810971;6547.836304092388;6561.540692151551;6562.475001999828;6349.8412539686415;6439.774607888725;6519.902201466982;6548.246951600579;6575.276719560845;6320.083587879778;6336.3663633636725;6390.616563006234;6409.198850143732;6413.679316034226;25380.984760609554;26594.202173934813;26718.5992210117;26761.197164085108;27682.169534913985;3206.599175103112;3336.9833150834334;3348.1294863335465;3425.400554902882;3536.4341705327024;16255.18724063792;16954.576135596584;17037.829621703873;17192.742108868377;17701.557461063578;1797.5326415132029;2028.381343690174;2062.2988184017354;2096.4758810297426;2098.8845613491253;129149;168657;169842;173569;174690.1265493677;18207.45377638673;21733.891330543247;22933.655995160087;23054.423639408815;23240;36499.452508212395;36518;36630.26739465196;36756.81621591929;36758;10393.220508461896;10669.89330106689;10829.67510974672;10831.620893268855;10939.56241750337;8581.828363432696;8584.05575386707;8610.655573777103;8761.780955476037;8816.250618697355;9181.862272065924;11066.731320079365;11083.334999899871;11139.610113645984;11282.323060616225;3884.514435695538;4485.506594274627;4543.909121817545;4551.795169217393;4600.263957766741;870.2298465857635;887.1793590928365;891.5734824281099;894.4320356573493;924.6255266616896;21537.569248615255;22239.332820015425;22702.886485567466;22790;22867.656985145237;783.3341659589304;800.6597196191595;864.1747131489412;927.9304052195977;939.1920552935571;11416.42917854104;11495.448114504603;11696.122790790725;11764.58823941158;11842.822357664641;396410;559215;671818;676617;696584;155661.2216938908;156142;156770.21614891852;159607;161410.38589613955;1183.2545496337252;1211.3277131192096;1240.8883200511998;1241.2118304876287;1248.5817251220942;19390.4182874514;19485.123169457125;19620.215191392184;19647.60704785925;19703.40889773309;49481.26815561438;52784.47215527796;64879;65166.34833651603;65619.6719016421;84940.57529712311;85400.57299713462;85785;86042;86402.70395944192;9967.551460184164;11510.48202830858;12158.817617735564;12188.817167742314;12288.21820835956;12743.93628031854;12798.360081996048;13499.257540835348;13686.589402317946;14047.508337208148;9905.455199964072;11652.242604230698;11669.241499302518;11695.824562631737;11820.70448238801;10733.355998640107;11491.425428728697;11633.302001879914;11700.531978721096;11730.592328920584;10876.673699789018;11872.584459543874;11879.336892834837;11903.928646421517;11943.10426718017;10326.277160598685;11556.075513958695;11897.762044759229;11925.701857453287;11969.880301196876;11417.543298268309;11551.653450396501;11894.464749086139;11933.76132477363;11955.760884782081 18:58:56 INFO - PROCESS | 1640 | |3;mootools.html;1496.8577985091413;1533.5552689719982;1554.1063888264243;1597.0258142533057;1607.8633316168125;10403.947980260098;10778.245522813406;11023.95272449117;11056.226064175511;11104.222704410695;10871.945640271808;11058.22592418531;11087.83368249477;11092.944535277333;11112;2300.1029598456616;2347.272345572869;2350.4946436516148;2352.1061996441326;2356.964645530318;2275.9089636414606;2292.266474728079;2292.4039749665126;2313.248194336839;2316.8494047886834;2285.6342985122383;2298.6666933294664;2303.424143964009;2307.2501437032947;2313.5835549601147;2005.1979208316643;2009.1661960286538;2188.6758511100875;2247.213475283654;2269.8978545965388;2244.3940136163146;2297.9195728149434;2301.102569997702;2303.7350704668843;2308.3651995701316;10788.73028174294;10809.67570972872;10869.67390978272;10876.347419154798;10952.452377381178;4997.400311962551;5776.537876969833;5890.293164820207;5915.3788852170765;5941.1088336749835;3406.5741782277214;3567.4277499723416;3587.264610754789;3697.556293244801;3729.4219395993664;982.3860087445347;1150.0856818829052;1234.1237721217947;1241.2738547949461;1250.9562165324262;14063.226522541256;14263.928680356532;14704.852951470562;14994.700105997928;15251.923740381228;4528.977355113203;4572.428446444194;4716.976415117902;4717.9056418872115;4730.337752714623;11558.595449159238;11673.649790506299;11731.941340293415;11746.823797643043;11756.647300580997;4887.3890763654545;5033.798648054037;5517.779288828402;5692.829215123553;5750.281214848144;4366.759828209479;4448.777561121931;5151.845444636667;5192.221166825007;5220.843374698765;3151.9684803151677;3725.2549490101546;3790.1851102012647;3819;3836.462895194675;1028.830243009895;1030.829913064336;1031.1544533482622;1039.3296323871061;1042.4110377636762;729.5476804381318;826.4875777018287;878.1218781218781;903.3586153830722;966.4298064142049;5399.325084364455;5928.614640048383;6036.2756469223095;6466.057943635635;6853.965730171317;988.4908574560283;990.5393991793851;991.2763682511807;1030.4332617060586;1034.6792494326783;3476.9652303477146;3944.290027794967;4172.582741725864;4249.80875860587;4317.935230971538;12281.89462948339;12954.676133096747;13044.347782610832;13376.39806208723;13531.932340338235;13238.867611323956;13356.46574137043;13445.731085378238;13470.663233419244;13507.257100859351;8718.782030449289;9944.80110397788;10096.798064038678;10104.141148002502;10112.74718132038;6244.250689917239;6391.3289104643345;6570.8357291068105;6703.162104736908;6748.490705134599;11625.12811539121;11898.940505297418;11935.880641193824;12040.277583345027;12088.75822483563;12014.579489717818;12067.491563554555;12123.515059397525;12261.14172007951;12400.19398739079;8295.792605184919;8545.700900468453;8548.786280343042;8576.056633770406;8585.65657373698;9632.807343853225;10077.596896124072;10119.797604048024;10181.592736290762;10195.7451063724;8963.372563920462;8997.820043599222;9062.184403403726;9065.546722663972;9242.260619150318;8748.737537873874;8754.430961987451;8854.29165666758;8862.955685221532;8873.023967363455;7428.702851885864;7745.65144568507;7774.416918731004;7786.377089832913;7786.922130778619;5693.202951586864;5744.540436764965;5854.268216472941;5928.110783382527;5931.110333450017;5723.742431590672;5786.681732504584;5971.581989260884;5974.671393073423;6111.052786818051 18:58:56 INFO - PROCESS | 1640 | |4;prototype.html;344.53315757149056;345.4145223845581;357.7156160852124;369.2891184469895;373.7794701126335;5034.974825125873;5230.509304848119;5293.232481290221;5384.769138516893;5410.486003829635;4209.85265515707;5186.662866913648;5188.8702782430455;5188.922166167501;5197.454267301936;3753.136778540937;4283.971846756787;4315.935260971088;4331.566843315659;4345.369921361401;3808.923821523582;3970.8610198643055;4098.446709694198;4270.893227669318;4293.248681480744;3429.6164165904706;3909.7263191576603;4206.3690446432975;4259.403683484315;4259.616634502894;3768.962310376902;4253.340732186501;4274.935875961832;4276.877879148208;4288.549702281278;3282.195862013798;3965.374196579389;3966.6596707942454;3967.7501587000293;3990.700697447703;4688.601468875149;4997.925031124536;5013.323201367842;5060.418051924003;5108.777681708469;4222.683298752575;4790.257510085942;4941.517544736565;4994.150994330972;5011.523905228997;4569.702969306984;4627.282771170474;5227.895442091137;5318.787248510055;5667.716614169275;3814.4278358246206;4131.793410329502;4387.42963414754;4397.274449715793;4417.447819022622;5192.350956130484;5751.482366587027;5812.534997200256;5949.960789798358;5975.701214939279;4069.8372065117655;4354.216241076637;4523;4524.615407690318;4531.274996000624;3766.9434958475645;4054.351303791379;4328.588784065509;4644.535546445329;4655.813767449332;4034.325754811779;4323.546027667113;4685.6017238535105;4781.947971446276;4959.280904268933;5014.323066386064;5084.898302034013;5670.796000259922;5671.532883195823;5676.262085928803;1291.6760320671387;1410.8871290296752;1489.0172486159083;1534.5549790560835;1542.7300222461024;877.6138499060393;1180.3154170581042;1182.379250893288;1184.9111316651113;1193.91045671575;4630.9536904631195;4755.809767609326;5232.927249913759;5262.526372626482;5279.287296214962;4811.566958973709;5178.6116041297055;5780.814932938738;5791.652500849878;5819.679917604488;4592.209038474997;4872.78072486739;5155.690658560498;5221.762507245532;5251.343582052244;2897.3046468847324;2901.722148113721;2940.8971635636635;3015.20097174249;3019.9245018874703;5639.154126881001;6007.738374941224;6023;6045.455908968214;6053.667048312385;4905.5094490550655;5126.718030508283;5655.5475561954945;6035.275766907999;6077.3314935357075;5522.6134170608475;6035.39646035393;6107.389261073946;6154.446099851035;6169.598976066542;4758.809647614056;5539.992847776889;5668.7165641718575;5798.536117110705;5911.098674874562;5427.457254274699;5601.355844077821;5715.542756579381;5776.277965254343;5813.680247586423;4801.5438533339275;5798;5865.706714664335;5958.225430693982;6058.7273572688455;4639.979204574988;4732.266498692708;5008.9499105009945;5021.669257646799;5041.521055499722;4863.124637565272;5016.322796422436;5539.8892022158525;5826.155207494974;5852.060158938595;5256.448072952285;5621.831345059655;5655.802046928337;5687.402822703557;5701.372848986691;5037.388035828646;5121.974390128025;5683.1759394886685;5723.3418156912485;5752.878188753153;4400.669949753846;4932.654714169974;4963.280324353021;5520.1443776214755;5603.91974707447;4852.805887764591;4861.4895435978715;5565.1374037024325;5582.7766889323975;5624.465675760796;5476;5508.338999320107;5531.640443371168;5542.2795036644975;5544.473275038865 18:58:56 INFO - PROCESS | 1640 | |5;yui.html;702.2836706559307;721.404652364438;766.7123986508238;819.8114433680254;827.9793005174872;15445.382184712613;16614.75077873833;16625.833741662595;16864.915675421606;17711;16351.754723679154;17037.21109283526;17043.659126817456;17346;17396.13019349034;951.9190868776145;973.7224890906093;979.0307595480494;980.872486576744;981.40134517944;919.2691810011057;967.385710074101;970.5195928015642;971.5336638413565;971.6016433262364;1009.7223263602494;1021.4433133941982;1022.1669339488293;1022.8005538919914;1023.1661196125134;1005.36661903001;1016.7508960304699;1017.0033367300014;1017.9847302290472;1019.5972590826619;742.7808796405019;749.9962500187519;751.1249394455452;753.4876284126792;754.475639430596;14607.70784584313;15167.24163791806;16630.33478660852;16882;16964.74552881708;14443.205623690692;14677;14692.706145877022;14732.631684207874;14806.333714982851;11584.884151158465;11657.533698652043;11904.761904761943;11993;12256.019518438503;3677.8712745053795;3678.8712395066045;3721.423179407196;3767.3783825668734;3856.6042609416745;105292;107666.46166769194;108191;108551;110348;11124.40907186879;11231.606893758679;11255.493502792395;11343.659690209306;11585.073194144448;24453.26640200797;24673.01307947698;24962.251132466055;25005.374865628506;25041.62437563438;7509.7233470310175;7825.413094017971;7849.529028258323;7934.563599001995;8025.9598702006115;3495.615482296945;3629.8003609801185;4202.892783956411;4231.280682284019;4295.892602684896;3646.817659117087;3879.321118804198;3892.591277915778;3898.317794385971;3916.823742931575;582.6038293960063;684.4866350237322;685.8114018644912;687.5359132585585;701.4143190435941;636.296891934415;649.4285029174297;674.6019848289533;684.9760258391032;702.5995182745888;10132.645357412455;10159.390436573829;10307.94846025765;10361.792764144675;10377.811792562747;658.7990662847782;675.368530424055;697.6302559643398;702.1924786495468;708.7384455668918;3964.6630036447227;4515.177254235791;4907.731861543199;4909.87635930286;4936.950630493649;92775.2167434978;106041.46979265055;106050.4092438614;108348;111498;60239.698801505714;73645.6317718408;74798.62600686961;79075.02312442235;89390.65914011296;74650;77759.833602496;77930.2587176603;78763.60618196987;80008.599957001;9184.632614695605;10661.613990181228;10787.892121078688;10919.56321747153;10951.83572246433;37148;42176.73469795911;46355.53644463647;47567.76216118897;50852.74573627256;64926.67536662286;66508.00237996338;66702;69263.96104058543;77381.83927241208;11547.711307217387;13800.792988104986;13821.516246931309;14138.717225655635;14239.145651260958;11015.449227538751;14977.251137443302;14986.550403487912;15307.61730956735;15392.461263855712;11719.355435451133;13554.796678049639;13729.313534323044;13808.240546770025;14208.928955355157;13629.522966696515;13781.86218137846;13930.024898257025;14430.783538247144;14639.853601463848;11404.429778511207;12944;13059;13721.794173087612;13811.447542098605;10698.518566664674;10769.956548727498;10927.672169834917;11047.337159770439;11372.54509819598;8640.26557742599;8771.442736737728;10111.848322275024;10482.936381074254;11424.828627570427 18:58:56 INFO - PROCESS | 1640 | __end_tp_report 18:58:56 INFO - PROCESS | 1640 | __start_cc_report 18:58:56 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,440 18:58:56 INFO - PROCESS | 1640 | __end_cc_report 18:58:56 INFO - PROCESS | 1640 | __startTimestamp1489028336505__endTimestamp 18:58:56 INFO - PROCESS | 1640 | 18:58:56 INFO - PROCESS | 1640 | ------- Summary: start ------- 18:58:56 INFO - PROCESS | 1640 | Number of tests: 6 18:58:56 INFO - PROCESS | 1640 | 18:58:56 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5945.35 Median:6207.94 stddev:665.90 (10.7%) stddev-sans-first:626.40 18:58:56 INFO - PROCESS | 1640 | Values: 2993.9 3459.0 3488.1 3492.8 3526.8 4476.2 4713.0 6049.8 6094.1 6125.1 5990.6 6041.9 6114.3 6141.3 6143.9 5402.1 5406.0 5409.9 5414.8 5544.7 5548.5 5548.8 5563.2 5567.2 5574.3 5423.9 5463.0 5494.5 5499.0 5506.1 5466.5 5477.8 5479.2 5489.9 5555.7 5408.8 5425.9 5430.7 5462.7 5514.1 5789.1 5792.1 5895.1 5916.1 5923.8 5717.6 6114.8 6256.4 6269.0 6421.7 6472.3 6490.2 6500.8 6511.0 6517.0 6133.5 6146.0 6187.9 6213.3 6216.8 6273.6 6308.2 6459.7 6469.0 6623.6 6230.7 6269.7 6290.8 6290.8 6340.7 6290.4 6322.4 6333.4 6415.0 6519.3 5365.9 6302.2 6318.8 6345.0 6406.1 6250.9 6388.2 6391.8 6410.8 6411.7 5148.6 5162.9 5176.9 5207.3 5235.1 4620.9 4623.9 4634.8 4636.8 4644.8 5220.5 6195.2 6212.5 6224.4 6229.9 6171.8 6286.7 6347.8 6375.8 6417.6 6159.3 6173.6 6196.3 6204.3 6207.9 5755.2 5757.0 5761.5 5772.7 5790.6 6334.6 6412.4 6500.7 6537.8 6734.1 6462.5 6468.9 6476.4 6480.8 6483.8 6570.7 6592.7 6615.4 6649.7 6670.1 6218.2 6299.8 6302.4 6383.6 6389.3 6477.5 6503.1 6513.8 6522.2 6533.9 6197.8 6316.8 6319.6 6336.8 6341.3 6194.4 6218.1 6223.9 6224.8 6268.1 6101.0 6149.8 6273.7 6284.5 6293.9 5571.9 6284.8 6300.1 6327.0 6344.4 5656.6 6087.2 6113.6 6141.1 6282.1 18:58:56 INFO - PROCESS | 1640 | 18:58:56 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:51911.77 Median:32851.93 stddev:97043.12 (295.4%) stddev-sans-first:97239.16 18:58:56 INFO - PROCESS | 1640 | Values: 694.5 712.9 802.2 925.2 931.9 38181.4 39155.8 41152.0 41253.2 41435.6 32911.5 39986.8 40157.2 40252.4 40268.4 7720.4 7896.8 9804.3 11115.2 11349.7 11035.2 11113.5 11123.4 11127.3 11140.8 11057.3 11087.0 11127.7 11133.8 11139.8 11065.6 11098.6 11098.8 11100.8 11100.8 10986.6 10994.8 11015.7 11071.6 11077.5 39316.8 39595.6 39595.8 39717.4 39784.2 3762.6 3790.0 3801.3 3806.7 3808.8 27828.0 28008.4 28264.9 28453.6 29132.4 3115.3 3154.9 3163.7 3204.3 3266.1 333297.0 340074.0 343514.0 345228.0 393987.0 47879.3 47886.3 48126.5 48199.0 48280.5 51840.5 51852.7 51973.0 52036.5 52057.2 14438.9 14462.3 14491.9 14504.3 14532.2 11726.8 11842.3 11927.7 11940.5 11970.7 13795.5 13875.5 13911.6 13912.4 13970.7 5405.2 5884.9 5887.9 5893.8 5940.6 1035.3 1090.6 1112.6 1121.7 1169.2 25814.9 26102.5 26608.1 26618.0 26651.7 1195.8 1211.0 1218.9 1266.1 1272.1 13217.3 13508.5 13897.4 13918.7 13919.8 374753.0 375359.0 377561.0 378729.1 378968.1 55596.7 55811.7 56061.0 56116.7 56220.4 1369.3 1415.0 1421.7 1422.6 1431.2 21248.7 23324.3 24881.4 25083.6 26738.0 46198.0 46357.0 46358.3 46440.8 46527.5 374236.0 375288.1 376289.0 376295.0 376693.0 28291.9 28940.8 32454.0 34015.0 34468.8 44591.3 44642.8 44820.6 45945.3 46165.5 32862.3 34624.7 35502.8 36314.0 36457.8 32237.8 35654.0 36181.8 37154.9 37192.1 35870.6 36404.6 36705.8 36823.1 37207.8 30553.9 31043.0 32586.0 32714.5 32841.5 19690.7 24129.4 24146.5 24352.4 24863.6 18:58:56 INFO - PROCESS | 1640 | 18:58:56 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:39552.12 Median:22681.33 stddev:103793.80 (457.6%) stddev-sans-first:104043.26 18:58:56 INFO - PROCESS | 1640 | Values: 363.9 390.7 391.3 397.4 436.5 21001.5 21449.5 21607.2 22303.2 22350.4 21427.9 21923.3 22074.9 22502.1 22659.8 6321.8 6709.6 6735.4 6738.8 6748.9 5236.9 5437.5 5714.4 6038.8 6326.7 5634.5 6519.1 6547.8 6561.5 6562.5 6349.8 6439.8 6519.9 6548.2 6575.3 6320.1 6336.4 6390.6 6409.2 6413.7 25381.0 26594.2 26718.6 26761.2 27682.2 3206.6 3337.0 3348.1 3425.4 3536.4 16255.2 16954.6 17037.8 17192.7 17701.6 1797.5 2028.4 2062.3 2096.5 2098.9 129149.0 168657.0 169842.0 173569.0 174690.1 18207.5 21733.9 22933.7 23054.4 23240.0 36499.5 36518.0 36630.3 36756.8 36758.0 10393.2 10669.9 10829.7 10831.6 10939.6 8581.8 8584.1 8610.7 8761.8 8816.3 9181.9 11066.7 11083.3 11139.6 11282.3 3884.5 4485.5 4543.9 4551.8 4600.3 870.2 887.2 891.6 894.4 924.6 21537.6 22239.3 22702.9 22790.0 22867.7 783.3 800.7 864.2 927.9 939.2 11416.4 11495.4 11696.1 11764.6 11842.8 396410.0 559215.0 671818.0 676617.0 696584.0 155661.2 156142.0 156770.2 159607.0 161410.4 1183.3 1211.3 1240.9 1241.2 1248.6 19390.4 19485.1 19620.2 19647.6 19703.4 49481.3 52784.5 64879.0 65166.3 65619.7 84940.6 85400.6 85785.0 86042.0 86402.7 9967.6 11510.5 12158.8 12188.8 12288.2 12743.9 12798.4 13499.3 13686.6 14047.5 9905.5 11652.2 11669.2 11695.8 11820.7 10733.4 11491.4 11633.3 11700.5 11730.6 10876.7 11872.6 11879.3 11903.9 11943.1 10326.3 11556.1 11897.8 11925.7 11969.9 11417.5 11551.7 11894.5 11933.8 11955.8 18:58:56 INFO - PROCESS | 1640 | 18:58:56 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:6581.15 Median:2351.30 stddev:4134.63 (175.8%) stddev-sans-first:4128.58 18:58:56 INFO - PROCESS | 1640 | Values: 1496.9 1533.6 1554.1 1597.0 1607.9 10403.9 10778.2 11024.0 11056.2 11104.2 10871.9 11058.2 11087.8 11092.9 11112.0 2300.1 2347.3 2350.5 2352.1 2357.0 2275.9 2292.3 2292.4 2313.2 2316.8 2285.6 2298.7 2303.4 2307.3 2313.6 2005.2 2009.2 2188.7 2247.2 2269.9 2244.4 2297.9 2301.1 2303.7 2308.4 10788.7 10809.7 10869.7 10876.3 10952.5 4997.4 5776.5 5890.3 5915.4 5941.1 3406.6 3567.4 3587.3 3697.6 3729.4 982.4 1150.1 1234.1 1241.3 1251.0 14063.2 14263.9 14704.9 14994.7 15251.9 4529.0 4572.4 4717.0 4717.9 4730.3 11558.6 11673.6 11731.9 11746.8 11756.6 4887.4 5033.8 5517.8 5692.8 5750.3 4366.8 4448.8 5151.8 5192.2 5220.8 3152.0 3725.3 3790.2 3819.0 3836.5 1028.8 1030.8 1031.2 1039.3 1042.4 729.5 826.5 878.1 903.4 966.4 5399.3 5928.6 6036.3 6466.1 6854.0 988.5 990.5 991.3 1030.4 1034.7 3477.0 3944.3 4172.6 4249.8 4317.9 12281.9 12954.7 13044.3 13376.4 13531.9 13238.9 13356.5 13445.7 13470.7 13507.3 8718.8 9944.8 10096.8 10104.1 10112.7 6244.3 6391.3 6570.8 6703.2 6748.5 11625.1 11898.9 11935.9 12040.3 12088.8 12014.6 12067.5 12123.5 12261.1 12400.2 8295.8 8545.7 8548.8 8576.1 8585.7 9632.8 10077.6 10119.8 10181.6 10195.7 8963.4 8997.8 9062.2 9065.5 9242.3 8748.7 8754.4 8854.3 8863.0 8873.0 7428.7 7745.7 7774.4 7786.4 7786.9 5693.2 5744.5 5854.3 5928.1 5931.1 5723.7 5786.7 5971.6 5974.7 6111.1 18:58:56 INFO - PROCESS | 1640 | 18:58:56 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4643.12 Median:5012.42 stddev:1331.14 (26.6%) stddev-sans-first:1295.18 18:58:56 INFO - PROCESS | 1640 | Values: 344.5 345.4 357.7 369.3 373.8 5035.0 5230.5 5293.2 5384.8 5410.5 4209.9 5186.7 5188.9 5188.9 5197.5 3753.1 4284.0 4315.9 4331.6 4345.4 3808.9 3970.9 4098.4 4270.9 4293.2 3429.6 3909.7 4206.4 4259.4 4259.6 3769.0 4253.3 4274.9 4276.9 4288.5 3282.2 3965.4 3966.7 3967.8 3990.7 4688.6 4997.9 5013.3 5060.4 5108.8 4222.7 4790.3 4941.5 4994.2 5011.5 4569.7 4627.3 5227.9 5318.8 5667.7 3814.4 4131.8 4387.4 4397.3 4417.4 5192.4 5751.5 5812.5 5950.0 5975.7 4069.8 4354.2 4523.0 4524.6 4531.3 3766.9 4054.4 4328.6 4644.5 4655.8 4034.3 4323.5 4685.6 4781.9 4959.3 5014.3 5084.9 5670.8 5671.5 5676.3 1291.7 1410.9 1489.0 1534.6 1542.7 877.6 1180.3 1182.4 1184.9 1193.9 4631.0 4755.8 5232.9 5262.5 5279.3 4811.6 5178.6 5780.8 5791.7 5819.7 4592.2 4872.8 5155.7 5221.8 5251.3 2897.3 2901.7 2940.9 3015.2 3019.9 5639.2 6007.7 6023.0 6045.5 6053.7 4905.5 5126.7 5655.5 6035.3 6077.3 5522.6 6035.4 6107.4 6154.4 6169.6 4758.8 5540.0 5668.7 5798.5 5911.1 5427.5 5601.4 5715.5 5776.3 5813.7 4801.5 5798.0 5865.7 5958.2 6058.7 4640.0 4732.3 5008.9 5021.7 5041.5 4863.1 5016.3 5539.9 5826.2 5852.1 5256.4 5621.8 5655.8 5687.4 5701.4 5037.4 5122.0 5683.2 5723.3 5752.9 4400.7 4932.7 4963.3 5520.1 5603.9 4852.8 4861.5 5565.1 5582.8 5624.5 5476.0 5508.3 5531.6 5542.3 5544.5 18:58:56 INFO - PROCESS | 1640 | 18:58:56 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20385.83 Median:21082.13 stddev:28871.92 (136.9%) stddev-sans-first:28915.08 18:58:56 INFO - PROCESS | 1640 | Values: 702.3 721.4 766.7 819.8 828.0 15445.4 16614.8 16625.8 16864.9 17711.0 16351.8 17037.2 17043.7 17346.0 17396.1 951.9 973.7 979.0 980.9 981.4 919.3 967.4 970.5 971.5 971.6 1009.7 1021.4 1022.2 1022.8 1023.2 1005.4 1016.8 1017.0 1018.0 1019.6 742.8 750.0 751.1 753.5 754.5 14607.7 15167.2 16630.3 16882.0 16964.7 14443.2 14677.0 14692.7 14732.6 14806.3 11584.9 11657.5 11904.8 11993.0 12256.0 3677.9 3678.9 3721.4 3767.4 3856.6 105292.0 107666.5 108191.0 108551.0 110348.0 11124.4 11231.6 11255.5 11343.7 11585.1 24453.3 24673.0 24962.3 25005.4 25041.6 7509.7 7825.4 7849.5 7934.6 8026.0 3495.6 3629.8 4202.9 4231.3 4295.9 3646.8 3879.3 3892.6 3898.3 3916.8 582.6 684.5 685.8 687.5 701.4 636.3 649.4 674.6 685.0 702.6 10132.6 10159.4 10307.9 10361.8 10377.8 658.8 675.4 697.6 702.2 708.7 3964.7 4515.2 4907.7 4909.9 4937.0 92775.2 106041.5 106050.4 108348.0 111498.0 60239.7 73645.6 74798.6 79075.0 89390.7 74650.0 77759.8 77930.3 78763.6 80008.6 9184.6 10661.6 10787.9 10919.6 10951.8 37148.0 42176.7 46355.5 47567.8 50852.7 64926.7 66508.0 66702.0 69264.0 77381.8 11547.7 13800.8 13821.5 14138.7 14239.1 11015.4 14977.3 14986.6 15307.6 15392.5 11719.4 13554.8 13729.3 13808.2 14208.9 13629.5 13781.9 13930.0 14430.8 14639.9 11404.4 12944.0 13059.0 13721.8 13811.4 10698.5 10770.0 10927.7 11047.3 11372.5 8640.3 8771.4 10111.8 10482.9 11424.8 18:58:56 INFO - PROCESS | 1640 | -------- Summary: end -------- 18:58:56 INFO - PROCESS | 1640 | 18:58:57 INFO - TEST-INFO | 1640: exit 0 18:58:57 INFO - TEST-OK | dromaeo_css | took 1117116ms 18:58:57 INFO - TEST-START | kraken 18:58:57 INFO - operating with platform_type : mac_ 18:58:57 INFO - Initialising browser for kraken test... 18:58:57 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpVarUVm/profile http://localhost:49241/getInfo.html) 18:58:59 INFO - PROCESS | 1652 | 2017-03-08 18:58:59.857 plugin-container[1653:10216] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 18:58:59 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:58:59 INFO - PROCESS | 1652 | 2017-03-08 18:58:59.860 plugin-container[1653:10216] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f13, name = 'com.apple.CFPasteboardClient' 18:58:59 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:58:59 INFO - PROCESS | 1652 | 2017-03-08 18:58:59.860 plugin-container[1653:10216] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:58:59 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 18:58:59 INFO - PROCESS | 1652 | colorDepth:24 18:58:59 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 18:58:59 INFO - PROCESS | 1652 | __metrics 18:59:00 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 18:59:00 INFO - TEST-INFO | 1652: exit 0 18:59:00 INFO - Browser initialized. 18:59:00 INFO - Running cycle 1/1 for kraken test... 18:59:00 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpVarUVm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:59:03 INFO - PROCESS | 1654 | 2017-03-08 18:59:03.434 plugin-container[1655:10340] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 18:59:03 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:59:03 INFO - PROCESS | 1654 | 2017-03-08 18:59:03.434 plugin-container[1655:10340] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470f, name = 'com.apple.CFPasteboardClient' 18:59:03 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:59:03 INFO - PROCESS | 1654 | 2017-03-08 18:59:03.434 plugin-container[1655:10340] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:59:13 INFO - PROCESS | 1654 | RSS: Main: 227749888 18:59:13 INFO - PROCESS | 1654 | 18:59:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 18:59:16 INFO - PROCESS | 1654 | RSS: Main: 242143232 18:59:16 INFO - PROCESS | 1654 | 18:59:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 18:59:20 INFO - PROCESS | 1654 | RSS: Main: 250523648 18:59:20 INFO - PROCESS | 1654 | 18:59:23 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 18:59:24 INFO - PROCESS | 1654 | RSS: Main: 253157376 18:59:24 INFO - PROCESS | 1654 | 18:59:26 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 18:59:26 INFO - PROCESS | 1654 | RSS: Main: 254337024 18:59:26 INFO - PROCESS | 1654 | 18:59:28 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:59:29 INFO - PROCESS | 1654 | RSS: Main: 254705664 18:59:29 INFO - PROCESS | 1654 | 18:59:32 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 18:59:33 INFO - PROCESS | 1654 | RSS: Main: 261394432 18:59:33 INFO - PROCESS | 1654 | 18:59:36 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 18:59:37 INFO - PROCESS | 1654 | RSS: Main: 261775360 18:59:37 INFO - PROCESS | 1654 | 18:59:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 18:59:41 INFO - PROCESS | 1654 | RSS: Main: 261513216 18:59:41 INFO - PROCESS | 1654 | 18:59:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:59:43 INFO - PROCESS | 1654 | RSS: Main: 261447680 18:59:43 INFO - PROCESS | 1654 | 18:59:45 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:59:46 INFO - PROCESS | 1654 | RSS: Main: 261378048 18:59:46 INFO - PROCESS | 1654 | 18:59:48 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:59:49 INFO - PROCESS | 1654 | RSS: Main: 261447680 18:59:49 INFO - PROCESS | 1654 | 18:59:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:59:52 INFO - PROCESS | 1654 | RSS: Main: 261775360 18:59:52 INFO - PROCESS | 1654 | 18:59:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:59:56 INFO - PROCESS | 1654 | RSS: Main: 261529600 18:59:56 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 18:59:58 INFO - PROCESS | 1654 | RSS: Main: 264822784 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | __start_tp_report 18:59:58 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 18:59:58 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 18:59:58 INFO - PROCESS | 1654 | |i|pagename|runs| 18:59:58 INFO - PROCESS | 1654 | |0;ai-astar;108;120;121;120;120;120;120;121;100;177 18:59:58 INFO - PROCESS | 1654 | |1;audio-beat-detection;141;112;114;153;114;115;114;114;135;116 18:59:58 INFO - PROCESS | 1654 | |2;audio-dft;197;210;219;245;280;265;196;199;197;197 18:59:58 INFO - PROCESS | 1654 | |3;audio-fft;83;61;60;88;68;80;69;62;75;62 18:59:58 INFO - PROCESS | 1654 | |4;audio-oscillator;90;71;85;70;70;74;93;99;98;96 18:59:58 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;91;90;91;91;103;90;106;130;92;90 18:59:58 INFO - PROCESS | 1654 | |6;imaging-darkroom;100;100;125;98;98;100;98;123;131;100 18:59:58 INFO - PROCESS | 1654 | |7;imaging-desaturate;91;91;89;92;91;137;93;80;98;143 18:59:58 INFO - PROCESS | 1654 | |8;json-parse-financial;68;68;69;68;100;58;60;70;96;97 18:59:58 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;60;61;56;66;81;88;48;56;79;82 18:59:58 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;107;76;99;106;73;111;109;107;78;99 18:59:58 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;141;142;146;140;155;171;150;151;201;156 18:59:58 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;175;176;174;171;177;177;202;224;193;174 18:59:58 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;66;68;70;75;70;67;67;68;67;68 18:59:58 INFO - PROCESS | 1654 | __end_tp_report 18:59:58 INFO - PROCESS | 1654 | __start_cc_report 18:59:58 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1008 18:59:58 INFO - PROCESS | 1654 | __end_cc_report 18:59:58 INFO - PROCESS | 1654 | __startTimestamp1489028398478__endTimestamp 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | ------- Summary: start ------- 18:59:58 INFO - PROCESS | 1654 | Number of tests: 14 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:122.70 Median:120.00 stddev:20.34 (16.9%) stddev-sans-first:20.86 18:59:58 INFO - PROCESS | 1654 | Values: 108.0 120.0 121.0 120.0 120.0 120.0 120.0 121.0 100.0 177.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:122.80 Median:115.50 stddev:14.63 (12.7%) stddev-sans-first:13.95 18:59:58 INFO - PROCESS | 1654 | Values: 141.0 112.0 114.0 153.0 114.0 115.0 114.0 114.0 135.0 116.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:220.50 Median:214.50 stddev:31.54 (14.7%) stddev-sans-first:32.29 18:59:58 INFO - PROCESS | 1654 | Values: 197.0 210.0 219.0 245.0 280.0 265.0 196.0 199.0 197.0 197.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:70.80 Median:72.00 stddev:10.14 (14.1%) stddev-sans-first:9.75 18:59:58 INFO - PROCESS | 1654 | Values: 83.0 61.0 60.0 88.0 68.0 80.0 69.0 62.0 75.0 62.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:84.60 Median:91.50 stddev:12.20 (13.3%) stddev-sans-first:12.79 18:59:58 INFO - PROCESS | 1654 | Values: 90.0 71.0 85.0 70.0 70.0 74.0 93.0 99.0 98.0 96.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:97.40 Median:90.50 stddev:12.84 (14.2%) stddev-sans-first:13.41 18:59:58 INFO - PROCESS | 1654 | Values: 91.0 90.0 91.0 91.0 103.0 90.0 106.0 130.0 92.0 90.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:107.30 Median:128.00 stddev:13.31 (10.4%) stddev-sans-first:13.85 18:59:58 INFO - PROCESS | 1654 | Values: 100.0 100.0 125.0 98.0 98.0 100.0 98.0 123.0 131.0 100.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:100.50 Median:91.00 stddev:21.33 (23.4%) stddev-sans-first:22.35 18:59:58 INFO - PROCESS | 1654 | Values: 91.0 91.0 89.0 92.0 91.0 137.0 93.0 80.0 98.0 143.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:75.40 Median:68.50 stddev:15.88 (23.2%) stddev-sans-first:16.62 18:59:58 INFO - PROCESS | 1654 | Values: 68.0 68.0 69.0 68.0 100.0 58.0 60.0 70.0 96.0 97.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:72.50 stddev:13.70 (18.9%) stddev-sans-first:14.25 18:59:58 INFO - PROCESS | 1654 | Values: 60.0 61.0 56.0 66.0 81.0 88.0 48.0 56.0 79.0 82.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:96.50 Median:74.50 stddev:14.92 (20.0%) stddev-sans-first:15.34 18:59:58 INFO - PROCESS | 1654 | Values: 107.0 76.0 99.0 106.0 73.0 111.0 109.0 107.0 78.0 99.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:155.30 Median:153.00 stddev:18.51 (12.1%) stddev-sans-first:18.90 18:59:58 INFO - PROCESS | 1654 | Values: 141.0 142.0 146.0 140.0 155.0 171.0 150.0 151.0 201.0 156.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.30 Median:177.00 stddev:17.05 (9.6%) stddev-sans-first:17.75 18:59:58 INFO - PROCESS | 1654 | Values: 175.0 176.0 174.0 171.0 177.0 177.0 202.0 224.0 193.0 174.0 18:59:58 INFO - PROCESS | 1654 | 18:59:58 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:68.00 stddev:2.59 (3.8%) stddev-sans-first:2.57 18:59:58 INFO - PROCESS | 1654 | Values: 66.0 68.0 70.0 75.0 70.0 67.0 67.0 68.0 67.0 68.0 18:59:58 INFO - PROCESS | 1654 | -------- Summary: end -------- 18:59:58 INFO - PROCESS | 1654 | 18:59:59 INFO - TEST-INFO | 1654: exit 0 18:59:59 INFO - TEST-OK | kraken | took 62206ms 18:59:59 INFO - SUITE-END | took 1179s 18:59:59 INFO - Completed test suite (00:19:39) 18:59:59 INFO - javascript benchmark 18:59:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7732.866885086168, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2993.8623323137217, 3458.9654103458965, 3488.145404375928, 3492.8428220730066, 3526.770759900606, 4476.171908196981, 4712.952870471294, 6049.818505444839, 6094.116353128794, 6125.08123781433, 5990.640561566309, 6041.879162416749, 6114.266288045441, 6141.2937512186, 6143.8771224575485, 5402.108652072403, 5405.972970135143, 5409.918851217235, 5414.756335964891, 5544.695041772701, 5548.500634942854, 5548.750306236209, 5563.221149039137, 5567.192757050226, 5574.275344205268, 5423.945760542383, 5462.9726851365485, 5494.532964698005, 5499.037668408013, 5506.1190209566275, 5466.508014278695, 5477.808276710336, 5479.1781232815, 5489.862753431156, 5555.666660000413, 5408.8377348679605, 5425.864353391197, 5430.728463576806, 5462.6995515246645, 5514.062609356379, 5789.07374820031, 5792.102224155263, 5895.086261629454, 5916.112583112525, 5923.763049478016, 5717.627769335371, 6114.814446323758, 6256.374362563753, 6268.968655156741, 6421.711023003976, 6472.3204063573585, 6490.221173459168, 6500.837479063014, 6510.990796426514, 6516.9674151629415, 6133.509319254494, 6146.0473626587955, 6187.876242475215, 6213.347598502138, 6216.7824126155365, 6273.592216506004, 6308.211473565804, 6459.741610335628, 6469.0, 6623.569467984566, 6230.688465576703, 6269.7178626961895, 6290.779822706275, 6290.8427289317215, 6340.714667839959, 6290.370962903673, 6322.430981211713, 6333.398327158912, 6414.973604223302, 6519.250286217051, 5365.892682146413, 6302.243730752339, 6318.842028949313, 6345.04824276353, 6406.135171751847, 6250.874982500415, 6388.169537960035, 6391.840203994844, 6410.8076757697345, 6411.711472983727, 5148.613853960968, 5162.9483705162465, 5176.922346164811, 5207.270982062514, 5235.136202526541, 4620.861374158781, 4623.86128416148, 4634.837780677727, 4636.83771068011, 4644.790984405709, 5220.530152286313, 6195.2255968004, 6212.534059945522, 6224.439800417984, 6229.875402492015, 6171.814845554641, 6286.748530058747, 6347.841303967438, 6375.808725738235, 6417.614943103429, 6159.3224745277985, 6173.59871608353, 6196.287426945869, 6204.286507051656, 6207.937920620826, 5755.1942728017275, 5757.0, 5761.53907687384, 5772.682502462405, 5790.594658373958, 6334.556581039283, 6412.39082287182, 6500.674966251763, 6537.836554086186, 6734.124563806674, 6462.483001359974, 6468.870622587616, 6476.4495017924, 6480.837979050561, 6483.805485835433, 6570.671466426755, 6592.67036648156, 6615.4376877965915, 6649.667516624053, 6670.132882725215, 6218.22272215973, 6299.811005669837, 6302.432781049728, 6383.553151279366, 6389.2971773105855, 6477.546571740121, 6503.089567460654, 6513.837154071186, 6522.249941256627, 6533.8693226136165, 6197.845053873689, 6316.778912738032, 6319.6208227506495, 6336.809895703136, 6341.334159913143, 6194.442500175006, 6218.098375735583, 6223.906641400473, 6224.782132625336, 6268.091126786499, 6101.038199791437, 6149.831532008904, 6273.749050037947, 6284.528660350401, 6293.905591416041, 5571.860703482445, 6284.811455656155, 6300.086487459384, 6326.9683651581445, 6344.365563443619, 5656.5757568181725, 6087.178230938944, 6113.5979309659515, 6141.07883817431, 6282.120503129474], "value": 5903.619834983382, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.517310469224, 712.9073220481337, 802.1697543042952, 925.1627277314033, 931.9254459643225, 38181.42727859081, 39155.80422097889, 41152.0, 41253.17493650125, 41435.58564414359, 32911.51898164582, 39986.80006599963, 40157.19685606286, 40252.39621405674, 40268.39597406041, 7720.4209684273765, 7896.775999720053, 9804.264680148981, 11115.221934464591, 11349.659510214666, 11035.227534072617, 11113.499892504857, 11123.388213648246, 11127.332360058423, 11140.777184456307, 11057.336559806437, 11087.0, 11127.72180695481, 11133.777324453506, 11139.832902506429, 11065.612703555376, 11098.61154859576, 11098.778024439465, 11100.777984440307, 11100.833487497694, 10986.615468458565, 10994.835077473765, 11015.669529914116, 11071.612493562767, 11077.504536887498, 39316.80341598302, 39595.60404395948, 39595.80202098971, 39717.40423893644, 39784.20431591381, 3762.5596101754595, 3790.0105084391166, 3801.3347664158937, 3806.7335286529956, 3808.771473711586, 27828.0, 28008.43983120326, 28264.85867570649, 28453.589693597525, 29132.41735165306, 3115.252339438541, 3154.858031388593, 3163.683631636841, 3204.2630195055035, 3266.069170286457, 333297.0, 340074.0, 343514.0, 345228.0, 393987.0, 47879.28181077287, 47886.28170577444, 48126.518734812904, 48199.0, 48280.517194827604, 51840.48159518432, 51852.70368240749, 51973.0, 52036.47963520316, 52057.21914171215, 14438.917081218951, 14462.27688615586, 14491.92754036223, 14504.274786260645, 14532.200728960008, 11726.765464690658, 11842.28946263227, 11927.701807454709, 11940.52237910474, 11970.700732481584, 13795.517156899661, 13875.514356997457, 13911.582652520441, 13912.44350226, 13970.720585588231, 5405.162199859028, 5884.911726324109, 5887.882242355129, 5893.793717219876, 5940.6435613863305, 1035.3011717091083, 1090.6346373964811, 1112.6161474291357, 1121.6915348279288, 1169.216624861345, 25814.870925645628, 26102.477950440883, 26608.06871759518, 26618.0, 26651.733482664924, 1195.7548702516137, 1210.9818352724717, 1218.9024878009739, 1266.1390254626942, 1272.0586765793432, 13217.273049982152, 13508.459661613426, 13897.374618142208, 13918.652033699036, 13919.791203131961, 374753.0, 375359.0, 377561.0, 378729.10635446646, 378968.051597419, 55596.72201639047, 55811.72094139585, 56061.0, 56116.71941640347, 56220.43779562152, 1369.301656155368, 1415.0236336927487, 1421.6516953346497, 1422.587449639593, 1431.1556181852757, 21248.681269781275, 23324.3002709919, 24881.377965551004, 25083.623745643465, 26738.0, 46198.0, 46357.0, 46358.30462543132, 46440.767796160806, 46527.53472465232, 374236.0, 375288.1235593805, 376289.0, 376295.0, 376693.0, 28291.858540707166, 28940.84236630511, 32454.0, 34015.0, 34468.82765586156, 44591.33113003243, 44642.77678611716, 44820.55179448164, 45945.31082033839, 46165.46014478284, 32862.34275314528, 34624.653753462146, 35502.8224858874, 36314.0, 36457.81771091127, 32237.838810805795, 35654.0, 36181.81909090438, 37154.88535343944, 37192.07019824526, 35870.64129358673, 36404.63595364012, 36705.816470917474, 36823.07942301464, 37207.81396093002, 30553.93061242846, 31043.0, 32586.02241932746, 32714.50928236126, 32841.50737738983, 19690.704639430707, 24129.396765081012, 24146.517069658155, 24352.391190219678, 24863.64886144881], "value": 18915.69534878467, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.8708258568208, 390.73234834138634, 391.2761391425861, 397.4396101496888, 436.45443196004993, 21001.47496312593, 21449.46376340592, 21607.243746468877, 22303.219387321446, 22350.441238969015, 21427.892860535714, 21923.342299730994, 22074.88962555189, 22502.099916003383, 22659.77340226597, 6321.77873774418, 6709.563878347916, 6735.360140786642, 6738.764143254987, 6748.932510674904, 5236.8690782730355, 5437.531866396071, 5714.428557144294, 6038.792241551685, 6326.715297811609, 5634.521065709419, 6519.119918810971, 6547.836304092388, 6561.540692151551, 6562.475001999828, 6349.8412539686415, 6439.774607888725, 6519.902201466982, 6548.246951600579, 6575.276719560845, 6320.083587879778, 6336.3663633636725, 6390.616563006234, 6409.198850143732, 6413.679316034226, 25380.984760609554, 26594.202173934813, 26718.5992210117, 26761.197164085108, 27682.169534913985, 3206.599175103112, 3336.9833150834334, 3348.1294863335465, 3425.400554902882, 3536.4341705327024, 16255.18724063792, 16954.576135596584, 17037.829621703873, 17192.742108868377, 17701.557461063578, 1797.5326415132029, 2028.381343690174, 2062.2988184017354, 2096.4758810297426, 2098.8845613491253, 129149.0, 168657.0, 169842.0, 173569.0, 174690.1265493677, 18207.45377638673, 21733.891330543247, 22933.655995160087, 23054.423639408815, 23240.0, 36499.452508212395, 36518.0, 36630.26739465196, 36756.81621591929, 36758.0, 10393.220508461896, 10669.89330106689, 10829.67510974672, 10831.620893268855, 10939.56241750337, 8581.828363432696, 8584.05575386707, 8610.655573777103, 8761.780955476037, 8816.250618697355, 9181.862272065924, 11066.731320079365, 11083.334999899871, 11139.610113645984, 11282.323060616225, 3884.514435695538, 4485.506594274627, 4543.909121817545, 4551.795169217393, 4600.263957766741, 870.2298465857635, 887.1793590928365, 891.5734824281099, 894.4320356573493, 924.6255266616896, 21537.569248615255, 22239.332820015425, 22702.886485567466, 22790.0, 22867.656985145237, 783.3341659589304, 800.6597196191595, 864.1747131489412, 927.9304052195977, 939.1920552935571, 11416.42917854104, 11495.448114504603, 11696.122790790725, 11764.58823941158, 11842.822357664641, 396410.0, 559215.0, 671818.0, 676617.0, 696584.0, 155661.2216938908, 156142.0, 156770.21614891852, 159607.0, 161410.38589613955, 1183.2545496337252, 1211.3277131192096, 1240.8883200511998, 1241.2118304876287, 1248.5817251220942, 19390.4182874514, 19485.123169457125, 19620.215191392184, 19647.60704785925, 19703.40889773309, 49481.26815561438, 52784.47215527796, 64879.0, 65166.34833651603, 65619.6719016421, 84940.57529712311, 85400.57299713462, 85785.0, 86042.0, 86402.70395944192, 9967.551460184164, 11510.48202830858, 12158.817617735564, 12188.817167742314, 12288.21820835956, 12743.93628031854, 12798.360081996048, 13499.257540835348, 13686.589402317946, 14047.508337208148, 9905.455199964072, 11652.242604230698, 11669.241499302518, 11695.824562631737, 11820.70448238801, 10733.355998640107, 11491.425428728697, 11633.302001879914, 11700.531978721096, 11730.592328920584, 10876.673699789018, 11872.584459543874, 11879.336892834837, 11903.928646421517, 11943.10426718017, 10326.277160598685, 11556.075513958695, 11897.762044759229, 11925.701857453287, 11969.880301196876, 11417.543298268309, 11551.653450396501, 11894.464749086139, 11933.76132477363, 11955.760884782081], "value": 11665.309792509091, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1496.8577985091413, 1533.5552689719982, 1554.1063888264243, 1597.0258142533057, 1607.8633316168125, 10403.947980260098, 10778.245522813406, 11023.95272449117, 11056.226064175511, 11104.222704410695, 10871.945640271808, 11058.22592418531, 11087.83368249477, 11092.944535277333, 11112.0, 2300.1029598456616, 2347.272345572869, 2350.4946436516148, 2352.1061996441326, 2356.964645530318, 2275.9089636414606, 2292.266474728079, 2292.4039749665126, 2313.248194336839, 2316.8494047886834, 2285.6342985122383, 2298.6666933294664, 2303.424143964009, 2307.2501437032947, 2313.5835549601147, 2005.1979208316643, 2009.1661960286538, 2188.6758511100875, 2247.213475283654, 2269.8978545965388, 2244.3940136163146, 2297.9195728149434, 2301.102569997702, 2303.7350704668843, 2308.3651995701316, 10788.73028174294, 10809.67570972872, 10869.67390978272, 10876.347419154798, 10952.452377381178, 4997.400311962551, 5776.537876969833, 5890.293164820207, 5915.3788852170765, 5941.1088336749835, 3406.5741782277214, 3567.4277499723416, 3587.264610754789, 3697.556293244801, 3729.4219395993664, 982.3860087445347, 1150.0856818829052, 1234.1237721217947, 1241.2738547949461, 1250.9562165324262, 14063.226522541256, 14263.928680356532, 14704.852951470562, 14994.700105997928, 15251.923740381228, 4528.977355113203, 4572.428446444194, 4716.976415117902, 4717.9056418872115, 4730.337752714623, 11558.595449159238, 11673.649790506299, 11731.941340293415, 11746.823797643043, 11756.647300580997, 4887.3890763654545, 5033.798648054037, 5517.779288828402, 5692.829215123553, 5750.281214848144, 4366.759828209479, 4448.777561121931, 5151.845444636667, 5192.221166825007, 5220.843374698765, 3151.9684803151677, 3725.2549490101546, 3790.1851102012647, 3819.0, 3836.462895194675, 1028.830243009895, 1030.829913064336, 1031.1544533482622, 1039.3296323871061, 1042.4110377636762, 729.5476804381318, 826.4875777018287, 878.1218781218781, 903.3586153830722, 966.4298064142049, 5399.325084364455, 5928.614640048383, 6036.2756469223095, 6466.057943635635, 6853.965730171317, 988.4908574560283, 990.5393991793851, 991.2763682511807, 1030.4332617060586, 1034.6792494326783, 3476.9652303477146, 3944.290027794967, 4172.582741725864, 4249.80875860587, 4317.935230971538, 12281.89462948339, 12954.676133096747, 13044.347782610832, 13376.39806208723, 13531.932340338235, 13238.867611323956, 13356.46574137043, 13445.731085378238, 13470.663233419244, 13507.257100859351, 8718.782030449289, 9944.80110397788, 10096.798064038678, 10104.141148002502, 10112.74718132038, 6244.250689917239, 6391.3289104643345, 6570.8357291068105, 6703.162104736908, 6748.490705134599, 11625.12811539121, 11898.940505297418, 11935.880641193824, 12040.277583345027, 12088.75822483563, 12014.579489717818, 12067.491563554555, 12123.515059397525, 12261.14172007951, 12400.19398739079, 8295.792605184919, 8545.700900468453, 8548.786280343042, 8576.056633770406, 8585.65657373698, 9632.807343853225, 10077.596896124072, 10119.797604048024, 10181.592736290762, 10195.7451063724, 8963.372563920462, 8997.820043599222, 9062.184403403726, 9065.546722663972, 9242.260619150318, 8748.737537873874, 8754.430961987451, 8854.29165666758, 8862.955685221532, 8873.023967363455, 7428.702851885864, 7745.65144568507, 7774.416918731004, 7786.377089832913, 7786.922130778619, 5693.202951586864, 5744.540436764965, 5854.268216472941, 5928.110783382527, 5931.110333450017, 5723.742431590672, 5786.681732504584, 5971.581989260884, 5974.671393073423, 6111.052786818051], "value": 5003.525719357156, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.53315757149056, 345.4145223845581, 357.7156160852124, 369.2891184469895, 373.7794701126335, 5034.974825125873, 5230.509304848119, 5293.232481290221, 5384.769138516893, 5410.486003829635, 4209.85265515707, 5186.662866913648, 5188.8702782430455, 5188.922166167501, 5197.454267301936, 3753.136778540937, 4283.971846756787, 4315.935260971088, 4331.566843315659, 4345.369921361401, 3808.923821523582, 3970.8610198643055, 4098.446709694198, 4270.893227669318, 4293.248681480744, 3429.6164165904706, 3909.7263191576603, 4206.3690446432975, 4259.403683484315, 4259.616634502894, 3768.962310376902, 4253.340732186501, 4274.935875961832, 4276.877879148208, 4288.549702281278, 3282.195862013798, 3965.374196579389, 3966.6596707942454, 3967.7501587000293, 3990.700697447703, 4688.601468875149, 4997.925031124536, 5013.323201367842, 5060.418051924003, 5108.777681708469, 4222.683298752575, 4790.257510085942, 4941.517544736565, 4994.150994330972, 5011.523905228997, 4569.702969306984, 4627.282771170474, 5227.895442091137, 5318.787248510055, 5667.716614169275, 3814.4278358246206, 4131.793410329502, 4387.42963414754, 4397.274449715793, 4417.447819022622, 5192.350956130484, 5751.482366587027, 5812.534997200256, 5949.960789798358, 5975.701214939279, 4069.8372065117655, 4354.216241076637, 4523.0, 4524.615407690318, 4531.274996000624, 3766.9434958475645, 4054.351303791379, 4328.588784065509, 4644.535546445329, 4655.813767449332, 4034.325754811779, 4323.546027667113, 4685.6017238535105, 4781.947971446276, 4959.280904268933, 5014.323066386064, 5084.898302034013, 5670.796000259922, 5671.532883195823, 5676.262085928803, 1291.6760320671387, 1410.8871290296752, 1489.0172486159083, 1534.5549790560835, 1542.7300222461024, 877.6138499060393, 1180.3154170581042, 1182.379250893288, 1184.9111316651113, 1193.91045671575, 4630.9536904631195, 4755.809767609326, 5232.927249913759, 5262.526372626482, 5279.287296214962, 4811.566958973709, 5178.6116041297055, 5780.814932938738, 5791.652500849878, 5819.679917604488, 4592.209038474997, 4872.78072486739, 5155.690658560498, 5221.762507245532, 5251.343582052244, 2897.3046468847324, 2901.722148113721, 2940.8971635636635, 3015.20097174249, 3019.9245018874703, 5639.154126881001, 6007.738374941224, 6023.0, 6045.455908968214, 6053.667048312385, 4905.5094490550655, 5126.718030508283, 5655.5475561954945, 6035.275766907999, 6077.3314935357075, 5522.6134170608475, 6035.39646035393, 6107.389261073946, 6154.446099851035, 6169.598976066542, 4758.809647614056, 5539.992847776889, 5668.7165641718575, 5798.536117110705, 5911.098674874562, 5427.457254274699, 5601.355844077821, 5715.542756579381, 5776.277965254343, 5813.680247586423, 4801.5438533339275, 5798.0, 5865.706714664335, 5958.225430693982, 6058.7273572688455, 4639.979204574988, 4732.266498692708, 5008.9499105009945, 5021.669257646799, 5041.521055499722, 4863.124637565272, 5016.322796422436, 5539.8892022158525, 5826.155207494974, 5852.060158938595, 5256.448072952285, 5621.831345059655, 5655.802046928337, 5687.402822703557, 5701.372848986691, 5037.388035828646, 5121.974390128025, 5683.1759394886685, 5723.3418156912485, 5752.878188753153, 4400.669949753846, 4932.654714169974, 4963.280324353021, 5520.1443776214755, 5603.91974707447, 4852.805887764591, 4861.4895435978715, 5565.1374037024325, 5582.7766889323975, 5624.465675760796, 5476.0, 5508.338999320107, 5531.640443371168, 5542.2795036644975, 5544.473275038865], "value": 4246.7997080233845, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.2836706559307, 721.404652364438, 766.7123986508238, 819.8114433680254, 827.9793005174872, 15445.382184712613, 16614.75077873833, 16625.833741662595, 16864.915675421606, 17711.0, 16351.754723679154, 17037.21109283526, 17043.659126817456, 17346.0, 17396.13019349034, 951.9190868776145, 973.7224890906093, 979.0307595480494, 980.872486576744, 981.40134517944, 919.2691810011057, 967.385710074101, 970.5195928015642, 971.5336638413565, 971.6016433262364, 1009.7223263602494, 1021.4433133941982, 1022.1669339488293, 1022.8005538919914, 1023.1661196125134, 1005.36661903001, 1016.7508960304699, 1017.0033367300014, 1017.9847302290472, 1019.5972590826619, 742.7808796405019, 749.9962500187519, 751.1249394455452, 753.4876284126792, 754.475639430596, 14607.70784584313, 15167.24163791806, 16630.33478660852, 16882.0, 16964.74552881708, 14443.205623690692, 14677.0, 14692.706145877022, 14732.631684207874, 14806.333714982851, 11584.884151158465, 11657.533698652043, 11904.761904761943, 11993.0, 12256.019518438503, 3677.8712745053795, 3678.8712395066045, 3721.423179407196, 3767.3783825668734, 3856.6042609416745, 105292.0, 107666.46166769194, 108191.0, 108551.0, 110348.0, 11124.40907186879, 11231.606893758679, 11255.493502792395, 11343.659690209306, 11585.073194144448, 24453.26640200797, 24673.01307947698, 24962.251132466055, 25005.374865628506, 25041.62437563438, 7509.7233470310175, 7825.413094017971, 7849.529028258323, 7934.563599001995, 8025.9598702006115, 3495.615482296945, 3629.8003609801185, 4202.892783956411, 4231.280682284019, 4295.892602684896, 3646.817659117087, 3879.321118804198, 3892.591277915778, 3898.317794385971, 3916.823742931575, 582.6038293960063, 684.4866350237322, 685.8114018644912, 687.5359132585585, 701.4143190435941, 636.296891934415, 649.4285029174297, 674.6019848289533, 684.9760258391032, 702.5995182745888, 10132.645357412455, 10159.390436573829, 10307.94846025765, 10361.792764144675, 10377.811792562747, 658.7990662847782, 675.368530424055, 697.6302559643398, 702.1924786495468, 708.7384455668918, 3964.6630036447227, 4515.177254235791, 4907.731861543199, 4909.87635930286, 4936.950630493649, 92775.2167434978, 106041.46979265055, 106050.4092438614, 108348.0, 111498.0, 60239.698801505714, 73645.6317718408, 74798.62600686961, 79075.02312442235, 89390.65914011296, 74650.0, 77759.833602496, 77930.2587176603, 78763.60618196987, 80008.599957001, 9184.632614695605, 10661.613990181228, 10787.892121078688, 10919.56321747153, 10951.83572246433, 37148.0, 42176.73469795911, 46355.53644463647, 47567.76216118897, 50852.74573627256, 64926.67536662286, 66508.00237996338, 66702.0, 69263.96104058543, 77381.83927241208, 11547.711307217387, 13800.792988104986, 13821.516246931309, 14138.717225655635, 14239.145651260958, 11015.449227538751, 14977.251137443302, 14986.550403487912, 15307.61730956735, 15392.461263855712, 11719.355435451133, 13554.796678049639, 13729.313534323044, 13808.240546770025, 14208.928955355157, 13629.522966696515, 13781.86218137846, 13930.024898257025, 14430.783538247144, 14639.853601463848, 11404.429778511207, 12944.0, 13059.0, 13721.794173087612, 13811.447542098605, 10698.518566664674, 10769.956548727498, 10927.672169834917, 11047.337159770439, 11372.54509819598, 8640.26557742599, 8771.442736737728, 10111.848322275024, 10482.936381074254, 11424.828627570427], "value": 7723.7153470834, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1574.3999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 120.0, 121.0, 120.0, 120.0, 120.0, 120.0, 121.0, 100.0, 177.0], "value": 122.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 112.0, 114.0, 153.0, 114.0, 115.0, 114.0, 114.0, 135.0, 116.0], "value": 122.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 210.0, 219.0, 245.0, 280.0, 265.0, 196.0, 199.0, 197.0, 197.0], "value": 220.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 60.0, 88.0, 68.0, 80.0, 69.0, 62.0, 75.0, 62.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 85.0, 70.0, 70.0, 74.0, 93.0, 99.0, 98.0, 96.0], "value": 84.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 91.0, 103.0, 90.0, 106.0, 130.0, 92.0, 90.0], "value": 97.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 125.0, 98.0, 98.0, 100.0, 98.0, 123.0, 131.0, 100.0], "value": 107.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 89.0, 92.0, 91.0, 137.0, 93.0, 80.0, 98.0, 143.0], "value": 100.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 68.0, 100.0, 58.0, 60.0, 70.0, 96.0, 97.0], "value": 75.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 56.0, 66.0, 81.0, 88.0, 48.0, 56.0, 79.0, 82.0], "value": 67.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 76.0, 99.0, 106.0, 73.0, 111.0, 109.0, 107.0, 78.0, 99.0], "value": 96.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 142.0, 146.0, 140.0, 155.0, 171.0, 150.0, 151.0, 201.0, 156.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 174.0, 171.0, 177.0, 177.0, 202.0, 224.0, 193.0, 174.0], "value": 184.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 70.0, 75.0, 70.0, 67.0, 67.0, 68.0, 67.0, 68.0], "value": 68.6, "unit": "score"}]}]} 18:59:59 INFO - Return code: 0 18:59:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:59:59 ERROR - # TBPL SUCCESS # 18:59:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:59:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:59:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:59:59 INFO - 2017-03-08 18:59:59.793 screenresolution[1659:10720] starting screenresolution argv=screenresolution get 18:59:59 INFO - 2017-03-08 18:59:59.796 screenresolution[1659:10720] Display 0: 1600x1200x32@60 18:59:59 INFO - 2017-03-08 18:59:59.802 screenresolution[1660:10723] starting screenresolution argv=screenresolution list 18:59:59 INFO - Available Modes on Display 0 18:59:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:59:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:59:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:59:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:00:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:00:00 INFO - Intel Iris: 19:00:00 INFO - Chipset Model: Intel Iris 19:00:00 INFO - Type: GPU 19:00:00 INFO - Bus: Built-In 19:00:00 INFO - VRAM (Dynamic, Max): 1536 MB 19:00:00 INFO - Vendor: Intel (0x8086) 19:00:00 INFO - Device ID: 0x0a2e 19:00:00 INFO - Revision ID: 0x0009 19:00:00 INFO - Displays: 19:00:00 INFO - Display: 19:00:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:00:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:00:00 INFO - Display Serial Number: 1600x1200 60 19:00:00 INFO - Main Display: Yes 19:00:00 INFO - Mirror: Off 19:00:00 INFO - Online: Yes 19:00:00 INFO - Rotation: Supported 19:00:00 INFO - Return code: 0 19:00:00 INFO - Running post-action listener: _resource_record_post_action 19:00:00 INFO - [mozharness: 2017-03-09 03:00:00.039561Z] Finished run-tests step (success) 19:00:00 INFO - Running post-run listener: _resource_record_post_run 19:00:00 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 38700544; Write bytes: 514068992; Read time: 251; Write time: 1653 19:00:00 INFO - TinderboxPrint: CPU usage
54.9% 19:00:00 INFO - TinderboxPrint: I/O read bytes / time
38,700,544 / 251 19:00:00 INFO - TinderboxPrint: I/O write bytes / time
514,068,992 / 1,653 19:00:00 INFO - TinderboxPrint: CPU idle
2,167.6 (45.1%) 19:00:00 INFO - TinderboxPrint: CPU system
965.8 (20.1%) 19:00:00 INFO - TinderboxPrint: CPU user
1,668.2 (34.7%) 19:00:00 INFO - TinderboxPrint: Swap in / out
248,737,792 / 0 19:00:00 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202324480; Write bytes: 202721280; Read time: 15588; Write time: 464 19:00:00 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 37733888; Write bytes: 301443584; Read time: 237; Write time: 1155 19:00:00 INFO - Running post-run listener: _upload_blobber_files 19:00:00 INFO - Blob upload gear active. 19:00:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:00:00 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 19:00:00 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'] 19:00:00 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 19:00:00 INFO - (blobuploader) - INFO - Open directory for files ... 19:00:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:00:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:00:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:00:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:00:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:00:02 INFO - (blobuploader) - INFO - Done attempting. 19:00:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:00:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:00:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:00:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:00:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:00:03 INFO - (blobuploader) - INFO - Done attempting. 19:00:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:00:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:00:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:00:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:00:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:00:04 INFO - (blobuploader) - INFO - Done attempting. 19:00:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:00:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:00:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:00:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:00:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:00:06 INFO - (blobuploader) - INFO - Done attempting. 19:00:06 INFO - (blobuploader) - INFO - Iteration through files over. 19:00:06 INFO - Return code: 0 19:00:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:00:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:00:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25038462f83e941e168a80c1eb62e38f86f3c07a8327f9e1932b834062f94c0125836257db596ebc88d64a0a35239e9ff56accc9eb7ef2e450a231640d6c2307", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7891d6fd512c6cefa638b4e14583ee4dcf4da081b03c9617ad195229dd245d124b83d1de0ff09ae7384e183d89c6acc984711d13b8384d7e07eca5ee0cc9261", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00db6c9965c576894c6eac68ec0e956e25a2a604585e1dc31e45f4f1577e221821cd327b26b44065d5bef47cefdfd466ef27868004edd3ed5bb501af75e1ba61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac77c2cd68cbe2af5f879eacf117af8c4609401399d508ccf88b60c7b896445f1b5f37bd79170ca0097b5c14b856d8b56afc80be122f33c15b2b06b1c26cae85"} 19:00:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:00:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:00:06 INFO - Contents: 19:00:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25038462f83e941e168a80c1eb62e38f86f3c07a8327f9e1932b834062f94c0125836257db596ebc88d64a0a35239e9ff56accc9eb7ef2e450a231640d6c2307", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7891d6fd512c6cefa638b4e14583ee4dcf4da081b03c9617ad195229dd245d124b83d1de0ff09ae7384e183d89c6acc984711d13b8384d7e07eca5ee0cc9261", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00db6c9965c576894c6eac68ec0e956e25a2a604585e1dc31e45f4f1577e221821cd327b26b44065d5bef47cefdfd466ef27868004edd3ed5bb501af75e1ba61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac77c2cd68cbe2af5f879eacf117af8c4609401399d508ccf88b60c7b896445f1b5f37bd79170ca0097b5c14b856d8b56afc80be122f33c15b2b06b1c26cae85"} 19:00:06 INFO - Running post-run listener: copy_logs_to_upload_dir 19:00:06 INFO - Copying logs to upload dir... 19:00:06 INFO - mkdir: /builds/slave/test/build/upload/logs 19:00:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.835295 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-08 19:00:06.990718) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-08 19:00:06.998383) ========= 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.bI58cTMBUv/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.Td2PpYmCSB/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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/25038462f83e941e168a80c1eb62e38f86f3c07a8327f9e1932b834062f94c0125836257db596ebc88d64a0a35239e9ff56accc9eb7ef2e450a231640d6c2307", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7891d6fd512c6cefa638b4e14583ee4dcf4da081b03c9617ad195229dd245d124b83d1de0ff09ae7384e183d89c6acc984711d13b8384d7e07eca5ee0cc9261", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00db6c9965c576894c6eac68ec0e956e25a2a604585e1dc31e45f4f1577e221821cd327b26b44065d5bef47cefdfd466ef27868004edd3ed5bb501af75e1ba61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac77c2cd68cbe2af5f879eacf117af8c4609401399d508ccf88b60c7b896445f1b5f37bd79170ca0097b5c14b856d8b56afc80be122f33c15b2b06b1c26cae85"} build_url:https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011317 build_url: 'https://queue.taskcluster.net/v1/task/E6EYdplUThKcenVGP3Ip5A/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/25038462f83e941e168a80c1eb62e38f86f3c07a8327f9e1932b834062f94c0125836257db596ebc88d64a0a35239e9ff56accc9eb7ef2e450a231640d6c2307", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7891d6fd512c6cefa638b4e14583ee4dcf4da081b03c9617ad195229dd245d124b83d1de0ff09ae7384e183d89c6acc984711d13b8384d7e07eca5ee0cc9261", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00db6c9965c576894c6eac68ec0e956e25a2a604585e1dc31e45f4f1577e221821cd327b26b44065d5bef47cefdfd466ef27868004edd3ed5bb501af75e1ba61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac77c2cd68cbe2af5f879eacf117af8c4609401399d508ccf88b60c7b896445f1b5f37bd79170ca0097b5c14b856d8b56afc80be122f33c15b2b06b1c26cae85"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-08 19:00:07.038949) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 19:00:07.039376) ========= 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.bI58cTMBUv/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.Td2PpYmCSB/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.005263 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 19:00:07.087783) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-08 19:00:07.088109) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-08 19:00:07.088478) ========= ========= Total master_lag: 0.21 =========