builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0377 starttime: 1488915640.31 results: success (0) buildid: 20170307083724 builduid: 25f950bfd07d44c29a7d11ce554bf148 revision: 3d341b9ba5353b6b8ab45b6ca03dcb1b2d789faa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-07 11:40:40.313781) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-07 11:40:40.314245) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-07 11:40:40.314526) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-07 11:40:40.340806) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 11:40:40.341167) ========= 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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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-07 11:40:40-- 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% 18.0M=0.001s 2017-03-07 11:40:40 (18.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109710 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 11:40:40.474984) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 11:40:40.475303) ========= 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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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.025931 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 11:40:40.523650) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-07 11:40:40.524033) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3d341b9ba5353b6b8ab45b6ca03dcb1b2d789faa --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 3d341b9ba5353b6b8ab45b6ca03dcb1b2d789faa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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-07 11:40:40,624 truncating revision to first 12 chars 2017-03-07 11:40:40,624 Setting DEBUG logging. 2017-03-07 11:40:40,624 attempt 1/10 2017-03-07 11:40:40,624 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3d341b9ba535?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-07 11:40:42,021 unpacking tar archive at: mozilla-central-3d341b9ba535/testing/mozharness/ program finished with exit code 0 elapsedTime=1.655383 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-07 11:40:42.202864) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-07 11:40:42.203207) ========= script_repo_revision: 3d341b9ba5353b6b8ab45b6ca03dcb1b2d789faa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-07 11:40:42.203895) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-07 11:40:42.204627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-07 11:40:42.227195) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-07 11:40:42.227928) ========= /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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 11:40:42 INFO - MultiFileLogger online at 20170307 11:40:42 in /builds/slave/test 11:40:42 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 11:40:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:40:42 INFO - {'append_to_log': False, 11:40:42 INFO - 'base_work_dir': '/builds/slave/test', 11:40:42 INFO - 'blob_upload_branch': 'Firefox', 11:40:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:40:42 INFO - 'branch': 'Firefox', 11:40:42 INFO - 'buildbot_json_path': 'buildprops.json', 11:40:42 INFO - 'config_files': ('talos/mac_config.py',), 11:40:42 INFO - 'default_actions': ('clobber', 11:40:42 INFO - 'read-buildbot-config', 11:40:42 INFO - 'download-and-extract', 11:40:42 INFO - 'populate-webroot', 11:40:42 INFO - 'create-virtualenv', 11:40:42 INFO - 'install', 11:40:42 INFO - 'run-tests'), 11:40:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:40:42 INFO - 'download_minidump_stackwalk': True, 11:40:42 INFO - 'download_symbols': 'ondemand', 11:40:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:40:42 INFO - 'tooltool.py': '/tools/tooltool.py', 11:40:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:40:42 INFO - '/tools/misc-python/virtualenv.py')}, 11:40:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:40:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:40:42 INFO - 'gecko_profile': False, 11:40:42 INFO - 'gecko_profile_interval': 0, 11:40:42 INFO - 'installer_path': 'installer.exe', 11:40:42 INFO - 'log_level': 'info', 11:40:42 INFO - 'log_name': 'talos', 11:40:42 INFO - 'log_to_console': True, 11:40:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:40:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:40:42 INFO - 'opt_config_files': (), 11:40:42 INFO - 'pip_index': False, 11:40:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:40:42 INFO - 'cmd': ('bash', 11:40:42 INFO - '-c', 11:40:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:40:42 INFO - 'enabled': True, 11:40:42 INFO - 'halt_on_failure': False, 11:40:42 INFO - 'name': 'check_screen_resolution'},), 11:40:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:40:42 INFO - 'cmd': ('bash', 11:40:42 INFO - '-c', 11:40:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:40:42 INFO - 'enabled': True, 11:40:42 INFO - 'halt_on_failure': False, 11:40:42 INFO - 'name': 'check_screen_resolution'},), 11:40:42 INFO - 'run_cmd_checks_enabled': True, 11:40:42 INFO - 'suite': 'dromaeojs-e10s', 11:40:42 INFO - 'system_bits': '32', 11:40:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:40:42 INFO - 'title': 't-yosemite-r7-0377', 11:40:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:40:42 INFO - 'use_talos_json': True, 11:40:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:40:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:40:42 INFO - 'work_dir': 'build'} 11:40:42 INFO - [mozharness: 2017-03-07 19:40:42.441976Z] Running clobber step. 11:40:42 INFO - Running pre-action listener: _resource_record_pre_action 11:40:42 INFO - Running main action method: clobber 11:40:42 INFO - rmtree: /builds/slave/test/build 11:40:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:40:44 INFO - Running post-action listener: _resource_record_post_action 11:40:44 INFO - [mozharness: 2017-03-07 19:40:44.871768Z] Finished clobber step (success) 11:40:44 INFO - [mozharness: 2017-03-07 19:40:44.871933Z] Running read-buildbot-config step. 11:40:44 INFO - Running pre-action listener: _resource_record_pre_action 11:40:44 INFO - Running main action method: read_buildbot_config 11:40:44 INFO - Using buildbot properties: 11:40:44 INFO - { 11:40:44 INFO - "project": "", 11:40:44 INFO - "product": "firefox", 11:40:44 INFO - "script_repo_revision": "production", 11:40:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 11:40:44 INFO - "repository": "", 11:40:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 11:40:44 INFO - "buildid": "20170307083724", 11:40:44 INFO - "pgo_build": "False", 11:40:44 INFO - "basedir": "/builds/slave/test", 11:40:44 INFO - "buildnumber": 102, 11:40:44 INFO - "slavename": "t-yosemite-r7-0377", 11:40:44 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:40:44 INFO - "platform": "yosemite_r7", 11:40:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:40:44 INFO - "branch": "mozilla-central", 11:40:44 INFO - "repo_path": "mozilla-central", 11:40:44 INFO - "revision": "3d341b9ba5353b6b8ab45b6ca03dcb1b2d789faa", 11:40:44 INFO - "stage_platform": "macosx64", 11:40:44 INFO - "builduid": "25f950bfd07d44c29a7d11ce554bf148", 11:40:44 INFO - "slavebuilddir": "test" 11:40:44 INFO - } 11:40:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 11:40:44 INFO - Running post-action listener: _resource_record_post_action 11:40:44 INFO - [mozharness: 2017-03-07 19:40:44.875388Z] Finished read-buildbot-config step (success) 11:40:44 INFO - [mozharness: 2017-03-07 19:40:44.875500Z] Running download-and-extract step. 11:40:44 INFO - Running pre-action listener: _resource_record_pre_action 11:40:44 INFO - Running main action method: download_and_extract 11:40:44 INFO - mkdir: /builds/slave/test/build/tests 11:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:44 INFO - trying https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 11:40:44 INFO - Downloading https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/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 11:40:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/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 11:40:46 INFO - Downloaded 1199 bytes. 11:40:46 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:40:46 INFO - Using the following test package requirements: 11:40:46 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:40:46 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:40:46 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 11:40:46 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:40:46 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 11:40:46 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:40:46 INFO - u'jsshell-mac.zip'], 11:40:46 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:40:46 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 11:40:46 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:40:46 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:40:46 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 11:40:46 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:40:46 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 11:40:46 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:40:46 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 11:40:46 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:40:46 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 11:40:46 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:40:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:40:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:40:46 INFO - Fetch https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:40:48 INFO - Expected file size: 51785075 11:40:48 INFO - Obtained file size: 51785075 11:41:04 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 11:41:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:41:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:41:04 INFO - Fetch https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:41:06 INFO - Expected file size: 51785075 11:41:06 INFO - Obtained file size: 51785075 11:41:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 11:41:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:41:21 INFO - Fetch https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 11:41:22 INFO - Expected file size: 14404424 11:41:22 INFO - Obtained file size: 14404424 11:41:22 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')]} 11:41:22 INFO - trying https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:41:22 INFO - Downloading https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 11:41:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/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 11:41:24 INFO - Downloaded 74922892 bytes. 11:41:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:41:24 INFO - mkdir: /builds/slave/test/properties 11:41:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:41:24 INFO - Writing to file /builds/slave/test/properties/build_url 11:41:24 INFO - Contents: 11:41:24 INFO - build_url:https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:41:25 INFO - Running post-action listener: _resource_record_post_action 11:41:25 INFO - Running post-action listener: set_extra_try_arguments 11:41:25 INFO - [mozharness: 2017-03-07 19:41:25.430562Z] Finished download-and-extract step (success) 11:41:25 INFO - [mozharness: 2017-03-07 19:41:25.430709Z] Running populate-webroot step. 11:41:25 INFO - Running pre-action listener: _resource_record_pre_action 11:41:25 INFO - Running main action method: populate_webroot 11:41:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:41:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:41:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:41:25 INFO - '--tppagecycles', 11:41:25 INFO - '7'], 11:41:25 INFO - 'tests': ['tsvgm']}}, 11:41:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:41:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:41:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:41:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:41:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:41:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:41:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:41:25 INFO - 'talos_options': ['--disable-e10s'], 11:41:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:41:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:41:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:41:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:41:25 INFO - 'talos_options': ['--disable-e10s'], 11:41:25 INFO - 'tests': ['damp', 'tps']}, 11:41:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:41:25 INFO - 'tests': ['damp', 'tps']}, 11:41:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:41:25 INFO - 'tests': ['dromaeo_dom']}, 11:41:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:41:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:41:25 INFO - 'tests': ['basic_compositor_video']}, 11:41:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:41:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:41:25 INFO - 'tests': ['a11yr', 11:41:25 INFO - 'ts_paint', 11:41:25 INFO - 'tpaint', 11:41:25 INFO - 'sessionrestore', 11:41:25 INFO - 'sessionrestore_no_auto_restore', 11:41:25 INFO - 'tabpaint']}, 11:41:25 INFO - 'other-e10s': {'tests': ['a11yr', 11:41:25 INFO - 'ts_paint', 11:41:25 INFO - 'tpaint', 11:41:25 INFO - 'sessionrestore', 11:41:25 INFO - 'sessionrestore_no_auto_restore', 11:41:25 INFO - 'tabpaint']}, 11:41:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:41:25 INFO - 'ts_paint', 11:41:25 INFO - 'tpaint', 11:41:25 INFO - 'sessionrestore', 11:41:25 INFO - 'sessionrestore_no_auto_restore', 11:41:25 INFO - 'tabpaint']}, 11:41:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:41:25 INFO - 'ts_paint', 11:41:25 INFO - 'tpaint', 11:41:25 INFO - 'sessionrestore', 11:41:25 INFO - 'sessionrestore_no_auto_restore', 11:41:25 INFO - 'tabpaint']}, 11:41:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:41:25 INFO - 'tests': ['a11yr', 11:41:25 INFO - 'ts_paint', 11:41:25 INFO - 'tpaint', 11:41:25 INFO - 'sessionrestore', 11:41:25 INFO - 'sessionrestore_no_auto_restore', 11:41:25 INFO - 'tabpaint']}, 11:41:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:41:25 INFO - 'tests': ['a11yr', 11:41:25 INFO - 'ts_paint', 11:41:25 INFO - 'tpaint', 11:41:25 INFO - 'sessionrestore', 11:41:25 INFO - 'sessionrestore_no_auto_restore', 11:41:25 INFO - 'tabpaint']}, 11:41:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:41:25 INFO - 'tests': ['tsvgx', 11:41:25 INFO - 'tsvgr_opacity', 11:41:25 INFO - 'tart', 11:41:25 INFO - 'tscrollx', 11:41:25 INFO - 'cart', 11:41:25 INFO - 'tsvg_static']}, 11:41:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:41:25 INFO - 'tsvgr_opacity', 11:41:25 INFO - 'tart', 11:41:25 INFO - 'tscrollx', 11:41:25 INFO - 'cart', 11:41:25 INFO - 'tsvg_static']}, 11:41:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:41:25 INFO - 'talos_options': ['--disable-e10s'], 11:41:25 INFO - 'tests': ['tp5o']}, 11:41:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:41:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:41:25 INFO - 'talos_options': ['--disable-e10s', 11:41:25 INFO - '--xperf_path', 11:41:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:41:25 INFO - 'tests': ['tp5n']}, 11:41:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:41:25 INFO - 'talos_options': ['--xperf_path', 11:41:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:41:25 INFO - 'tests': ['tp5n']}}, 11:41:25 INFO - 'talos.zip': {'path': '', 11:41:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:41:25 INFO - Running post-action listener: _resource_record_post_action 11:41:25 INFO - [mozharness: 2017-03-07 19:41:25.448949Z] Finished populate-webroot step (success) 11:41:25 INFO - [mozharness: 2017-03-07 19:41:25.449054Z] Running create-virtualenv step. 11:41:25 INFO - Running pre-action listener: _resource_record_pre_action 11:41:25 INFO - Running main action method: create_virtualenv 11:41:25 INFO - Creating virtualenv /builds/slave/test/build/venv 11:41:25 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 11:41:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:41:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:41:25 INFO - Using real prefix '/tools/python27' 11:41:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:41:26 INFO - Installing distribute.............................................................................................................................................................................................done. 11:41:29 INFO - Installing pip.................done. 11:41:29 INFO - Return code: 0 11:41:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:41:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:41:29 INFO - Reading from file tmpfile_stdout 11:41:29 INFO - Output received: 11:41:29 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) 11:41:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:41:29 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')]} 11:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:29 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')]} 11:41:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:29 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 0x10cdebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b0c90e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc9d30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbebe8>, '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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 11:41:29 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 11:41:29 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 11:41:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XdUlPcC8Y/Render', 11:41:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:29 INFO - 'HOME': '/Users/cltbld', 11:41:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:29 INFO - 'LOGNAME': 'cltbld', 11:41:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:29 INFO - 'MOZ_NO_REMOTE': '1', 11:41:29 INFO - 'NO_EM_RESTART': '1', 11:41:29 INFO - 'PAGER': '/bin/cat', 11:41:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:29 INFO - 'PWD': '/builds/slave/test', 11:41:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:29 INFO - 'SHELL': '/bin/bash', 11:41:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4icBxn5TmQ/Listeners', 11:41:29 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 11:41:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:29 INFO - 'USER': 'cltbld', 11:41:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:29 INFO - 'XPC_FLAGS': '0x0', 11:41:29 INFO - 'XPC_SERVICE_NAME': '0', 11:41:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:29 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 11:41:29 INFO - Cleaning up... 11:41:29 INFO - Return code: 0 11:41:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:41:29 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')]} 11:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:29 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')]} 11:41:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:29 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 0x10cdebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b0c90e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc9d30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbebe8>, '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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 11:41:29 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 11:41:29 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 11:41:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XdUlPcC8Y/Render', 11:41:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:29 INFO - 'HOME': '/Users/cltbld', 11:41:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:29 INFO - 'LOGNAME': 'cltbld', 11:41:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:29 INFO - 'MOZ_NO_REMOTE': '1', 11:41:29 INFO - 'NO_EM_RESTART': '1', 11:41:29 INFO - 'PAGER': '/bin/cat', 11:41:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:29 INFO - 'PWD': '/builds/slave/test', 11:41:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:29 INFO - 'SHELL': '/bin/bash', 11:41:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4icBxn5TmQ/Listeners', 11:41:29 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 11:41:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:29 INFO - 'USER': 'cltbld', 11:41:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:29 INFO - 'XPC_FLAGS': '0x0', 11:41:29 INFO - 'XPC_SERVICE_NAME': '0', 11:41:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:29 INFO - Downloading/unpacking psutil>=3.1.1 11:41: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 11:41: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 11:41: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 11:41: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 11:41: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 11:41: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 11:41:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:41:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:41:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:41:33 INFO - Installing collected packages: psutil 11:41:33 INFO - Running setup.py install for psutil 11:41:33 INFO - building 'psutil._psutil_osx' extension 11:41: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:41:34 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 11:41:34 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 11:41:34 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 11:41:34 INFO - building 'psutil._psutil_posix' extension 11:41:34 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 11:41:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:41:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:41:34 INFO - ^ 11:41:34 INFO - 1 warning generated. 11:41:34 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 11:41:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:41:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:41:34 INFO - Successfully installed psutil 11:41:34 INFO - Cleaning up... 11:41:34 INFO - Return code: 0 11:41:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:41: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')]} 11:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cdebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b0c90e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc9d30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbebe8>, '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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 11:41:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:41:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:41:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XdUlPcC8Y/Render', 11:41:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:35 INFO - 'HOME': '/Users/cltbld', 11:41:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:35 INFO - 'LOGNAME': 'cltbld', 11:41:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:35 INFO - 'MOZ_NO_REMOTE': '1', 11:41:35 INFO - 'NO_EM_RESTART': '1', 11:41:35 INFO - 'PAGER': '/bin/cat', 11:41:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:35 INFO - 'PWD': '/builds/slave/test', 11:41:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:35 INFO - 'SHELL': '/bin/bash', 11:41:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4icBxn5TmQ/Listeners', 11:41:35 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 11:41:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:35 INFO - 'USER': 'cltbld', 11:41:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:35 INFO - 'XPC_FLAGS': '0x0', 11:41:35 INFO - 'XPC_SERVICE_NAME': '0', 11:41:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:41:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:41:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:41:38 INFO - Installing collected packages: mozsystemmonitor 11:41:38 INFO - Running setup.py install for mozsystemmonitor 11:41:38 INFO - Successfully installed mozsystemmonitor 11:41:38 INFO - Cleaning up... 11:41:38 INFO - Return code: 0 11:41:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:41: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')]} 11:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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')]} 11:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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 0x10cdebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b0c90e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc9d30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbebe8>, '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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 11:41: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 11:41: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 11:41:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XdUlPcC8Y/Render', 11:41:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:38 INFO - 'HOME': '/Users/cltbld', 11:41:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:38 INFO - 'LOGNAME': 'cltbld', 11:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:38 INFO - 'MOZ_NO_REMOTE': '1', 11:41:38 INFO - 'NO_EM_RESTART': '1', 11:41:39 INFO - 'PAGER': '/bin/cat', 11:41:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:39 INFO - 'PWD': '/builds/slave/test', 11:41:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:39 INFO - 'SHELL': '/bin/bash', 11:41:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4icBxn5TmQ/Listeners', 11:41:39 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 11:41:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:39 INFO - 'USER': 'cltbld', 11:41:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:39 INFO - 'XPC_FLAGS': '0x0', 11:41:39 INFO - 'XPC_SERVICE_NAME': '0', 11:41:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:39 INFO - Downloading/unpacking jsonschema==2.5.1 11:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:41:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:41:41 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 11:41:41 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 11:41:41 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 11:41:41 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 11:41:41 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 11:41:41 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 11:41:42 INFO - Downloading functools32-3.2.3-2.tar.gz 11:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:41:42 INFO - warning: no files found matching '*.txt' 11:41:42 INFO - no previously-included directories found matching 'build' 11:41:42 INFO - no previously-included directories found matching 'dist' 11:41:42 INFO - no previously-included directories found matching '.git*' 11:41:42 INFO - Installing collected packages: jsonschema, functools32 11:41:42 INFO - Running setup.py install for functools32 11:41:42 INFO - warning: no files found matching '*.txt' 11:41:42 INFO - no previously-included directories found matching 'build' 11:41:42 INFO - no previously-included directories found matching 'dist' 11:41:42 INFO - no previously-included directories found matching '.git*' 11:41:42 INFO - Successfully installed jsonschema functools32 11:41:42 INFO - Cleaning up... 11:41:42 INFO - Return code: 0 11:41:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:41: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')]} 11:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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')]} 11:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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', '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 0x10cdebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b0c90e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc9d30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbebe8>, '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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 11:41: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:41: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 functools32==3.2.3-2 11:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XdUlPcC8Y/Render', 11:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:42 INFO - 'HOME': '/Users/cltbld', 11:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:42 INFO - 'LOGNAME': 'cltbld', 11:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:42 INFO - 'MOZ_NO_REMOTE': '1', 11:41:42 INFO - 'NO_EM_RESTART': '1', 11:41:42 INFO - 'PAGER': '/bin/cat', 11:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:42 INFO - 'PWD': '/builds/slave/test', 11:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:42 INFO - 'SHELL': '/bin/bash', 11:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4icBxn5TmQ/Listeners', 11:41:42 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 11:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:42 INFO - 'USER': 'cltbld', 11:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:42 INFO - 'XPC_FLAGS': '0x0', 11:41:42 INFO - 'XPC_SERVICE_NAME': '0', 11:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:41:42 INFO - Cleaning up... 11:41:42 INFO - Return code: 0 11:41:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:41: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')]} 11:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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')]} 11:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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 0x10cdebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b0c90e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc9d30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbebe8>, '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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 11:41: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 11:41: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 11:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XdUlPcC8Y/Render', 11:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:42 INFO - 'HOME': '/Users/cltbld', 11:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:42 INFO - 'LOGNAME': 'cltbld', 11:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:42 INFO - 'MOZ_NO_REMOTE': '1', 11:41:42 INFO - 'NO_EM_RESTART': '1', 11:41:42 INFO - 'PAGER': '/bin/cat', 11:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:42 INFO - 'PWD': '/builds/slave/test', 11:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:42 INFO - 'SHELL': '/bin/bash', 11:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4icBxn5TmQ/Listeners', 11:41:42 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 11:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:42 INFO - 'USER': 'cltbld', 11:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:42 INFO - 'XPC_FLAGS': '0x0', 11:41:42 INFO - 'XPC_SERVICE_NAME': '0', 11:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:43 INFO - Downloading/unpacking blobuploader==1.2.4 11:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:45 INFO - Downloading blobuploader-1.2.4.tar.gz 11:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:41:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:41:45 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 11:41:45 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 11:41:45 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 11:41:45 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 11:41:45 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 11:41:45 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 11:41:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:41:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:41: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 11:41: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 11:41: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 11:41: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 11:41: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 11:41: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 11:41:46 INFO - Downloading docopt-0.6.1.tar.gz 11:41:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:41:47 INFO - Installing collected packages: blobuploader, requests, docopt 11:41:47 INFO - Running setup.py install for blobuploader 11:41:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:41:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:41:47 INFO - Running setup.py install for requests 11:41:47 INFO - Running setup.py install for docopt 11:41:47 INFO - Successfully installed blobuploader requests docopt 11:41:47 INFO - Cleaning up... 11:41:47 INFO - Return code: 0 11:41:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:41:47 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')]} 11:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:47 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')]} 11:41:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:47 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 0x10cdebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b0c90e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc9d30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbebe8>, '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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 11:41:47 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 11:41:47 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 11:41:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XdUlPcC8Y/Render', 11:41:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:47 INFO - 'HOME': '/Users/cltbld', 11:41:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:47 INFO - 'LOGNAME': 'cltbld', 11:41:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:47 INFO - 'MOZ_NO_REMOTE': '1', 11:41:47 INFO - 'NO_EM_RESTART': '1', 11:41:47 INFO - 'PAGER': '/bin/cat', 11:41:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:47 INFO - 'PWD': '/builds/slave/test', 11:41:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:47 INFO - 'SHELL': '/bin/bash', 11:41:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4icBxn5TmQ/Listeners', 11:41:47 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 11:41:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:47 INFO - 'USER': 'cltbld', 11:41:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:47 INFO - 'XPC_FLAGS': '0x0', 11:41:47 INFO - 'XPC_SERVICE_NAME': '0', 11:41:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:41:48 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-ibXHZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:41:48 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-KWLokf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:41:48 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-UyX7hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:41:48 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-5X0ygk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:41:48 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-6pWO2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:41:48 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-vr2i0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:41:48 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-LjbWeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:41:48 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-0tuhMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:41:49 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-d6spru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:41:49 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-4jfskQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:41:49 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-o0bkPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:41:49 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-UHBSuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:41:49 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-5ybHEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:41:49 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-vbA0gS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:41:49 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-t4c2t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:41:49 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-aVLtfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:41:49 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-23mi1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:41:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:41:49 INFO - Running setup.py install for manifestparser 11:41:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:41:50 INFO - Running setup.py install for mozcrash 11:41:50 INFO - Running setup.py install for mozdebug 11:41:50 INFO - Running setup.py install for mozdevice 11:41:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:41:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:41:50 INFO - Running setup.py install for mozfile 11:41:50 INFO - Running setup.py install for mozhttpd 11:41:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:41:50 INFO - Running setup.py install for mozinfo 11:41:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:41:51 INFO - Running setup.py install for mozInstall 11:41:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:41:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:41:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:41:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:41:51 INFO - Running setup.py install for mozleak 11:41:51 INFO - Running setup.py install for mozlog 11:41:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:41:51 INFO - Running setup.py install for moznetwork 11:41:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:41:51 INFO - Running setup.py install for mozprocess 11:41:51 INFO - Running setup.py install for mozprofile 11:41:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:41:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:41:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:41:51 INFO - Running setup.py install for mozrunner 11:41:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:41:52 INFO - Running setup.py install for mozscreenshot 11:41:52 INFO - Running setup.py install for moztest 11:41:52 INFO - Running setup.py install for mozversion 11:41:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:41:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:41:52 INFO - Cleaning up... 11:41:52 INFO - Return code: 0 11:41:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:41: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')]} 11:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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')]} 11:41:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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 0x10cdebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b0c90e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc9d30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbebe8>, '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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 11:41: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 11:41: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 11:41:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XdUlPcC8Y/Render', 11:41:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:52 INFO - 'HOME': '/Users/cltbld', 11:41:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:52 INFO - 'LOGNAME': 'cltbld', 11:41:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:52 INFO - 'MOZ_NO_REMOTE': '1', 11:41:52 INFO - 'NO_EM_RESTART': '1', 11:41:52 INFO - 'PAGER': '/bin/cat', 11:41:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:52 INFO - 'PWD': '/builds/slave/test', 11:41:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:52 INFO - 'SHELL': '/bin/bash', 11:41:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4icBxn5TmQ/Listeners', 11:41:52 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 11:41:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:52 INFO - 'USER': 'cltbld', 11:41:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:52 INFO - 'XPC_FLAGS': '0x0', 11:41:52 INFO - 'XPC_SERVICE_NAME': '0', 11:41:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:41:52 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-pFH9T6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:41: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)) 11:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:41:53 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-3PNj_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:41: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)) 11:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:41:53 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-S4oNAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:41: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)) 11:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:41:53 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-_v8f58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:41: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)) 11:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:41:53 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-Q8RusE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:41: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)) 11:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:41:53 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-hhP7eA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:41: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)) 11:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:41:53 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-bnRqBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:41: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)) 11:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:41:53 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-rZhtnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:41: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)) 11:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:41:53 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-r56AsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:41:53 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)) 11:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:41:53 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-tKa8Qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:41:53 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)) 11:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:41:53 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-Fr9xjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:41: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)) 11:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:41:54 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-XlnI9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:41: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)) 11:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:41:54 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-nAZAX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:41: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)) 11:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:41:54 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-oPMBvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:41: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)) 11:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:41:54 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-WdcSYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:41: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)) 11:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:41:54 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-1V3MG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:41: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)) 11:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:41:54 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-RpVp_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:41: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)) 11:41: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)) 11:41: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)) 11:41: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)) 11:41: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)) 11:41: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)) 11:41:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:41: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 11:41: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 11:41: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 11:41: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 11:41: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 11:41: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 11:41:57 INFO - Downloading blessings-1.6.tar.gz 11:41:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:41:57 INFO - Installing collected packages: blessings 11:41:57 INFO - Running setup.py install for blessings 11:41:57 INFO - Successfully installed blessings 11:41:57 INFO - Cleaning up... 11:41:57 INFO - Return code: 0 11:41:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:41:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:41:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:41:58 INFO - Reading from file tmpfile_stdout 11:41:58 INFO - Current package versions: 11:41:58 INFO - blessings == 1.6 11:41:58 INFO - blobuploader == 1.2.4 11:41:58 INFO - docopt == 0.6.1 11:41:58 INFO - functools32 == 3.2.3-2 11:41:58 INFO - jsonschema == 2.5.1 11:41:58 INFO - manifestparser == 1.1 11:41:58 INFO - mozInstall == 1.12 11:41:58 INFO - mozcrash == 1.0 11:41:58 INFO - mozdebug == 0.1 11:41:58 INFO - mozdevice == 0.49 11:41:58 INFO - mozfile == 1.2 11:41:58 INFO - mozhttpd == 0.7 11:41:58 INFO - mozinfo == 0.9 11:41:58 INFO - mozleak == 0.1 11:41:58 INFO - mozlog == 3.4 11:41:58 INFO - moznetwork == 0.27 11:41:58 INFO - mozprocess == 0.25 11:41:58 INFO - mozprofile == 0.28 11:41:58 INFO - mozrunner == 6.13 11:41:58 INFO - mozscreenshot == 0.1 11:41:58 INFO - mozsystemmonitor == 0.3 11:41:58 INFO - moztest == 0.8 11:41:58 INFO - mozversion == 1.4 11:41:58 INFO - psutil == 3.1.1 11:41:58 INFO - requests == 1.2.3 11:41:58 INFO - wsgiref == 0.1.2 11:41:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:41: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')]} 11:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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')]} 11:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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 0x10cdebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b0c90e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc9d30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbebe8>, '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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 11:41: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 11:41: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 11:41:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XdUlPcC8Y/Render', 11:41:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:58 INFO - 'HOME': '/Users/cltbld', 11:41:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:58 INFO - 'LOGNAME': 'cltbld', 11:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:58 INFO - 'MOZ_NO_REMOTE': '1', 11:41:58 INFO - 'NO_EM_RESTART': '1', 11:41:58 INFO - 'PAGER': '/bin/cat', 11:41:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:58 INFO - 'PWD': '/builds/slave/test', 11:41:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:58 INFO - 'SHELL': '/bin/bash', 11:41:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4icBxn5TmQ/Listeners', 11:41:58 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 11:41:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:58 INFO - 'USER': 'cltbld', 11:41:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:58 INFO - 'XPC_FLAGS': '0x0', 11:41:58 INFO - 'XPC_SERVICE_NAME': '0', 11:41:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41: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)) 11:41: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)) 11:41: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)) 11:41: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)) 11:41: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)) 11:41: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)) 11:41: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)) 11:41: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)) 11:41: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)) 11:41: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)) 11:41: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)) 11:41:58 INFO - Cleaning up... 11:41:58 INFO - Return code: 0 11:41:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:41: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')]} 11:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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')]} 11:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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 0x10cdebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b0c90e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc9d30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbebe8>, '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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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 11:41: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 11:41: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 11:41:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XdUlPcC8Y/Render', 11:41:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:58 INFO - 'HOME': '/Users/cltbld', 11:41:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:58 INFO - 'LOGNAME': 'cltbld', 11:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:58 INFO - 'MOZ_NO_REMOTE': '1', 11:41:58 INFO - 'NO_EM_RESTART': '1', 11:41:58 INFO - 'PAGER': '/bin/cat', 11:41:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:58 INFO - 'PWD': '/builds/slave/test', 11:41:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:58 INFO - 'SHELL': '/bin/bash', 11:41:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4icBxn5TmQ/Listeners', 11:41:58 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 11:41:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:58 INFO - 'USER': 'cltbld', 11:41:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:58 INFO - 'XPC_FLAGS': '0x0', 11:41:58 INFO - 'XPC_SERVICE_NAME': '0', 11:41:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:41:58 INFO - Cleaning up... 11:41:58 INFO - Return code: 0 11:41:58 INFO - Running post-action listener: _resource_record_post_action 11:41:58 INFO - Running post-action listener: _start_resource_monitoring 11:41:58 INFO - Starting resource monitoring. 11:41:58 INFO - [mozharness: 2017-03-07 19:41:58.660251Z] Finished create-virtualenv step (success) 11:41:58 INFO - [mozharness: 2017-03-07 19:41:58.661257Z] Running install step. 11:41:58 INFO - Running pre-action listener: _resource_record_pre_action 11:41:58 INFO - Running main action method: install 11:41:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:41:58 INFO - Reading from file tmpfile_stdout 11:41:58 INFO - Detecting whether we're running mozinstall >=1.0... 11:41:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:41:59 INFO - Reading from file tmpfile_stdout 11:41:59 INFO - Output received: 11:41:59 INFO - Usage: mozinstall [options] installer 11:41:59 INFO - Options: 11:41:59 INFO - -h, --help show this help message and exit 11:41:59 INFO - -d DEST, --destination=DEST 11:41:59 INFO - Directory to install application into. [default: 11:41:59 INFO - "/builds/slave/test"] 11:41:59 INFO - --app=APP Application being installed. [default: firefox] 11:41:59 INFO - mkdir: /builds/slave/test/build/application 11:41: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'] 11:41: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 11:42:19 INFO - Reading from file tmpfile_stdout 11:42:19 INFO - Output received: 11:42:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:42:19 INFO - Running post-action listener: _resource_record_post_action 11:42:19 INFO - [mozharness: 2017-03-07 19:42:19.302911Z] Finished install step (success) 11:42:19 INFO - [mozharness: 2017-03-07 19:42:19.303037Z] Running run-tests step. 11:42:19 INFO - Running pre-action listener: _resource_record_pre_action 11:42:19 INFO - Running main action method: run_tests 11:42:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:42:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:42:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:42:19 INFO - 2017-03-07 11:42:19.317 screenresolution[1626:7484] starting screenresolution argv=screenresolution get 11:42:19 INFO - 2017-03-07 11:42:19.320 screenresolution[1626:7484] Display 0: 1600x1200x32@60 11:42:19 INFO - 2017-03-07 11:42:19.325 screenresolution[1627:7487] starting screenresolution argv=screenresolution list 11:42:19 INFO - Available Modes on Display 0 11:42:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:42:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:42:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:42:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:42:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:42:19 INFO - Intel Iris: 11:42:19 INFO - Chipset Model: Intel Iris 11:42:19 INFO - Type: GPU 11:42:19 INFO - Bus: Built-In 11:42:19 INFO - VRAM (Dynamic, Max): 1536 MB 11:42:19 INFO - Vendor: Intel (0x8086) 11:42:19 INFO - Device ID: 0x0a2e 11:42:19 INFO - Revision ID: 0x0009 11:42:19 INFO - Displays: 11:42:19 INFO - Display: 11:42:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:42:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:42:19 INFO - Display Serial Number: 1600x1200 60 11:42:19 INFO - Main Display: Yes 11:42:19 INFO - Mirror: Off 11:42:19 INFO - Online: Yes 11:42:19 INFO - Rotation: Supported 11:42:19 INFO - Return code: 0 11:42:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:42:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:42:19 INFO - Python 2.7.3 11:42:19 INFO - Return code: 0 11:42:19 INFO - grabbing minidump binary from tooltool 11:42: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')]} 11:42: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 0x10cbc9d30>, '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 0x10caf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbbebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:42: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 11:42: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 11:42: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 11:42:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:42:19 INFO - Return code: 0 11:42:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:42:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:42:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:42:19 INFO - ENV: RUST_BACKTRACE is now 1 11:42:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:42:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:42: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/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0377', '--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 11:42: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/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0377 --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 11:42:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XdUlPcC8Y/Render', 11:42:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:19 INFO - 'HOME': '/Users/cltbld', 11:42:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:19 INFO - 'LOGNAME': 'cltbld', 11:42:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:42:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:42:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:19 INFO - 'MOZ_NO_REMOTE': '1', 11:42:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:42:19 INFO - 'NO_EM_RESTART': '1', 11:42:19 INFO - 'PAGER': '/bin/cat', 11:42:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:19 INFO - 'PWD': '/builds/slave/test', 11:42:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:42:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:19 INFO - 'RUST_BACKTRACE': '1', 11:42:19 INFO - 'SHELL': '/bin/bash', 11:42:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4icBxn5TmQ/Listeners', 11:42:19 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 11:42:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:19 INFO - 'USER': 'cltbld', 11:42:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:19 INFO - 'XPC_FLAGS': '0x0', 11:42:19 INFO - 'XPC_SERVICE_NAME': '0', 11:42:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42: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/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0377', '--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 11:42:19 INFO - mozversion application_buildid: 20170307083724 11:42:19 INFO - mozversion application_changeset: 3d341b9ba5353b6b8ab45b6ca03dcb1b2d789faa 11:42:19 INFO - mozversion application_display_name: Nightly 11:42:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:42:19 INFO - mozversion application_name: Firefox 11:42:19 INFO - mozversion application_remotingname: firefox 11:42:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:42:19 INFO - mozversion application_vendor: Mozilla 11:42:19 INFO - mozversion application_version: 55.0a1 11:42:19 INFO - mozversion platform_buildid: 20170307083724 11:42:19 INFO - mozversion platform_changeset: 3d341b9ba5353b6b8ab45b6ca03dcb1b2d789faa 11:42:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:42:19 INFO - mozversion platform_version: 55.0a1 11:42:19 INFO - using testdate: 1488915739 11:42:19 INFO - actual date: 1488915739 11:42:19 INFO - starting webserver on 'localhost:49240' 11:42:19 INFO - SUITE-START | Running 2 tests 11:42:19 INFO - TEST-START | dromaeo_css 11:42:19 INFO - operating with platform_type : mac_ 11:42:19 INFO - Initialising browser for dromaeo_css test... 11:42:19 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/tmp7khmPS/profile http://localhost:49240/getInfo.html) 11:42:22 INFO - PROCESS | 1637 | 2017-03-07 11:42:22.901 plugin-container[1639:7668] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 11:42:22 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:22 INFO - PROCESS | 1637 | 2017-03-07 11:42:22.904 plugin-container[1639:7668] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9323, name = 'com.apple.CFPasteboardClient' 11:42:22 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:22 INFO - PROCESS | 1637 | 2017-03-07 11:42:22.904 plugin-container[1639:7668] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:42:22 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 11:42:22 INFO - PROCESS | 1637 | colorDepth:24 11:42:22 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 11:42:22 INFO - PROCESS | 1637 | __metrics 11:42:23 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 11:42:23 INFO - TEST-INFO | 1637: exit 0 11:42:23 INFO - Browser initialized. 11:42:23 INFO - Running cycle 1/1 for dromaeo_css test... 11:42:23 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/tmp7khmPS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:42:26 INFO - PROCESS | 1640 | 2017-03-07 11:42:26.520 plugin-container[1641:7810] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 11:42:26 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:26 INFO - PROCESS | 1640 | 2017-03-07 11:42:26.521 plugin-container[1641:7810] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 11:42:26 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:26 INFO - PROCESS | 1640 | 2017-03-07 11:42:26.521 plugin-container[1641:7810] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:42:36 INFO - PROCESS | 1640 | RSS: Main: 226996224 11:42:36 INFO - PROCESS | 1640 | 11:45:40 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 11:45:42 INFO - PROCESS | 1640 | RSS: Main: 249544704 11:45:42 INFO - PROCESS | 1640 | 11:47:24 INFO - PROCESS | 1640 | ************************* 11:47:24 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 11:47:24 INFO - PROCESS | 1640 | 11:47:24 ERROR - PROCESS | 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:47:24 INFO - PROCESS | 1640 | Full stack: 11:47:24 INFO - PROCESS | 1640 | ************************* 11:48:44 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 11:48:45 INFO - PROCESS | 1640 | RSS: Main: 259911680 11:48:45 INFO - PROCESS | 1640 | 11:51:47 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 11:51:48 INFO - PROCESS | 1640 | RSS: Main: 271020032 11:51:48 INFO - PROCESS | 1640 | 11:54:50 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 11:54:50 INFO - PROCESS | 1640 | RSS: Main: 263401472 11:54:50 INFO - PROCESS | 1640 | 11:57:53 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 11:57:54 INFO - PROCESS | 1640 | RSS: Main: 273580032 11:57:54 INFO - PROCESS | 1640 | 12:00:57 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 12:00:57 INFO - PROCESS | 1640 | RSS: Main: 268120064 12:00:57 INFO - PROCESS | 1640 | 12:00:57 INFO - PROCESS | 1640 | __start_tp_report 12:00:57 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 12:00:57 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 12:00:57 INFO - PROCESS | 1640 | |i|pagename|runs| 12:00:57 INFO - PROCESS | 1640 | |0;dojo.html;3462.0479368173756;3471.9479207811873;3486.2504561519268;3497.0732755819704;3525.7884526928387;5769.7115144242825;5994;6000.039993601024;6045.576809623322;6060.969695151518;4780.378550788392;5316;5822.9809783287865;5962.552808539366;6087.878242435148;5297.337832770903;5349.3045904032515;5458.590605704569;5475.561955043607;5516.172574113895;5360.303160589118;5420.322459692538;5483.972580137094;5506.091494903336;5513.61404701671;5347.037533244014;5387.973060134714;5489.34127904654;5508.779648814043;5520.66875987438;5429.294191755086;5443.673379597237;5445.237666726662;5446.428125046892;5489.368722596912;5369.731513424314;5381.784728610851;5388.030154572176;5404.351477822647;5443.972780136113;4857.63567732418;5790.507806836424;5823.67969761663;5909.3204281507315;5982.042873140276;6137.897974673433;6240.937590624081;6277.937220627781;6280.120783090371;6296.685165741695;6425.8393540161405;6458.418742313214;6462.32145624712;6462.386073323027;6495.870082598369;5158.819441319536;6135.049067394561;6138.7237574309265;6156.4151405617295;6200.224971878515;6108.22065524731;6257.0614407838275;6443.4523065539015;6446.065320528497;6466.870662586722;6135.263289243823;6150.446459818638;6210.347913469111;6280.466160376419;6307.306196318401;6317.3366796486625;6379;6398.680065996681;6400.295967443578;6410.45511131559;6193.349698281616;6300.936990630126;6305.432511074026;6308.684565771693;6332.746690132343;4719.669623126349;5490.478404551562;6172.506199504029;6194.008958566607;6202.224721909762;4426.955730442654;5084.33903592538;5123.359580052494;5132.717700526507;5163.302954101224;4572.0627271409285;4580.106879158532;4584.4498660160325;4589.173948689268;4604.723716577016;5851.063829787214;5928.644281343133;5987.730552125165;6022.09668549721;6156.846078847975;6220.409061139275;6270.811875643738;6285.654289014148;6313.558050936482;6337.746490140435;6069.666168360694;6111.938880611226;6112.877742445126;6138.263408391022;6141.07883817431;4890.315355850184;5676.659400435987;5689.544836413077;5701.572382071361;5718.456746609066;5417.349918009864;6209.96895015531;6288.842778930563;6392.9360706392345;6407.135036769976;6271.592346497463;6272.153259309935;6302.212223472066;6312.652804095819;6318.368163183645;6431.517636177399;6471.805845824632;6486;6502.902456463063;6527.05357723137;5831.79588714393;6190.907136392868;6211.937880621317;6239.75040998355;6270.811875643738;5294.841154765363;6367.331430199948;6387.201599800025;6390.265119511316;6417.582857114273;5379.273798037215;6122.215193971364;6156.860980718581;6201.596896201732;6310.400511951358;6165;6176.938230617637;6231.9688401557705;6245.344238854855;6297.4647154992335;4758.31004504338;6023.518118550418;6124.448799608057;6127.877442451037;6270.4670103039925;5573.637713548606;6199.907001394892;6206.10011548332;6221.626702397871;6255.218097737783;5936.851578710567;6247.843803904939;6253.843653908689;6255.781047663492;6265.060240963892 12:00:57 INFO - PROCESS | 1640 | |1;ext.html;690.3165865792863;733.8128777161825;778.937684985201;923.7275003873857;926.9953650231748;37629.623703762954;38357;39226.80386598066;39269;39276.21447571047;37999.81000095003;38123.42814857779;38186.85439436814;38218.61781382185;38322.8083859581;7670.041244844395;7820.726274580391;10345.689629311133;10696.625618103368;10760.40817755023;10498.160147188206;10542;10580.470976451184;10609.893901061007;10614.04473597376;10585;10600.363978161335;10632.840507392395;10639.78720425591;10648.893511064867;10043.497825108716;10526.368417894873;10601.257911946166;10612.310199836986;10628.681139565826;9632.47021413822;10312;10346.12057975073;10388.065074143364;10400.427976461368;33813;33886.32227355464;34036.31927361464;34117.829410852784;34143;3713.9442908356395;3780.8298626561873;3791.658750712449;3801.82891769871;3943.4676318696897;25157.49685006308;25249.747502524922;25716.87141564299;25807.35481612956;26031.088911887993;3012.774041946863;3152.936941261162;3174.587303650511;3204.9198770030703;3216.099492142204;334696;335023.99999999756;335114;336415;339748;36767.4484882727;43608.78195609002;43847.12305753867;43848;43970;45824.770876145405;45876;45912.770436147606;46182.538174618494;46188.307175392394;11305.604303849326;12996.805047924288;13075.346232688327;13091;13148.60554183376;10964.890351096386;11027.503762330714;11128.332300062022;11158.776824463464;11174.10607151426;9902.158316543175;10537.367757934548;11785.705357366134;11833.171677982631;11910.821337679941;5111.7699703513435;5268.104422248227;5633.943660563424;5638.167595531469;5695.145728140729;1081.9513121909376;1132.6828488023366;1151.6084531259412;1186.258588382261;1196.9945245993408;19498.902505487382;25799.613005804928;26361.94552217928;26538.46923061528;26601.733982660313;1025.5333823110466;1154.7286387698884;1233.3709807997807;1276.7063575377715;1280.475005247844;12821.423035963404;13751.243681597409;13764;13800.102993305403;13829.239391833344;452406;453851;500602;598679;600953;49987.000259994595;50243.74878125586;50335.74832125889;50402.49597503978;50463;1192.4991503568522;1226.2458587968335;1374.7250549890061;1389.1873254146235;1394.3725323604622;27185.912563497237;27588.17235482939;27931.022414215404;28114.85942570274;28493;40256.79871600623;40465.79767101146;40519.59480405158;40624.59375406208;40911.7954410226;422590;424794;425559;427170;427798;24840.503189936462;28978.565321520615;29965.850170749007;30516.62675179578;31575.684243157273;38534.80732596319;39357.212855743295;39531.60468395394;40324.790256292355;40438;27878.721212787612;32220.83889580537;32820.179495512806;34299.657003429646;34331.31337373288;25440.491190176464;30239.697603024568;32779.68881244723;32820.343593128484;34035.65964340424;32416.67583324231;33118.006459806245;33378.83310583432;33791.662083378855;33845.83077084697;26341.73658263393;26708.332291692863;28603.14190574286;29868.3902579915;29929.55105673373;18870.433886983414;22826;22955.65566516537;23339.53320933606;23504.059837606303 12:00:57 INFO - PROCESS | 1640 | |2;jquery.html;365.3533246154307;383.2794346628338;393.83032393790444;400.9558948515663;404.7470331043098;19967.201311947523;21547.569048619036;21570.13719451224;21582.244621438254;21902.561948761035;20571.28000519982;21358;21416.57166856666;22120.889395553;22174.88912555439;6540.7383704651875;6541.378569035934;6795.388415042645;6804.217514985766;6805.761798337059;6445.613263204223;6787.015882697006;7211.314925082109;7588.089429268468;7732.806679833021;7097.716091356339;7683.193264707209;7695.307422331989;7727.8068048298965;7731.922680773177;7514.2485751424965;7571.091469023752;7578.204288549678;7586.051743532059;7663.080430348366;7144.142702875688;7346.742863999788;7405.7778266652085;7429.145648250466;7438.256174382573;25530.48939021228;25587.01074157628;25868;26000.609990850153;26164;2830.6461692288462;2852.229897927569;2949.8820047198087;2952.5718770778117;2957.6450825900815;14563.49027784033;15964.361425542964;16098.517044488684;16257.618102461305;16790.58023549409;2020.2220656671443;2052.6613108837023;2066.3904148276297;2074.9481262968397;2076.408223656251;161505;169292.1535392315;171152;171396;173979;20138.89930550338;20443.38669839907;20547.897260513902;20751.273705420237;20858.47853803637;27471.313217169332;31091.600877960547;31498.842505787325;31894.68105318917;32017;8307.418480706292;9752.804943901083;10089;10109.876925845509;10198.286119971532;8267.958660206781;8276.751697449086;8295.958520207481;8367.581620918929;8478.533680647624;8689.652413903374;8713.869291960626;9455.716328510156;9641;9644;3458.429359155711;3954.8220330085214;4046.3323551614244;4055.6958228133008;4065.6747460203114;937.0535758883576;1025.4462590201208;1027.2501074215863;1032.194887987371;1070.6252811515908;20178.59642807165;20772.480687982617;21385.144594216057;22002.339929802132;22051.448713782283;1047.9101734196502;1052.1587222360868;1077.4558847781977;1085.685151306128;1099.1042300525046;9252.629894804268;9275.953620231856;10752.731181720363;10855.2401331906;11251.549938002388;656260;656290;684831;701977;703322;145390.2730486341;155102;157538.2123089377;157928;161473;1085.7991271614776;1229.8893099621077;1284.858665546789;1286.2346903592534;1299.4672184404537;18732;18765.718514222026;18916.716249256548;18927.432177034712;19046.047697614787;61548.15292547201;62383.37616623776;63238;63371.68314158584;64178.71642567216;87880;88940;89683.75790605287;89705.10294897229;92598.07401925894;9847.901520984698;11282.94358528202;11355.318680879174;11452.427378630868;11790.94104529472;12827.615171544869;13064.54274100402;13230.603081907559;13282.468701251843;13381.39783709716;11055.778884422105;11311.321320720783;11348.602798901999;11521.711957201138;11594.246373985665;11032.393218373067;11051.834222486508;11192.608258710907;11416.257943233662;11449.370284634091;11129.276597021168;11651.30092194471;11745.647630571097;11764;11812.3503207321;11452.275569140345;11698.883011169779;11770.58802941893;11790;11814.586489472827;11041.227114102257;11156.665300041012;11330.206885517935;11386.804385539399;11435.313881167156 12:00:57 INFO - PROCESS | 1640 | |3;mootools.html;1453.723792479429;1514.469935522568;1520.680657062017;1521.497905691122;1565.27214845097;10451.73870653234;10608.257421980465;10875.728106797334;11084.002439780417;11123.72190695233;10719.732006699836;10869.945650271737;10906.727331816708;10907.618233361834;10940.56237750491;2337.474068334624;2367.6803631509783;2369.893354799038;2374.0622454130607;2377.500724847784;2098.401955442699;2277.6469647204626;2315.386422598013;2326.8952897119584;2331.4870728439714;2313.4447732544236;2321.129576408847;2328.266596022256;2340.4616938104245;2347.225415612852;2008.3774030050586;2236.4408897775556;2255.977440225593;2264.7735226477384;2271.7387500437494;2183.3559100065518;2196.363054714131;2210.3700445372992;2214.4574579228033;2217.7782221777857;10735.033846953733;10743.570257189704;10799.730006749895;10803.621873234399;10833.133349332034;5036.06832735945;5664.57515686321;5840.123981402781;5856.502197313233;5867.530597552228;3132.592762940826;3332.3668502984356;3564.8574057037686;3627.0025742920643;3643.7813731176216;1148.5118824499555;1167.655541615217;1192.6481687902003;1204.692803335144;1223.1621339382552;12533.623991280276;14361.138331700131;14361.569152925429;14417.639559011004;14475;3831.444440556104;4570.337301091323;4609.423822022247;4666.673332866734;4694.671373003857;11119.27724697892;11291.3789741565;11328.037116844996;11342.20604557673;11391.259568128045;5663.66018038919;5667.894760521741;5673.461021202979;5681.119426488818;5681.488666020078;4949.604031677457;4986.526280003394;4999.825006124768;5064.088464076502;5085.160948443539;3758.642928921748;3802.334591446486;3848.5189351331082;3881.262560113569;3884.0872394987155;1034.8447732840134;1036.7874585709912;1038.3873514626255;1048.3447845096814;1050.3172937590475;748.277911815101;794.7496538590326;798.5767543202112;819.7827575692446;825.7398919340509;6418.165638467063;6426.710798014101;6646.767363142363;6667.833304167338;6698.732050718014;949.1742184299703;972.7081875437341;977.4184360305607;993.9563458368684;1012.7620009297809;4010.2781499329817;4010.47863777707;4215.262329092397;4359.585839345257;4597.264437689953;10865.891341086646;13094.541691040768;13373.197608143542;13376.598702038955;13380.598582042554;11638.76722465546;12040.976516996154;12830.679233019062;12942.676433089247;13082.084254102312;8066.637001334837;9535.618575256913;9822.361546499598;9864.260180486348;9875.061869122423;6431.614103153825;6458.128152699325;6619.238787539494;6783.219929708147;6905.965470172617;9867.40795552269;11787.705307367385;11808;11884.405779710807;12207.38963051826;11334.696509901467;11642.94178529102;11964.64106076819;12073.034157267573;12194;8958.238549723086;10012.249081319076;10016.348937319051;10017.348872323275;10028;9741.951290243504;9980.500974951368;10047.748806279902;10052.949735251277;10069.244806639677;8815.823683526165;9022.413543119676;9059.320550958573;9135.451872887648;9148.268138548767;8778.604962776819;8802.91197088021;8805.251553618014;8851.955740221258;8928.196462318163;7746.845063098819;7748.690052397841;7781.494202877021;7793.415493838098;7801;5349.170878513836;5630.971845140748;5746.425357464387;5792.1311803229855;5922.7927022554;5810.4189581041555;5863.888999959926;5972.850678733066;5983.551233657406;6087.726052327567 12:00:57 INFO - PROCESS | 1640 | |4;prototype.html;286.5672834020631;352.28661959531945;362.4852709153004;368.39031403027985;376.29632587062196;5067.906753712518;5106.872328191797;5304.681719096857;5439.456054394558;5467.535259502942;4408.0604534005015;4927.852164435073;5126.461721519243;5251.92335571208;5279.676942145454;3787.9621203787883;3940.054386947126;4466.392098844413;4567.520410356906;4589.724616523003;3825.2923209206247;4151;4606.953930460686;4628.745419001953;4670.719756814585;3888.47505586745;4449.26587113126;4560.635149188073;4574.931376029363;4602.470715867667;4092.3452247640535;4197.328427451593;4596.827808908741;4601.953980460186;4637.420322459692;3796.240751849641;3943.585923478051;4091.590840915914;4353.25994580922;4417.690761646654;4827.131116399047;5105.8555406729265;5134.532956327981;5158.92261616076;5176.127249376595;4857.514248575149;5214.530692237717;5267.28891599633;5293.841184764463;5306.893862122774;4933.535150688633;5472.644278121949;5700.543956483471;5705;5780.450857168562;4809.927851082237;4821.927671084936;4830;4847.684900481493;4849.636277279218;5104.69371837691;5239.170847742214;5832.791964419553;5835.679037652885;5980.551458640576;4403.427554417905;4791.185498465269;4794.688345257547;4935.259711043373;4965.48141191514;5093.663650309053;5094.439611642716;5104.452405578088;5115.335006449138;5140.37840701245;4657.464391594942;4833.661643684979;4868.756562171877;5263.1841964548785;5318.149096144675;5064.898702025939;5412.431694672003;5646.265985421869;5688;5705.62913410627;1739.3912130754136;1749.5013921032444;1749.7112976358758;1751.808770036362;1761.779777527809;1236.4003458322886;1290.9930254401581;1371.2800779590796;1381.2334154544324;1406.6706961920886;4835.95164048362;4952.702837829742;5233.215017747369;5272.841814745564;5279.762410691528;4981.886321049345;5414.160805075219;5571.718504743932;5698;5811.4479124483105;4831.202851529459;4876;4942.332785073969;5178.317046959678;5244.475552444725;2634.1439032314574;2743.341598016462;3029.909102726922;3059.265776213693;3077.7680689052554;5600.60795744294;5723.570732195102;5838.7080645967535;5871.2073870027;6075.665838378844;5203.947960520422;5698.686572238566;5849.718609742204;5882.764689412461;5985.401459854067;5158.509941555471;5477.890442191054;5552.5008247773685;5813.982553053199;6047.969760151259;5128.538431541179;5141.794328226829;5609.80365687199;5714.114372241991;5827.59206855533;4570.611498022572;5043.856361046467;5175.663581867167;5508.697021663686;5751.9424805751405;5916.615419997686;6079.604825686316;6092.177556029879;6092.847678808065;6094.2077529920825;4419.535948725466;4790.401199850019;4800.207965685623;4995.625328100335;5114.74426278692;4716.64625153108;5146.614003949793;5267.630416091767;5727.914081288867;5858.8242352729485;5043.899122017612;5044.722540260321;5237.816676416307;5657.179708942263;5675.701340956919;5033.874153146201;5653.349864765604;5659.547236221175;5662.348829884616;5690.829275121753;5083.237514372874;5193.792248310177;5275.498827611371;5494.066008778437;5553.944460555343;4926.753662316798;5064.594832413324;5340.169710053678;5486.8477619698715;5599.552035837205;4380.3210502372185;5136.845894623168;5217;5304.549113325411;5515.503604675599 12:00:57 INFO - PROCESS | 1640 | |5;yui.html;709.3580309819612;712.5190496414918;754.0235395163263;828.581566309014;847.6818547159166;15509.224538773058;16335.34658613654;16465.176741162923;16519.091449970263;16522.91738541307;13091.429028516568;13135.802962955564;13424.463021479154;14326.355314010869;15475.994060386067;901.9549022548863;922.0456827183866;925.9953700231489;926.263620421763;927.7541451515353;810.2100452059225;865.3682811547574;923.3490389275545;924.694850699271;933.6032186320821;982.3074732313737;982.8427451607744;984.9753756156082;985.9556319965583;986.9358491698052;831.5509624802629;979.1236843025453;980.9656662016831;981.3277904635312;981.3572110267714;720.3516834848625;726.5858460677416;732.6959311885561;732.7728404194663;733.2777214443769;14922.552323430315;14980.775288370683;14986.025908315925;15155.469558565397;15857.207139642971;11581.478833452515;13744.518941836988;14015;14035.298235088307;14329.140251584939;11261.774764504664;11471.827922581168;11601.679664067136;11733.882661173364;12227.14409991301;3101.7518598512065;3597.172650290448;3658.1769101952113;3668.559772827278;3680.6319368063246;115247;118053.819461806;118234;118601;119526.4023679876;10577.31247468912;10688.946555267174;10718.73203169927;11025;11122.277051991756;22299.99650015721;22364.217252396415;22536.661950070764;22559.436014099778;22608.626094260195;7465.85068298631;7494.250574942462;7604.12552556452;7692.692292308245;7797.883031754528;3686.9815650921573;4084.326086195804;4140.710150289511;4204.852830150991;4235.682323825726;3383.035834787074;3436.9656303437146;3449.275652113034;3470.878519251814;3484.3553942520716;637.9968100159563;639.523554951564;643.112504743451;657.6251536624078;657.9144711187515;633.9144215530845;660.6663634864459;687.7489716253632;689.648279377511;715.3526058916689;10130;10235.488225588691;10504;10532.789344213073;10632.574697012033;660.9933900660932;669.7174910047303;686.4062585863293;707.4906067631297;716.1120210938399;4164.562720914321;4735.289706544046;4892.363992681;4902.950970490321;4955.182394904872;92106;105567;108692;109118.3632245517;111355.44322278336;92387;92532;93512;93851;94247.52876235712;81192.59403702944;82983.5850820742;83093.58453207817;83536;87111.99999999873;9339.859902101338;9507.096825801538;9540.522973851419;9580.377275477073;9591.664291749756;43088.56911430971;45661.54338456573;47483.76258118687;48032;53393;53068.73465632647;58074;59394;65222;67000;12462.379890614162;12628.494860205488;12664.493420263454;12896.935515322364;13257.66855828612;11687.051250309483;14352.56942291733;14529.200893950934;14575.562733118024;14764.852351476777;13165.670858228621;13428;13598.388072536958;14006.369713363127;14058.507952221624;11515.25150865191;13357.398917048562;13669;14021.859781402056;14120.717585648435;13215.471381144647;13297.335133243105;13361.26513041792;13671.111377760812;13964;10823.188260880308;10829.566817327219;11023.393713345842;11192.328460292409;11285.887141128484;9647.9035209647;9867.506624668595;10371.303461100017;10677.46612669379;11219 12:00:57 INFO - PROCESS | 1640 | __end_tp_report 12:00:57 INFO - PROCESS | 1640 | __start_cc_report 12:00:57 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,422 12:00:57 INFO - PROCESS | 1640 | __end_cc_report 12:00:57 INFO - PROCESS | 1640 | __startTimestamp1488916857805__endTimestamp 12:00:57 INFO - PROCESS | 1640 | 12:00:57 INFO - PROCESS | 1640 | ------- Summary: start ------- 12:00:57 INFO - PROCESS | 1640 | Number of tests: 6 12:00:57 INFO - PROCESS | 1640 | 12:00:57 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5839.34 Median:6135.05 stddev:651.63 (10.6%) stddev-sans-first:626.37 12:00:57 INFO - PROCESS | 1640 | Values: 3462.0 3471.9 3486.3 3497.1 3525.8 5769.7 5994.0 6000.0 6045.6 6061.0 4780.4 5316.0 5823.0 5962.6 6087.9 5297.3 5349.3 5458.6 5475.6 5516.2 5360.3 5420.3 5484.0 5506.1 5513.6 5347.0 5388.0 5489.3 5508.8 5520.7 5429.3 5443.7 5445.2 5446.4 5489.4 5369.7 5381.8 5388.0 5404.4 5444.0 4857.6 5790.5 5823.7 5909.3 5982.0 6137.9 6240.9 6277.9 6280.1 6296.7 6425.8 6458.4 6462.3 6462.4 6495.9 5158.8 6135.0 6138.7 6156.4 6200.2 6108.2 6257.1 6443.5 6446.1 6466.9 6135.3 6150.4 6210.3 6280.5 6307.3 6317.3 6379.0 6398.7 6400.3 6410.5 6193.3 6300.9 6305.4 6308.7 6332.7 4719.7 5490.5 6172.5 6194.0 6202.2 4427.0 5084.3 5123.4 5132.7 5163.3 4572.1 4580.1 4584.4 4589.2 4604.7 5851.1 5928.6 5987.7 6022.1 6156.8 6220.4 6270.8 6285.7 6313.6 6337.7 6069.7 6111.9 6112.9 6138.3 6141.1 4890.3 5676.7 5689.5 5701.6 5718.5 5417.3 6210.0 6288.8 6392.9 6407.1 6271.6 6272.2 6302.2 6312.7 6318.4 6431.5 6471.8 6486.0 6502.9 6527.1 5831.8 6190.9 6211.9 6239.8 6270.8 5294.8 6367.3 6387.2 6390.3 6417.6 5379.3 6122.2 6156.9 6201.6 6310.4 6165.0 6176.9 6232.0 6245.3 6297.5 4758.3 6023.5 6124.4 6127.9 6270.5 5573.6 6199.9 6206.1 6221.6 6255.2 5936.9 6247.8 6253.8 6255.8 6265.1 12:00:57 INFO - PROCESS | 1640 | 12:00:57 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:55127.67 Median:17375.14 stddev:116182.06 (668.7%) stddev-sans-first:116436.09 12:00:57 INFO - PROCESS | 1640 | Values: 690.3 733.8 778.9 923.7 927.0 37629.6 38357.0 39226.8 39269.0 39276.2 37999.8 38123.4 38186.9 38218.6 38322.8 7670.0 7820.7 10345.7 10696.6 10760.4 10498.2 10542.0 10580.5 10609.9 10614.0 10585.0 10600.4 10632.8 10639.8 10648.9 10043.5 10526.4 10601.3 10612.3 10628.7 9632.5 10312.0 10346.1 10388.1 10400.4 33813.0 33886.3 34036.3 34117.8 34143.0 3713.9 3780.8 3791.7 3801.8 3943.5 25157.5 25249.7 25716.9 25807.4 26031.1 3012.8 3152.9 3174.6 3204.9 3216.1 334696.0 335024.0 335114.0 336415.0 339748.0 36767.4 43608.8 43847.1 43848.0 43970.0 45824.8 45876.0 45912.8 46182.5 46188.3 11305.6 12996.8 13075.3 13091.0 13148.6 10964.9 11027.5 11128.3 11158.8 11174.1 9902.2 10537.4 11785.7 11833.2 11910.8 5111.8 5268.1 5633.9 5638.2 5695.1 1082.0 1132.7 1151.6 1186.3 1197.0 19498.9 25799.6 26361.9 26538.5 26601.7 1025.5 1154.7 1233.4 1276.7 1280.5 12821.4 13751.2 13764.0 13800.1 13829.2 452406.0 453851.0 500602.0 598679.0 600953.0 49987.0 50243.7 50335.7 50402.5 50463.0 1192.5 1226.2 1374.7 1389.2 1394.4 27185.9 27588.2 27931.0 28114.9 28493.0 40256.8 40465.8 40519.6 40624.6 40911.8 422590.0 424794.0 425559.0 427170.0 427798.0 24840.5 28978.6 29965.9 30516.6 31575.7 38534.8 39357.2 39531.6 40324.8 40438.0 27878.7 32220.8 32820.2 34299.7 34331.3 25440.5 30239.7 32779.7 32820.3 34035.7 32416.7 33118.0 33378.8 33791.7 33845.8 26341.7 26708.3 28603.1 29868.4 29929.6 18870.4 22826.0 22955.7 23339.5 23504.1 12:00:57 INFO - PROCESS | 1640 | 12:00:57 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:41730.58 Median:21482.07 stddev:114758.12 (534.2%) stddev-sans-first:115038.01 12:00:57 INFO - PROCESS | 1640 | Values: 365.4 383.3 393.8 401.0 404.7 19967.2 21547.6 21570.1 21582.2 21902.6 20571.3 21358.0 21416.6 22120.9 22174.9 6540.7 6541.4 6795.4 6804.2 6805.8 6445.6 6787.0 7211.3 7588.1 7732.8 7097.7 7683.2 7695.3 7727.8 7731.9 7514.2 7571.1 7578.2 7586.1 7663.1 7144.1 7346.7 7405.8 7429.1 7438.3 25530.5 25587.0 25868.0 26000.6 26164.0 2830.6 2852.2 2949.9 2952.6 2957.6 14563.5 15964.4 16098.5 16257.6 16790.6 2020.2 2052.7 2066.4 2074.9 2076.4 161505.0 169292.2 171152.0 171396.0 173979.0 20138.9 20443.4 20547.9 20751.3 20858.5 27471.3 31091.6 31498.8 31894.7 32017.0 8307.4 9752.8 10089.0 10109.9 10198.3 8268.0 8276.8 8296.0 8367.6 8478.5 8689.7 8713.9 9455.7 9641.0 9644.0 3458.4 3954.8 4046.3 4055.7 4065.7 937.1 1025.4 1027.3 1032.2 1070.6 20178.6 20772.5 21385.1 22002.3 22051.4 1047.9 1052.2 1077.5 1085.7 1099.1 9252.6 9276.0 10752.7 10855.2 11251.5 656260.0 656290.0 684831.0 701977.0 703322.0 145390.3 155102.0 157538.2 157928.0 161473.0 1085.8 1229.9 1284.9 1286.2 1299.5 18732.0 18765.7 18916.7 18927.4 19046.0 61548.2 62383.4 63238.0 63371.7 64178.7 87880.0 88940.0 89683.8 89705.1 92598.1 9847.9 11282.9 11355.3 11452.4 11790.9 12827.6 13064.5 13230.6 13282.5 13381.4 11055.8 11311.3 11348.6 11521.7 11594.2 11032.4 11051.8 11192.6 11416.3 11449.4 11129.3 11651.3 11745.6 11764.0 11812.4 11452.3 11698.9 11770.6 11790.0 11814.6 11041.2 11156.7 11330.2 11386.8 11435.3 12:00:57 INFO - PROCESS | 1640 | 12:00:57 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:6539.99 Median:2375.78 stddev:4023.91 (169.4%) stddev-sans-first:4017.04 12:00:57 INFO - PROCESS | 1640 | Values: 1453.7 1514.5 1520.7 1521.5 1565.3 10451.7 10608.3 10875.7 11084.0 11123.7 10719.7 10869.9 10906.7 10907.6 10940.6 2337.5 2367.7 2369.9 2374.1 2377.5 2098.4 2277.6 2315.4 2326.9 2331.5 2313.4 2321.1 2328.3 2340.5 2347.2 2008.4 2236.4 2256.0 2264.8 2271.7 2183.4 2196.4 2210.4 2214.5 2217.8 10735.0 10743.6 10799.7 10803.6 10833.1 5036.1 5664.6 5840.1 5856.5 5867.5 3132.6 3332.4 3564.9 3627.0 3643.8 1148.5 1167.7 1192.6 1204.7 1223.2 12533.6 14361.1 14361.6 14417.6 14475.0 3831.4 4570.3 4609.4 4666.7 4694.7 11119.3 11291.4 11328.0 11342.2 11391.3 5663.7 5667.9 5673.5 5681.1 5681.5 4949.6 4986.5 4999.8 5064.1 5085.2 3758.6 3802.3 3848.5 3881.3 3884.1 1034.8 1036.8 1038.4 1048.3 1050.3 748.3 794.7 798.6 819.8 825.7 6418.2 6426.7 6646.8 6667.8 6698.7 949.2 972.7 977.4 994.0 1012.8 4010.3 4010.5 4215.3 4359.6 4597.3 10865.9 13094.5 13373.2 13376.6 13380.6 11638.8 12041.0 12830.7 12942.7 13082.1 8066.6 9535.6 9822.4 9864.3 9875.1 6431.6 6458.1 6619.2 6783.2 6906.0 9867.4 11787.7 11808.0 11884.4 12207.4 11334.7 11642.9 11964.6 12073.0 12194.0 8958.2 10012.2 10016.3 10017.3 10028.0 9742.0 9980.5 10047.7 10052.9 10069.2 8815.8 9022.4 9059.3 9135.5 9148.3 8778.6 8802.9 8805.3 8852.0 8928.2 7746.8 7748.7 7781.5 7793.4 7801.0 5349.2 5631.0 5746.4 5792.1 5922.8 5810.4 5863.9 5972.9 5983.6 6087.7 12:00:57 INFO - PROCESS | 1640 | 12:00:57 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4753.08 Median:5110.81 stddev:1266.51 (24.8%) stddev-sans-first:1224.89 12:00:57 INFO - PROCESS | 1640 | Values: 286.6 352.3 362.5 368.4 376.3 5067.9 5106.9 5304.7 5439.5 5467.5 4408.1 4927.9 5126.5 5251.9 5279.7 3788.0 3940.1 4466.4 4567.5 4589.7 3825.3 4151.0 4607.0 4628.7 4670.7 3888.5 4449.3 4560.6 4574.9 4602.5 4092.3 4197.3 4596.8 4602.0 4637.4 3796.2 3943.6 4091.6 4353.3 4417.7 4827.1 5105.9 5134.5 5158.9 5176.1 4857.5 5214.5 5267.3 5293.8 5306.9 4933.5 5472.6 5700.5 5705.0 5780.5 4809.9 4821.9 4830.0 4847.7 4849.6 5104.7 5239.2 5832.8 5835.7 5980.6 4403.4 4791.2 4794.7 4935.3 4965.5 5093.7 5094.4 5104.5 5115.3 5140.4 4657.5 4833.7 4868.8 5263.2 5318.1 5064.9 5412.4 5646.3 5688.0 5705.6 1739.4 1749.5 1749.7 1751.8 1761.8 1236.4 1291.0 1371.3 1381.2 1406.7 4836.0 4952.7 5233.2 5272.8 5279.8 4981.9 5414.2 5571.7 5698.0 5811.4 4831.2 4876.0 4942.3 5178.3 5244.5 2634.1 2743.3 3029.9 3059.3 3077.8 5600.6 5723.6 5838.7 5871.2 6075.7 5203.9 5698.7 5849.7 5882.8 5985.4 5158.5 5477.9 5552.5 5814.0 6048.0 5128.5 5141.8 5609.8 5714.1 5827.6 4570.6 5043.9 5175.7 5508.7 5751.9 5916.6 6079.6 6092.2 6092.8 6094.2 4419.5 4790.4 4800.2 4995.6 5114.7 4716.6 5146.6 5267.6 5727.9 5858.8 5043.9 5044.7 5237.8 5657.2 5675.7 5033.9 5653.3 5659.5 5662.3 5690.8 5083.2 5193.8 5275.5 5494.1 5553.9 4926.8 5064.6 5340.2 5486.8 5599.6 4380.3 5136.8 5217.0 5304.5 5515.5 12:00:57 INFO - PROCESS | 1640 | 12:00:57 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20870.37 Median:3674.60 stddev:30878.17 (840.3%) stddev-sans-first:30927.69 12:00:57 INFO - PROCESS | 1640 | Values: 709.4 712.5 754.0 828.6 847.7 15509.2 16335.3 16465.2 16519.1 16522.9 13091.4 13135.8 13424.5 14326.4 15476.0 902.0 922.0 926.0 926.3 927.8 810.2 865.4 923.3 924.7 933.6 982.3 982.8 985.0 986.0 986.9 831.6 979.1 981.0 981.3 981.4 720.4 726.6 732.7 732.8 733.3 14922.6 14980.8 14986.0 15155.5 15857.2 11581.5 13744.5 14015.0 14035.3 14329.1 11261.8 11471.8 11601.7 11733.9 12227.1 3101.8 3597.2 3658.2 3668.6 3680.6 115247.0 118053.8 118234.0 118601.0 119526.4 10577.3 10688.9 10718.7 11025.0 11122.3 22300.0 22364.2 22536.7 22559.4 22608.6 7465.9 7494.3 7604.1 7692.7 7797.9 3687.0 4084.3 4140.7 4204.9 4235.7 3383.0 3437.0 3449.3 3470.9 3484.4 638.0 639.5 643.1 657.6 657.9 633.9 660.7 687.7 689.6 715.4 10130.0 10235.5 10504.0 10532.8 10632.6 661.0 669.7 686.4 707.5 716.1 4164.6 4735.3 4892.4 4903.0 4955.2 92106.0 105567.0 108692.0 109118.4 111355.4 92387.0 92532.0 93512.0 93851.0 94247.5 81192.6 82983.6 83093.6 83536.0 87112.0 9339.9 9507.1 9540.5 9580.4 9591.7 43088.6 45661.5 47483.8 48032.0 53393.0 53068.7 58074.0 59394.0 65222.0 67000.0 12462.4 12628.5 12664.5 12896.9 13257.7 11687.1 14352.6 14529.2 14575.6 14764.9 13165.7 13428.0 13598.4 14006.4 14058.5 11515.3 13357.4 13669.0 14021.9 14120.7 13215.5 13297.3 13361.3 13671.1 13964.0 10823.2 10829.6 11023.4 11192.3 11285.9 9647.9 9867.5 10371.3 10677.5 11219.0 12:00:57 INFO - PROCESS | 1640 | -------- Summary: end -------- 12:00:57 INFO - PROCESS | 1640 | 12:00:58 INFO - TEST-INFO | 1640: exit 0 12:00:58 INFO - TEST-OK | dromaeo_css | took 1118581ms 12:00:58 INFO - TEST-START | kraken 12:00:58 INFO - operating with platform_type : mac_ 12:00:58 INFO - Initialising browser for kraken test... 12:00:58 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/tmpLiQ3wl/profile http://localhost:49240/getInfo.html) 12:01:01 INFO - PROCESS | 1652 | 2017-03-07 12:01:01.370 plugin-container[1653:10297] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 12:01:01 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:01:01 INFO - PROCESS | 1652 | 2017-03-07 12:01:01.373 plugin-container[1653:10297] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 12:01:01 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:01:01 INFO - PROCESS | 1652 | 2017-03-07 12:01:01.373 plugin-container[1653:10297] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:01:01 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 12:01:01 INFO - PROCESS | 1652 | colorDepth:24 12:01:01 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 12:01:01 INFO - PROCESS | 1652 | __metrics 12:01:01 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 12:01:02 INFO - TEST-INFO | 1652: exit 0 12:01:02 INFO - Browser initialized. 12:01:02 INFO - Running cycle 1/1 for kraken test... 12:01:02 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/tmpLiQ3wl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:01:04 INFO - PROCESS | 1654 | 2017-03-07 12:01:04.871 plugin-container[1655:10420] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 12:01:04 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:01:04 INFO - PROCESS | 1654 | 2017-03-07 12:01:04.871 plugin-container[1655:10420] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 12:01:04 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:01:04 INFO - PROCESS | 1654 | 2017-03-07 12:01:04.871 plugin-container[1655:10420] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:01:14 INFO - PROCESS | 1654 | RSS: Main: 226877440 12:01:14 INFO - PROCESS | 1654 | 12:01:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 12:01:18 INFO - PROCESS | 1654 | RSS: Main: 240926720 12:01:18 INFO - PROCESS | 1654 | 12:01:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 12:01:21 INFO - PROCESS | 1654 | RSS: Main: 249405440 12:01:21 INFO - PROCESS | 1654 | 12:01:25 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 12:01:25 INFO - PROCESS | 1654 | RSS: Main: 252059648 12:01:25 INFO - PROCESS | 1654 | 12:01:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 12:01:28 INFO - PROCESS | 1654 | RSS: Main: 254316544 12:01:28 INFO - PROCESS | 1654 | 12:01:30 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:01:31 INFO - PROCESS | 1654 | RSS: Main: 254668800 12:01:31 INFO - PROCESS | 1654 | 12:01:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 12:01:35 INFO - PROCESS | 1654 | RSS: Main: 260706304 12:01:35 INFO - PROCESS | 1654 | 12:01:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 12:01:39 INFO - PROCESS | 1654 | RSS: Main: 258420736 12:01:39 INFO - PROCESS | 1654 | 12:01:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 12:01:43 INFO - PROCESS | 1654 | RSS: Main: 258240512 12:01:43 INFO - PROCESS | 1654 | 12:01:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:01:45 INFO - PROCESS | 1654 | RSS: Main: 259985408 12:01:45 INFO - PROCESS | 1654 | 12:01:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:01:48 INFO - PROCESS | 1654 | RSS: Main: 259919872 12:01:48 INFO - PROCESS | 1654 | 12:01:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:01:50 INFO - PROCESS | 1654 | RSS: Main: 260194304 12:01:50 INFO - PROCESS | 1654 | 12:01:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:01:54 INFO - PROCESS | 1654 | RSS: Main: 260505600 12:01:54 INFO - PROCESS | 1654 | 12:01:57 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:01:58 INFO - PROCESS | 1654 | RSS: Main: 268697600 12:01:58 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 12:02:00 INFO - PROCESS | 1654 | RSS: Main: 271523840 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | __start_tp_report 12:02:00 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 12:02:00 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 12:02:00 INFO - PROCESS | 1654 | |i|pagename|runs| 12:02:00 INFO - PROCESS | 1654 | |0;ai-astar;108;126;119;119;121;122;121;121;120;121 12:02:00 INFO - PROCESS | 1654 | |1;audio-beat-detection;145;111;100;163;142;102;135;141;142;114 12:02:00 INFO - PROCESS | 1654 | |2;audio-dft;212;199;249;282;271;220;200;195;194;194 12:02:00 INFO - PROCESS | 1654 | |3;audio-fft;84;54;55;113;84;52;75;82;68;52 12:02:00 INFO - PROCESS | 1654 | |4;audio-oscillator;95;72;82;73;82;98;114;100;87;83 12:02:00 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;104;89;88;120;120;88;96;89;79;134 12:02:00 INFO - PROCESS | 1654 | |6;imaging-darkroom;122;110;98;146;150;100;147;151;104;126 12:02:00 INFO - PROCESS | 1654 | |7;imaging-desaturate;92;144;131;144;141;90;91;91;107;140 12:02:00 INFO - PROCESS | 1654 | |8;json-parse-financial;66;66;66;66;66;66;67;69;66;63 12:02:00 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;68;84;66;68;64;56;59;57;58;60 12:02:00 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;87;88;86;89;89;90;90;89;95;89 12:02:00 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;147;158;144;140;139;142;149;150;195;151 12:02:00 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;176;209;170;175;167;210;201;209;228;210 12:02:00 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;69;79;94;103;69;59;65;78;80;85 12:02:00 INFO - PROCESS | 1654 | __end_tp_report 12:02:00 INFO - PROCESS | 1654 | __start_cc_report 12:02:00 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,986 12:02:00 INFO - PROCESS | 1654 | __end_cc_report 12:02:00 INFO - PROCESS | 1654 | __startTimestamp1488916920634__endTimestamp 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | ------- Summary: start ------- 12:02:00 INFO - PROCESS | 1654 | Number of tests: 14 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:119.80 Median:121.00 stddev:4.59 (3.8%) stddev-sans-first:2.09 12:02:00 INFO - PROCESS | 1654 | Values: 108.0 126.0 119.0 119.0 121.0 122.0 121.0 121.0 120.0 121.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:141.50 stddev:21.20 (15.0%) stddev-sans-first:21.74 12:02:00 INFO - PROCESS | 1654 | Values: 145.0 111.0 100.0 163.0 142.0 102.0 135.0 141.0 142.0 114.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:221.60 Median:216.00 stddev:33.57 (15.5%) stddev-sans-first:35.43 12:02:00 INFO - PROCESS | 1654 | Values: 212.0 199.0 249.0 282.0 271.0 220.0 200.0 195.0 194.0 194.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:71.90 Median:71.50 stddev:19.74 (27.6%) stddev-sans-first:20.45 12:02:00 INFO - PROCESS | 1654 | Values: 84.0 54.0 55.0 113.0 84.0 52.0 75.0 82.0 68.0 52.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:88.60 Median:82.50 stddev:13.10 (15.9%) stddev-sans-first:13.69 12:02:00 INFO - PROCESS | 1654 | Values: 95.0 72.0 82.0 73.0 82.0 98.0 114.0 100.0 87.0 83.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:100.70 Median:88.00 stddev:18.12 (20.6%) stddev-sans-first:19.18 12:02:00 INFO - PROCESS | 1654 | Values: 104.0 89.0 88.0 120.0 120.0 88.0 96.0 89.0 79.0 134.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:125.40 Median:146.50 stddev:21.74 (14.8%) stddev-sans-first:23.03 12:02:00 INFO - PROCESS | 1654 | Values: 122.0 110.0 98.0 146.0 150.0 100.0 147.0 151.0 104.0 126.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:117.10 Median:117.00 stddev:24.87 (21.3%) stddev-sans-first:24.66 12:02:00 INFO - PROCESS | 1654 | Values: 92.0 144.0 131.0 144.0 141.0 90.0 91.0 91.0 107.0 140.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:66.10 Median:66.00 stddev:1.45 (2.2%) stddev-sans-first:1.54 12:02:00 INFO - PROCESS | 1654 | Values: 66.0 66.0 66.0 66.0 66.0 66.0 67.0 69.0 66.0 63.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:64.00 Median:65.00 stddev:8.34 (12.8%) stddev-sans-first:8.72 12:02:00 INFO - PROCESS | 1654 | Values: 68.0 84.0 66.0 68.0 64.0 56.0 59.0 57.0 58.0 60.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:89.00 stddev:2.39 (2.7%) stddev-sans-first:2.40 12:02:00 INFO - PROCESS | 1654 | Values: 87.0 88.0 86.0 89.0 89.0 90.0 90.0 89.0 95.0 89.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:149.50 stddev:16.32 (10.9%) stddev-sans-first:17.23 12:02:00 INFO - PROCESS | 1654 | Values: 147.0 158.0 144.0 140.0 139.0 142.0 149.0 150.0 195.0 151.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.50 Median:209.00 stddev:21.43 (10.3%) stddev-sans-first:21.54 12:02:00 INFO - PROCESS | 1654 | Values: 176.0 209.0 170.0 175.0 167.0 210.0 201.0 209.0 228.0 210.0 12:02:00 INFO - PROCESS | 1654 | 12:02:00 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.10 Median:78.50 stddev:13.44 (17.1%) stddev-sans-first:13.85 12:02:00 INFO - PROCESS | 1654 | Values: 69.0 79.0 94.0 103.0 69.0 59.0 65.0 78.0 80.0 85.0 12:02:00 INFO - PROCESS | 1654 | -------- Summary: end -------- 12:02:00 INFO - PROCESS | 1654 | 12:02:01 INFO - TEST-INFO | 1654: exit 0 12:02:01 INFO - TEST-OK | kraken | took 63034ms 12:02:01 INFO - SUITE-END | took 1181s 12:02:01 INFO - Completed test suite (00:19:41) 12:02:01 INFO - javascript benchmark 12:02:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7657.392692914844, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3462.0479368173756, 3471.9479207811873, 3486.2504561519268, 3497.0732755819704, 3525.7884526928387, 5769.7115144242825, 5994.0, 6000.039993601024, 6045.576809623322, 6060.969695151518, 4780.378550788392, 5316.0, 5822.9809783287865, 5962.552808539366, 6087.878242435148, 5297.337832770903, 5349.3045904032515, 5458.590605704569, 5475.561955043607, 5516.172574113895, 5360.303160589118, 5420.322459692538, 5483.972580137094, 5506.091494903336, 5513.61404701671, 5347.037533244014, 5387.973060134714, 5489.34127904654, 5508.779648814043, 5520.66875987438, 5429.294191755086, 5443.673379597237, 5445.237666726662, 5446.428125046892, 5489.368722596912, 5369.731513424314, 5381.784728610851, 5388.030154572176, 5404.351477822647, 5443.972780136113, 4857.63567732418, 5790.507806836424, 5823.67969761663, 5909.3204281507315, 5982.042873140276, 6137.897974673433, 6240.937590624081, 6277.937220627781, 6280.120783090371, 6296.685165741695, 6425.8393540161405, 6458.418742313214, 6462.32145624712, 6462.386073323027, 6495.870082598369, 5158.819441319536, 6135.049067394561, 6138.7237574309265, 6156.4151405617295, 6200.224971878515, 6108.22065524731, 6257.0614407838275, 6443.4523065539015, 6446.065320528497, 6466.870662586722, 6135.263289243823, 6150.446459818638, 6210.347913469111, 6280.466160376419, 6307.306196318401, 6317.3366796486625, 6379.0, 6398.680065996681, 6400.295967443578, 6410.45511131559, 6193.349698281616, 6300.936990630126, 6305.432511074026, 6308.684565771693, 6332.746690132343, 4719.669623126349, 5490.478404551562, 6172.506199504029, 6194.008958566607, 6202.224721909762, 4426.955730442654, 5084.33903592538, 5123.359580052494, 5132.717700526507, 5163.302954101224, 4572.0627271409285, 4580.106879158532, 4584.4498660160325, 4589.173948689268, 4604.723716577016, 5851.063829787214, 5928.644281343133, 5987.730552125165, 6022.09668549721, 6156.846078847975, 6220.409061139275, 6270.811875643738, 6285.654289014148, 6313.558050936482, 6337.746490140435, 6069.666168360694, 6111.938880611226, 6112.877742445126, 6138.263408391022, 6141.07883817431, 4890.315355850184, 5676.659400435987, 5689.544836413077, 5701.572382071361, 5718.456746609066, 5417.349918009864, 6209.96895015531, 6288.842778930563, 6392.9360706392345, 6407.135036769976, 6271.592346497463, 6272.153259309935, 6302.212223472066, 6312.652804095819, 6318.368163183645, 6431.517636177399, 6471.805845824632, 6486.0, 6502.902456463063, 6527.05357723137, 5831.79588714393, 6190.907136392868, 6211.937880621317, 6239.75040998355, 6270.811875643738, 5294.841154765363, 6367.331430199948, 6387.201599800025, 6390.265119511316, 6417.582857114273, 5379.273798037215, 6122.215193971364, 6156.860980718581, 6201.596896201732, 6310.400511951358, 6165.0, 6176.938230617637, 6231.9688401557705, 6245.344238854855, 6297.4647154992335, 4758.31004504338, 6023.518118550418, 6124.448799608057, 6127.877442451037, 6270.4670103039925, 5573.637713548606, 6199.907001394892, 6206.10011548332, 6221.626702397871, 6255.218097737783, 5936.851578710567, 6247.843803904939, 6253.843653908689, 6255.781047663492, 6265.060240963892], "value": 5802.3830994982445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.3165865792863, 733.8128777161825, 778.937684985201, 923.7275003873857, 926.9953650231748, 37629.623703762954, 38357.0, 39226.80386598066, 39269.0, 39276.21447571047, 37999.81000095003, 38123.42814857779, 38186.85439436814, 38218.61781382185, 38322.8083859581, 7670.041244844395, 7820.726274580391, 10345.689629311133, 10696.625618103368, 10760.40817755023, 10498.160147188206, 10542.0, 10580.470976451184, 10609.893901061007, 10614.04473597376, 10585.0, 10600.363978161335, 10632.840507392395, 10639.78720425591, 10648.893511064867, 10043.497825108716, 10526.368417894873, 10601.257911946166, 10612.310199836986, 10628.681139565826, 9632.47021413822, 10312.0, 10346.12057975073, 10388.065074143364, 10400.427976461368, 33813.0, 33886.32227355464, 34036.31927361464, 34117.829410852784, 34143.0, 3713.9442908356395, 3780.8298626561873, 3791.658750712449, 3801.82891769871, 3943.4676318696897, 25157.49685006308, 25249.747502524922, 25716.87141564299, 25807.35481612956, 26031.088911887993, 3012.774041946863, 3152.936941261162, 3174.587303650511, 3204.9198770030703, 3216.099492142204, 334696.0, 335023.99999999756, 335114.0, 336415.0, 339748.0, 36767.4484882727, 43608.78195609002, 43847.12305753867, 43848.0, 43970.0, 45824.770876145405, 45876.0, 45912.770436147606, 46182.538174618494, 46188.307175392394, 11305.604303849326, 12996.805047924288, 13075.346232688327, 13091.0, 13148.60554183376, 10964.890351096386, 11027.503762330714, 11128.332300062022, 11158.776824463464, 11174.10607151426, 9902.158316543175, 10537.367757934548, 11785.705357366134, 11833.171677982631, 11910.821337679941, 5111.7699703513435, 5268.104422248227, 5633.943660563424, 5638.167595531469, 5695.145728140729, 1081.9513121909376, 1132.6828488023366, 1151.6084531259412, 1186.258588382261, 1196.9945245993408, 19498.902505487382, 25799.613005804928, 26361.94552217928, 26538.46923061528, 26601.733982660313, 1025.5333823110466, 1154.7286387698884, 1233.3709807997807, 1276.7063575377715, 1280.475005247844, 12821.423035963404, 13751.243681597409, 13764.0, 13800.102993305403, 13829.239391833344, 452406.0, 453851.0, 500602.0, 598679.0, 600953.0, 49987.000259994595, 50243.74878125586, 50335.74832125889, 50402.49597503978, 50463.0, 1192.4991503568522, 1226.2458587968335, 1374.7250549890061, 1389.1873254146235, 1394.3725323604622, 27185.912563497237, 27588.17235482939, 27931.022414215404, 28114.85942570274, 28493.0, 40256.79871600623, 40465.79767101146, 40519.59480405158, 40624.59375406208, 40911.7954410226, 422590.0, 424794.0, 425559.0, 427170.0, 427798.0, 24840.503189936462, 28978.565321520615, 29965.850170749007, 30516.62675179578, 31575.684243157273, 38534.80732596319, 39357.212855743295, 39531.60468395394, 40324.790256292355, 40438.0, 27878.721212787612, 32220.83889580537, 32820.179495512806, 34299.657003429646, 34331.31337373288, 25440.491190176464, 30239.697603024568, 32779.68881244723, 32820.343593128484, 34035.65964340424, 32416.67583324231, 33118.006459806245, 33378.83310583432, 33791.662083378855, 33845.83077084697, 26341.73658263393, 26708.332291692863, 28603.14190574286, 29868.3902579915, 29929.55105673373, 18870.433886983414, 22826.0, 22955.65566516537, 23339.53320933606, 23504.059837606303], "value": 17992.69969651346, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.3533246154307, 383.2794346628338, 393.83032393790444, 400.9558948515663, 404.7470331043098, 19967.201311947523, 21547.569048619036, 21570.13719451224, 21582.244621438254, 21902.561948761035, 20571.28000519982, 21358.0, 21416.57166856666, 22120.889395553, 22174.88912555439, 6540.7383704651875, 6541.378569035934, 6795.388415042645, 6804.217514985766, 6805.761798337059, 6445.613263204223, 6787.015882697006, 7211.314925082109, 7588.089429268468, 7732.806679833021, 7097.716091356339, 7683.193264707209, 7695.307422331989, 7727.8068048298965, 7731.922680773177, 7514.2485751424965, 7571.091469023752, 7578.204288549678, 7586.051743532059, 7663.080430348366, 7144.142702875688, 7346.742863999788, 7405.7778266652085, 7429.145648250466, 7438.256174382573, 25530.48939021228, 25587.01074157628, 25868.0, 26000.609990850153, 26164.0, 2830.6461692288462, 2852.229897927569, 2949.8820047198087, 2952.5718770778117, 2957.6450825900815, 14563.49027784033, 15964.361425542964, 16098.517044488684, 16257.618102461305, 16790.58023549409, 2020.2220656671443, 2052.6613108837023, 2066.3904148276297, 2074.9481262968397, 2076.408223656251, 161505.0, 169292.1535392315, 171152.0, 171396.0, 173979.0, 20138.89930550338, 20443.38669839907, 20547.897260513902, 20751.273705420237, 20858.47853803637, 27471.313217169332, 31091.600877960547, 31498.842505787325, 31894.68105318917, 32017.0, 8307.418480706292, 9752.804943901083, 10089.0, 10109.876925845509, 10198.286119971532, 8267.958660206781, 8276.751697449086, 8295.958520207481, 8367.581620918929, 8478.533680647624, 8689.652413903374, 8713.869291960626, 9455.716328510156, 9641.0, 9644.0, 3458.429359155711, 3954.8220330085214, 4046.3323551614244, 4055.6958228133008, 4065.6747460203114, 937.0535758883576, 1025.4462590201208, 1027.2501074215863, 1032.194887987371, 1070.6252811515908, 20178.59642807165, 20772.480687982617, 21385.144594216057, 22002.339929802132, 22051.448713782283, 1047.9101734196502, 1052.1587222360868, 1077.4558847781977, 1085.685151306128, 1099.1042300525046, 9252.629894804268, 9275.953620231856, 10752.731181720363, 10855.2401331906, 11251.549938002388, 656260.0, 656290.0, 684831.0, 701977.0, 703322.0, 145390.2730486341, 155102.0, 157538.2123089377, 157928.0, 161473.0, 1085.7991271614776, 1229.8893099621077, 1284.858665546789, 1286.2346903592534, 1299.4672184404537, 18732.0, 18765.718514222026, 18916.716249256548, 18927.432177034712, 19046.047697614787, 61548.15292547201, 62383.37616623776, 63238.0, 63371.68314158584, 64178.71642567216, 87880.0, 88940.0, 89683.75790605287, 89705.10294897229, 92598.07401925894, 9847.901520984698, 11282.94358528202, 11355.318680879174, 11452.427378630868, 11790.94104529472, 12827.615171544869, 13064.54274100402, 13230.603081907559, 13282.468701251843, 13381.39783709716, 11055.778884422105, 11311.321320720783, 11348.602798901999, 11521.711957201138, 11594.246373985665, 11032.393218373067, 11051.834222486508, 11192.608258710907, 11416.257943233662, 11449.370284634091, 11129.276597021168, 11651.30092194471, 11745.647630571097, 11764.0, 11812.3503207321, 11452.275569140345, 11698.883011169779, 11770.58802941893, 11790.0, 11814.586489472827, 11041.227114102257, 11156.665300041012, 11330.206885517935, 11386.804385539399, 11435.313881167156], "value": 11681.341814537784, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1453.723792479429, 1514.469935522568, 1520.680657062017, 1521.497905691122, 1565.27214845097, 10451.73870653234, 10608.257421980465, 10875.728106797334, 11084.002439780417, 11123.72190695233, 10719.732006699836, 10869.945650271737, 10906.727331816708, 10907.618233361834, 10940.56237750491, 2337.474068334624, 2367.6803631509783, 2369.893354799038, 2374.0622454130607, 2377.500724847784, 2098.401955442699, 2277.6469647204626, 2315.386422598013, 2326.8952897119584, 2331.4870728439714, 2313.4447732544236, 2321.129576408847, 2328.266596022256, 2340.4616938104245, 2347.225415612852, 2008.3774030050586, 2236.4408897775556, 2255.977440225593, 2264.7735226477384, 2271.7387500437494, 2183.3559100065518, 2196.363054714131, 2210.3700445372992, 2214.4574579228033, 2217.7782221777857, 10735.033846953733, 10743.570257189704, 10799.730006749895, 10803.621873234399, 10833.133349332034, 5036.06832735945, 5664.57515686321, 5840.123981402781, 5856.502197313233, 5867.530597552228, 3132.592762940826, 3332.3668502984356, 3564.8574057037686, 3627.0025742920643, 3643.7813731176216, 1148.5118824499555, 1167.655541615217, 1192.6481687902003, 1204.692803335144, 1223.1621339382552, 12533.623991280276, 14361.138331700131, 14361.569152925429, 14417.639559011004, 14475.0, 3831.444440556104, 4570.337301091323, 4609.423822022247, 4666.673332866734, 4694.671373003857, 11119.27724697892, 11291.3789741565, 11328.037116844996, 11342.20604557673, 11391.259568128045, 5663.66018038919, 5667.894760521741, 5673.461021202979, 5681.119426488818, 5681.488666020078, 4949.604031677457, 4986.526280003394, 4999.825006124768, 5064.088464076502, 5085.160948443539, 3758.642928921748, 3802.334591446486, 3848.5189351331082, 3881.262560113569, 3884.0872394987155, 1034.8447732840134, 1036.7874585709912, 1038.3873514626255, 1048.3447845096814, 1050.3172937590475, 748.277911815101, 794.7496538590326, 798.5767543202112, 819.7827575692446, 825.7398919340509, 6418.165638467063, 6426.710798014101, 6646.767363142363, 6667.833304167338, 6698.732050718014, 949.1742184299703, 972.7081875437341, 977.4184360305607, 993.9563458368684, 1012.7620009297809, 4010.2781499329817, 4010.47863777707, 4215.262329092397, 4359.585839345257, 4597.264437689953, 10865.891341086646, 13094.541691040768, 13373.197608143542, 13376.598702038955, 13380.598582042554, 11638.76722465546, 12040.976516996154, 12830.679233019062, 12942.676433089247, 13082.084254102312, 8066.637001334837, 9535.618575256913, 9822.361546499598, 9864.260180486348, 9875.061869122423, 6431.614103153825, 6458.128152699325, 6619.238787539494, 6783.219929708147, 6905.965470172617, 9867.40795552269, 11787.705307367385, 11808.0, 11884.405779710807, 12207.38963051826, 11334.696509901467, 11642.94178529102, 11964.64106076819, 12073.034157267573, 12194.0, 8958.238549723086, 10012.249081319076, 10016.348937319051, 10017.348872323275, 10028.0, 9741.951290243504, 9980.500974951368, 10047.748806279902, 10052.949735251277, 10069.244806639677, 8815.823683526165, 9022.413543119676, 9059.320550958573, 9135.451872887648, 9148.268138548767, 8778.604962776819, 8802.91197088021, 8805.251553618014, 8851.955740221258, 8928.196462318163, 7746.845063098819, 7748.690052397841, 7781.494202877021, 7793.415493838098, 7801.0, 5349.170878513836, 5630.971845140748, 5746.425357464387, 5792.1311803229855, 5922.7927022554, 5810.4189581041555, 5863.888999959926, 5972.850678733066, 5983.551233657406, 6087.726052327567], "value": 4989.272633810707, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.5672834020631, 352.28661959531945, 362.4852709153004, 368.39031403027985, 376.29632587062196, 5067.906753712518, 5106.872328191797, 5304.681719096857, 5439.456054394558, 5467.535259502942, 4408.0604534005015, 4927.852164435073, 5126.461721519243, 5251.92335571208, 5279.676942145454, 3787.9621203787883, 3940.054386947126, 4466.392098844413, 4567.520410356906, 4589.724616523003, 3825.2923209206247, 4151.0, 4606.953930460686, 4628.745419001953, 4670.719756814585, 3888.47505586745, 4449.26587113126, 4560.635149188073, 4574.931376029363, 4602.470715867667, 4092.3452247640535, 4197.328427451593, 4596.827808908741, 4601.953980460186, 4637.420322459692, 3796.240751849641, 3943.585923478051, 4091.590840915914, 4353.25994580922, 4417.690761646654, 4827.131116399047, 5105.8555406729265, 5134.532956327981, 5158.92261616076, 5176.127249376595, 4857.514248575149, 5214.530692237717, 5267.28891599633, 5293.841184764463, 5306.893862122774, 4933.535150688633, 5472.644278121949, 5700.543956483471, 5705.0, 5780.450857168562, 4809.927851082237, 4821.927671084936, 4830.0, 4847.684900481493, 4849.636277279218, 5104.69371837691, 5239.170847742214, 5832.791964419553, 5835.679037652885, 5980.551458640576, 4403.427554417905, 4791.185498465269, 4794.688345257547, 4935.259711043373, 4965.48141191514, 5093.663650309053, 5094.439611642716, 5104.452405578088, 5115.335006449138, 5140.37840701245, 4657.464391594942, 4833.661643684979, 4868.756562171877, 5263.1841964548785, 5318.149096144675, 5064.898702025939, 5412.431694672003, 5646.265985421869, 5688.0, 5705.62913410627, 1739.3912130754136, 1749.5013921032444, 1749.7112976358758, 1751.808770036362, 1761.779777527809, 1236.4003458322886, 1290.9930254401581, 1371.2800779590796, 1381.2334154544324, 1406.6706961920886, 4835.95164048362, 4952.702837829742, 5233.215017747369, 5272.841814745564, 5279.762410691528, 4981.886321049345, 5414.160805075219, 5571.718504743932, 5698.0, 5811.4479124483105, 4831.202851529459, 4876.0, 4942.332785073969, 5178.317046959678, 5244.475552444725, 2634.1439032314574, 2743.341598016462, 3029.909102726922, 3059.265776213693, 3077.7680689052554, 5600.60795744294, 5723.570732195102, 5838.7080645967535, 5871.2073870027, 6075.665838378844, 5203.947960520422, 5698.686572238566, 5849.718609742204, 5882.764689412461, 5985.401459854067, 5158.509941555471, 5477.890442191054, 5552.5008247773685, 5813.982553053199, 6047.969760151259, 5128.538431541179, 5141.794328226829, 5609.80365687199, 5714.114372241991, 5827.59206855533, 4570.611498022572, 5043.856361046467, 5175.663581867167, 5508.697021663686, 5751.9424805751405, 5916.615419997686, 6079.604825686316, 6092.177556029879, 6092.847678808065, 6094.2077529920825, 4419.535948725466, 4790.401199850019, 4800.207965685623, 4995.625328100335, 5114.74426278692, 4716.64625153108, 5146.614003949793, 5267.630416091767, 5727.914081288867, 5858.8242352729485, 5043.899122017612, 5044.722540260321, 5237.816676416307, 5657.179708942263, 5675.701340956919, 5033.874153146201, 5653.349864765604, 5659.547236221175, 5662.348829884616, 5690.829275121753, 5083.237514372874, 5193.792248310177, 5275.498827611371, 5494.066008778437, 5553.944460555343, 4926.753662316798, 5064.594832413324, 5340.169710053678, 5486.8477619698715, 5599.552035837205, 4380.3210502372185, 5136.845894623168, 5217.0, 5304.549113325411, 5515.503604675599], "value": 4380.547099753898, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.3580309819612, 712.5190496414918, 754.0235395163263, 828.581566309014, 847.6818547159166, 15509.224538773058, 16335.34658613654, 16465.176741162923, 16519.091449970263, 16522.91738541307, 13091.429028516568, 13135.802962955564, 13424.463021479154, 14326.355314010869, 15475.994060386067, 901.9549022548863, 922.0456827183866, 925.9953700231489, 926.263620421763, 927.7541451515353, 810.2100452059225, 865.3682811547574, 923.3490389275545, 924.694850699271, 933.6032186320821, 982.3074732313737, 982.8427451607744, 984.9753756156082, 985.9556319965583, 986.9358491698052, 831.5509624802629, 979.1236843025453, 980.9656662016831, 981.3277904635312, 981.3572110267714, 720.3516834848625, 726.5858460677416, 732.6959311885561, 732.7728404194663, 733.2777214443769, 14922.552323430315, 14980.775288370683, 14986.025908315925, 15155.469558565397, 15857.207139642971, 11581.478833452515, 13744.518941836988, 14015.0, 14035.298235088307, 14329.140251584939, 11261.774764504664, 11471.827922581168, 11601.679664067136, 11733.882661173364, 12227.14409991301, 3101.7518598512065, 3597.172650290448, 3658.1769101952113, 3668.559772827278, 3680.6319368063246, 115247.0, 118053.819461806, 118234.0, 118601.0, 119526.4023679876, 10577.31247468912, 10688.946555267174, 10718.73203169927, 11025.0, 11122.277051991756, 22299.99650015721, 22364.217252396415, 22536.661950070764, 22559.436014099778, 22608.626094260195, 7465.85068298631, 7494.250574942462, 7604.12552556452, 7692.692292308245, 7797.883031754528, 3686.9815650921573, 4084.326086195804, 4140.710150289511, 4204.852830150991, 4235.682323825726, 3383.035834787074, 3436.9656303437146, 3449.275652113034, 3470.878519251814, 3484.3553942520716, 637.9968100159563, 639.523554951564, 643.112504743451, 657.6251536624078, 657.9144711187515, 633.9144215530845, 660.6663634864459, 687.7489716253632, 689.648279377511, 715.3526058916689, 10130.0, 10235.488225588691, 10504.0, 10532.789344213073, 10632.574697012033, 660.9933900660932, 669.7174910047303, 686.4062585863293, 707.4906067631297, 716.1120210938399, 4164.562720914321, 4735.289706544046, 4892.363992681, 4902.950970490321, 4955.182394904872, 92106.0, 105567.0, 108692.0, 109118.3632245517, 111355.44322278336, 92387.0, 92532.0, 93512.0, 93851.0, 94247.52876235712, 81192.59403702944, 82983.5850820742, 83093.58453207817, 83536.0, 87111.99999999873, 9339.859902101338, 9507.096825801538, 9540.522973851419, 9580.377275477073, 9591.664291749756, 43088.56911430971, 45661.54338456573, 47483.76258118687, 48032.0, 53393.0, 53068.73465632647, 58074.0, 59394.0, 65222.0, 67000.0, 12462.379890614162, 12628.494860205488, 12664.493420263454, 12896.935515322364, 13257.66855828612, 11687.051250309483, 14352.56942291733, 14529.200893950934, 14575.562733118024, 14764.852351476777, 13165.670858228621, 13428.0, 13598.388072536958, 14006.369713363127, 14058.507952221624, 11515.25150865191, 13357.398917048562, 13669.0, 14021.859781402056, 14120.717585648435, 13215.471381144647, 13297.335133243105, 13361.26513041792, 13671.111377760812, 13964.0, 10823.188260880308, 10829.566817327219, 11023.393713345842, 11192.328460292409, 11285.887141128484, 9647.9035209647, 9867.506624668595, 10371.303461100017, 10677.46612669379, 11219.0], "value": 7562.841395799992, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1619.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 126.0, 119.0, 119.0, 121.0, 122.0, 121.0, 121.0, 120.0, 121.0], "value": 119.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 100.0, 163.0, 142.0, 102.0, 135.0, 141.0, 142.0, 114.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 199.0, 249.0, 282.0, 271.0, 220.0, 200.0, 195.0, 194.0, 194.0], "value": 221.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 54.0, 55.0, 113.0, 84.0, 52.0, 75.0, 82.0, 68.0, 52.0], "value": 71.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 72.0, 82.0, 73.0, 82.0, 98.0, 114.0, 100.0, 87.0, 83.0], "value": 88.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 89.0, 88.0, 120.0, 120.0, 88.0, 96.0, 89.0, 79.0, 134.0], "value": 100.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 110.0, 98.0, 146.0, 150.0, 100.0, 147.0, 151.0, 104.0, 126.0], "value": 125.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 144.0, 131.0, 144.0, 141.0, 90.0, 91.0, 91.0, 107.0, 140.0], "value": 117.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 67.0, 69.0, 66.0, 63.0], "value": 66.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 84.0, 66.0, 68.0, 64.0, 56.0, 59.0, 57.0, 58.0, 60.0], "value": 64.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 86.0, 89.0, 89.0, 90.0, 90.0, 89.0, 95.0, 89.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 158.0, 144.0, 140.0, 139.0, 142.0, 149.0, 150.0, 195.0, 151.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 209.0, 170.0, 175.0, 167.0, 210.0, 201.0, 209.0, 228.0, 210.0], "value": 195.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 94.0, 103.0, 69.0, 59.0, 65.0, 78.0, 80.0, 85.0], "value": 78.1, "unit": "score"}]}]} 12:02:01 INFO - Return code: 0 12:02:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:02:01 ERROR - # TBPL SUCCESS # 12:02:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:02:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:02:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:02:01 INFO - 2017-03-07 12:02:01.944 screenresolution[1659:10835] starting screenresolution argv=screenresolution get 12:02:01 INFO - 2017-03-07 12:02:01.947 screenresolution[1659:10835] Display 0: 1600x1200x32@60 12:02:01 INFO - 2017-03-07 12:02:01.953 screenresolution[1660:10838] starting screenresolution argv=screenresolution list 12:02:01 INFO - Available Modes on Display 0 12:02:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:02:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:02:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:02:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:02:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:02:02 INFO - Intel Iris: 12:02:02 INFO - Chipset Model: Intel Iris 12:02:02 INFO - Type: GPU 12:02:02 INFO - Bus: Built-In 12:02:02 INFO - VRAM (Dynamic, Max): 1536 MB 12:02:02 INFO - Vendor: Intel (0x8086) 12:02:02 INFO - Device ID: 0x0a2e 12:02:02 INFO - Revision ID: 0x0009 12:02:02 INFO - Displays: 12:02:02 INFO - Display: 12:02:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:02:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:02:02 INFO - Display Serial Number: 1600x1200 60 12:02:02 INFO - Main Display: Yes 12:02:02 INFO - Mirror: Off 12:02:02 INFO - Online: Yes 12:02:02 INFO - Rotation: Supported 12:02:02 INFO - Return code: 0 12:02:02 INFO - Running post-action listener: _resource_record_post_action 12:02:02 INFO - [mozharness: 2017-03-07 20:02:02.188228Z] Finished run-tests step (success) 12:02:02 INFO - Running post-run listener: _resource_record_post_run 12:02:02 INFO - Total resource usage - Wall time: 1203s; CPU: 55.0%; Read bytes: 38963712; Write bytes: 516122624; Read time: 252; Write time: 1617 12:02:02 INFO - TinderboxPrint: CPU usage
55.0% 12:02:02 INFO - TinderboxPrint: I/O read bytes / time
38,963,712 / 252 12:02:02 INFO - TinderboxPrint: I/O write bytes / time
516,122,624 / 1,617 12:02:02 INFO - TinderboxPrint: CPU idle
2,168.4 (45.0%) 12:02:02 INFO - TinderboxPrint: CPU system
967.6 (20.1%) 12:02:02 INFO - TinderboxPrint: CPU user
1,677.8 (34.9%) 12:02:02 INFO - TinderboxPrint: Swap in / out
248,991,744 / 0 12:02:02 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 203684352; Write bytes: 203724800; Read time: 15568; Write time: 432 12:02:02 INFO - run-tests - Wall time: 1183s; CPU: 55.0%; Read bytes: 37116416; Write bytes: 301625344; Read time: 234; Write time: 1149 12:02:02 INFO - Running post-run listener: _upload_blobber_files 12:02:02 INFO - Blob upload gear active. 12:02:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:02:02 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 12:02:02 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'] 12:02:02 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 12:02:03 INFO - (blobuploader) - INFO - Open directory for files ... 12:02:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:02:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:02:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:03 INFO - (blobuploader) - INFO - Done attempting. 12:02:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:02:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:02:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:04 INFO - (blobuploader) - INFO - Done attempting. 12:02:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:02:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:02:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:05 INFO - (blobuploader) - INFO - Done attempting. 12:02:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:02:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:02:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:08 INFO - (blobuploader) - INFO - Done attempting. 12:02:08 INFO - (blobuploader) - INFO - Iteration through files over. 12:02:08 INFO - Return code: 0 12:02:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:02:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:02:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c0f603b1f22e5eae0f15a10ead671f0b42530c3b8ee3991ab8f380f00463eb739b5dfd3c23d3833557f8d4a18374238b6c77327efc92d0592cfa6e7e606c4d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f64ccf335a79c68633014cb56232c8f3f82306d4f03dc6b36b273364d39ae82bfeee360e32f647af3e7bf5b935f5f50ecedd246c27f847ebb065f2d66045a9c0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6b672027e862360f283c62c58eb9c5aac4613d8ce0eb3a6c150cad61dc087f7f004fac5eb14ecd35ffc0ba45c7e0459ff9133ab23f5131869e4c9400642cde9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b674684dd7d2ad01ff047489db654f43acde536ad5aa86c501ce1c2e32e186c64957b1e6b6a07c835ba01ae6818e8cc5b6ca971faa74d5a95af32e9fa5b69383"} 12:02:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:02:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:02:08 INFO - Contents: 12:02:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c0f603b1f22e5eae0f15a10ead671f0b42530c3b8ee3991ab8f380f00463eb739b5dfd3c23d3833557f8d4a18374238b6c77327efc92d0592cfa6e7e606c4d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f64ccf335a79c68633014cb56232c8f3f82306d4f03dc6b36b273364d39ae82bfeee360e32f647af3e7bf5b935f5f50ecedd246c27f847ebb065f2d66045a9c0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6b672027e862360f283c62c58eb9c5aac4613d8ce0eb3a6c150cad61dc087f7f004fac5eb14ecd35ffc0ba45c7e0459ff9133ab23f5131869e4c9400642cde9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b674684dd7d2ad01ff047489db654f43acde536ad5aa86c501ce1c2e32e186c64957b1e6b6a07c835ba01ae6818e8cc5b6ca971faa74d5a95af32e9fa5b69383"} 12:02:08 INFO - Running post-run listener: copy_logs_to_upload_dir 12:02:08 INFO - Copying logs to upload dir... 12:02:08 INFO - mkdir: /builds/slave/test/build/upload/logs 12:02:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.877751 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-07 12:02:08.147259) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-07 12:02:08.151084) ========= 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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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/5c0f603b1f22e5eae0f15a10ead671f0b42530c3b8ee3991ab8f380f00463eb739b5dfd3c23d3833557f8d4a18374238b6c77327efc92d0592cfa6e7e606c4d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f64ccf335a79c68633014cb56232c8f3f82306d4f03dc6b36b273364d39ae82bfeee360e32f647af3e7bf5b935f5f50ecedd246c27f847ebb065f2d66045a9c0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6b672027e862360f283c62c58eb9c5aac4613d8ce0eb3a6c150cad61dc087f7f004fac5eb14ecd35ffc0ba45c7e0459ff9133ab23f5131869e4c9400642cde9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b674684dd7d2ad01ff047489db654f43acde536ad5aa86c501ce1c2e32e186c64957b1e6b6a07c835ba01ae6818e8cc5b6ca971faa74d5a95af32e9fa5b69383"} build_url:https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011138 build_url: 'https://queue.taskcluster.net/v1/task/OSUD1lmgSRmfIwIlJrU8LQ/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/5c0f603b1f22e5eae0f15a10ead671f0b42530c3b8ee3991ab8f380f00463eb739b5dfd3c23d3833557f8d4a18374238b6c77327efc92d0592cfa6e7e606c4d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f64ccf335a79c68633014cb56232c8f3f82306d4f03dc6b36b273364d39ae82bfeee360e32f647af3e7bf5b935f5f50ecedd246c27f847ebb065f2d66045a9c0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6b672027e862360f283c62c58eb9c5aac4613d8ce0eb3a6c150cad61dc087f7f004fac5eb14ecd35ffc0ba45c7e0459ff9133ab23f5131869e4c9400642cde9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b674684dd7d2ad01ff047489db654f43acde536ad5aa86c501ce1c2e32e186c64957b1e6b6a07c835ba01ae6818e8cc5b6ca971faa74d5a95af32e9fa5b69383"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-07 12:02:08.184637) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 12:02:08.184953) ========= 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.9XdUlPcC8Y/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.4icBxn5TmQ/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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.005273 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 12:02:08.233331) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-07 12:02:08.233629) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-07 12:02:08.233939) ========= ========= Total master_lag: 0.18 =========