builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0323 starttime: 1492794541.32 results: success (0) buildid: 20170421084120 builduid: 1c391f3310904a7baa1d4022a9db8cc2 revision: 188cc9e6d18d8dff122b0190e19c1e658b6d0f29 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-21 10:09:01.316525) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-21 10:09:01.317049) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-21 10:09:01.317340) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-21 10:09:01.349266) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 10:09:01.349642) ========= 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.WELOgCndKJ/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.QwQZLQu7DR/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-21 10:09:01-- 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.1M=0.001s 2017-04-21 10:09:01 (18.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086914 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 10:09:01.461450) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 10:09:01.461781) ========= 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.WELOgCndKJ/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.QwQZLQu7DR/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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.025854 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 10:09:01.513793) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-21 10:09:01.514166) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 188cc9e6d18d8dff122b0190e19c1e658b6d0f29 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 188cc9e6d18d8dff122b0190e19c1e658b6d0f29 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WELOgCndKJ/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.QwQZLQu7DR/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-21 10:09:01,624 truncating revision to first 12 chars 2017-04-21 10:09:01,624 Setting DEBUG logging. 2017-04-21 10:09:01,624 attempt 1/10 2017-04-21 10:09:01,624 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/188cc9e6d18d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-21 10:09:03,741 unpacking tar archive at: mozilla-beta-188cc9e6d18d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.383553 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-21 10:09:03.923041) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-21 10:09:03.923356) ========= script_repo_revision: 188cc9e6d18d8dff122b0190e19c1e658b6d0f29 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-21 10:09:03.923753) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-21 10:09:03.924030) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-21 10:09:03.944272) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-04-21 10:09:03.944683) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WELOgCndKJ/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.QwQZLQu7DR/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 10:09:04 INFO - MultiFileLogger online at 20170421 10:09:04 in /builds/slave/test 10:09:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:09:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:09:04 INFO - {'append_to_log': False, 10:09:04 INFO - 'base_work_dir': '/builds/slave/test', 10:09:04 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:09:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:09:04 INFO - 'branch': 'Mozilla-Beta', 10:09:04 INFO - 'buildbot_json_path': 'buildprops.json', 10:09:04 INFO - 'config_files': ('talos/mac_config.py',), 10:09:04 INFO - 'default_actions': ('clobber', 10:09:04 INFO - 'read-buildbot-config', 10:09:04 INFO - 'download-and-extract', 10:09:04 INFO - 'populate-webroot', 10:09:04 INFO - 'create-virtualenv', 10:09:04 INFO - 'install', 10:09:04 INFO - 'run-tests'), 10:09:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:09:04 INFO - 'download_minidump_stackwalk': True, 10:09:04 INFO - 'download_symbols': 'ondemand', 10:09:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:09:04 INFO - 'tooltool.py': '/tools/tooltool.py', 10:09:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:09:04 INFO - '/tools/misc-python/virtualenv.py')}, 10:09:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:09:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:09:04 INFO - 'gecko_profile': False, 10:09:04 INFO - 'gecko_profile_interval': 0, 10:09:04 INFO - 'installer_path': 'installer.exe', 10:09:04 INFO - 'log_level': 'info', 10:09:04 INFO - 'log_name': 'talos', 10:09:04 INFO - 'log_to_console': True, 10:09:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:09:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:09:04 INFO - 'opt_config_files': (), 10:09:04 INFO - 'pip_index': False, 10:09:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:09:04 INFO - 'cmd': ('bash', 10:09:04 INFO - '-c', 10:09:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:09:04 INFO - 'enabled': True, 10:09:04 INFO - 'halt_on_failure': False, 10:09:04 INFO - 'name': 'check_screen_resolution'},), 10:09:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:09:04 INFO - 'cmd': ('bash', 10:09:04 INFO - '-c', 10:09:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:09:04 INFO - 'enabled': True, 10:09:04 INFO - 'halt_on_failure': False, 10:09:04 INFO - 'name': 'check_screen_resolution'},), 10:09:04 INFO - 'run_cmd_checks_enabled': True, 10:09:04 INFO - 'suite': 'dromaeojs-e10s', 10:09:04 INFO - 'system_bits': '32', 10:09:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:09:04 INFO - 'title': 't-yosemite-r7-0323', 10:09:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:09:04 INFO - 'use_talos_json': True, 10:09:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:09:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:09:04 INFO - 'work_dir': 'build'} 10:09:04 INFO - [mozharness: 2017-04-21 17:09:04.160379Z] Running clobber step. 10:09:04 INFO - Running pre-action listener: _resource_record_pre_action 10:09:04 INFO - Running main action method: clobber 10:09:04 INFO - rmtree: /builds/slave/test/build 10:09:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:09:06 INFO - Running post-action listener: _resource_record_post_action 10:09:06 INFO - [mozharness: 2017-04-21 17:09:06.625522Z] Finished clobber step (success) 10:09:06 INFO - [mozharness: 2017-04-21 17:09:06.625650Z] Running read-buildbot-config step. 10:09:06 INFO - Running pre-action listener: _resource_record_pre_action 10:09:06 INFO - Running main action method: read_buildbot_config 10:09:06 INFO - Using buildbot properties: 10:09:06 INFO - { 10:09:06 INFO - "project": "", 10:09:06 INFO - "product": "firefox", 10:09:06 INFO - "script_repo_revision": "production", 10:09:06 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 10:09:06 INFO - "repository": "", 10:09:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 10:09:06 INFO - "buildid": "20170421084120", 10:09:06 INFO - "pgo_build": "False", 10:09:06 INFO - "basedir": "/builds/slave/test", 10:09:06 INFO - "buildnumber": 10, 10:09:06 INFO - "slavename": "t-yosemite-r7-0323", 10:09:06 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:09:06 INFO - "platform": "yosemite_r7", 10:09:06 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 10:09:06 INFO - "branch": "mozilla-beta", 10:09:06 INFO - "repo_path": "releases/mozilla-beta", 10:09:06 INFO - "revision": "188cc9e6d18d8dff122b0190e19c1e658b6d0f29", 10:09:06 INFO - "stage_platform": "macosx64", 10:09:06 INFO - "builduid": "1c391f3310904a7baa1d4022a9db8cc2", 10:09:06 INFO - "slavebuilddir": "test" 10:09:06 INFO - } 10:09:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 10:09:06 INFO - Running post-action listener: _resource_record_post_action 10:09:06 INFO - [mozharness: 2017-04-21 17:09:06.629202Z] Finished read-buildbot-config step (success) 10:09:06 INFO - [mozharness: 2017-04-21 17:09:06.629359Z] Running download-and-extract step. 10:09:06 INFO - Running pre-action listener: _resource_record_pre_action 10:09:06 INFO - Running main action method: download_and_extract 10:09:06 INFO - mkdir: /builds/slave/test/build/tests 10:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:09:06 INFO - trying https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 10:09:06 INFO - Downloading https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:09:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 10:09:07 INFO - Downloaded 1165 bytes. 10:09:07 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:09:07 INFO - Using the following test package requirements: 10:09:07 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:09:07 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:09:07 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 10:09:07 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:09:07 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 10:09:07 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:09:07 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:09:07 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 10:09:07 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:09:07 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:09:07 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 10:09:07 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:09:07 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 10:09:07 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:09:07 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 10:09:07 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:09:07 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 10:09:07 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 10:09:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:09:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:09:07 INFO - Fetch https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:09:09 INFO - Expected file size: 51220748 10:09:09 INFO - Obtained file size: 51220748 10:09:25 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 10:09:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:09:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:09:25 INFO - Fetch https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:09:27 INFO - Expected file size: 51220748 10:09:27 INFO - Obtained file size: 51220748 10:09:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 10:09:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 10:09:42 INFO - Fetch https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 10:09:43 INFO - Expected file size: 14404424 10:09:43 INFO - Obtained file size: 14404424 10:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:09:43 INFO - trying https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:09:43 INFO - Downloading https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 10:09:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 10:09:45 INFO - Downloaded 59543571 bytes. 10:09:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:09:45 INFO - mkdir: /builds/slave/test/properties 10:09:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:09:45 INFO - Writing to file /builds/slave/test/properties/build_url 10:09:45 INFO - Contents: 10:09:45 INFO - build_url:https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:09:46 INFO - Running post-action listener: _resource_record_post_action 10:09:46 INFO - Running post-action listener: set_extra_try_arguments 10:09:46 INFO - [mozharness: 2017-04-21 17:09:46.269746Z] Finished download-and-extract step (success) 10:09:46 INFO - [mozharness: 2017-04-21 17:09:46.269897Z] Running populate-webroot step. 10:09:46 INFO - Running pre-action listener: _resource_record_pre_action 10:09:46 INFO - Running main action method: populate_webroot 10:09:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:09:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:09:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:09:46 INFO - '--tppagecycles', 10:09:46 INFO - '7'], 10:09:46 INFO - 'tests': ['tsvgm']}}, 10:09:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:09:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:09:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:09:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:09:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:09:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:09:46 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:09:46 INFO - 'talos_options': ['--disable-e10s'], 10:09:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:09:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:09:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:09:46 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:09:46 INFO - 'talos_options': ['--disable-e10s'], 10:09:46 INFO - 'tests': ['damp', 'tps']}, 10:09:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:09:46 INFO - 'tests': ['damp', 'tps']}, 10:09:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:09:46 INFO - 'tests': ['dromaeo_dom']}, 10:09:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:09:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:09:46 INFO - 'tests': ['basic_compositor_video']}, 10:09:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:09:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:09:46 INFO - 'tests': ['a11yr', 10:09:46 INFO - 'ts_paint', 10:09:46 INFO - 'tpaint', 10:09:46 INFO - 'sessionrestore', 10:09:46 INFO - 'sessionrestore_no_auto_restore', 10:09:46 INFO - 'tabpaint']}, 10:09:46 INFO - 'other-e10s': {'tests': ['a11yr', 10:09:46 INFO - 'ts_paint', 10:09:46 INFO - 'tpaint', 10:09:46 INFO - 'sessionrestore', 10:09:46 INFO - 'sessionrestore_no_auto_restore', 10:09:46 INFO - 'tabpaint']}, 10:09:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:09:46 INFO - 'ts_paint', 10:09:46 INFO - 'tpaint', 10:09:46 INFO - 'sessionrestore', 10:09:46 INFO - 'sessionrestore_no_auto_restore', 10:09:46 INFO - 'tabpaint']}, 10:09:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:09:46 INFO - 'ts_paint', 10:09:46 INFO - 'tpaint', 10:09:46 INFO - 'sessionrestore', 10:09:46 INFO - 'sessionrestore_no_auto_restore', 10:09:46 INFO - 'tabpaint']}, 10:09:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:09:46 INFO - 'tests': ['a11yr', 10:09:46 INFO - 'ts_paint', 10:09:46 INFO - 'tpaint', 10:09:46 INFO - 'sessionrestore', 10:09:46 INFO - 'sessionrestore_no_auto_restore', 10:09:46 INFO - 'tabpaint']}, 10:09:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:09:46 INFO - 'tests': ['a11yr', 10:09:46 INFO - 'ts_paint', 10:09:46 INFO - 'tpaint', 10:09:46 INFO - 'sessionrestore', 10:09:46 INFO - 'sessionrestore_no_auto_restore', 10:09:46 INFO - 'tabpaint']}, 10:09:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:09:46 INFO - 'tests': ['tsvgx', 10:09:46 INFO - 'tsvgr_opacity', 10:09:46 INFO - 'tart', 10:09:46 INFO - 'tscrollx', 10:09:46 INFO - 'cart', 10:09:46 INFO - 'tsvg_static']}, 10:09:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:09:46 INFO - 'tsvgr_opacity', 10:09:46 INFO - 'tart', 10:09:46 INFO - 'tscrollx', 10:09:46 INFO - 'cart', 10:09:46 INFO - 'tsvg_static']}, 10:09:46 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:09:46 INFO - 'talos_options': ['--disable-e10s'], 10:09:46 INFO - 'tests': ['tp5o']}, 10:09:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:09:46 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:09:46 INFO - 'talos_options': ['--disable-e10s', 10:09:46 INFO - '--xperf_path', 10:09:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:09:46 INFO - 'tests': ['tp5n']}, 10:09:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:09:46 INFO - 'talos_options': ['--xperf_path', 10:09:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:09:46 INFO - 'tests': ['tp5n']}}, 10:09:46 INFO - 'talos.zip': {'path': '', 10:09:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:09:46 INFO - Running post-action listener: _resource_record_post_action 10:09:46 INFO - [mozharness: 2017-04-21 17:09:46.290442Z] Finished populate-webroot step (success) 10:09:46 INFO - [mozharness: 2017-04-21 17:09:46.290578Z] Running create-virtualenv step. 10:09:46 INFO - Running pre-action listener: _resource_record_pre_action 10:09:46 INFO - Running main action method: create_virtualenv 10:09:46 INFO - Creating virtualenv /builds/slave/test/build/venv 10:09:46 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 10:09:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:09:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:09:46 INFO - Using real prefix '/tools/python27' 10:09:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:09:47 INFO - Installing distribute.............................................................................................................................................................................................done. 10:09:50 INFO - Installing pip.................done. 10:09:50 INFO - Return code: 0 10:09:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:09:50 INFO - Reading from file tmpfile_stdout 10:09:50 INFO - Output received: 10:09:50 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) 10:09:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10748fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc2a594af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10726dd30>, '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 0x107194db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107262be8>, '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.WELOgCndKJ/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.QwQZLQu7DR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 10:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WELOgCndKJ/Render', 10:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:09:50 INFO - 'HOME': '/Users/cltbld', 10:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:09:50 INFO - 'LOGNAME': 'cltbld', 10:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:50 INFO - 'MOZ_NO_REMOTE': '1', 10:09:50 INFO - 'NO_EM_RESTART': '1', 10:09:50 INFO - 'PAGER': '/bin/cat', 10:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:50 INFO - 'PWD': '/builds/slave/test', 10:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:09:50 INFO - 'SHELL': '/bin/bash', 10:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwQZLQu7DR/Listeners', 10:09:50 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:09:50 INFO - 'USER': 'cltbld', 10:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:50 INFO - 'XPC_FLAGS': '0x0', 10:09:50 INFO - 'XPC_SERVICE_NAME': '0', 10:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:09:50 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 10:09:50 INFO - Cleaning up... 10:09:50 INFO - Return code: 0 10:09:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10748fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc2a594af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10726dd30>, '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 0x107194db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107262be8>, '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.WELOgCndKJ/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.QwQZLQu7DR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 10:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WELOgCndKJ/Render', 10:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:09:50 INFO - 'HOME': '/Users/cltbld', 10:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:09:50 INFO - 'LOGNAME': 'cltbld', 10:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:50 INFO - 'MOZ_NO_REMOTE': '1', 10:09:50 INFO - 'NO_EM_RESTART': '1', 10:09:50 INFO - 'PAGER': '/bin/cat', 10:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:50 INFO - 'PWD': '/builds/slave/test', 10:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:09:50 INFO - 'SHELL': '/bin/bash', 10:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwQZLQu7DR/Listeners', 10:09:50 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:09:50 INFO - 'USER': 'cltbld', 10:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:50 INFO - 'XPC_FLAGS': '0x0', 10:09:50 INFO - 'XPC_SERVICE_NAME': '0', 10:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:09:50 INFO - Downloading/unpacking psutil>=3.1.1 10:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:09:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:09:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:09:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:09:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:09:54 INFO - Installing collected packages: psutil 10:09:54 INFO - Running setup.py install for psutil 10:09:54 INFO - building 'psutil._psutil_osx' extension 10:09:54 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 10:09:54 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 10:09:55 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 10:09:55 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 10:09:55 INFO - building 'psutil._psutil_posix' extension 10:09:55 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 10:09:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:09:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:09:55 INFO - ^ 10:09:55 INFO - 1 warning generated. 10:09:55 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 10:09:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:09:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:09:55 INFO - Successfully installed psutil 10:09:55 INFO - Cleaning up... 10:09:55 INFO - Return code: 0 10:09:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:09:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:09:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:56 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 0x10748fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc2a594af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10726dd30>, '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 0x107194db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107262be8>, '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.WELOgCndKJ/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.QwQZLQu7DR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 10:09:56 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 10:09:56 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 10:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WELOgCndKJ/Render', 10:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:09:56 INFO - 'HOME': '/Users/cltbld', 10:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:09:56 INFO - 'LOGNAME': 'cltbld', 10:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:56 INFO - 'MOZ_NO_REMOTE': '1', 10:09:56 INFO - 'NO_EM_RESTART': '1', 10:09:56 INFO - 'PAGER': '/bin/cat', 10:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:56 INFO - 'PWD': '/builds/slave/test', 10:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:09:56 INFO - 'SHELL': '/bin/bash', 10:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwQZLQu7DR/Listeners', 10:09:56 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:09:56 INFO - 'USER': 'cltbld', 10:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:56 INFO - 'XPC_FLAGS': '0x0', 10:09:56 INFO - 'XPC_SERVICE_NAME': '0', 10:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:09:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:09:56 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 10:09:56 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 10:09:56 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 10:09:56 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 10:09:56 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 10:09:56 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 10:09:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:09:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:09:59 INFO - Installing collected packages: mozsystemmonitor 10:09:59 INFO - Running setup.py install for mozsystemmonitor 10:09:59 INFO - Successfully installed mozsystemmonitor 10:09:59 INFO - Cleaning up... 10:09:59 INFO - Return code: 0 10:09:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:09:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:09:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:09:59 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 0x10748fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc2a594af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10726dd30>, '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 0x107194db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107262be8>, '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.WELOgCndKJ/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.QwQZLQu7DR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 10:09:59 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 10:09:59 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 10:09:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WELOgCndKJ/Render', 10:09:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:09:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:09:59 INFO - 'HOME': '/Users/cltbld', 10:09:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:09:59 INFO - 'LOGNAME': 'cltbld', 10:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:09:59 INFO - 'MOZ_NO_REMOTE': '1', 10:09:59 INFO - 'NO_EM_RESTART': '1', 10:09:59 INFO - 'PAGER': '/bin/cat', 10:09:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:09:59 INFO - 'PWD': '/builds/slave/test', 10:09:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:09:59 INFO - 'SHELL': '/bin/bash', 10:09:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwQZLQu7DR/Listeners', 10:09:59 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:09:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:09:59 INFO - 'USER': 'cltbld', 10:09:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:09:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:09:59 INFO - 'XPC_FLAGS': '0x0', 10:09:59 INFO - 'XPC_SERVICE_NAME': '0', 10:09:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:00 INFO - Downloading/unpacking jsonschema==2.5.1 10:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:10:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:03 INFO - Downloading functools32-3.2.3-2.tar.gz 10:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:10:03 INFO - warning: no files found matching '*.txt' 10:10:03 INFO - no previously-included directories found matching 'build' 10:10:03 INFO - no previously-included directories found matching 'dist' 10:10:03 INFO - no previously-included directories found matching '.git*' 10:10:03 INFO - Installing collected packages: jsonschema, functools32 10:10:03 INFO - Running setup.py install for functools32 10:10:03 INFO - warning: no files found matching '*.txt' 10:10:03 INFO - no previously-included directories found matching 'build' 10:10:03 INFO - no previously-included directories found matching 'dist' 10:10:03 INFO - no previously-included directories found matching '.git*' 10:10:03 INFO - Successfully installed jsonschema functools32 10:10:03 INFO - Cleaning up... 10:10:03 INFO - Return code: 0 10:10:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:03 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 0x10748fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc2a594af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10726dd30>, '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 0x107194db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107262be8>, '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.WELOgCndKJ/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.QwQZLQu7DR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 10:10:03 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 10:10:03 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 10:10:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WELOgCndKJ/Render', 10:10:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:03 INFO - 'HOME': '/Users/cltbld', 10:10:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:03 INFO - 'LOGNAME': 'cltbld', 10:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:03 INFO - 'MOZ_NO_REMOTE': '1', 10:10:03 INFO - 'NO_EM_RESTART': '1', 10:10:03 INFO - 'PAGER': '/bin/cat', 10:10:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:03 INFO - 'PWD': '/builds/slave/test', 10:10:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:03 INFO - 'SHELL': '/bin/bash', 10:10:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwQZLQu7DR/Listeners', 10:10:03 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:10:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:03 INFO - 'USER': 'cltbld', 10:10:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:03 INFO - 'XPC_FLAGS': '0x0', 10:10:03 INFO - 'XPC_SERVICE_NAME': '0', 10:10:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:10:03 INFO - Cleaning up... 10:10:03 INFO - Return code: 0 10:10:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:03 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 0x10748fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc2a594af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10726dd30>, '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 0x107194db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107262be8>, '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.WELOgCndKJ/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.QwQZLQu7DR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 10:10:03 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 10:10:03 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 10:10:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WELOgCndKJ/Render', 10:10:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:03 INFO - 'HOME': '/Users/cltbld', 10:10:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:03 INFO - 'LOGNAME': 'cltbld', 10:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:03 INFO - 'MOZ_NO_REMOTE': '1', 10:10:03 INFO - 'NO_EM_RESTART': '1', 10:10:03 INFO - 'PAGER': '/bin/cat', 10:10:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:03 INFO - 'PWD': '/builds/slave/test', 10:10:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:03 INFO - 'SHELL': '/bin/bash', 10:10:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwQZLQu7DR/Listeners', 10:10:03 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:10:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:03 INFO - 'USER': 'cltbld', 10:10:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:03 INFO - 'XPC_FLAGS': '0x0', 10:10:03 INFO - 'XPC_SERVICE_NAME': '0', 10:10:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:04 INFO - Downloading/unpacking blobuploader==1.2.4 10:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:06 INFO - Downloading blobuploader-1.2.4.tar.gz 10:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:10:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:10:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:10:07 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 10:10:07 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 10:10:07 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 10:10:07 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 10:10:07 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 10:10:07 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 10:10:08 INFO - Downloading docopt-0.6.1.tar.gz 10:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:10:08 INFO - Installing collected packages: blobuploader, requests, docopt 10:10:08 INFO - Running setup.py install for blobuploader 10:10:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:10:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:10:08 INFO - Running setup.py install for requests 10:10:08 INFO - Running setup.py install for docopt 10:10:08 INFO - Successfully installed blobuploader requests docopt 10:10:08 INFO - Cleaning up... 10:10:09 INFO - Return code: 0 10:10:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:09 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 0x10748fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc2a594af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10726dd30>, '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 0x107194db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107262be8>, '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.WELOgCndKJ/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.QwQZLQu7DR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 10:10:09 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 10:10:09 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 10:10:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WELOgCndKJ/Render', 10:10:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:09 INFO - 'HOME': '/Users/cltbld', 10:10:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:09 INFO - 'LOGNAME': 'cltbld', 10:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:09 INFO - 'MOZ_NO_REMOTE': '1', 10:10:09 INFO - 'NO_EM_RESTART': '1', 10:10:09 INFO - 'PAGER': '/bin/cat', 10:10:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:09 INFO - 'PWD': '/builds/slave/test', 10:10:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:09 INFO - 'SHELL': '/bin/bash', 10:10:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwQZLQu7DR/Listeners', 10:10:09 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:10:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:09 INFO - 'USER': 'cltbld', 10:10:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:09 INFO - 'XPC_FLAGS': '0x0', 10:10:09 INFO - 'XPC_SERVICE_NAME': '0', 10:10:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:10:09 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-saY6ER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:10:09 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-rQJ198-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:10:09 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-vS1bxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:10:09 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-R7Wcev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:10:09 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-fvyR3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:10:09 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-WeVv9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:10:09 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-ttQBnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:10:10 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-GdDjRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:10:10 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-_KMExa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:10:10 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-Hp9rNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:10:10 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-bdJVN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:10:10 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-xt7EmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:10:10 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-7XlE23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:10:10 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-OOW3Bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:10:10 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-jJ2Yi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:10:10 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-TKBIux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:10:10 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-LXATuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:10:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:10:11 INFO - Running setup.py install for manifestparser 10:10:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:10:11 INFO - Running setup.py install for mozcrash 10:10:11 INFO - Running setup.py install for mozdebug 10:10:11 INFO - Running setup.py install for mozdevice 10:10:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:10:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:10:11 INFO - Running setup.py install for mozfile 10:10:11 INFO - Running setup.py install for mozhttpd 10:10:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:10:11 INFO - Running setup.py install for mozinfo 10:10:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:10:12 INFO - Running setup.py install for mozInstall 10:10:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:10:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:10:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:10:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:10:12 INFO - Running setup.py install for mozleak 10:10:12 INFO - Running setup.py install for mozlog 10:10:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:10:12 INFO - Running setup.py install for moznetwork 10:10:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:10:12 INFO - Running setup.py install for mozprocess 10:10:12 INFO - Running setup.py install for mozprofile 10:10:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:10:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:10:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:10:13 INFO - Running setup.py install for mozrunner 10:10:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:10:13 INFO - Running setup.py install for mozscreenshot 10:10:13 INFO - Running setup.py install for moztest 10:10:13 INFO - Running setup.py install for mozversion 10:10:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:10:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:10:13 INFO - Cleaning up... 10:10:13 INFO - Return code: 0 10:10:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:10:13 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')]} 10:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:13 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')]} 10:10:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:13 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 0x10748fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc2a594af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10726dd30>, '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 0x107194db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107262be8>, '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.WELOgCndKJ/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.QwQZLQu7DR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 10:10:13 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 10:10:13 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 10:10:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WELOgCndKJ/Render', 10:10:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:13 INFO - 'HOME': '/Users/cltbld', 10:10:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:13 INFO - 'LOGNAME': 'cltbld', 10:10:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:13 INFO - 'MOZ_NO_REMOTE': '1', 10:10:13 INFO - 'NO_EM_RESTART': '1', 10:10:13 INFO - 'PAGER': '/bin/cat', 10:10:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:13 INFO - 'PWD': '/builds/slave/test', 10:10:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:13 INFO - 'SHELL': '/bin/bash', 10:10:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwQZLQu7DR/Listeners', 10:10:13 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:10:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:13 INFO - 'USER': 'cltbld', 10:10:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:13 INFO - 'XPC_FLAGS': '0x0', 10:10:13 INFO - 'XPC_SERVICE_NAME': '0', 10:10:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:10:13 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-IdG8Z2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:10:14 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)) 10:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:10:14 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-jEeWmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:10:14 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)) 10:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:10:14 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-izgjwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:10:14 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)) 10:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:10:14 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-kwepEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:10:14 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)) 10:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:10:14 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-xEsG7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:10:14 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)) 10:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:10:14 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-_nBk_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:10:14 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)) 10:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:10:14 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-NTHjyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:10:14 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)) 10:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:10:14 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-AjWNkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:10:14 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)) 10:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:10:14 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-qEZ1ZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:10:14 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)) 10:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:10:14 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-14yTbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:10:15 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)) 10:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:10:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-IKmCTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:10:15 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)) 10:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:10:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-nZ1hEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:10:15 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)) 10:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:10:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-PUBESP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:10:15 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)) 10:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:10:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-YsFHv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:10:15 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)) 10:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:10:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-X8YhLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:10:15 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)) 10:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:10:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-0kviyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:10:15 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)) 10:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:10:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-MxGg5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:10:15 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)) 10:10:15 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)) 10:10:15 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)) 10:10:15 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)) 10:10:15 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)) 10:10:15 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)) 10:10:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:10:15 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 10:10:15 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 10:10:15 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 10:10:15 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 10:10:15 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 10:10:15 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 10:10:18 INFO - Downloading blessings-1.6.tar.gz 10:10:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:10:18 INFO - Installing collected packages: blessings 10:10:18 INFO - Running setup.py install for blessings 10:10:18 INFO - Successfully installed blessings 10:10:18 INFO - Cleaning up... 10:10:18 INFO - Return code: 0 10:10:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:10:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:10:19 INFO - Reading from file tmpfile_stdout 10:10:19 INFO - Current package versions: 10:10:19 INFO - blessings == 1.6 10:10:19 INFO - blobuploader == 1.2.4 10:10:19 INFO - docopt == 0.6.1 10:10:19 INFO - functools32 == 3.2.3-2 10:10:19 INFO - jsonschema == 2.5.1 10:10:19 INFO - manifestparser == 1.1 10:10:19 INFO - mozInstall == 1.12 10:10:19 INFO - mozcrash == 1.0 10:10:19 INFO - mozdebug == 0.1 10:10:19 INFO - mozdevice == 0.49 10:10:19 INFO - mozfile == 1.2 10:10:19 INFO - mozhttpd == 0.7 10:10:19 INFO - mozinfo == 0.9 10:10:19 INFO - mozleak == 0.1 10:10:19 INFO - mozlog == 3.4 10:10:19 INFO - moznetwork == 0.27 10:10:19 INFO - mozprocess == 0.25 10:10:19 INFO - mozprofile == 0.28 10:10:19 INFO - mozrunner == 6.13 10:10:19 INFO - mozscreenshot == 0.1 10:10:19 INFO - mozsystemmonitor == 0.3 10:10:19 INFO - moztest == 0.8 10:10:19 INFO - mozversion == 1.4 10:10:19 INFO - psutil == 3.1.1 10:10:19 INFO - requests == 1.2.3 10:10:19 INFO - wsgiref == 0.1.2 10:10:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:10: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')]} 10:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10: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')]} 10:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:19 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 0x10748fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc2a594af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10726dd30>, '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 0x107194db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107262be8>, '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.WELOgCndKJ/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.QwQZLQu7DR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 10:10:19 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 10:10:19 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 10:10:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WELOgCndKJ/Render', 10:10:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:19 INFO - 'HOME': '/Users/cltbld', 10:10:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:19 INFO - 'LOGNAME': 'cltbld', 10:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:19 INFO - 'MOZ_NO_REMOTE': '1', 10:10:19 INFO - 'NO_EM_RESTART': '1', 10:10:19 INFO - 'PAGER': '/bin/cat', 10:10:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:19 INFO - 'PWD': '/builds/slave/test', 10:10:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:19 INFO - 'SHELL': '/bin/bash', 10:10:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwQZLQu7DR/Listeners', 10:10:19 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:10:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:19 INFO - 'USER': 'cltbld', 10:10:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:19 INFO - 'XPC_FLAGS': '0x0', 10:10:19 INFO - 'XPC_SERVICE_NAME': '0', 10:10:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:19 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)) 10:10:19 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)) 10:10:19 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)) 10:10:19 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)) 10:10:19 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)) 10:10:19 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)) 10:10:19 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)) 10:10:19 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)) 10:10:19 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)) 10:10:19 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)) 10:10:19 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)) 10:10:19 INFO - Cleaning up... 10:10:19 INFO - Return code: 0 10:10:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:10: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')]} 10:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10: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')]} 10:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:19 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 0x10748fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc2a594af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10726dd30>, '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 0x107194db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107262be8>, '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.WELOgCndKJ/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.QwQZLQu7DR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 10:10:19 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 10:10:19 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 10:10:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WELOgCndKJ/Render', 10:10:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:19 INFO - 'HOME': '/Users/cltbld', 10:10:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:19 INFO - 'LOGNAME': 'cltbld', 10:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:19 INFO - 'MOZ_NO_REMOTE': '1', 10:10:19 INFO - 'NO_EM_RESTART': '1', 10:10:19 INFO - 'PAGER': '/bin/cat', 10:10:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:19 INFO - 'PWD': '/builds/slave/test', 10:10:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:19 INFO - 'SHELL': '/bin/bash', 10:10:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwQZLQu7DR/Listeners', 10:10:19 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:10:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:19 INFO - 'USER': 'cltbld', 10:10:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:19 INFO - 'XPC_FLAGS': '0x0', 10:10:19 INFO - 'XPC_SERVICE_NAME': '0', 10:10:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:10:19 INFO - Cleaning up... 10:10:19 INFO - Return code: 0 10:10:19 INFO - Running post-action listener: _resource_record_post_action 10:10:19 INFO - Running post-action listener: _start_resource_monitoring 10:10:19 INFO - Starting resource monitoring. 10:10:19 INFO - [mozharness: 2017-04-21 17:10:19.653661Z] Finished create-virtualenv step (success) 10:10:19 INFO - [mozharness: 2017-04-21 17:10:19.654655Z] Running install step. 10:10:19 INFO - Running pre-action listener: _resource_record_pre_action 10:10:19 INFO - Running main action method: install 10:10:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:10:19 INFO - Reading from file tmpfile_stdout 10:10:19 INFO - Detecting whether we're running mozinstall >=1.0... 10:10:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:10:20 INFO - Reading from file tmpfile_stdout 10:10:20 INFO - Output received: 10:10:20 INFO - Usage: mozinstall [options] installer 10:10:20 INFO - Options: 10:10:20 INFO - -h, --help show this help message and exit 10:10:20 INFO - -d DEST, --destination=DEST 10:10:20 INFO - Directory to install application into. [default: 10:10:20 INFO - "/builds/slave/test"] 10:10:20 INFO - --app=APP Application being installed. [default: firefox] 10:10:20 INFO - mkdir: /builds/slave/test/build/application 10:10:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 10:10:34 INFO - Reading from file tmpfile_stdout 10:10:34 INFO - Output received: 10:10:34 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:10:34 INFO - Running post-action listener: _resource_record_post_action 10:10:34 INFO - [mozharness: 2017-04-21 17:10:34.701129Z] Finished install step (success) 10:10:34 INFO - [mozharness: 2017-04-21 17:10:34.701282Z] Running run-tests step. 10:10:34 INFO - Running pre-action listener: _resource_record_pre_action 10:10:34 INFO - Running main action method: run_tests 10:10:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:10:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:10:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:10:34 INFO - 2017-04-21 10:10:34.715 screenresolution[1619:7699] starting screenresolution argv=screenresolution get 10:10:34 INFO - 2017-04-21 10:10:34.718 screenresolution[1619:7699] Display 0: 1600x1200x32@60 10:10:34 INFO - 2017-04-21 10:10:34.724 screenresolution[1620:7701] starting screenresolution argv=screenresolution list 10:10:34 INFO - Available Modes on Display 0 10:10:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:10:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:10:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:10:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:10:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:10:34 INFO - Intel Iris: 10:10:34 INFO - Chipset Model: Intel Iris 10:10:34 INFO - Type: GPU 10:10:34 INFO - Bus: Built-In 10:10:34 INFO - VRAM (Dynamic, Max): 1536 MB 10:10:34 INFO - Vendor: Intel (0x8086) 10:10:34 INFO - Device ID: 0x0a2e 10:10:34 INFO - Revision ID: 0x0009 10:10:34 INFO - Displays: 10:10:34 INFO - Display: 10:10:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:10:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:10:34 INFO - Display Serial Number: 1600x1200 60 10:10:34 INFO - Main Display: Yes 10:10:34 INFO - Mirror: Off 10:10:34 INFO - Online: Yes 10:10:34 INFO - Rotation: Supported 10:10:34 INFO - Return code: 0 10:10:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:10:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:10:34 INFO - Python 2.7.3 10:10:34 INFO - Return code: 0 10:10:34 INFO - grabbing minidump binary from tooltool 10:10: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')]} 10:10:34 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 0x10726dd30>, '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 0x107194db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107262be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:10:34 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 10:10:34 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 10:10:34 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 10:10:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:10:35 INFO - Return code: 0 10:10:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:10:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:10:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:10:35 INFO - ENV: RUST_BACKTRACE is now 1 10:10:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:10:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0323', '--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 10:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0323 --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 10:10:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WELOgCndKJ/Render', 10:10:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:35 INFO - 'HOME': '/Users/cltbld', 10:10:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:35 INFO - 'LOGNAME': 'cltbld', 10:10:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:10:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:35 INFO - 'MOZ_NO_REMOTE': '1', 10:10:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:10:35 INFO - 'NO_EM_RESTART': '1', 10:10:35 INFO - 'PAGER': '/bin/cat', 10:10:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:35 INFO - 'PWD': '/builds/slave/test', 10:10:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:10:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:35 INFO - 'RUST_BACKTRACE': '1', 10:10:35 INFO - 'SHELL': '/bin/bash', 10:10:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwQZLQu7DR/Listeners', 10:10:35 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:10:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:35 INFO - 'USER': 'cltbld', 10:10:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:35 INFO - 'XPC_FLAGS': '0x0', 10:10:35 INFO - 'XPC_SERVICE_NAME': '0', 10:10:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0323', '--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 10:10:35 INFO - mozversion application_buildid: 20170421084120 10:10:35 INFO - mozversion application_changeset: 188cc9e6d18d8dff122b0190e19c1e658b6d0f29 10:10:35 INFO - mozversion application_display_name: Firefox 10:10:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:10:35 INFO - mozversion application_name: Firefox 10:10:35 INFO - mozversion application_remotingname: firefox 10:10:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:10:35 INFO - mozversion application_vendor: Mozilla 10:10:35 INFO - mozversion application_version: 54.0 10:10:35 INFO - mozversion platform_buildid: 20170421084120 10:10:35 INFO - mozversion platform_changeset: 188cc9e6d18d8dff122b0190e19c1e658b6d0f29 10:10:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:10:35 INFO - mozversion platform_version: 54.0 10:10:35 INFO - using testdate: 1492794635 10:10:35 INFO - actual date: 1492794635 10:10:35 INFO - starting webserver on 'localhost:49251' 10:10:35 INFO - SUITE-START | Running 2 tests 10:10:35 INFO - TEST-START | dromaeo_css 10:10:35 INFO - operating with platform_type : mac_ 10:10:35 INFO - Initialising browser for dromaeo_css test... 10:10:35 INFO - TEST-INFO | started process 1630 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpR_12Lc/profile http://localhost:49251/getInfo.html) 10:10:38 INFO - PROCESS | 1630 | 2017-04-21 10:10:38.154 plugin-container[1633:7892] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4b, name = 'com.apple.tsm.portname' 10:10:38 INFO - PROCESS | 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:10:38 INFO - PROCESS | 1630 | 2017-04-21 10:10:38.154 plugin-container[1633:7892] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a0b, name = 'com.apple.CFPasteboardClient' 10:10:38 INFO - PROCESS | 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:10:38 INFO - PROCESS | 1630 | 2017-04-21 10:10:38.154 plugin-container[1633:7892] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:10:38 INFO - PROCESS | 1630 | __metrics Screen width/height:1600/1200 10:10:38 INFO - PROCESS | 1630 | colorDepth:24 10:10:38 INFO - PROCESS | 1630 | Browser inner width/height: 1024/689 10:10:38 INFO - PROCESS | 1630 | __metrics 10:10:38 ERROR - PROCESS | 1630 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:10:38 INFO - TEST-INFO | 1630: exit 0 10:10:38 INFO - Browser initialized. 10:10:38 INFO - Running cycle 1/1 for dromaeo_css test... 10:10:38 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpR_12Lc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:10:41 INFO - PROCESS | 1634 | 2017-04-21 10:10:41.029 plugin-container[1635:8025] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 10:10:41 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:10:41 INFO - PROCESS | 1634 | 2017-04-21 10:10:41.029 plugin-container[1635:8025] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 10:10:41 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:10:41 INFO - PROCESS | 1634 | 2017-04-21 10:10:41.029 plugin-container[1635:8025] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:10:50 INFO - PROCESS | 1634 | RSS: Main: 174280704 10:10:50 INFO - PROCESS | 1634 | 10:13:53 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 10:13:55 INFO - PROCESS | 1634 | RSS: Main: 190267392 10:13:55 INFO - PROCESS | 1634 | 10:16:57 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 10:16:58 INFO - PROCESS | 1634 | RSS: Main: 199770112 10:16:58 INFO - PROCESS | 1634 | 10:20:00 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 10:20:00 INFO - PROCESS | 1634 | RSS: Main: 204378112 10:20:00 INFO - PROCESS | 1634 | 10:23:02 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 10:23:03 INFO - PROCESS | 1634 | RSS: Main: 207917056 10:23:03 INFO - PROCESS | 1634 | 10:26:06 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 10:26:06 INFO - PROCESS | 1634 | RSS: Main: 210124800 10:26:06 INFO - PROCESS | 1634 | 10:29:09 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 10:29:09 INFO - PROCESS | 1634 | RSS: Main: 209174528 10:29:09 INFO - PROCESS | 1634 | 10:29:09 INFO - PROCESS | 1634 | __start_tp_report 10:29:09 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 10:29:09 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 10:29:09 INFO - PROCESS | 1634 | |i|pagename|runs| 10:29:09 INFO - PROCESS | 1634 | |0;dojo.html;3053.2977415194505;3096.8916087936927;3101.4572449821285;3661.1579336752548;3714.4614030965513;6117.174181485498;6169.660668663228;6201;6203.968980155104;6220.222472190976;6159.476444502223;6171.506279497629;6176.197094377736;6193.442590166884;6222.12890195373;5618.634788738739;5683.374828768832;5684.744186511617;5695.800646977356;5763.250777398932;5725.713714314288;5739.225204597367;5740.54075673945;5744.281964754406;5748.310202775673;5499.422560631137;5515.503604675579;5653.519450846662;5704.344000439961;5712.971435142818;5725.57058220633;5738.684372359518;5747.39652336507;5756.194132821387;5803.941960580382;5511.641743286674;5643.435656434365;5686.346070201938;5690.829275121711;5768.019436695772;6024.48791852693;6027.8794424111275;6047.0627052806885;6124.448799608012;6249.40630640093;6503.93496065038;6527.738890444377;6555.6066636002;6569.113169722122;6569.57297775643;6704.83237919051;6712.563683360566;6721.924492081293;6735.326467353275;6735.831604209885;6090.36051214625;6304.936950630527;6313.842153946142;6314.273858506331;6321.146645202884;6620.470362370998;6644.202695676453;6708.060871477997;6727.091842601284;6762.087118239073;6503.674816259168;6529.575577587441;6540.411362977355;6562.639054852029;6563.835904102436;6593.769218077345;6648.53460257787;6689.632070236184;6698.66506674674;6713.765018224436;6305.842353941096;6340.904886426707;6494.383033611799;6579.736810527621;6607.735690572419;6296.5592408531875;6591.505637077143;6611.801645950629;6617.669116544153;6635.369639884299;5140.897182056338;5240.633155679142;5301.575873930076;5313.282706834528;5345.251664766935;4744.644151688568;4745.098431298043;4762.690425122356;4776.641751868554;4817.13291607514;6165.136880836686;6217.96891015542;6373.74505019794;6394.200724909387;6444.77443289492;6518.087467754518;6580.473562115019;6708.865822683617;6718.193816742022;6718.462522998148;6374.936250637435;6401.64790936503;6402;6402.839929001719;6412.551121421549;5670.432956704297;5850.3272123705465;5867.413258674099;5870.530357571384;5874.32445268791;6780.660966951633;6864.691088901011;6875.89686154698;6892.620905850226;6907.861842763217;6533.67331633407;6681.398674119353;6687.565308254948;6696.229933557704;6719.361660642231;6931.6880740367815;6969.860602788017;6990.720371185095;6996.755113571001;7009.9649501752165;6302.180716506825;6370.681465926593;6439.710213040331;6505.707243173973;6551.475881929339;5349.812756553502;6677.09859169006;6679.532432729662;6708.228553716385;6727.529072965041;6240.375962403723;6461.450776683847;6466.494332869245;6487.253965793994;6495.415412612888;6230.971889638159;6261.436470717657;6308.653024083719;6308.84227894288;6501.122348482894;6227.875442491216;6314.368563143649;6317.842053948688;6322.494200464043;6338.524610654127;5147.819826306061;6441.613503189824;6450.967745161244;6453.290138084901;6456.677166141768;6191.19514463135;6202.875942481215;6282.968585157045;6283.2460104787715;6287.30839607652 10:29:09 INFO - PROCESS | 1634 | |1;ext.html;783.1854870934229;794.6543253684644;838.865781474964;868.3010176807671;883.4743327720005;39932.401013984774;40825;42425.36361954566;42793.93015174614;42909;32322.515162272586;40818.59181408185;41116.588834111724;41196.176076478456;41410.58589414105;8261.58692065396;11280.774384512304;11548.538058477692;11750.177487575873;11755.588554400598;11333.943330283337;11389.088872890148;11419.657410277705;11423;11431.714207144847;11441.713957151054;11452.141089418285;11464.541418343257;11464.942675286611;11492.023178029878;11370.71573210668;11389.658310250707;11438.370889601068;11438.370889601068;11443.828342574869;9112.772180695469;9464.479453630047;11139.164562657752;11170;11225;28290.434191316264;35598.644013559795;35653.75211867572;35835.82082089599;35851.64148358509;3890.027493126718;3944.1520073184115;3946.881593552198;4000.9997500624845;4135.441715368417;25869.585703728288;26225.344366390804;26268;26270.47459050808;26284.080057198098;3227.854746536388;3269.869205231788;3312.0560640217664;3410.437277849152;3416.6754158354915;305173;347628;356533.21733391425;367865;369210.9999999973;44769.55230447719;45841.54158458373;47563.28655070177;47612;47630.76184619124;47840.282395764094;47865.282020769715;47871;48008;48069;14002.649933751534;14177.574672759834;14189.290535473185;14191.503297384543;14326.856731432552;12162.75674486523;12199.963003144654;12234.327112008925;12263.570775022832;12324.938375308246;12261.26432414058;12339.50641974329;12360.381980900918;12382.257064576153;12446.875531244752;5895.764169433261;5915.112733089984;5923.7926672566255;5939.732712027969;5963.493103086286;1086.6739978006567;1168.351564881499;1177.7938860699344;1186.4720199511135;1199.4482538032428;23756.524869502515;26890;27709.58435623467;28440;28454;1074.9140068794477;1270.1490001698885;1291.9547815826402;1300.3823183987531;1324.3113580937859;14371.856281437262;14432.350544225535;14639.48761793332;14676.8992325576;14697.338619762137;202388;202824;203347.98326008572;203729;204002;57084;57427.13859292114;57643;57709.84580308454;57933;1498.3482185249452;1507.743683573817;1539.58431223569;1541.9691606167814;1543.9691206176037;25727.614085788355;26894.731052690004;27276.863615681796;27439.588406173523;27552.173434796987;48655;48662.27006594975;48672;48752.5124748748;48777.5122248773;403753;403759;403865;404167;406435.967820159;27715.861420692767;28330.150095497167;29874.8506257476;32700.83649581832;32826.67173328236;44590;45932.540674594165;46107.3083903735;47046.76476617595;47177.528224717316;30966.380672386877;31053.068407947798;31253.374932500767;31351.84324078365;31943.68056319407;26119.347016323984;31204;31989.36021279515;32202.83898580492;32257;29660.703392966658;31779.52330714995;36732.12587190873;36838;38011.619883800806;31364.215894602818;31462.056138315886;31695;31746.68253317437;31945.520817188226;21370.57258854783;23218.490798098068;25302.114425995;25320.100992425272;25415.11047113416 10:29:09 INFO - PROCESS | 1634 | |2;jquery.html;425.46816479400746;440.9823607055718;442.838363997141;450.3784777007731;450.8827704796753;20830.89584552077;21692.457688557795;22300.88849555752;22496.21263255782;22573;18728.06359682017;22502.324930252118;24093.63859542104;24158.396040098964;24714.517128972275;6827.761028364008;6986.720531178759;7097.432205423554;7230.530015548999;7269;6479.092926990202;6541.574797638162;6565.310642382553;6566.179227596551;6567.277599464079;6396.808095757135;6559.967200163993;7008.36924676779;7028.894566581505;7057.470689698293;6892.793216203522;6929.653517324114;7077.858442831141;7106.751263705772;7125.002499650027;6762.932370676329;6765.289644587346;6770.424513916323;6776.322367763184;6799.42204912583;26554.6016809748;26872.925082996655;27517.31206719828;27536.449271014466;27685.584716229274;2901.1151598762367;3033.7117973792674;3047.54286856971;3053.5419687046897;3067.156531953708;14469.842412606966;15117;16594.58513537159;16949.49151525456;17454.95270283787;2096.2453516733963;2101.6637338025994;2127.6914847347202;2129.3292612827063;2132.2963422070834;170985;175420.12289938595;176961;179205.1039744806;181199;18761.90619046896;19945.800541994686;20414.69377959332;20757.584848303253;21142.15431382758;30784.230394239876;30942.226444338623;31173.844130779653;31472.370552589276;31625.367492650017;9138.68014619485;9968.601255949681;9977.650782222587;10119.443430611387;10279.280450368551;7508.436867234979;7901.617216987004;8512.489250644981;8523.721441783782;8627.180417860292;9536.856947145798;9575.23398128162;9615.807683846284;9671.468069256118;9691.27315451344;3602.225521512887;4045.7774822384463;4093.2222877653153;4125.339945608688;4126.216018956388;955.3837774635322;962.4610218277787;982.570360126015;983.6508039645965;1127.3574062784298;15806.762898556532;17803.821961780217;18033.909830450764;18624.906875465535;21924.780752192273;940.219617717293;1045.7647029418472;1081.2863510083307;1082.3614067700096;1089.8801131875487;9798.902010979798;11464.369459679798;11544.307341559534;11594.956453919187;11668.241564298294;617380;750412;779401;791385;807588;123638;140669;151906;170382;171681.28318716653;1314.625331780438;1322.2066759943957;1365.6108009217326;1369.2469141972074;1370.7943808428815;16023.599410014844;18478.891266523515;19859.503512412306;21046.78953210448;21151.153953841673;59256.40743592509;59411.70294148502;59888.70055649694;60263;60654.696726516086;86335;86921;87093;87596.5620171895;89751;11674.649760507198;11796.410179491162;11920;11993.940030299793;12089.57686480969;12770.489180432678;12937.964962802766;12948.8057679133;13260.270685112411;13271.601851944457;11438;11565.94217028943;11605.419729013347;11767.293962362286;11928.463219154984;8949.418287811272;9496.857547136937;10187.726534183226;10395.220358472994;10846.132309415385;10333.431661258703;11706.76586468283;12007.519699211934;12107.394630268274;12235.510579576718;11822.34977076269;13648.522301719393;14056.508022219174;14061.648458788202;14299.57101286963;11198.552057917592;11567.414101628523;12260.26438413698;12287.56993505223;12502.187357821713 10:29:09 INFO - PROCESS | 1634 | |3;mootools.html;1580.6048487878031;1634.926428310726;1646.448439772676;1649.356750867162;1666.2002238925315;11279.943600281997;11536.65390038298;11942.223755455889;12043.638690839267;12084.879151208484;11420.771584568303;11615.303081815096;11616.419179041035;11617.825732614017;11655.533778648865;2511.7488251174827;2541.2503311523146;2547.503236868811;2549.566573682469;2552.247087109298;2429.1133736186343;2433.111914151331;2434.853908765471;2442.829001969863;2465.149523414428;2053.917843286267;2267.2178098555974;2402.759724027592;2416.6858308419883;2523.5205310990946;2013.577148798754;2474.938126546842;2483.5902076157413;2487.0300582772734;2520.1935380678005;2474.183519438581;2501.2871331670567;2504.799616030713;2506.147909710707;2507;10835.133189344911;10957.287776294515;10967.616133435371;11028.117750579935;11040.171987100919;5927.496162826165;6064.484518815906;6086.726097325587;6093.482054025413;6102.084687296897;3694.7598406103784;3773.7735735855663;3807.200487897545;3846.673032792216;3863.9227215455535;1291.767481853266;1846.9722954155698;1933.5456167800696;1950.3271371376852;1978.1592823050146;12512.311822849648;14147.504837330644;14426.783598246035;16017.359305627877;16129.435469758619;4862.635302352338;4938.456769755324;4978.203487442064;4984.077945580079;4994.200927851599;12186.964108050737;12333.136680432284;12400.31798251105;12415.875841241652;12418.56535021288;5880.411958804086;6073.96963015191;6106.816795496142;6109.572329936862;6144.508439324933;5504.889902201933;5510.366307874566;5540.2243685884005;5583.664980101207;5592.412796656374;3926.6680458275437;4028.4158796974275;4064.8170832312617;4180.6446452051305;4185.434966279513;1092.1754075672816;1096.3257596578208;1098.9175811814146;1107.2028139739375;1110.2062025651699;853.3002937591116;875.8292133033997;878.4290211362537;897.8249241397865;899.1682693508609;7175.820604484929;7321;7391.48259621832;7471.402287816962;7482.476226664081;925.726910561386;942.8161508505913;1090.487470888681;1110.1396417776289;1132.4111462039693;3590.461430785403;4345.587169218919;4446.5331140230455;4463.308187230984;4676.205045142334;13896.51362202318;14057.578272651837;14233;14468.348924298432;14517;14117.929410353088;14316.427342906167;14427.92786036063;14475.92762036183;14483;10590.364578125336;10675;10716.303440276357;10786.352818830896;10793.352398856094;6920.826979325557;6929.376356127771;7072.504924655431;7130.857382852418;7133.500654954103;12437.93781031089;12682.9853611709;12734.936325318315;12773.61679149627;12901.032422568533;11715.648530544097;12993.220406775623;13051.28217948022;13218.537351192663;13331.466741330627;7363.631818408951;8442.620082096444;8633.30933525304;8650.56747162652;8978;10280.845787313347;10457.111145552713;10518;10557.15542756593;10557.841632375674;7425.257474252531;8834.249088827522;8942.821143577223;9054.818903622023;9110.772230694285;8719.738407847775;8761.737147885573;8790.472571645721;8830.64677412922;8886.86669699968;7426.5915374654915;7909.8813517796125;8263.628136733741;8265.876011859707;8323.6670533178;5928.644281343133;5950.761969521171;5991.131285963424;6098.298695650057;6175;5717.742701578356;5836.795712150054;6066.939330606638;6158.168647232566;6377.202849643794 10:29:09 INFO - PROCESS | 1634 | |4;prototype.html;386.28729993162614;398.5556104942988;402.07522697795065;406.23823395358966;410.6058184143221;4791.928121078186;5412.83761487156;5474.644148130369;5544.695041772701;5572.4148964358765;4831.4685384607665;4954.671304486895;5170.431252562215;5369.362344484931;5390.21841832934;3943.6647884929816;4322.935155972663;4541.250693635546;4696.882577935562;4704.01215744694;3871.341871881787;4131.710780245384;4429.734215947037;4716.080364328957;4721.598664113537;3531.446163687969;4430.326006063601;4479.910401791963;4692.835750748724;4745.549172828576;3861.2663593917064;4669.626429885601;4679.15775160471;4684;4696.2486002239475;3604.243108947098;3840.7695538267512;4099.282625540519;4494.842680506166;4521.253993091136;4960.355153830015;5131.512506311894;5237.921431178535;5296.788128474856;5351.384590772033;5117.46266642001;5453.618246722731;5456.91814622781;5461.590380721462;5555.333359996826;5569.331680198402;5736.541076713853;5777.220075289824;5906.438888305604;5923.022701254288;4382.912341753147;4484.058347746977;4982.103221420143;5007.474215207387;5027.79888804451;5379.408265090837;5917.822465326047;5962.688208594102;5983.943567685343;6169.598976066542;4290.485141783006;4561.680682352204;4750.5249475052215;5164.2770012198325;5188.610854185876;4567.817287308537;4574.359589657451;5036.647434679538;5139.58883289336;5244.010913779185;4715.718395397177;4987.476314986947;5459.918101228484;5469.972650136724;5489.670619762827;5045.637677826966;5711.7144142792695;5735.799247026334;5753.964286428483;5759.136129580596;1597.370681904451;1664.983350166507;1735.2885317019961;1792.1487293535467;1801.819818018214;1031.8606988056667;1265.8923991460645;1275.9218460400946;1345.132389608697;1498.460554200487;5198.9999999999245;5563.443655634485;5572.442755724476;5575.832725018256;5595.524380427628;5328.813491527778;5389.386147718354;5480.808171713971;6033.4871535919065;6180.567360284737;4682.578567928835;4721.748736584807;5207.140821764367;5231.947680523146;5304.859455217147;2850.3016760893715;2927.6779554249015;3104.3480869017308;3113.984430077835;3127.890523831655;4990.627577416167;5931;6031.547633927473;6278.995360742259;6298.582818865719;5364.597655175878;5738.454846789548;5957.880842383128;6111.327753947151;6165.630062196283;5463.989162005121;5736.4550367715;5982.4017598239825;6246.250449946036;6318.977927063295;4982.451930287593;5679.914801278067;5866.600174541828;5928.851778705567;5949.44174005182;4954.497978520968;5102.979404119117;5448.945510544844;5836.328822185334;5918.437748413894;6044.370698746059;6072.423119803611;6163.167972323679;6255.405736455212;6351.96824015877;4351.630111440563;4594.931076033795;5179.689218646893;5213.765380557808;5268.420473747808;5832.50423713989;5904.675242861684;5913.58604897653;6023.6385816851125;6066.605670631571;4941.481144479922;5310.920336194883;5602.607817452739;5714.571257245786;5765.711714414347;4845.612351011981;5081.949180508148;5474.70062573712;5806.883862322646;5830.47525722687;4823.541763532458;5681.289838770153;5720.456556627114;5720.684242624303;5721.1990321354215;5834.766609335748;5848.883022339444;5850.23946886902;5887.882242355215;5932.347441781314;4844.685095468783;5567.3040869891265;5569.916451253153;5683.5737319700365;5695.800646977336 10:29:09 INFO - PROCESS | 1634 | |5;yui.html;731.9524230924986;760.5398733766068;760.5778792770012;811.5009269299379;891.5185799668177;16292.918535407322;16534.42129525467;17064.48806535806;17226.655466890654;17287.098419173883;16298.918505407457;16306.510804675878;16345;16512;16536.33854645816;1014.8477728340753;1044.185535282481;1049.380865289479;1054.0302921312411;1054.451685123739;1002.268344108801;1040.7866387390566;1042.1818872185336;1043.9217058720587;1044.7805960748215;1079.4656644960717;1099.6591056772427;1108.130117857482;1108.534415545473;1108.9390083545402;1085.3704851186333;1092.344593244059;1095.2059756676426;1096.7641956979248;1097.1003776902942;803.8673618852882;816.2245866426919;816.7386436340373;820.5774026376421;824.7690646618956;14948.701025979528;17291.913540432342;17850.464486065437;17966.730499042525;18132.456026319232;13109.016823738259;13484.932575337061;14686.779698304534;14960.401583936738;15043.849561504354;11170.050545703625;11774.587889423829;12052.758944821055;12090.972267357285;12167.878321216764;3803.6386543278345;3873.1672690371706;3882.6893848492055;3912.139329347539;4040.979795101035;94056.52971735098;110077.44961275143;119794.40102799605;119936;121444.78555214423;9902.851457228147;11308.660740177807;11404.258723182966;11482.48328825205;11714;23453.296401107993;24172.87913560421;24289.878550607133;24386.634200487006;24515.264542063767;7949.682012719542;7960.004999375078;7980.12218655959;8101.311388532041;8143.389245806588;4400.779961001937;4413.492448368479;4416.933745993812;4487.6634252431195;4693.741844198602;3661.5972243053243;3756.6619004289746;3758.586555478895;3759.2669429460993;3767.566729826105;534.986625334362;653.7613770973655;657.1916542652564;658.3942772649174;660.7423104989058;592.7836339735966;702.9894551581731;753.8567672142275;754.999999999989;759.8898159766914;10375.975832261483;10683.717953845588;10688.839667404995;10733.570657173781;10955.17836162291;723.1435828477535;739.918240528194;742.9108506979197;746.1754760989029;758.2682711183734;4891.608671306287;5182.637215394886;5192.221166824931;5202.687838729613;5234.3718753749035;88858.55570722105;90823.00473474023;111943;114660;115305;58897.705511472166;81185;91342.54328728447;95206.57190142153;96494;66292.668536657;75828.62085689536;76186;78940.21059789328;79324.6033769839;9527.333086683866;10997.505112269808;11245.325280483197;11315.886841131483;11480.483378247833;43855;48814.26778598253;49560.75219623879;50042.249366260265;50665;54582.181267280226;61980;63639;65119.37201569809;65954.01068984065;12018.75962480763;12689.87310126887;12691.87308126944;12921.741565168455;13127.212367257613;12674.809877852023;14985;15104.244787760788;15195.468158614396;15794.289256983233;13833.446662133401;14005.929970350084;14007.57977260643;14015.649608759863;14783;14100.78848817248;14151.221682807545;14163.498572488805;14171.787423188454;14278.928605356907;13629.454821807414;13878.583642490741;13911.513097041556;14079.859201408264;14118.78821817653;9087.409318394282;9466.52667366615;9752.902470975483;9805.947870019485;10306.227032972407;10563.20775941792;11389.487473063566;11433.828492572784;11445.313281203154;11662.88337116618 10:29:09 INFO - PROCESS | 1634 | __end_tp_report 10:29:09 INFO - PROCESS | 1634 | __start_cc_report 10:29:09 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,388 10:29:09 INFO - PROCESS | 1634 | __end_cc_report 10:29:09 INFO - PROCESS | 1634 | __startTimestamp1492795749561__endTimestamp 10:29:09 INFO - PROCESS | 1634 | 10:29:09 INFO - PROCESS | 1634 | ------- Summary: start ------- 10:29:09 INFO - PROCESS | 1634 | Number of tests: 6 10:29:09 INFO - PROCESS | 1634 | 10:29:09 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:6145.41 Median:6314.37 stddev:702.80 (11.1%) stddev-sans-first:661.77 10:29:09 INFO - PROCESS | 1634 | Values: 3053.3 3096.9 3101.5 3661.2 3714.5 6117.2 6169.7 6201.0 6204.0 6220.2 6159.5 6171.5 6176.2 6193.4 6222.1 5618.6 5683.4 5684.7 5695.8 5763.3 5725.7 5739.2 5740.5 5744.3 5748.3 5499.4 5515.5 5653.5 5704.3 5713.0 5725.6 5738.7 5747.4 5756.2 5803.9 5511.6 5643.4 5686.3 5690.8 5768.0 6024.5 6027.9 6047.1 6124.4 6249.4 6503.9 6527.7 6555.6 6569.1 6569.6 6704.8 6712.6 6721.9 6735.3 6735.8 6090.4 6304.9 6313.8 6314.3 6321.1 6620.5 6644.2 6708.1 6727.1 6762.1 6503.7 6529.6 6540.4 6562.6 6563.8 6593.8 6648.5 6689.6 6698.7 6713.8 6305.8 6340.9 6494.4 6579.7 6607.7 6296.6 6591.5 6611.8 6617.7 6635.4 5140.9 5240.6 5301.6 5313.3 5345.3 4744.6 4745.1 4762.7 4776.6 4817.1 6165.1 6218.0 6373.7 6394.2 6444.8 6518.1 6580.5 6708.9 6718.2 6718.5 6374.9 6401.6 6402.0 6402.8 6412.6 5670.4 5850.3 5867.4 5870.5 5874.3 6780.7 6864.7 6875.9 6892.6 6907.9 6533.7 6681.4 6687.6 6696.2 6719.4 6931.7 6969.9 6990.7 6996.8 7010.0 6302.2 6370.7 6439.7 6505.7 6551.5 5349.8 6677.1 6679.5 6708.2 6727.5 6240.4 6461.5 6466.5 6487.3 6495.4 6231.0 6261.4 6308.7 6308.8 6501.1 6227.9 6314.4 6317.8 6322.5 6338.5 5147.8 6441.6 6451.0 6453.3 6456.7 6191.2 6202.9 6283.0 6283.2 6287.3 10:29:09 INFO - PROCESS | 1634 | 10:29:09 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:47480.38 Median:31128.53 stddev:87201.97 (280.1%) stddev-sans-first:87376.11 10:29:09 INFO - PROCESS | 1634 | Values: 783.2 794.7 838.9 868.3 883.5 39932.4 40825.0 42425.4 42793.9 42909.0 32322.5 40818.6 41116.6 41196.2 41410.6 8261.6 11280.8 11548.5 11750.2 11755.6 11333.9 11389.1 11419.7 11423.0 11431.7 11441.7 11452.1 11464.5 11464.9 11492.0 11370.7 11389.7 11438.4 11438.4 11443.8 9112.8 9464.5 11139.2 11170.0 11225.0 28290.4 35598.6 35653.8 35835.8 35851.6 3890.0 3944.2 3946.9 4001.0 4135.4 25869.6 26225.3 26268.0 26270.5 26284.1 3227.9 3269.9 3312.1 3410.4 3416.7 305173.0 347628.0 356533.2 367865.0 369211.0 44769.6 45841.5 47563.3 47612.0 47630.8 47840.3 47865.3 47871.0 48008.0 48069.0 14002.6 14177.6 14189.3 14191.5 14326.9 12162.8 12200.0 12234.3 12263.6 12324.9 12261.3 12339.5 12360.4 12382.3 12446.9 5895.8 5915.1 5923.8 5939.7 5963.5 1086.7 1168.4 1177.8 1186.5 1199.4 23756.5 26890.0 27709.6 28440.0 28454.0 1074.9 1270.1 1292.0 1300.4 1324.3 14371.9 14432.4 14639.5 14676.9 14697.3 202388.0 202824.0 203348.0 203729.0 204002.0 57084.0 57427.1 57643.0 57709.8 57933.0 1498.3 1507.7 1539.6 1542.0 1544.0 25727.6 26894.7 27276.9 27439.6 27552.2 48655.0 48662.3 48672.0 48752.5 48777.5 403753.0 403759.0 403865.0 404167.0 406436.0 27715.9 28330.2 29874.9 32700.8 32826.7 44590.0 45932.5 46107.3 47046.8 47177.5 30966.4 31053.1 31253.4 31351.8 31943.7 26119.3 31204.0 31989.4 32202.8 32257.0 29660.7 31779.5 36732.1 36838.0 38011.6 31364.2 31462.1 31695.0 31746.7 31945.5 21370.6 23218.5 25302.1 25320.1 25415.1 10:29:09 INFO - PROCESS | 1634 | 10:29:09 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:43676.30 Median:23333.32 stddev:126069.80 (540.3%) stddev-sans-first:126381.63 10:29:09 INFO - PROCESS | 1634 | Values: 425.5 441.0 442.8 450.4 450.9 20830.9 21692.5 22300.9 22496.2 22573.0 18728.1 22502.3 24093.6 24158.4 24714.5 6827.8 6986.7 7097.4 7230.5 7269.0 6479.1 6541.6 6565.3 6566.2 6567.3 6396.8 6560.0 7008.4 7028.9 7057.5 6892.8 6929.7 7077.9 7106.8 7125.0 6762.9 6765.3 6770.4 6776.3 6799.4 26554.6 26872.9 27517.3 27536.4 27685.6 2901.1 3033.7 3047.5 3053.5 3067.2 14469.8 15117.0 16594.6 16949.5 17455.0 2096.2 2101.7 2127.7 2129.3 2132.3 170985.0 175420.1 176961.0 179205.1 181199.0 18761.9 19945.8 20414.7 20757.6 21142.2 30784.2 30942.2 31173.8 31472.4 31625.4 9138.7 9968.6 9977.7 10119.4 10279.3 7508.4 7901.6 8512.5 8523.7 8627.2 9536.9 9575.2 9615.8 9671.5 9691.3 3602.2 4045.8 4093.2 4125.3 4126.2 955.4 962.5 982.6 983.7 1127.4 15806.8 17803.8 18033.9 18624.9 21924.8 940.2 1045.8 1081.3 1082.4 1089.9 9798.9 11464.4 11544.3 11595.0 11668.2 617380.0 750412.0 779401.0 791385.0 807588.0 123638.0 140669.0 151906.0 170382.0 171681.3 1314.6 1322.2 1365.6 1369.2 1370.8 16023.6 18478.9 19859.5 21046.8 21151.2 59256.4 59411.7 59888.7 60263.0 60654.7 86335.0 86921.0 87093.0 87596.6 89751.0 11674.6 11796.4 11920.0 11993.9 12089.6 12770.5 12938.0 12948.8 13260.3 13271.6 11438.0 11565.9 11605.4 11767.3 11928.5 8949.4 9496.9 10187.7 10395.2 10846.1 10333.4 11706.8 12007.5 12107.4 12235.5 11822.3 13648.5 14056.5 14061.6 14299.6 11198.6 11567.4 12260.3 12287.6 12502.2 10:29:09 INFO - PROCESS | 1634 | 10:29:09 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:6959.16 Median:2509.37 stddev:4301.18 (171.4%) stddev-sans-first:4294.26 10:29:09 INFO - PROCESS | 1634 | Values: 1580.6 1634.9 1646.4 1649.4 1666.2 11279.9 11536.7 11942.2 12043.6 12084.9 11420.8 11615.3 11616.4 11617.8 11655.5 2511.7 2541.3 2547.5 2549.6 2552.2 2429.1 2433.1 2434.9 2442.8 2465.1 2053.9 2267.2 2402.8 2416.7 2523.5 2013.6 2474.9 2483.6 2487.0 2520.2 2474.2 2501.3 2504.8 2506.1 2507.0 10835.1 10957.3 10967.6 11028.1 11040.2 5927.5 6064.5 6086.7 6093.5 6102.1 3694.8 3773.8 3807.2 3846.7 3863.9 1291.8 1847.0 1933.5 1950.3 1978.2 12512.3 14147.5 14426.8 16017.4 16129.4 4862.6 4938.5 4978.2 4984.1 4994.2 12187.0 12333.1 12400.3 12415.9 12418.6 5880.4 6074.0 6106.8 6109.6 6144.5 5504.9 5510.4 5540.2 5583.7 5592.4 3926.7 4028.4 4064.8 4180.6 4185.4 1092.2 1096.3 1098.9 1107.2 1110.2 853.3 875.8 878.4 897.8 899.2 7175.8 7321.0 7391.5 7471.4 7482.5 925.7 942.8 1090.5 1110.1 1132.4 3590.5 4345.6 4446.5 4463.3 4676.2 13896.5 14057.6 14233.0 14468.3 14517.0 14117.9 14316.4 14427.9 14475.9 14483.0 10590.4 10675.0 10716.3 10786.4 10793.4 6920.8 6929.4 7072.5 7130.9 7133.5 12437.9 12683.0 12734.9 12773.6 12901.0 11715.6 12993.2 13051.3 13218.5 13331.5 7363.6 8442.6 8633.3 8650.6 8978.0 10280.8 10457.1 10518.0 10557.2 10557.8 7425.3 8834.2 8942.8 9054.8 9110.8 8719.7 8761.7 8790.5 8830.6 8886.9 7426.6 7909.9 8263.6 8265.9 8323.7 5928.6 5950.8 5991.1 6098.3 6175.0 5717.7 5836.8 6066.9 6158.2 6377.2 10:29:09 INFO - PROCESS | 1634 | 10:29:09 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4894.37 Median:5300.82 stddev:1340.69 (25.3%) stddev-sans-first:1301.05 10:29:09 INFO - PROCESS | 1634 | Values: 386.3 398.6 402.1 406.2 410.6 4791.9 5412.8 5474.6 5544.7 5572.4 4831.5 4954.7 5170.4 5369.4 5390.2 3943.7 4322.9 4541.3 4696.9 4704.0 3871.3 4131.7 4429.7 4716.1 4721.6 3531.4 4430.3 4479.9 4692.8 4745.5 3861.3 4669.6 4679.2 4684.0 4696.2 3604.2 3840.8 4099.3 4494.8 4521.3 4960.4 5131.5 5237.9 5296.8 5351.4 5117.5 5453.6 5456.9 5461.6 5555.3 5569.3 5736.5 5777.2 5906.4 5923.0 4382.9 4484.1 4982.1 5007.5 5027.8 5379.4 5917.8 5962.7 5983.9 6169.6 4290.5 4561.7 4750.5 5164.3 5188.6 4567.8 4574.4 5036.6 5139.6 5244.0 4715.7 4987.5 5459.9 5470.0 5489.7 5045.6 5711.7 5735.8 5754.0 5759.1 1597.4 1665.0 1735.3 1792.1 1801.8 1031.9 1265.9 1275.9 1345.1 1498.5 5199.0 5563.4 5572.4 5575.8 5595.5 5328.8 5389.4 5480.8 6033.5 6180.6 4682.6 4721.7 5207.1 5231.9 5304.9 2850.3 2927.7 3104.3 3114.0 3127.9 4990.6 5931.0 6031.5 6279.0 6298.6 5364.6 5738.5 5957.9 6111.3 6165.6 5464.0 5736.5 5982.4 6246.3 6319.0 4982.5 5679.9 5866.6 5928.9 5949.4 4954.5 5103.0 5448.9 5836.3 5918.4 6044.4 6072.4 6163.2 6255.4 6352.0 4351.6 4594.9 5179.7 5213.8 5268.4 5832.5 5904.7 5913.6 6023.6 6066.6 4941.5 5310.9 5602.6 5714.6 5765.7 4845.6 5081.9 5474.7 5806.9 5830.5 4823.5 5681.3 5720.5 5720.7 5721.2 5834.8 5848.9 5850.2 5887.9 5932.3 4844.7 5567.3 5569.9 5683.6 5695.8 10:29:09 INFO - PROCESS | 1634 | 10:29:09 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:20711.85 Median:17256.88 stddev:29551.06 (171.2%) stddev-sans-first:29595.88 10:29:09 INFO - PROCESS | 1634 | Values: 732.0 760.5 760.6 811.5 891.5 16292.9 16534.4 17064.5 17226.7 17287.1 16298.9 16306.5 16345.0 16512.0 16536.3 1014.8 1044.2 1049.4 1054.0 1054.5 1002.3 1040.8 1042.2 1043.9 1044.8 1079.5 1099.7 1108.1 1108.5 1108.9 1085.4 1092.3 1095.2 1096.8 1097.1 803.9 816.2 816.7 820.6 824.8 14948.7 17291.9 17850.5 17966.7 18132.5 13109.0 13484.9 14686.8 14960.4 15043.8 11170.1 11774.6 12052.8 12091.0 12167.9 3803.6 3873.2 3882.7 3912.1 4041.0 94056.5 110077.4 119794.4 119936.0 121444.8 9902.9 11308.7 11404.3 11482.5 11714.0 23453.3 24172.9 24289.9 24386.6 24515.3 7949.7 7960.0 7980.1 8101.3 8143.4 4400.8 4413.5 4416.9 4487.7 4693.7 3661.6 3756.7 3758.6 3759.3 3767.6 535.0 653.8 657.2 658.4 660.7 592.8 703.0 753.9 755.0 759.9 10376.0 10683.7 10688.8 10733.6 10955.2 723.1 739.9 742.9 746.2 758.3 4891.6 5182.6 5192.2 5202.7 5234.4 88858.6 90823.0 111943.0 114660.0 115305.0 58897.7 81185.0 91342.5 95206.6 96494.0 66292.7 75828.6 76186.0 78940.2 79324.6 9527.3 10997.5 11245.3 11315.9 11480.5 43855.0 48814.3 49560.8 50042.2 50665.0 54582.2 61980.0 63639.0 65119.4 65954.0 12018.8 12689.9 12691.9 12921.7 13127.2 12674.8 14985.0 15104.2 15195.5 15794.3 13833.4 14005.9 14007.6 14015.6 14783.0 14100.8 14151.2 14163.5 14171.8 14278.9 13629.5 13878.6 13911.5 14079.9 14118.8 9087.4 9466.5 9752.9 9805.9 10306.2 10563.2 11389.5 11433.8 11445.3 11662.9 10:29:09 INFO - PROCESS | 1634 | -------- Summary: end -------- 10:29:09 INFO - PROCESS | 1634 | 10:29:10 INFO - TEST-INFO | 1634: exit 0 10:29:10 INFO - TEST-OK | dromaeo_css | took 1114798ms 10:29:10 INFO - TEST-START | kraken 10:29:10 INFO - operating with platform_type : mac_ 10:29:10 INFO - Initialising browser for kraken test... 10:29:10 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpiynjqM/profile http://localhost:49251/getInfo.html) 10:29:12 INFO - PROCESS | 1646 | 2017-04-21 10:29:12.779 plugin-container[1648:10488] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 10:29:12 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:29:12 INFO - PROCESS | 1646 | 2017-04-21 10:29:12.779 plugin-container[1648:10488] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 10:29:12 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:29:12 INFO - PROCESS | 1646 | 2017-04-21 10:29:12.779 plugin-container[1648:10488] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:29:12 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 10:29:12 INFO - PROCESS | 1646 | colorDepth:24 10:29:12 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 10:29:12 INFO - PROCESS | 1646 | __metrics 10:29:12 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:29:13 INFO - TEST-INFO | 1646: exit 0 10:29:13 INFO - Browser initialized. 10:29:13 INFO - Running cycle 1/1 for kraken test... 10:29:13 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpiynjqM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:29:15 INFO - PROCESS | 1649 | 2017-04-21 10:29:15.913 plugin-container[1650:10611] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 10:29:15 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:29:15 INFO - PROCESS | 1649 | 2017-04-21 10:29:15.914 plugin-container[1650:10611] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient' 10:29:15 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:29:15 INFO - PROCESS | 1649 | 2017-04-21 10:29:15.914 plugin-container[1650:10611] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:29:25 INFO - PROCESS | 1649 | RSS: Main: 174657536 10:29:25 INFO - PROCESS | 1649 | 10:29:28 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 10:29:29 INFO - PROCESS | 1649 | RSS: Main: 188309504 10:29:29 INFO - PROCESS | 1649 | 10:29:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 10:29:32 INFO - PROCESS | 1649 | RSS: Main: 196333568 10:29:32 INFO - PROCESS | 1649 | 10:29:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 10:29:36 INFO - PROCESS | 1649 | RSS: Main: 200011776 10:29:36 INFO - PROCESS | 1649 | 10:29:38 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 10:29:39 INFO - PROCESS | 1649 | RSS: Main: 200314880 10:29:39 INFO - PROCESS | 1649 | 10:29:41 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:29:41 INFO - PROCESS | 1649 | RSS: Main: 201756672 10:29:41 INFO - PROCESS | 1649 | 10:29:45 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 10:29:45 INFO - PROCESS | 1649 | RSS: Main: 205905920 10:29:45 INFO - PROCESS | 1649 | 10:29:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 10:29:49 INFO - PROCESS | 1649 | RSS: Main: 204861440 10:29:49 INFO - PROCESS | 1649 | 10:29:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 10:29:53 INFO - PROCESS | 1649 | RSS: Main: 205287424 10:29:53 INFO - PROCESS | 1649 | 10:29:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:29:56 INFO - PROCESS | 1649 | RSS: Main: 204689408 10:29:56 INFO - PROCESS | 1649 | 10:29:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:29:58 INFO - PROCESS | 1649 | RSS: Main: 204664832 10:29:58 INFO - PROCESS | 1649 | 10:30:00 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:30:01 INFO - PROCESS | 1649 | RSS: Main: 204726272 10:30:01 INFO - PROCESS | 1649 | 10:30:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:30:05 INFO - PROCESS | 1649 | RSS: Main: 205090816 10:30:05 INFO - PROCESS | 1649 | 10:30:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:30:08 INFO - PROCESS | 1649 | RSS: Main: 204922880 10:30:08 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 10:30:10 INFO - PROCESS | 1649 | RSS: Main: 207007744 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | __start_tp_report 10:30:10 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 10:30:10 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 10:30:10 INFO - PROCESS | 1649 | |i|pagename|runs| 10:30:10 INFO - PROCESS | 1649 | |0;ai-astar;109;120;120;119;117;119;119;124;120;119 10:30:10 INFO - PROCESS | 1649 | |1;audio-beat-detection;139;109;126;176;137;110;112;111;129;108 10:30:10 INFO - PROCESS | 1649 | |2;audio-dft;196;199;195;197;195;193;195;197;283;247 10:30:10 INFO - PROCESS | 1649 | |3;audio-fft;84;59;59;94;87;83;60;61;89;52 10:30:10 INFO - PROCESS | 1649 | |4;audio-oscillator;90;69;86;85;59;77;113;101;98;81 10:30:10 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;120;133;88;92;88;113;137;89;136;136 10:30:10 INFO - PROCESS | 1649 | |6;imaging-darkroom;95;96;96;96;138;120;116;139;154;140 10:30:10 INFO - PROCESS | 1649 | |7;imaging-desaturate;148;79;145;150;95;148;88;146;147;100 10:30:10 INFO - PROCESS | 1649 | |8;json-parse-financial;53;60;91;105;105;103;96;69;67;67 10:30:10 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;55;64;57;52;59;56;56;58;57;56 10:30:10 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;72;71;84;72;90;75;76;79;77;74 10:30:10 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;152;149;145;160;155;149;147;153;201;146 10:30:10 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;166;165;197;228;184;166;161;172;163;162 10:30:10 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;62;67;72;81;84;83;85;86;63;67 10:30:10 INFO - PROCESS | 1649 | __end_tp_report 10:30:10 INFO - PROCESS | 1649 | __start_cc_report 10:30:10 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,985 10:30:10 INFO - PROCESS | 1649 | __end_cc_report 10:30:10 INFO - PROCESS | 1649 | __startTimestamp1492795810619__endTimestamp 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | ------- Summary: start ------- 10:30:10 INFO - PROCESS | 1649 | Number of tests: 14 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:118.60 Median:119.50 stddev:3.81 (3.2%) stddev-sans-first:1.87 10:30:10 INFO - PROCESS | 1649 | Values: 109.0 120.0 120.0 119.0 117.0 119.0 119.0 124.0 120.0 119.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:125.70 Median:127.50 stddev:21.31 (16.7%) stddev-sans-first:22.06 10:30:10 INFO - PROCESS | 1649 | Values: 139.0 109.0 126.0 176.0 137.0 110.0 112.0 111.0 129.0 108.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:209.70 Median:197.00 stddev:30.40 (15.4%) stddev-sans-first:31.83 10:30:10 INFO - PROCESS | 1649 | Values: 196.0 199.0 195.0 197.0 195.0 193.0 195.0 197.0 283.0 247.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:72.80 Median:83.50 stddev:15.85 (19.0%) stddev-sans-first:16.28 10:30:10 INFO - PROCESS | 1649 | Values: 84.0 59.0 59.0 94.0 87.0 83.0 60.0 61.0 89.0 52.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:83.00 stddev:15.77 (19.0%) stddev-sans-first:16.66 10:30:10 INFO - PROCESS | 1649 | Values: 90.0 69.0 86.0 85.0 59.0 77.0 113.0 101.0 98.0 81.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:112.50 stddev:21.98 (19.5%) stddev-sans-first:23.18 10:30:10 INFO - PROCESS | 1649 | Values: 120.0 133.0 88.0 92.0 88.0 113.0 137.0 89.0 136.0 136.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:119.00 Median:124.50 stddev:22.61 (18.2%) stddev-sans-first:22.25 10:30:10 INFO - PROCESS | 1649 | Values: 95.0 96.0 96.0 96.0 138.0 120.0 116.0 139.0 154.0 140.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:124.60 Median:149.00 stddev:29.84 (20.0%) stddev-sans-first:30.43 10:30:10 INFO - PROCESS | 1649 | Values: 148.0 79.0 145.0 150.0 95.0 148.0 88.0 146.0 147.0 100.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:81.60 Median:67.00 stddev:20.33 (30.3%) stddev-sans-first:18.74 10:30:10 INFO - PROCESS | 1649 | Values: 53.0 60.0 91.0 105.0 105.0 103.0 96.0 69.0 67.0 67.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:57.00 Median:57.00 stddev:3.09 (5.4%) stddev-sans-first:3.19 10:30:10 INFO - PROCESS | 1649 | Values: 55.0 64.0 57.0 52.0 59.0 56.0 56.0 58.0 57.0 56.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:77.00 Median:76.50 stddev:5.98 (7.8%) stddev-sans-first:6.06 10:30:10 INFO - PROCESS | 1649 | Values: 72.0 71.0 84.0 72.0 90.0 75.0 76.0 79.0 77.0 74.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:155.70 Median:152.50 stddev:16.55 (10.9%) stddev-sans-first:17.50 10:30:10 INFO - PROCESS | 1649 | Values: 152.0 149.0 145.0 160.0 155.0 149.0 147.0 153.0 201.0 146.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:169.00 stddev:21.43 (12.7%) stddev-sans-first:22.40 10:30:10 INFO - PROCESS | 1649 | Values: 166.0 165.0 197.0 228.0 184.0 166.0 161.0 172.0 163.0 162.0 10:30:10 INFO - PROCESS | 1649 | 10:30:10 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:82.00 stddev:9.73 (11.9%) stddev-sans-first:9.11 10:30:10 INFO - PROCESS | 1649 | Values: 62.0 67.0 72.0 81.0 84.0 83.0 85.0 86.0 63.0 67.0 10:30:10 INFO - PROCESS | 1649 | -------- Summary: end -------- 10:30:10 INFO - PROCESS | 1649 | 10:30:11 INFO - TEST-INFO | 1649: exit 0 10:30:11 INFO - TEST-OK | kraken | took 61362ms 10:30:11 INFO - SUITE-END | took 1176s 10:30:11 INFO - Completed test suite (00:19:36) 10:30:11 INFO - javascript benchmark 10:30:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7973.149960464816, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3053.2977415194505, 3096.8916087936927, 3101.4572449821285, 3661.1579336752548, 3714.4614030965513, 6117.174181485498, 6169.660668663228, 6201.0, 6203.968980155104, 6220.222472190976, 6159.476444502223, 6171.506279497629, 6176.197094377736, 6193.442590166884, 6222.12890195373, 5618.634788738739, 5683.374828768832, 5684.744186511617, 5695.800646977356, 5763.250777398932, 5725.713714314288, 5739.225204597367, 5740.54075673945, 5744.281964754406, 5748.310202775673, 5499.422560631137, 5515.503604675579, 5653.519450846662, 5704.344000439961, 5712.971435142818, 5725.57058220633, 5738.684372359518, 5747.39652336507, 5756.194132821387, 5803.941960580382, 5511.641743286674, 5643.435656434365, 5686.346070201938, 5690.829275121711, 5768.019436695772, 6024.48791852693, 6027.8794424111275, 6047.0627052806885, 6124.448799608012, 6249.40630640093, 6503.93496065038, 6527.738890444377, 6555.6066636002, 6569.113169722122, 6569.57297775643, 6704.83237919051, 6712.563683360566, 6721.924492081293, 6735.326467353275, 6735.831604209885, 6090.36051214625, 6304.936950630527, 6313.842153946142, 6314.273858506331, 6321.146645202884, 6620.470362370998, 6644.202695676453, 6708.060871477997, 6727.091842601284, 6762.087118239073, 6503.674816259168, 6529.575577587441, 6540.411362977355, 6562.639054852029, 6563.835904102436, 6593.769218077345, 6648.53460257787, 6689.632070236184, 6698.66506674674, 6713.765018224436, 6305.842353941096, 6340.904886426707, 6494.383033611799, 6579.736810527621, 6607.735690572419, 6296.5592408531875, 6591.505637077143, 6611.801645950629, 6617.669116544153, 6635.369639884299, 5140.897182056338, 5240.633155679142, 5301.575873930076, 5313.282706834528, 5345.251664766935, 4744.644151688568, 4745.098431298043, 4762.690425122356, 4776.641751868554, 4817.13291607514, 6165.136880836686, 6217.96891015542, 6373.74505019794, 6394.200724909387, 6444.77443289492, 6518.087467754518, 6580.473562115019, 6708.865822683617, 6718.193816742022, 6718.462522998148, 6374.936250637435, 6401.64790936503, 6402.0, 6402.839929001719, 6412.551121421549, 5670.432956704297, 5850.3272123705465, 5867.413258674099, 5870.530357571384, 5874.32445268791, 6780.660966951633, 6864.691088901011, 6875.89686154698, 6892.620905850226, 6907.861842763217, 6533.67331633407, 6681.398674119353, 6687.565308254948, 6696.229933557704, 6719.361660642231, 6931.6880740367815, 6969.860602788017, 6990.720371185095, 6996.755113571001, 7009.9649501752165, 6302.180716506825, 6370.681465926593, 6439.710213040331, 6505.707243173973, 6551.475881929339, 5349.812756553502, 6677.09859169006, 6679.532432729662, 6708.228553716385, 6727.529072965041, 6240.375962403723, 6461.450776683847, 6466.494332869245, 6487.253965793994, 6495.415412612888, 6230.971889638159, 6261.436470717657, 6308.653024083719, 6308.84227894288, 6501.122348482894, 6227.875442491216, 6314.368563143649, 6317.842053948688, 6322.494200464043, 6338.524610654127, 5147.819826306061, 6441.613503189824, 6450.967745161244, 6453.290138084901, 6456.677166141768, 6191.19514463135, 6202.875942481215, 6282.968585157045, 6283.2460104787715, 6287.30839607652], "value": 6097.635128511047, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.1854870934229, 794.6543253684644, 838.865781474964, 868.3010176807671, 883.4743327720005, 39932.401013984774, 40825.0, 42425.36361954566, 42793.93015174614, 42909.0, 32322.515162272586, 40818.59181408185, 41116.588834111724, 41196.176076478456, 41410.58589414105, 8261.58692065396, 11280.774384512304, 11548.538058477692, 11750.177487575873, 11755.588554400598, 11333.943330283337, 11389.088872890148, 11419.657410277705, 11423.0, 11431.714207144847, 11441.713957151054, 11452.141089418285, 11464.541418343257, 11464.942675286611, 11492.023178029878, 11370.71573210668, 11389.658310250707, 11438.370889601068, 11438.370889601068, 11443.828342574869, 9112.772180695469, 9464.479453630047, 11139.164562657752, 11170.0, 11225.0, 28290.434191316264, 35598.644013559795, 35653.75211867572, 35835.82082089599, 35851.64148358509, 3890.027493126718, 3944.1520073184115, 3946.881593552198, 4000.9997500624845, 4135.441715368417, 25869.585703728288, 26225.344366390804, 26268.0, 26270.47459050808, 26284.080057198098, 3227.854746536388, 3269.869205231788, 3312.0560640217664, 3410.437277849152, 3416.6754158354915, 305173.0, 347628.0, 356533.21733391425, 367865.0, 369210.9999999973, 44769.55230447719, 45841.54158458373, 47563.28655070177, 47612.0, 47630.76184619124, 47840.282395764094, 47865.282020769715, 47871.0, 48008.0, 48069.0, 14002.649933751534, 14177.574672759834, 14189.290535473185, 14191.503297384543, 14326.856731432552, 12162.75674486523, 12199.963003144654, 12234.327112008925, 12263.570775022832, 12324.938375308246, 12261.26432414058, 12339.50641974329, 12360.381980900918, 12382.257064576153, 12446.875531244752, 5895.764169433261, 5915.112733089984, 5923.7926672566255, 5939.732712027969, 5963.493103086286, 1086.6739978006567, 1168.351564881499, 1177.7938860699344, 1186.4720199511135, 1199.4482538032428, 23756.524869502515, 26890.0, 27709.58435623467, 28440.0, 28454.0, 1074.9140068794477, 1270.1490001698885, 1291.9547815826402, 1300.3823183987531, 1324.3113580937859, 14371.856281437262, 14432.350544225535, 14639.48761793332, 14676.8992325576, 14697.338619762137, 202388.0, 202824.0, 203347.98326008572, 203729.0, 204002.0, 57084.0, 57427.13859292114, 57643.0, 57709.84580308454, 57933.0, 1498.3482185249452, 1507.743683573817, 1539.58431223569, 1541.9691606167814, 1543.9691206176037, 25727.614085788355, 26894.731052690004, 27276.863615681796, 27439.588406173523, 27552.173434796987, 48655.0, 48662.27006594975, 48672.0, 48752.5124748748, 48777.5122248773, 403753.0, 403759.0, 403865.0, 404167.0, 406435.967820159, 27715.861420692767, 28330.150095497167, 29874.8506257476, 32700.83649581832, 32826.67173328236, 44590.0, 45932.540674594165, 46107.3083903735, 47046.76476617595, 47177.528224717316, 30966.380672386877, 31053.068407947798, 31253.374932500767, 31351.84324078365, 31943.68056319407, 26119.347016323984, 31204.0, 31989.36021279515, 32202.83898580492, 32257.0, 29660.703392966658, 31779.52330714995, 36732.12587190873, 36838.0, 38011.619883800806, 31364.215894602818, 31462.056138315886, 31695.0, 31746.68253317437, 31945.520817188226, 21370.57258854783, 23218.490798098068, 25302.114425995, 25320.100992425272, 25415.11047113416], "value": 18630.41795212733, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.46816479400746, 440.9823607055718, 442.838363997141, 450.3784777007731, 450.8827704796753, 20830.89584552077, 21692.457688557795, 22300.88849555752, 22496.21263255782, 22573.0, 18728.06359682017, 22502.324930252118, 24093.63859542104, 24158.396040098964, 24714.517128972275, 6827.761028364008, 6986.720531178759, 7097.432205423554, 7230.530015548999, 7269.0, 6479.092926990202, 6541.574797638162, 6565.310642382553, 6566.179227596551, 6567.277599464079, 6396.808095757135, 6559.967200163993, 7008.36924676779, 7028.894566581505, 7057.470689698293, 6892.793216203522, 6929.653517324114, 7077.858442831141, 7106.751263705772, 7125.002499650027, 6762.932370676329, 6765.289644587346, 6770.424513916323, 6776.322367763184, 6799.42204912583, 26554.6016809748, 26872.925082996655, 27517.31206719828, 27536.449271014466, 27685.584716229274, 2901.1151598762367, 3033.7117973792674, 3047.54286856971, 3053.5419687046897, 3067.156531953708, 14469.842412606966, 15117.0, 16594.58513537159, 16949.49151525456, 17454.95270283787, 2096.2453516733963, 2101.6637338025994, 2127.6914847347202, 2129.3292612827063, 2132.2963422070834, 170985.0, 175420.12289938595, 176961.0, 179205.1039744806, 181199.0, 18761.90619046896, 19945.800541994686, 20414.69377959332, 20757.584848303253, 21142.15431382758, 30784.230394239876, 30942.226444338623, 31173.844130779653, 31472.370552589276, 31625.367492650017, 9138.68014619485, 9968.601255949681, 9977.650782222587, 10119.443430611387, 10279.280450368551, 7508.436867234979, 7901.617216987004, 8512.489250644981, 8523.721441783782, 8627.180417860292, 9536.856947145798, 9575.23398128162, 9615.807683846284, 9671.468069256118, 9691.27315451344, 3602.225521512887, 4045.7774822384463, 4093.2222877653153, 4125.339945608688, 4126.216018956388, 955.3837774635322, 962.4610218277787, 982.570360126015, 983.6508039645965, 1127.3574062784298, 15806.762898556532, 17803.821961780217, 18033.909830450764, 18624.906875465535, 21924.780752192273, 940.219617717293, 1045.7647029418472, 1081.2863510083307, 1082.3614067700096, 1089.8801131875487, 9798.902010979798, 11464.369459679798, 11544.307341559534, 11594.956453919187, 11668.241564298294, 617380.0, 750412.0, 779401.0, 791385.0, 807588.0, 123638.0, 140669.0, 151906.0, 170382.0, 171681.28318716653, 1314.625331780438, 1322.2066759943957, 1365.6108009217326, 1369.2469141972074, 1370.7943808428815, 16023.599410014844, 18478.891266523515, 19859.503512412306, 21046.78953210448, 21151.153953841673, 59256.40743592509, 59411.70294148502, 59888.70055649694, 60263.0, 60654.696726516086, 86335.0, 86921.0, 87093.0, 87596.5620171895, 89751.0, 11674.649760507198, 11796.410179491162, 11920.0, 11993.940030299793, 12089.57686480969, 12770.489180432678, 12937.964962802766, 12948.8057679133, 13260.270685112411, 13271.601851944457, 11438.0, 11565.94217028943, 11605.419729013347, 11767.293962362286, 11928.463219154984, 8949.418287811272, 9496.857547136937, 10187.726534183226, 10395.220358472994, 10846.132309415385, 10333.431661258703, 11706.76586468283, 12007.519699211934, 12107.394630268274, 12235.510579576718, 11822.34977076269, 13648.522301719393, 14056.508022219174, 14061.648458788202, 14299.57101286963, 11198.552057917592, 11567.414101628523, 12260.26438413698, 12287.56993505223, 12502.187357821713], "value": 11792.194790530071, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1580.6048487878031, 1634.926428310726, 1646.448439772676, 1649.356750867162, 1666.2002238925315, 11279.943600281997, 11536.65390038298, 11942.223755455889, 12043.638690839267, 12084.879151208484, 11420.771584568303, 11615.303081815096, 11616.419179041035, 11617.825732614017, 11655.533778648865, 2511.7488251174827, 2541.2503311523146, 2547.503236868811, 2549.566573682469, 2552.247087109298, 2429.1133736186343, 2433.111914151331, 2434.853908765471, 2442.829001969863, 2465.149523414428, 2053.917843286267, 2267.2178098555974, 2402.759724027592, 2416.6858308419883, 2523.5205310990946, 2013.577148798754, 2474.938126546842, 2483.5902076157413, 2487.0300582772734, 2520.1935380678005, 2474.183519438581, 2501.2871331670567, 2504.799616030713, 2506.147909710707, 2507.0, 10835.133189344911, 10957.287776294515, 10967.616133435371, 11028.117750579935, 11040.171987100919, 5927.496162826165, 6064.484518815906, 6086.726097325587, 6093.482054025413, 6102.084687296897, 3694.7598406103784, 3773.7735735855663, 3807.200487897545, 3846.673032792216, 3863.9227215455535, 1291.767481853266, 1846.9722954155698, 1933.5456167800696, 1950.3271371376852, 1978.1592823050146, 12512.311822849648, 14147.504837330644, 14426.783598246035, 16017.359305627877, 16129.435469758619, 4862.635302352338, 4938.456769755324, 4978.203487442064, 4984.077945580079, 4994.200927851599, 12186.964108050737, 12333.136680432284, 12400.31798251105, 12415.875841241652, 12418.56535021288, 5880.411958804086, 6073.96963015191, 6106.816795496142, 6109.572329936862, 6144.508439324933, 5504.889902201933, 5510.366307874566, 5540.2243685884005, 5583.664980101207, 5592.412796656374, 3926.6680458275437, 4028.4158796974275, 4064.8170832312617, 4180.6446452051305, 4185.434966279513, 1092.1754075672816, 1096.3257596578208, 1098.9175811814146, 1107.2028139739375, 1110.2062025651699, 853.3002937591116, 875.8292133033997, 878.4290211362537, 897.8249241397865, 899.1682693508609, 7175.820604484929, 7321.0, 7391.48259621832, 7471.402287816962, 7482.476226664081, 925.726910561386, 942.8161508505913, 1090.487470888681, 1110.1396417776289, 1132.4111462039693, 3590.461430785403, 4345.587169218919, 4446.5331140230455, 4463.308187230984, 4676.205045142334, 13896.51362202318, 14057.578272651837, 14233.0, 14468.348924298432, 14517.0, 14117.929410353088, 14316.427342906167, 14427.92786036063, 14475.92762036183, 14483.0, 10590.364578125336, 10675.0, 10716.303440276357, 10786.352818830896, 10793.352398856094, 6920.826979325557, 6929.376356127771, 7072.504924655431, 7130.857382852418, 7133.500654954103, 12437.93781031089, 12682.9853611709, 12734.936325318315, 12773.61679149627, 12901.032422568533, 11715.648530544097, 12993.220406775623, 13051.28217948022, 13218.537351192663, 13331.466741330627, 7363.631818408951, 8442.620082096444, 8633.30933525304, 8650.56747162652, 8978.0, 10280.845787313347, 10457.111145552713, 10518.0, 10557.15542756593, 10557.841632375674, 7425.257474252531, 8834.249088827522, 8942.821143577223, 9054.818903622023, 9110.772230694285, 8719.738407847775, 8761.737147885573, 8790.472571645721, 8830.64677412922, 8886.86669699968, 7426.5915374654915, 7909.8813517796125, 8263.628136733741, 8265.876011859707, 8323.6670533178, 5928.644281343133, 5950.761969521171, 5991.131285963424, 6098.298695650057, 6175.0, 5717.742701578356, 5836.795712150054, 6066.939330606638, 6158.168647232566, 6377.202849643794], "value": 5354.34986801314, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.28729993162614, 398.5556104942988, 402.07522697795065, 406.23823395358966, 410.6058184143221, 4791.928121078186, 5412.83761487156, 5474.644148130369, 5544.695041772701, 5572.4148964358765, 4831.4685384607665, 4954.671304486895, 5170.431252562215, 5369.362344484931, 5390.21841832934, 3943.6647884929816, 4322.935155972663, 4541.250693635546, 4696.882577935562, 4704.01215744694, 3871.341871881787, 4131.710780245384, 4429.734215947037, 4716.080364328957, 4721.598664113537, 3531.446163687969, 4430.326006063601, 4479.910401791963, 4692.835750748724, 4745.549172828576, 3861.2663593917064, 4669.626429885601, 4679.15775160471, 4684.0, 4696.2486002239475, 3604.243108947098, 3840.7695538267512, 4099.282625540519, 4494.842680506166, 4521.253993091136, 4960.355153830015, 5131.512506311894, 5237.921431178535, 5296.788128474856, 5351.384590772033, 5117.46266642001, 5453.618246722731, 5456.91814622781, 5461.590380721462, 5555.333359996826, 5569.331680198402, 5736.541076713853, 5777.220075289824, 5906.438888305604, 5923.022701254288, 4382.912341753147, 4484.058347746977, 4982.103221420143, 5007.474215207387, 5027.79888804451, 5379.408265090837, 5917.822465326047, 5962.688208594102, 5983.943567685343, 6169.598976066542, 4290.485141783006, 4561.680682352204, 4750.5249475052215, 5164.2770012198325, 5188.610854185876, 4567.817287308537, 4574.359589657451, 5036.647434679538, 5139.58883289336, 5244.010913779185, 4715.718395397177, 4987.476314986947, 5459.918101228484, 5469.972650136724, 5489.670619762827, 5045.637677826966, 5711.7144142792695, 5735.799247026334, 5753.964286428483, 5759.136129580596, 1597.370681904451, 1664.983350166507, 1735.2885317019961, 1792.1487293535467, 1801.819818018214, 1031.8606988056667, 1265.8923991460645, 1275.9218460400946, 1345.132389608697, 1498.460554200487, 5198.9999999999245, 5563.443655634485, 5572.442755724476, 5575.832725018256, 5595.524380427628, 5328.813491527778, 5389.386147718354, 5480.808171713971, 6033.4871535919065, 6180.567360284737, 4682.578567928835, 4721.748736584807, 5207.140821764367, 5231.947680523146, 5304.859455217147, 2850.3016760893715, 2927.6779554249015, 3104.3480869017308, 3113.984430077835, 3127.890523831655, 4990.627577416167, 5931.0, 6031.547633927473, 6278.995360742259, 6298.582818865719, 5364.597655175878, 5738.454846789548, 5957.880842383128, 6111.327753947151, 6165.630062196283, 5463.989162005121, 5736.4550367715, 5982.4017598239825, 6246.250449946036, 6318.977927063295, 4982.451930287593, 5679.914801278067, 5866.600174541828, 5928.851778705567, 5949.44174005182, 4954.497978520968, 5102.979404119117, 5448.945510544844, 5836.328822185334, 5918.437748413894, 6044.370698746059, 6072.423119803611, 6163.167972323679, 6255.405736455212, 6351.96824015877, 4351.630111440563, 4594.931076033795, 5179.689218646893, 5213.765380557808, 5268.420473747808, 5832.50423713989, 5904.675242861684, 5913.58604897653, 6023.6385816851125, 6066.605670631571, 4941.481144479922, 5310.920336194883, 5602.607817452739, 5714.571257245786, 5765.711714414347, 4845.612351011981, 5081.949180508148, 5474.70062573712, 5806.883862322646, 5830.47525722687, 4823.541763532458, 5681.289838770153, 5720.456556627114, 5720.684242624303, 5721.1990321354215, 5834.766609335748, 5848.883022339444, 5850.23946886902, 5887.882242355215, 5932.347441781314, 4844.685095468783, 5567.3040869891265, 5569.916451253153, 5683.5737319700365, 5695.800646977336], "value": 4508.278498606292, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.9524230924986, 760.5398733766068, 760.5778792770012, 811.5009269299379, 891.5185799668177, 16292.918535407322, 16534.42129525467, 17064.48806535806, 17226.655466890654, 17287.098419173883, 16298.918505407457, 16306.510804675878, 16345.0, 16512.0, 16536.33854645816, 1014.8477728340753, 1044.185535282481, 1049.380865289479, 1054.0302921312411, 1054.451685123739, 1002.268344108801, 1040.7866387390566, 1042.1818872185336, 1043.9217058720587, 1044.7805960748215, 1079.4656644960717, 1099.6591056772427, 1108.130117857482, 1108.534415545473, 1108.9390083545402, 1085.3704851186333, 1092.344593244059, 1095.2059756676426, 1096.7641956979248, 1097.1003776902942, 803.8673618852882, 816.2245866426919, 816.7386436340373, 820.5774026376421, 824.7690646618956, 14948.701025979528, 17291.913540432342, 17850.464486065437, 17966.730499042525, 18132.456026319232, 13109.016823738259, 13484.932575337061, 14686.779698304534, 14960.401583936738, 15043.849561504354, 11170.050545703625, 11774.587889423829, 12052.758944821055, 12090.972267357285, 12167.878321216764, 3803.6386543278345, 3873.1672690371706, 3882.6893848492055, 3912.139329347539, 4040.979795101035, 94056.52971735098, 110077.44961275143, 119794.40102799605, 119936.0, 121444.78555214423, 9902.851457228147, 11308.660740177807, 11404.258723182966, 11482.48328825205, 11714.0, 23453.296401107993, 24172.87913560421, 24289.878550607133, 24386.634200487006, 24515.264542063767, 7949.682012719542, 7960.004999375078, 7980.12218655959, 8101.311388532041, 8143.389245806588, 4400.779961001937, 4413.492448368479, 4416.933745993812, 4487.6634252431195, 4693.741844198602, 3661.5972243053243, 3756.6619004289746, 3758.586555478895, 3759.2669429460993, 3767.566729826105, 534.986625334362, 653.7613770973655, 657.1916542652564, 658.3942772649174, 660.7423104989058, 592.7836339735966, 702.9894551581731, 753.8567672142275, 754.999999999989, 759.8898159766914, 10375.975832261483, 10683.717953845588, 10688.839667404995, 10733.570657173781, 10955.17836162291, 723.1435828477535, 739.918240528194, 742.9108506979197, 746.1754760989029, 758.2682711183734, 4891.608671306287, 5182.637215394886, 5192.221166824931, 5202.687838729613, 5234.3718753749035, 88858.55570722105, 90823.00473474023, 111943.0, 114660.0, 115305.0, 58897.705511472166, 81185.0, 91342.54328728447, 95206.57190142153, 96494.0, 66292.668536657, 75828.62085689536, 76186.0, 78940.21059789328, 79324.6033769839, 9527.333086683866, 10997.505112269808, 11245.325280483197, 11315.886841131483, 11480.483378247833, 43855.0, 48814.26778598253, 49560.75219623879, 50042.249366260265, 50665.0, 54582.181267280226, 61980.0, 63639.0, 65119.37201569809, 65954.01068984065, 12018.75962480763, 12689.87310126887, 12691.87308126944, 12921.741565168455, 13127.212367257613, 12674.809877852023, 14985.0, 15104.244787760788, 15195.468158614396, 15794.289256983233, 13833.446662133401, 14005.929970350084, 14007.57977260643, 14015.649608759863, 14783.0, 14100.78848817248, 14151.221682807545, 14163.498572488805, 14171.787423188454, 14278.928605356907, 13629.454821807414, 13878.583642490741, 13911.513097041556, 14079.859201408264, 14118.78821817653, 9087.409318394282, 9466.52667366615, 9752.902470975483, 9805.947870019485, 10306.227032972407, 10563.20775941792, 11389.487473063566, 11433.828492572784, 11445.313281203154, 11662.88337116618], "value": 7944.138152792974, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1592.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 120.0, 120.0, 119.0, 117.0, 119.0, 119.0, 124.0, 120.0, 119.0], "value": 118.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 126.0, 176.0, 137.0, 110.0, 112.0, 111.0, 129.0, 108.0], "value": 125.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 195.0, 197.0, 195.0, 193.0, 195.0, 197.0, 283.0, 247.0], "value": 209.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 59.0, 94.0, 87.0, 83.0, 60.0, 61.0, 89.0, 52.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 86.0, 85.0, 59.0, 77.0, 113.0, 101.0, 98.0, 81.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 133.0, 88.0, 92.0, 88.0, 113.0, 137.0, 89.0, 136.0, 136.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 138.0, 120.0, 116.0, 139.0, 154.0, 140.0], "value": 119.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 79.0, 145.0, 150.0, 95.0, 148.0, 88.0, 146.0, 147.0, 100.0], "value": 124.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 60.0, 91.0, 105.0, 105.0, 103.0, 96.0, 69.0, 67.0, 67.0], "value": 81.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 64.0, 57.0, 52.0, 59.0, 56.0, 56.0, 58.0, 57.0, 56.0], "value": 57.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 84.0, 72.0, 90.0, 75.0, 76.0, 79.0, 77.0, 74.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 149.0, 145.0, 160.0, 155.0, 149.0, 147.0, 153.0, 201.0, 146.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 197.0, 228.0, 184.0, 166.0, 161.0, 172.0, 163.0, 162.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 67.0, 72.0, 81.0, 84.0, 83.0, 85.0, 86.0, 63.0, 67.0], "value": 75.0, "unit": "score"}]}]} 10:30:11 INFO - Return code: 0 10:30:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:30:11 ERROR - # TBPL SUCCESS # 10:30:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:30:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:30:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:30:11 INFO - 2017-04-21 10:30:11.977 screenresolution[1655:11005] starting screenresolution argv=screenresolution get 10:30:11 INFO - 2017-04-21 10:30:11.980 screenresolution[1655:11005] Display 0: 1600x1200x32@60 10:30:11 INFO - 2017-04-21 10:30:11.986 screenresolution[1656:11008] starting screenresolution argv=screenresolution list 10:30:11 INFO - Available Modes on Display 0 10:30:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:30:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:30:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:30:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:30:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:30:12 INFO - Intel Iris: 10:30:12 INFO - Chipset Model: Intel Iris 10:30:12 INFO - Type: GPU 10:30:12 INFO - Bus: Built-In 10:30:12 INFO - VRAM (Dynamic, Max): 1536 MB 10:30:12 INFO - Vendor: Intel (0x8086) 10:30:12 INFO - Device ID: 0x0a2e 10:30:12 INFO - Revision ID: 0x0009 10:30:12 INFO - Displays: 10:30:12 INFO - Display: 10:30:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:30:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:30:12 INFO - Display Serial Number: 1600x1200 60 10:30:12 INFO - Main Display: Yes 10:30:12 INFO - Mirror: Off 10:30:12 INFO - Online: Yes 10:30:12 INFO - Rotation: Supported 10:30:12 INFO - Return code: 0 10:30:12 INFO - Running post-action listener: _resource_record_post_action 10:30:12 INFO - [mozharness: 2017-04-21 17:30:12.227233Z] Finished run-tests step (success) 10:30:12 INFO - Running post-run listener: _resource_record_post_run 10:30:12 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 39700480; Write bytes: 427423744; Read time: 250; Write time: 1440 10:30:12 INFO - TinderboxPrint: CPU usage
54.4% 10:30:12 INFO - TinderboxPrint: I/O read bytes / time
39,700,480 / 250 10:30:12 INFO - TinderboxPrint: I/O write bytes / time
427,423,744 / 1,440 10:30:12 INFO - TinderboxPrint: CPU idle
2,172.8 (45.6%) 10:30:12 INFO - TinderboxPrint: CPU system
955.9 (20.0%) 10:30:12 INFO - TinderboxPrint: CPU user
1,641.1 (34.4%) 10:30:12 INFO - TinderboxPrint: Swap in / out
162,193,408 / 0 10:30:12 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123202048; Write bytes: 125835264; Read time: 11081; Write time: 290 10:30:12 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35629056; Write bytes: 300183552; Read time: 223; Write time: 1141 10:30:12 INFO - Running post-run listener: _upload_blobber_files 10:30:12 INFO - Blob upload gear active. 10:30:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:30:12 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:30:12 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:30:12 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:30:13 INFO - (blobuploader) - INFO - Open directory for files ... 10:30:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:30:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:30:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:30:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:30:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:30:13 INFO - (blobuploader) - INFO - Done attempting. 10:30:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:30:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:30:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:30:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:30:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:30:15 INFO - (blobuploader) - INFO - Done attempting. 10:30:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:30:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:30:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:30:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:30:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:30:16 INFO - (blobuploader) - INFO - Done attempting. 10:30:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:30:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:30:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:30:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:30:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:30:18 INFO - (blobuploader) - INFO - Done attempting. 10:30:18 INFO - (blobuploader) - INFO - Iteration through files over. 10:30:18 INFO - Return code: 0 10:30:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:30:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:30:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/290b8ba0fc1aae3458b1ed287a370e94ed86d4d0a67ab36f6171ae7edea96776c8d703c4c1bd71f88a73d637a4783d3f17a0176d218727d912d3f74a2bb35f70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7de7c416d8b11924f322bd94fed28c4fc9222b0d5867cd22a64d7518ef3a571760433bf1d9bb7c95f6b54f1cb1525ae3fb162868f60c0083c7f25f407e0f8f9c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00bf04b799ffa1d31d71c74a3909de38e01f5ffded0d8567a6932ec07b451778e4de2d75ff51aa6c523120707ee3b548fcc58e390cff23b7340a426705ecfc2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/297b7855316fb43adc1030d442fc48c30ea22b87fcbc1cf6e430077f2fc6744af3020099d230f38e7a713ccf693be6644f030105c74b27c7a4d6fd4c5897de7e"} 10:30:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:30:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:30:18 INFO - Contents: 10:30:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/290b8ba0fc1aae3458b1ed287a370e94ed86d4d0a67ab36f6171ae7edea96776c8d703c4c1bd71f88a73d637a4783d3f17a0176d218727d912d3f74a2bb35f70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7de7c416d8b11924f322bd94fed28c4fc9222b0d5867cd22a64d7518ef3a571760433bf1d9bb7c95f6b54f1cb1525ae3fb162868f60c0083c7f25f407e0f8f9c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00bf04b799ffa1d31d71c74a3909de38e01f5ffded0d8567a6932ec07b451778e4de2d75ff51aa6c523120707ee3b548fcc58e390cff23b7340a426705ecfc2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/297b7855316fb43adc1030d442fc48c30ea22b87fcbc1cf6e430077f2fc6744af3020099d230f38e7a713ccf693be6644f030105c74b27c7a4d6fd4c5897de7e"} 10:30:18 INFO - Running post-run listener: copy_logs_to_upload_dir 10:30:18 INFO - Copying logs to upload dir... 10:30:18 INFO - mkdir: /builds/slave/test/build/upload/logs 10:30:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.411486 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-04-21 10:30:18.416578) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-21 10:30:18.424526) ========= 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.WELOgCndKJ/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.QwQZLQu7DR/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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/Mozilla-Beta/sha512/290b8ba0fc1aae3458b1ed287a370e94ed86d4d0a67ab36f6171ae7edea96776c8d703c4c1bd71f88a73d637a4783d3f17a0176d218727d912d3f74a2bb35f70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7de7c416d8b11924f322bd94fed28c4fc9222b0d5867cd22a64d7518ef3a571760433bf1d9bb7c95f6b54f1cb1525ae3fb162868f60c0083c7f25f407e0f8f9c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00bf04b799ffa1d31d71c74a3909de38e01f5ffded0d8567a6932ec07b451778e4de2d75ff51aa6c523120707ee3b548fcc58e390cff23b7340a426705ecfc2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/297b7855316fb43adc1030d442fc48c30ea22b87fcbc1cf6e430077f2fc6744af3020099d230f38e7a713ccf693be6644f030105c74b27c7a4d6fd4c5897de7e"} build_url:https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011087 build_url: 'https://queue.taskcluster.net/v1/task/K0JdtRjhR1uxtH50ff1B0Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/290b8ba0fc1aae3458b1ed287a370e94ed86d4d0a67ab36f6171ae7edea96776c8d703c4c1bd71f88a73d637a4783d3f17a0176d218727d912d3f74a2bb35f70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7de7c416d8b11924f322bd94fed28c4fc9222b0d5867cd22a64d7518ef3a571760433bf1d9bb7c95f6b54f1cb1525ae3fb162868f60c0083c7f25f407e0f8f9c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00bf04b799ffa1d31d71c74a3909de38e01f5ffded0d8567a6932ec07b451778e4de2d75ff51aa6c523120707ee3b548fcc58e390cff23b7340a426705ecfc2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/297b7855316fb43adc1030d442fc48c30ea22b87fcbc1cf6e430077f2fc6744af3020099d230f38e7a713ccf693be6644f030105c74b27c7a4d6fd4c5897de7e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-21 10:30:18.466931) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 10:30:18.467391) ========= 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.WELOgCndKJ/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.QwQZLQu7DR/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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.004523 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 10:30:18.518914) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-21 10:30:18.519365) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-21 10:30:18.519770) ========= ========= Total master_lag: 0.22 =========