builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0109 starttime: 1472780971.39 results: success (0) buildid: 20160901174542 builduid: eae1db59bb6a4ca7ac9b3e2e285e6f88 revision: 2d457db23476fecab9e6e66a737c6723d0d2767c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 18:49:31.394289) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 18:49:31.394785) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 18:49:31.395094) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 18:49:31.420760) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:49:31.421082) ========= 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.67SDdTS0O5/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.xqqZBREk3t/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 --2016-09-01 18:49:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 25.6M=0s 2016-09-01 18:49:31 (25.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.420830 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:49:31.865221) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:49:31.865533) ========= 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.67SDdTS0O5/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.xqqZBREk3t/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.028680 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:49:31.915388) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 18:49:31.915754) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2d457db23476fecab9e6e66a737c6723d0d2767c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 2d457db23476fecab9e6e66a737c6723d0d2767c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.67SDdTS0O5/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.xqqZBREk3t/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 2016-09-01 18:49:31,906 truncating revision to first 12 chars 2016-09-01 18:49:31,907 Setting DEBUG logging. 2016-09-01 18:49:31,907 attempt 1/10 2016-09-01 18:49:31,907 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2d457db23476?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 18:49:33,526 unpacking tar archive at: mozilla-central-2d457db23476/testing/mozharness/ program finished with exit code 0 elapsedTime=1.907014 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 18:49:33.846772) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 18:49:33.847104) ========= script_repo_revision: 2d457db23476fecab9e6e66a737c6723d0d2767c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 18:49:33.847571) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 18:49:33.847901) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 18:49:33.867481) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-01 18:49:33.867814) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.67SDdTS0O5/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.xqqZBREk3t/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 18:49:33 INFO - MultiFileLogger online at 20160901 18:49:33 in /builds/slave/test 18:49:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:49:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:49:33 INFO - {'append_to_log': False, 18:49:33 INFO - 'base_work_dir': '/builds/slave/test', 18:49:33 INFO - 'blob_upload_branch': 'Firefox', 18:49:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:49:33 INFO - 'branch': 'Firefox', 18:49:33 INFO - 'buildbot_json_path': 'buildprops.json', 18:49:33 INFO - 'config_files': ('talos/mac_config.py',), 18:49:33 INFO - 'default_actions': ('clobber', 18:49:33 INFO - 'read-buildbot-config', 18:49:33 INFO - 'download-and-extract', 18:49:33 INFO - 'populate-webroot', 18:49:33 INFO - 'create-virtualenv', 18:49:33 INFO - 'install', 18:49:33 INFO - 'run-tests'), 18:49:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:49:33 INFO - 'download_minidump_stackwalk': True, 18:49:33 INFO - 'download_symbols': 'ondemand', 18:49:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:49:33 INFO - 'tooltool.py': '/tools/tooltool.py', 18:49:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:49:33 INFO - '/tools/misc-python/virtualenv.py')}, 18:49:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:49:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:49:33 INFO - 'installer_path': 'installer.exe', 18:49:33 INFO - 'log_level': 'info', 18:49:33 INFO - 'log_name': 'talos', 18:49:33 INFO - 'log_to_console': True, 18:49:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:49:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:49:33 INFO - 'opt_config_files': (), 18:49:33 INFO - 'pip_index': False, 18:49:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:49:33 INFO - 'cmd': ('bash', 18:49:33 INFO - '-c', 18:49:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:49:33 INFO - 'enabled': True, 18:49:33 INFO - 'halt_on_failure': False, 18:49:33 INFO - 'name': 'check_screen_resolution'},), 18:49:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:49:33 INFO - 'cmd': ('bash', 18:49:33 INFO - '-c', 18:49:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:49:33 INFO - 'enabled': True, 18:49:33 INFO - 'halt_on_failure': False, 18:49:33 INFO - 'name': 'check_screen_resolution'},), 18:49:33 INFO - 'run_cmd_checks_enabled': True, 18:49:33 INFO - 'sps_profile': False, 18:49:33 INFO - 'sps_profile_interval': 0, 18:49:33 INFO - 'suite': 'dromaeojs', 18:49:33 INFO - 'system_bits': '32', 18:49:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:49:33 INFO - 'title': 't-yosemite-r7-0109', 18:49:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:49:33 INFO - 'use_talos_json': True, 18:49:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:49:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:49:33 INFO - 'work_dir': 'build'} 18:49:33 INFO - [mozharness: 2016-09-02 01:49:33.994069Z] Running clobber step. 18:49:33 INFO - Running pre-action listener: _resource_record_pre_action 18:49:33 INFO - Running main action method: clobber 18:49:33 INFO - rmtree: /builds/slave/test/build 18:49:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:49:36 INFO - Running post-action listener: _resource_record_post_action 18:49:36 INFO - [mozharness: 2016-09-02 01:49:36.095193Z] Finished clobber step (success) 18:49:36 INFO - [mozharness: 2016-09-02 01:49:36.095346Z] Running read-buildbot-config step. 18:49:36 INFO - Running pre-action listener: _resource_record_pre_action 18:49:36 INFO - Running main action method: read_buildbot_config 18:49:36 INFO - Using buildbot properties: 18:49:36 INFO - { 18:49:36 INFO - "project": "", 18:49:36 INFO - "product": "firefox", 18:49:36 INFO - "script_repo_revision": "production", 18:49:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:49:36 INFO - "repository": "", 18:49:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:49:36 INFO - "buildid": "20160901174542", 18:49:36 INFO - "pgo_build": "False", 18:49:36 INFO - "basedir": "/builds/slave/test", 18:49:36 INFO - "buildnumber": 25, 18:49:36 INFO - "slavename": "t-yosemite-r7-0109", 18:49:36 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:49:36 INFO - "platform": "yosemite_r7", 18:49:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:49:36 INFO - "branch": "mozilla-central", 18:49:36 INFO - "repo_path": "mozilla-central", 18:49:36 INFO - "revision": "2d457db23476fecab9e6e66a737c6723d0d2767c", 18:49:36 INFO - "stage_platform": "macosx64", 18:49:36 INFO - "builduid": "eae1db59bb6a4ca7ac9b3e2e285e6f88", 18:49:36 INFO - "slavebuilddir": "test" 18:49:36 INFO - } 18:49:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:49:36 INFO - Running post-action listener: _resource_record_post_action 18:49:36 INFO - [mozharness: 2016-09-02 01:49:36.098530Z] Finished read-buildbot-config step (success) 18:49:36 INFO - [mozharness: 2016-09-02 01:49:36.098660Z] Running download-and-extract step. 18:49:36 INFO - Running pre-action listener: _resource_record_pre_action 18:49:36 INFO - Running main action method: download_and_extract 18:49:36 INFO - mkdir: /builds/slave/test/build/tests 18:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:36 INFO - trying https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:49:36 INFO - Downloading https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 18:49:37 INFO - Downloaded 1199 bytes. 18:49:37 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:49:37 INFO - Using the following test package requirements: 18:49:37 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:49:37 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:49:37 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:49:37 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:49:37 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:49:37 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:49:37 INFO - u'jsshell-mac.zip'], 18:49:37 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:49:37 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:49:37 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:49:37 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:49:37 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:49:37 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:49:37 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:49:37 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:49:37 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:49:37 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:49:37 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:49:37 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:37 INFO - trying https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:49:37 INFO - Downloading https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:49:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:49:38 INFO - Downloaded 16399153 bytes. 18:49:38 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:49:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:45 INFO - trying https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:49:45 INFO - Downloading https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:49:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:49:45 INFO - Downloaded 16399153 bytes. 18:49:45 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:52 INFO - trying https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:49:52 INFO - Downloading https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:49:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:49:53 INFO - Downloaded 14413998 bytes. 18:49:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:54 INFO - trying https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:49:54 INFO - Downloading https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:49:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 18:49:56 INFO - Downloaded 111575473 bytes. 18:49:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:49:56 INFO - mkdir: /builds/slave/test/properties 18:49:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:49:56 INFO - Writing to file /builds/slave/test/properties/build_url 18:49:56 INFO - Contents: 18:49:56 INFO - build_url:https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:49:57 INFO - Running post-action listener: _resource_record_post_action 18:49:57 INFO - Running post-action listener: set_extra_try_arguments 18:49:57 INFO - [mozharness: 2016-09-02 01:49:57.481332Z] Finished download-and-extract step (success) 18:49:57 INFO - [mozharness: 2016-09-02 01:49:57.481456Z] Running populate-webroot step. 18:49:57 INFO - Running pre-action listener: _resource_record_pre_action 18:49:57 INFO - Running main action method: populate_webroot 18:49:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:49:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:49:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:49:57 INFO - '--tppagecycles', 18:49:57 INFO - '7'], 18:49:57 INFO - 'tests': ['tsvgm']}}, 18:49:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:49:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:49:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:49:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:49:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:49:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:49:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:57 INFO - 'talos_options': ['--disable-e10s'], 18:49:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:49:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:49:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:57 INFO - 'talos_options': ['--disable-e10s'], 18:49:57 INFO - 'tests': ['damp', 'tps']}, 18:49:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:57 INFO - 'tests': ['damp', 'tps']}, 18:49:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:49:57 INFO - 'tests': ['dromaeo_dom']}, 18:49:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:49:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:49:57 INFO - 'tests': ['basic_compositor_video']}, 18:49:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:49:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:49:57 INFO - 'tests': ['a11yr', 18:49:57 INFO - 'ts_paint', 18:49:57 INFO - 'tpaint', 18:49:57 INFO - 'sessionrestore', 18:49:57 INFO - 'sessionrestore_no_auto_restore', 18:49:57 INFO - 'tabpaint']}, 18:49:57 INFO - 'other-e10s': {'tests': ['a11yr', 18:49:57 INFO - 'ts_paint', 18:49:57 INFO - 'tpaint', 18:49:57 INFO - 'sessionrestore', 18:49:57 INFO - 'sessionrestore_no_auto_restore', 18:49:57 INFO - 'tabpaint']}, 18:49:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:49:57 INFO - 'ts_paint', 18:49:57 INFO - 'tpaint', 18:49:57 INFO - 'sessionrestore', 18:49:57 INFO - 'sessionrestore_no_auto_restore', 18:49:57 INFO - 'tabpaint']}, 18:49:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:49:57 INFO - 'ts_paint', 18:49:57 INFO - 'tpaint', 18:49:57 INFO - 'sessionrestore', 18:49:57 INFO - 'sessionrestore_no_auto_restore', 18:49:57 INFO - 'tabpaint']}, 18:49:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:49:57 INFO - 'tests': ['a11yr', 18:49:57 INFO - 'ts_paint', 18:49:57 INFO - 'tpaint', 18:49:57 INFO - 'sessionrestore', 18:49:57 INFO - 'sessionrestore_no_auto_restore', 18:49:57 INFO - 'tabpaint']}, 18:49:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:49:57 INFO - 'tests': ['a11yr', 18:49:57 INFO - 'ts_paint', 18:49:57 INFO - 'tpaint', 18:49:57 INFO - 'sessionrestore', 18:49:57 INFO - 'sessionrestore_no_auto_restore', 18:49:57 INFO - 'tabpaint']}, 18:49:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:49:57 INFO - 'tests': ['tsvgx', 18:49:57 INFO - 'tsvgr_opacity', 18:49:57 INFO - 'tart', 18:49:57 INFO - 'tscrollx', 18:49:57 INFO - 'cart']}, 18:49:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:49:57 INFO - 'tsvgr_opacity', 18:49:57 INFO - 'tart', 18:49:57 INFO - 'tscrollx', 18:49:57 INFO - 'cart']}, 18:49:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:57 INFO - 'talos_options': ['--disable-e10s'], 18:49:57 INFO - 'tests': ['tp5o']}, 18:49:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:57 INFO - 'tests': ['tp5o']}, 18:49:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:57 INFO - 'talos_options': ['--disable-e10s', 18:49:57 INFO - '--xperf_path', 18:49:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:49:57 INFO - 'tests': ['tp5n']}, 18:49:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:57 INFO - 'talos_options': ['--xperf_path', 18:49:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:49:57 INFO - 'tests': ['tp5n']}}, 18:49:57 INFO - 'talos.zip': {'path': '', 18:49:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:49:57 INFO - Running post-action listener: _resource_record_post_action 18:49:57 INFO - [mozharness: 2016-09-02 01:49:57.500188Z] Finished populate-webroot step (success) 18:49:57 INFO - [mozharness: 2016-09-02 01:49:57.500321Z] Running create-virtualenv step. 18:49:57 INFO - Running pre-action listener: _resource_record_pre_action 18:49:57 INFO - Running main action method: create_virtualenv 18:49:57 INFO - Creating virtualenv /builds/slave/test/build/venv 18:49:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:49:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:49:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:49:57 INFO - Using real prefix '/tools/python27' 18:49:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:49:58 INFO - Installing distribute.............................................................................................................................................................................................done. 18:50:01 INFO - Installing pip.................done. 18:50:01 INFO - Return code: 0 18:50:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:50:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1038b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd0d8c970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10381bd30>, '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 0x103580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103813b20>, '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.67SDdTS0O5/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.xqqZBREk3t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:50:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:50:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67SDdTS0O5/Render', 18:50:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:01 INFO - 'HOME': '/Users/cltbld', 18:50:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:01 INFO - 'LOGNAME': 'cltbld', 18:50:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:01 INFO - 'MOZ_NO_REMOTE': '1', 18:50:01 INFO - 'NO_EM_RESTART': '1', 18:50:01 INFO - 'PAGER': '/bin/cat', 18:50:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:01 INFO - 'PWD': '/builds/slave/test', 18:50:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:01 INFO - 'SHELL': '/bin/bash', 18:50:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqqZBREk3t/Listeners', 18:50:01 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 18:50:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:01 INFO - 'USER': 'cltbld', 18:50:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:01 INFO - 'XPC_FLAGS': '0x0', 18:50:01 INFO - 'XPC_SERVICE_NAME': '0', 18:50:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:50:01 INFO - Cleaning up... 18:50:01 INFO - Return code: 0 18:50:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:50:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1038b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd0d8c970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10381bd30>, '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 0x103580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103813b20>, '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.67SDdTS0O5/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.xqqZBREk3t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:50:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:50:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67SDdTS0O5/Render', 18:50:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:01 INFO - 'HOME': '/Users/cltbld', 18:50:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:01 INFO - 'LOGNAME': 'cltbld', 18:50:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:01 INFO - 'MOZ_NO_REMOTE': '1', 18:50:01 INFO - 'NO_EM_RESTART': '1', 18:50:01 INFO - 'PAGER': '/bin/cat', 18:50:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:01 INFO - 'PWD': '/builds/slave/test', 18:50:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:01 INFO - 'SHELL': '/bin/bash', 18:50:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqqZBREk3t/Listeners', 18:50:01 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 18:50:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:01 INFO - 'USER': 'cltbld', 18:50:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:01 INFO - 'XPC_FLAGS': '0x0', 18:50:01 INFO - 'XPC_SERVICE_NAME': '0', 18:50:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:01 INFO - Downloading/unpacking psutil>=3.1.1 18:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:50:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:04 INFO - Installing collected packages: psutil 18:50:04 INFO - Running setup.py install for psutil 18:50:04 INFO - building 'psutil._psutil_osx' extension 18:50:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:50:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:50:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:50:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:50:05 INFO - building 'psutil._psutil_posix' extension 18:50:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:50:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:50:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:50:05 INFO - ^ 18:50:05 INFO - 1 warning generated. 18:50:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:50:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:05 INFO - Successfully installed psutil 18:50:05 INFO - Cleaning up... 18:50:06 INFO - Return code: 0 18:50:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:50: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')]} 18:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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')]} 18:50:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1038b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd0d8c970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10381bd30>, '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 0x103580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103813b20>, '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.67SDdTS0O5/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.xqqZBREk3t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:50:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:50:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67SDdTS0O5/Render', 18:50:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:06 INFO - 'HOME': '/Users/cltbld', 18:50:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:06 INFO - 'LOGNAME': 'cltbld', 18:50:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:06 INFO - 'MOZ_NO_REMOTE': '1', 18:50:06 INFO - 'NO_EM_RESTART': '1', 18:50:06 INFO - 'PAGER': '/bin/cat', 18:50:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:06 INFO - 'PWD': '/builds/slave/test', 18:50:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:06 INFO - 'SHELL': '/bin/bash', 18:50:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqqZBREk3t/Listeners', 18:50:06 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 18:50:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:06 INFO - 'USER': 'cltbld', 18:50:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:06 INFO - 'XPC_FLAGS': '0x0', 18:50:06 INFO - 'XPC_SERVICE_NAME': '0', 18:50:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:50: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 18:50: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 18:50: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 18:50: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 18:50: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 18:50: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 18:50:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:50:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:50:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:50:08 INFO - Installing collected packages: mozsystemmonitor 18:50:08 INFO - Running setup.py install for mozsystemmonitor 18:50:08 INFO - Successfully installed mozsystemmonitor 18:50:08 INFO - Cleaning up... 18:50:08 INFO - Return code: 0 18:50:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:50:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:08 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 0x1038b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd0d8c970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10381bd30>, '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 0x103580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103813b20>, '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.67SDdTS0O5/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.xqqZBREk3t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:50:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67SDdTS0O5/Render', 18:50:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:08 INFO - 'HOME': '/Users/cltbld', 18:50:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:08 INFO - 'LOGNAME': 'cltbld', 18:50:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:08 INFO - 'MOZ_NO_REMOTE': '1', 18:50:08 INFO - 'NO_EM_RESTART': '1', 18:50:08 INFO - 'PAGER': '/bin/cat', 18:50:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:08 INFO - 'PWD': '/builds/slave/test', 18:50:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:08 INFO - 'SHELL': '/bin/bash', 18:50:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqqZBREk3t/Listeners', 18:50:08 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 18:50:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:08 INFO - 'USER': 'cltbld', 18:50:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:08 INFO - 'XPC_FLAGS': '0x0', 18:50:08 INFO - 'XPC_SERVICE_NAME': '0', 18:50:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:09 INFO - Downloading/unpacking jsonschema==2.5.1 18:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:50:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:12 INFO - Downloading functools32-3.2.3-2.tar.gz 18:50:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:50:12 INFO - warning: no files found matching '*.txt' 18:50:12 INFO - no previously-included directories found matching 'build' 18:50:12 INFO - no previously-included directories found matching 'dist' 18:50:12 INFO - no previously-included directories found matching '.git*' 18:50:12 INFO - Installing collected packages: jsonschema, functools32 18:50:12 INFO - Running setup.py install for functools32 18:50:12 INFO - warning: no files found matching '*.txt' 18:50:12 INFO - no previously-included directories found matching 'build' 18:50:12 INFO - no previously-included directories found matching 'dist' 18:50:12 INFO - no previously-included directories found matching '.git*' 18:50:12 INFO - Successfully installed jsonschema functools32 18:50:12 INFO - Cleaning up... 18:50:12 INFO - Return code: 0 18:50:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:12 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 0x1038b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd0d8c970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10381bd30>, '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 0x103580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103813b20>, '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.67SDdTS0O5/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.xqqZBREk3t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:50:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:50:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67SDdTS0O5/Render', 18:50:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:12 INFO - 'HOME': '/Users/cltbld', 18:50:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:12 INFO - 'LOGNAME': 'cltbld', 18:50:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:12 INFO - 'MOZ_NO_REMOTE': '1', 18:50:12 INFO - 'NO_EM_RESTART': '1', 18:50:12 INFO - 'PAGER': '/bin/cat', 18:50:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:12 INFO - 'PWD': '/builds/slave/test', 18:50:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:12 INFO - 'SHELL': '/bin/bash', 18:50:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqqZBREk3t/Listeners', 18:50:12 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 18:50:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:12 INFO - 'USER': 'cltbld', 18:50:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:12 INFO - 'XPC_FLAGS': '0x0', 18:50:12 INFO - 'XPC_SERVICE_NAME': '0', 18:50:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:50:12 INFO - Cleaning up... 18:50:12 INFO - Return code: 0 18:50:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:12 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 0x1038b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd0d8c970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10381bd30>, '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 0x103580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103813b20>, '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.67SDdTS0O5/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.xqqZBREk3t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:50:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:50:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67SDdTS0O5/Render', 18:50:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:12 INFO - 'HOME': '/Users/cltbld', 18:50:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:12 INFO - 'LOGNAME': 'cltbld', 18:50:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:12 INFO - 'MOZ_NO_REMOTE': '1', 18:50:12 INFO - 'NO_EM_RESTART': '1', 18:50:12 INFO - 'PAGER': '/bin/cat', 18:50:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:12 INFO - 'PWD': '/builds/slave/test', 18:50:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:12 INFO - 'SHELL': '/bin/bash', 18:50:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqqZBREk3t/Listeners', 18:50:12 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 18:50:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:12 INFO - 'USER': 'cltbld', 18:50:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:12 INFO - 'XPC_FLAGS': '0x0', 18:50:12 INFO - 'XPC_SERVICE_NAME': '0', 18:50:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:13 INFO - Downloading/unpacking blobuploader==1.2.4 18:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:15 INFO - Downloading blobuploader-1.2.4.tar.gz 18:50:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:50:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:50: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 18:50: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 18:50: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 18:50: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 18:50: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 18:50: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 18:50:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:50:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:16 INFO - Downloading docopt-0.6.1.tar.gz 18:50:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:50:16 INFO - Installing collected packages: blobuploader, requests, docopt 18:50:16 INFO - Running setup.py install for blobuploader 18:50:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:50:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:50:16 INFO - Running setup.py install for requests 18:50:17 INFO - Running setup.py install for docopt 18:50:17 INFO - Successfully installed blobuploader requests docopt 18:50:17 INFO - Cleaning up... 18:50:17 INFO - Return code: 0 18:50:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:17 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 0x1038b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd0d8c970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10381bd30>, '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 0x103580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103813b20>, '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.67SDdTS0O5/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.xqqZBREk3t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:50:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:50:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67SDdTS0O5/Render', 18:50:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:17 INFO - 'HOME': '/Users/cltbld', 18:50:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:17 INFO - 'LOGNAME': 'cltbld', 18:50:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:17 INFO - 'MOZ_NO_REMOTE': '1', 18:50:17 INFO - 'NO_EM_RESTART': '1', 18:50:17 INFO - 'PAGER': '/bin/cat', 18:50:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:17 INFO - 'PWD': '/builds/slave/test', 18:50:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:17 INFO - 'SHELL': '/bin/bash', 18:50:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqqZBREk3t/Listeners', 18:50:17 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 18:50:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:17 INFO - 'USER': 'cltbld', 18:50:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:17 INFO - 'XPC_FLAGS': '0x0', 18:50:17 INFO - 'XPC_SERVICE_NAME': '0', 18:50:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:50:17 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-6rmxCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:50:17 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-bdpftv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:50:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-6aRK7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:50:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-BCd6MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:50:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-6Zjjsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:50:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-0mOES2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:50:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-1jPZJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:50:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-66FtE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:50:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-LKf548-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:50:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-SJx2yY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:50:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:50:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-usMx2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:50:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-RgXr5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:50:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-VpYDDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:50:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-j8p9jD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-AncXGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:50:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-oQTDvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:50:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:50:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-UruuDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:50:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:50:19 INFO - Running setup.py install for manifestparser 18:50:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:50:19 INFO - Running setup.py install for mozcrash 18:50:19 INFO - Running setup.py install for mozdebug 18:50:20 INFO - Running setup.py install for mozdevice 18:50:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:50:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:50:20 INFO - Running setup.py install for mozfile 18:50:20 INFO - Running setup.py install for mozhttpd 18:50:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:50:20 INFO - Running setup.py install for mozinfo 18:50:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:50:20 INFO - Running setup.py install for mozInstall 18:50:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:50:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:50:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:50:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:50:20 INFO - Running setup.py install for mozleak 18:50:20 INFO - Running setup.py install for mozlog 18:50:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:50:21 INFO - Running setup.py install for moznetwork 18:50:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:50:21 INFO - Running setup.py install for mozprocess 18:50:21 INFO - Running setup.py install for mozprofile 18:50:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:50:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:50:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:50:21 INFO - Running setup.py install for mozrunner 18:50:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:50:21 INFO - Running setup.py install for mozscreenshot 18:50:21 INFO - Running setup.py install for moztest 18:50:22 INFO - Running setup.py install for mozversion 18:50:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:50:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:50:22 INFO - Cleaning up... 18:50:22 INFO - Return code: 0 18:50:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:22 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 0x1038b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd0d8c970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10381bd30>, '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 0x103580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103813b20>, '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.67SDdTS0O5/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.xqqZBREk3t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:50:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:50:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67SDdTS0O5/Render', 18:50:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:22 INFO - 'HOME': '/Users/cltbld', 18:50:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:22 INFO - 'LOGNAME': 'cltbld', 18:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:22 INFO - 'MOZ_NO_REMOTE': '1', 18:50:22 INFO - 'NO_EM_RESTART': '1', 18:50:22 INFO - 'PAGER': '/bin/cat', 18:50:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:22 INFO - 'PWD': '/builds/slave/test', 18:50:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:22 INFO - 'SHELL': '/bin/bash', 18:50:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqqZBREk3t/Listeners', 18:50:22 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 18:50:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:22 INFO - 'USER': 'cltbld', 18:50:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:22 INFO - 'XPC_FLAGS': '0x0', 18:50:22 INFO - 'XPC_SERVICE_NAME': '0', 18:50:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:50:22 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-A62yjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:50:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:50:22 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-UsU1Si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:50:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:50:22 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-JYruT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:50:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:50:22 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-PYBcZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:50:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:50:22 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-xKw42_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:50:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-cYdZj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:50:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-tAptkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:50:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-7EcDxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:50:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-0stKA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:50:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-2or7ox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:50:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-UcOSXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:50:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-UH6trh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:50:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-BQcK6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:50:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-O8z5fS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-BLMUSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:50:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:50:24 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-pOhVu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:50:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:50:24 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-qjgAvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:50:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:50:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:50:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:50:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:50:24 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:50:24 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:50:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:26 INFO - Downloading blessings-1.6.tar.gz 18:50:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:50:26 INFO - Installing collected packages: blessings 18:50:26 INFO - Running setup.py install for blessings 18:50:27 INFO - Successfully installed blessings 18:50:27 INFO - Cleaning up... 18:50:27 INFO - Return code: 0 18:50:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:50:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:50:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:50:27 INFO - Reading from file tmpfile_stdout 18:50:27 INFO - Current package versions: 18:50:27 INFO - blessings == 1.6 18:50:27 INFO - blobuploader == 1.2.4 18:50:27 INFO - docopt == 0.6.1 18:50:27 INFO - functools32 == 3.2.3-2 18:50:27 INFO - jsonschema == 2.5.1 18:50:27 INFO - manifestparser == 1.1 18:50:27 INFO - mozInstall == 1.12 18:50:27 INFO - mozcrash == 0.17 18:50:27 INFO - mozdebug == 0.1 18:50:27 INFO - mozdevice == 0.48 18:50:27 INFO - mozfile == 1.2 18:50:27 INFO - mozhttpd == 0.7 18:50:27 INFO - mozinfo == 0.9 18:50:27 INFO - mozleak == 0.1 18:50:27 INFO - mozlog == 3.3 18:50:27 INFO - moznetwork == 0.27 18:50:27 INFO - mozprocess == 0.23 18:50:27 INFO - mozprofile == 0.28 18:50:27 INFO - mozrunner == 6.12 18:50:27 INFO - mozscreenshot == 0.1 18:50:27 INFO - mozsystemmonitor == 0.3 18:50:27 INFO - moztest == 0.7 18:50:27 INFO - mozversion == 1.4 18:50:27 INFO - psutil == 3.1.1 18:50:27 INFO - requests == 1.2.3 18:50:27 INFO - wsgiref == 0.1.2 18:50:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:27 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 0x1038b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd0d8c970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10381bd30>, '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 0x103580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103813b20>, '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.67SDdTS0O5/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.xqqZBREk3t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:50:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:50:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67SDdTS0O5/Render', 18:50:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:27 INFO - 'HOME': '/Users/cltbld', 18:50:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:27 INFO - 'LOGNAME': 'cltbld', 18:50:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:27 INFO - 'MOZ_NO_REMOTE': '1', 18:50:27 INFO - 'NO_EM_RESTART': '1', 18:50:27 INFO - 'PAGER': '/bin/cat', 18:50:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:27 INFO - 'PWD': '/builds/slave/test', 18:50:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:27 INFO - 'SHELL': '/bin/bash', 18:50:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqqZBREk3t/Listeners', 18:50:27 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 18:50:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:27 INFO - 'USER': 'cltbld', 18:50:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:27 INFO - 'XPC_FLAGS': '0x0', 18:50:27 INFO - 'XPC_SERVICE_NAME': '0', 18:50:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:50:27 INFO - Cleaning up... 18:50:27 INFO - Return code: 0 18:50:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1038b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cd0d8c970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10381bd30>, '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 0x103580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103813b20>, '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.67SDdTS0O5/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.xqqZBREk3t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:50:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:50:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67SDdTS0O5/Render', 18:50:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:28 INFO - 'HOME': '/Users/cltbld', 18:50:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:28 INFO - 'LOGNAME': 'cltbld', 18:50:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:28 INFO - 'MOZ_NO_REMOTE': '1', 18:50:28 INFO - 'NO_EM_RESTART': '1', 18:50:28 INFO - 'PAGER': '/bin/cat', 18:50:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:28 INFO - 'PWD': '/builds/slave/test', 18:50:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:28 INFO - 'SHELL': '/bin/bash', 18:50:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqqZBREk3t/Listeners', 18:50:28 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 18:50:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:28 INFO - 'USER': 'cltbld', 18:50:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:28 INFO - 'XPC_FLAGS': '0x0', 18:50:28 INFO - 'XPC_SERVICE_NAME': '0', 18:50:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:50:28 INFO - Cleaning up... 18:50:28 INFO - Return code: 0 18:50:28 INFO - Running post-action listener: _resource_record_post_action 18:50:28 INFO - Running post-action listener: _start_resource_monitoring 18:50:28 INFO - Starting resource monitoring. 18:50:28 INFO - [mozharness: 2016-09-02 01:50:28.820097Z] Finished create-virtualenv step (success) 18:50:28 INFO - [mozharness: 2016-09-02 01:50:28.821129Z] Running install step. 18:50:28 INFO - Running pre-action listener: _resource_record_pre_action 18:50:28 INFO - Running main action method: install 18:50:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:50:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:50:29 INFO - Reading from file tmpfile_stdout 18:50:29 INFO - Detecting whether we're running mozinstall >=1.0... 18:50:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:50:29 INFO - Reading from file tmpfile_stdout 18:50:29 INFO - Output received: 18:50:29 INFO - Usage: mozinstall [options] installer 18:50:29 INFO - Options: 18:50:29 INFO - -h, --help show this help message and exit 18:50:29 INFO - -d DEST, --destination=DEST 18:50:29 INFO - Directory to install application into. [default: 18:50:29 INFO - "/builds/slave/test"] 18:50:29 INFO - --app=APP Application being installed. [default: firefox] 18:50:29 INFO - mkdir: /builds/slave/test/build/application 18:50:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:51:03 INFO - Reading from file tmpfile_stdout 18:51:03 INFO - Output received: 18:51:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:51:03 INFO - Running post-action listener: _resource_record_post_action 18:51:03 INFO - [mozharness: 2016-09-02 01:51:03.125184Z] Finished install step (success) 18:51:03 INFO - [mozharness: 2016-09-02 01:51:03.125299Z] Running run-tests step. 18:51:03 INFO - Running pre-action listener: _resource_record_pre_action 18:51:03 INFO - Running main action method: run_tests 18:51:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:51:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:51:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:51:03 INFO - 2016-09-01 18:51:03.139 screenresolution[1643:7530] starting screenresolution argv=screenresolution get 18:51:03 INFO - 2016-09-01 18:51:03.142 screenresolution[1643:7530] Display 0: 1600x1200x32@60 18:51:03 INFO - 2016-09-01 18:51:03.148 screenresolution[1644:7532] starting screenresolution argv=screenresolution list 18:51:03 INFO - Available Modes on Display 0 18:51:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:51:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:51:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:51:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:51:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:51:03 INFO - Intel Iris: 18:51:03 INFO - Chipset Model: Intel Iris 18:51:03 INFO - Type: GPU 18:51:03 INFO - Bus: Built-In 18:51:03 INFO - VRAM (Dynamic, Max): 1536 MB 18:51:03 INFO - Vendor: Intel (0x8086) 18:51:03 INFO - Device ID: 0x0a2e 18:51:03 INFO - Revision ID: 0x0009 18:51:03 INFO - Displays: 18:51:03 INFO - Display: 18:51:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:51:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:51:03 INFO - Display Serial Number: 1600x1200 60 18:51:03 INFO - Main Display: Yes 18:51:03 INFO - Mirror: Off 18:51:03 INFO - Online: Yes 18:51:03 INFO - Rotation: Supported 18:51:03 INFO - Return code: 0 18:51:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:51:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:51:03 INFO - Python 2.7.3 18:51:03 INFO - Return code: 0 18:51:03 INFO - grabbing minidump binary from tooltool 18:51: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')]} 18:51:03 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 0x10381bd30>, '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 0x103580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103813b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:51:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:51:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:51:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:51:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:51:03 INFO - Return code: 0 18:51:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:51:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:51:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:51:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:51:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:51:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:51:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0109 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:51:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.67SDdTS0O5/Render', 18:51:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:03 INFO - 'HOME': '/Users/cltbld', 18:51:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:03 INFO - 'LOGNAME': 'cltbld', 18:51:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:51:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:51:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:03 INFO - 'MOZ_NO_REMOTE': '1', 18:51:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:51:03 INFO - 'NO_EM_RESTART': '1', 18:51:03 INFO - 'PAGER': '/bin/cat', 18:51:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:03 INFO - 'PWD': '/builds/slave/test', 18:51:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:51:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:03 INFO - 'SHELL': '/bin/bash', 18:51:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xqqZBREk3t/Listeners', 18:51:03 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 18:51:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:03 INFO - 'USER': 'cltbld', 18:51:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:03 INFO - 'XPC_FLAGS': '0x0', 18:51:03 INFO - 'XPC_SERVICE_NAME': '0', 18:51:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:51:03 INFO - mozversion application_buildid: 20160901174542 18:51:03 INFO - mozversion application_changeset: 2d457db23476fecab9e6e66a737c6723d0d2767c 18:51:03 INFO - mozversion application_display_name: Nightly 18:51:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:51:03 INFO - mozversion application_name: Firefox 18:51:03 INFO - mozversion application_remotingname: firefox 18:51:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:51:03 INFO - mozversion application_vendor: Mozilla 18:51:03 INFO - mozversion application_version: 51.0a1 18:51:03 INFO - mozversion platform_buildid: 20160901174542 18:51:03 INFO - mozversion platform_changeset: 2d457db23476fecab9e6e66a737c6723d0d2767c 18:51:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:51:03 INFO - mozversion platform_version: 51.0a1 18:51:03 INFO - using testdate: 1472781063 18:51:03 INFO - actual date: 1472781063 18:51:03 INFO - starting webserver on 'localhost:49279' 18:51:03 INFO - SUITE-START | Running 2 tests 18:51:03 INFO - TEST-START | dromaeo_css 18:51:03 INFO - operating with platform_type : mac_ 18:51:03 INFO - Initialising browser for dromaeo_css test... 18:51:03 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpT7ENAA/profile http://localhost:49279/getInfo.html) 18:51:06 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 18:51:06 INFO - PROCESS | 1654 | colorDepth:24 18:51:06 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 18:51:06 INFO - PROCESS | 1654 | __metrics 18:51:06 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:51:06 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:51:07 INFO - TEST-INFO | 1654: exit 0 18:51:07 INFO - Browser initialized. 18:51:07 INFO - Running cycle 1/1 for dromaeo_css test... 18:51:07 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpT7ENAA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:51:19 INFO - PROCESS | 1656 | RSS: Main: 233803776 18:51:19 INFO - PROCESS | 1656 | 18:52:10 INFO - PROCESS | 1656 | 1472781130563 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:54:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 18:54:24 INFO - PROCESS | 1656 | RSS: Main: 403578880 18:54:24 INFO - PROCESS | 1656 | 18:57:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 18:57:27 INFO - PROCESS | 1656 | RSS: Main: 385769472 18:57:27 INFO - PROCESS | 1656 | 19:00:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 19:00:30 INFO - PROCESS | 1656 | RSS: Main: 365457408 19:00:30 INFO - PROCESS | 1656 | 19:03:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 19:03:33 INFO - PROCESS | 1656 | RSS: Main: 339374080 19:03:33 INFO - PROCESS | 1656 | 19:06:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 19:06:37 INFO - PROCESS | 1656 | RSS: Main: 325632000 19:06:37 INFO - PROCESS | 1656 | 19:09:41 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 19:09:41 INFO - PROCESS | 1656 | RSS: Main: 487337984 19:09:41 INFO - PROCESS | 1656 | 19:09:41 INFO - PROCESS | 1656 | __start_tp_report 19:09:41 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 19:09:41 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 19:09:41 INFO - PROCESS | 1656 | |i|pagename|runs| 19:09:41 INFO - PROCESS | 1656 | |0;dojo.html;3292.341531693662;3319.5020746887963;3345.799252044876;3372.443546814776;3411.283630437608;5535.086710692741;5617.83146505605;5629.971850140754;5632.887342253152;5706.31524217094;5637.915431268524;5672.404397538261;5673.971630141844;5683.2895888014;5692.288463942007;5100.591952643789;5150.2532132840715;5151.330327057477;5155.7679904404395;5196.58427325815;5220.738963051832;5234.084035293828;5238.3975842778;5238.580913526908;5240.082985477546;5157.097507936115;5167.4057483389515;5192.169252919533;5202.505761952609;5223.660462069973;5081.110805609003;5239.476052394749;5253.0544501989625;5254.949010197975;5268.525832675077;5033.245013248005;5059.367579052619;5069.315642388267;5176.275321454999;5185.455527169668;5632.549396048306;5667.971660141714;5669.461401166883;5709.714514274311;5751.338596061465;6193.225846769154;6194.628322300631;6209.410106039919;6210.068489726532;6213.03697926822;6096.542759293026;6146.477549408306;6196.4423201911595;6256.530760192958;6259.436650701458;5732.570057245724;5770.855728606776;5770.913436298459;5785.768569257267;5789.942100578983;6283.403076707706;6293.590916590407;6308.116863639094;6309.400606942425;6310.905336419957;5894.882102358015;5981.551383646157;6003.519718422515;6092.147099406086;6097.847553811191;6161.938380616226;6204.937950620526;6210.565260431817;6228.875422491525;6235.781747638811;6076.969615151985;6141.6007959482495;6165.260168779775;6182.258129024546;6188.257409110935;6022.09668549721;6066.787662431793;6086.2087928569;6179.938200617937;6203.069539569011;4887.29134275528;4899.314096026559;4903.215485522299;4909.337239472697;4916.483769204182;4402.3396490526675;4430.778461076998;4440.911181776346;4444.400005999149;4449.377087207794;5544.16837474374;5638.126090455986;5664.20701101846;5680.204771332017;5757.971210143923;6220.595661282002;6249.406306400884;6270.2475702915945;6277.52918531112;6282.057691346243;5668;5669.716514174274;5686.346070201897;5697.23087383198;5716.114002329646;5299.894002120013;5316.680999140064;5341.919871201935;5343.732813359316;5382;6269.46709529686;6290.748370065146;6294.937050629618;6315.368463153648;6315.842103947346;6215.9378406217165;6216.968915155395;6221.657808820377;6222.595531290452;6235.781747638811;6226.3151053385;6235.064740288993;6248.625082495065;6297.307296197506;6307.148534947723;6133.916185115316;6142;6143.784967526115;6153.9845925421805;6158.137860699416;6225.501959843292;6227.595206311576;6256.812295630956;6257.311695713377;6272.560920735505;5900.675462849413;5902.173695682522;5948.078047902582;6051.667158306335;6068.241469816273;5991.88016239664;5994.910076348945;6015.4586087252355;6046.879062418815;6135.233095863017;4920.360353154067;5924.6149000314845;5937.910931335947;5939.821805345847;5947.524198064058;6032.969835150971;6052.727627256696;6052.84867878289;6078.240219972504;6085.056816193497;5752.568557358131;5790.986577348863;5791.478766910998;5856.385079566755;5892.292924849045 19:09:41 INFO - PROCESS | 1656 | |1;ext.html;438.76745324977753;493.62237888015676;505.81386648309723;522.4566450891074;526.1187510919211;30542.930997415096;33845.66154338459;39868.60131398689;41174.79412602933;43513.56486435135;42748.78625606876;43063.923401914886;43140.35289470653;43296;43329.133417331635;8854.424462409956;9778.559964801583;9966.601335946554;9970.700878973643;9971.252156088287;12049.397530123502;12327.876721232808;12328.630141095782;12487.438065287039;12488.438945131858;12204.69488262796;12322.383880805968;12323.876761232408;12324.260544367367;12350.567730129447;12188.451519681592;12272.815907761302;12277.877221227764;12286.569970051005;12291.877081229162;12073.45669444877;12142.150049496538;12159.392030398445;12160.939195304056;12177.807994561064;40037.59962400368;40339.394909076385;40386.39420408696;40413.79793101045;40492.595074049175;3539.50446937429;3602.3335682898487;3833.6358045985585;4014.310412023922;4153.937690934638;30579.847100764353;30912.69087309143;31638.841805790824;31865.362692746014;31898;3222.0494953988627;3317.369699757063;3338;3343.21434462901;3344.866205351783;352598;354226;355638;357366.9999999974;362550.9999999974;57047.42952570504;57688.84622307531;57937.4206257932;58168.709156453944;58280.83438331295;52040.739796300775;59001.114983275285;59982;60221;60231.397686023454;16036.599085022734;16212.75680864788;16225.675486490203;16229.188540572924;16272.837271627131;11482;11825.763484730258;12513.061520386007;12828.166169198972;12895.548655797189;12892.871071289166;15006.849931500763;15701.607459813367;15730.528084157493;15974.281157347947;5844.649321040752;6607.273199948002;6654.467642588581;6683.7660681875905;6691.631960242136;847.6016272351985;847.7838151271386;900.5542256583034;927.5501381829943;934.8784657994418;23045.287929510207;29776.219521318297;29940.550891736206;30009.849950750107;30041.699583004327;1050.1021626509225;1061.8991195836538;1064.9520771565299;1076.4294923690456;1078.2613909472036;15380.538583842503;15415.920885537904;15486.303116359792;15489.922550387402;15707.371705131894;616575.3369865156;618361;619747;627610;628727;56767.71616141893;56790.432095678516;56903.43096569064;56922.43077569254;56937.59092886169;1346.6094832498661;1372.025861638228;1372.2452651042086;1374.5944946240884;1376.5388594820647;28774;29612.555811663275;29717.257068573457;30228.84885575558;30380;48624.75687621539;49175.50824491852;49230.75384623197;49389.75305123451;49409;567722;569829;569852;571691;572217;29704.851475742482;39090.15245528279;39567;39735;40221.17898799609;35970.4604430939;45382.31926521039;45769;45803;46994.53005469902;38215.426768597936;38488.10590226005;38935;39209.803950980066;39958.801235962965;37884.43173352347;38285;38559.30942104569;38829;39910;37462;37905.4314185293;38291.234175316895;38398.35215654509;38407.84776456711;31787;33840.15399615029;34032.829835850665;34068.318633626695;34509;19823;24704.7647617622;25391;25635;25842.61236081498 19:09:41 INFO - PROCESS | 1656 | |2;jquery.html;367.13356478710244;376.6647683561629;388.64439038279966;401.5542747550217;408.43023981545747;19104.522386940316;19906.303279385225;19996.800031999675;20235.291764788253;20245.39263822084;18964.336248231313;19194.616107677837;19702.11340489678;20019.799802001977;20270;5281.603879709018;5795.768169273224;5892.852678683024;5983;6021.337652858182;5927.407259274081;6007.128966299884;6013.609115407507;6014.428629280212;6018.8796224075495;5708.628939118965;5796.927807980518;5895.616784908989;5904.763809447617;5905.793297234598;5583.274174357348;5841;5871.765129394819;5878.647281163144;5882.323532793741;5024.070546948826;5067.5185857343495;5088.745562721849;5103.030424219386;5110.080185566597;25014.874925625438;25083.37291567707;25090.24729258107;25133.748662513506;25378.70076890011;2247.9662805057937;2448.681671382709;2457.127719659513;2593.5850263957677;2839.5456726923594;9685.806283874354;10076.647317343857;10348.430836304;14815.777763333559;16024.038557686576;1811.1306572845108;1829.634073185355;1833.5507800588941;1850.1581780289937;1890.750066784737;114453.99999999917;114567.4271628645;118903;119415;121141;10002.849672287635;10540.310891188023;11250.324980501196;11312.547498100148;11395.037290304415;14367.497137600134;14408.13551186932;14414.42342306317;14427.422903083969;14447.277636118151;7403.9264306676305;8018.238267364591;8186.549739764251;8503.74488765338;8569.700060497977;8419.158084191531;8428.873566896502;8441.535715535581;8596.957015214884;8621.353398495137;4422.049259409242;4444.777761111932;4446.177457170434;4471.485779135376;4521.3896124023495;1776.3338747969512;1884.5477085499656;1890.9788714094234;1903.5526651236949;1932.8936908470168;847.737201467547;938.5166639180827;960.6301573894101;964.0744884910564;984.6652138273022;19141.861701863305;20471.692924606145;20482.590348192953;20548.075336609887;20860.165593376096;760.9771706848803;770.603139383218;797.9202079791975;937.1378331934636;947.4220725357526;10495.265950278716;10802.135829133651;10810.516143419945;10876.603131687598;10938.289011214245;528519;535810;556116;556916;560208;72170;74345.88481172787;75172.62413687896;97184;126632.99999999815;934.4113208678625;946.9431834090115;949.5774380400655;968.9757756056155;969.9806003879883;13414.956355279288;15342.922659995902;16652;17112.058836764096;17163.399281025104;54686.45313546814;55044.44955550393;55737.72131139319;56151.43848561626;56509.15236271377;59085.70457147687;75263.62368188125;75295;76429;79272;6957.373836354753;7236.167840698387;7753.844923101619;7873.448858580074;8088.757337279891;7581.886271706039;12420.875791241971;12802.743945121232;13247.536336228572;13430.328483575977;11123.499442524944;11460.083193344679;11618.477168527268;11644.592439264585;11689.181757276909;11159.27464714791;11204.215704900578;11493.712657183638;11670.766584668429;11705.765884682089;11128.276662016942;11401.828972565252;11812.940935295612;11844.052475801744;11930.343831089027;7936.404769642185;7940.28537431634;8138.145494722969;8290.046644635944;8411.663533458837;8341.83316333682;10564.735881603021;11304.717382065513;11551.133664975328;11560.13299002595 19:09:41 INFO - PROCESS | 1656 | |3;mootools.html;1307.3397934043319;1437.9065360751551;1465.069680752722;1490.1655073159031;1889.5465088378785;7582.620868956551;8331.000279966405;8686.739397818068;8704.347173961962;8779.780505487366;8567.35744819138;8648.91351086489;8680.696175633853;8691.130886911322;8703.608337624806;2370.0519792083132;2399.9040038398443;2402.015173778751;2403.495265994143;2405.0860672944345;2343.5781559319316;2368.3368656776042;2389.3429306940557;2390.569697454466;2393.3298676370646;2361.445060410802;2376.940576485584;2388.092524840567;2395.3891757601787;2396.820238482112;2339.4034521197154;2365.7161140663143;2371.5256948610345;2373.4659701567184;2374.9168779092647;2305.8501197422115;2336.2874323331375;2337.614293641547;2342.61346877765;2345.3784747042046;8461.915380846174;8538.146185381474;8707.651693932236;8717.259032982203;8717.389782715212;5159.277701121846;5346.893062138735;5351.197320401932;5352.411234764173;5356.062689029443;3568.7858728476376;3573.6247693992277;3630.8366123524506;3642.271545690846;3644.5079914211506;1070.5557193764578;1078.6278733836814;1080.330195278932;1106.2145876427746;1108.706192858885;11411.714707132305;11423.94288028563;11489.999999999916;11513.42432878361;11574.942125289404;3681.5950245472977;4318.805653745589;4409.250427427345;4545.2954792007295;4593.402857628487;8873.423227490193;8873.73378798637;8892.110788921056;8901.020887702347;8902.643894244158;5174.301469301671;5177.8187763428095;5183.844484665466;5185.818496352685;5186.507281808223;4353.281708518059;4374.518802931675;4538.455385353713;4850.6362022847725;4860.1737704590305;3651.397519409321;3679.4848721179055;3759.3797023491366;3795.0891785971166;3805.162864169878;1000.3247807729754;1130.2879186112696;1149.6661776593528;1168.5793114478781;1177.152450235846;761.6800943603699;854.3165467625874;898.3935843305743;900.7297810656777;942.9858552121724;5240.816571419982;5573.203867977259;5758.992176369203;5853.385394533512;5952.136940143655;978.7944531648291;996.6113215845681;1007.09361574582;1017.6438246613626;1043.9217058720474;3713.405855063177;3759.323321802102;3771.585125636178;3888.0862997195636;3951.05174758056;10285.588576456857;10495.947520262349;10603.787924241473;10608.893911060944;10627.9903409176;10553.736156595993;10566.471676416148;10574.577016919391;10580.894191058145;10626.309289896131;7594.772156835304;7892.368610511146;7974.242446967529;8162.795930101676;8165.755027349189;5833.737481813413;5860.472557469848;5869.090291004731;5889.204957330706;6008.819735407945;9239.76900577491;9555.61777528891;9683.515824208902;9930.453825039693;10022.3485473444;9825.852612210965;10035.799284014425;10040.748981275234;10178.847317290098;10181.74545636365;6823.931760682528;7083.1854336749875;7387;7434.591097489473;7533.096028476618;6876.656167191721;8319.667213311399;8328.08391076969;8328.875066874123;8353.95823020881;7250.492465527363;7351.632418379167;7376.7418140364825;7451.217622149813;7499.775006749806;6895.310468953065;6946;7249.42004639617;7269.164046134762;7284.089488813898;6798.966005169942;6854.383105520527;6925.065116209449;6929.272426395156;6932.514723969273;4298.957010429981;4900.754962251945;4930.087933732271;4933.457319694759;4944.085344211332;4903.31353610502;4943.728094954668;4986.626003049858;5048.469910659474;5070 19:09:41 INFO - PROCESS | 1656 | |4;prototype.html;300.4036986581636;305.20189703924245;309.16371215861096;314.2207325831937;317.84425631440604;4356.78216089195;5201.6098792590565;5231.110711179099;5325.121354976434;5330.706811125391;4138.937915931256;4730.499430421489;5054.671446355985;5221.5822734181265;5337.786488540453;3155.365269999542;3425.8629654813903;3649.6350364963555;3683.1712864605515;4042.4936539345645;3988.3419235826054;3997.9999999999854;3999.7800120993334;4022.557518672944;4029.53660329063;3289.125198636778;3312.7846689965077;3621.963780362189;3782.9810850945846;3959.366501359783;3926.2343842950636;3942.9605703943025;3964.8414063437426;3970.4838371011583;3978.622030907059;2879.294572829649;3179.666135055832;3278.7868788528667;3450.0339904826687;3469.271452994874;4654.25531914892;4806.615470762331;4954.752262386866;5121.897562048775;5246.265522826808;4543.341215523764;4556.612687921531;4557.817687292538;4587.151376995266;4601.401817763703;5441.265429167091;5459.808906688247;5483.890322193533;5520.337559492847;5551.056320425538;3849.3840985442475;3863.3625451800417;3869.7678139311734;3875.767453952772;3875.8255878485534;5198.792048318062;5454.61817672763;5717.313922329264;5728.481952282648;5768.211854325173;3877.302085624615;3881.825317860703;3882.2235552889533;3891.3579259422445;3893.7274390792372;3950.696270230817;3969.8610548631236;3970.8014599269923;3985.3224951757693;3988.743545783069;3884.9291711688898;4363.2364336241035;4493.573110554492;4520.728756274634;4570.154521413549;4549.795259213343;5460.31746031746;5477.3427188737605;5480.780768769284;5485.561155107582;1211.2913945341897;1319.8284223050941;1344.2069179184327;1369.657585603599;1376.1192836584594;766.5592284436472;826.5949684654476;864.5590748718199;896;923.6813299411692;4322.438083049184;4474.970756725971;4765.285207218945;5113.233015047773;5125.692458452505;5592.685718856065;5623.971880140573;5637.379888212294;5710.942890571124;5739.598228124075;4704.506026867198;5058.215976523645;5138.691678499302;5150.922736158961;5198.792048318101;2594.805389595788;2797.5523916173315;2819.2951762059483;2904.956425653617;2967.762578993675;4848.490908454632;5162.870928226749;5358.4373640767935;5375.279910428703;5923.881522369528;4469.4636643602325;5691.857703557444;5790.191756997852;5829.571754920072;5847.9415205848245;5862.091375836752;5866.2373891393845;5874.089516125007;5964.403559644001;6041.879162416727;4981.953789704058;5302.734863256745;5363.973180134074;5648.689322087161;5673.517750991212;4820.493848145894;5101.933716844684;5282.061272710029;5588.41321661236;5603.579731520071;4636.374089498009;4672.322513235494;5465.617406781487;5897.941020589739;5958.55699163341;3570.357335679603;3690.760100593453;3735.96264037367;3744.1544426235714;3745.400735882246;5646.160610784706;5691.7438715257085;5708.172315014217;5719.342275638355;5767.68277744728;5306.495882891119;5492.3409190895745;5542.2795036644975;5552.639078459887;5564.221009058654;5182.585393168462;5422.376426710979;5476.507114359568;5537.446255374431;5628.85927851807;4708.208903049337;4876.683015603974;5094.8216812412875;5127.282180494808;5154.355705536808;5365.490278423544;5442.510174084351;5511.319047690493;5517.337919449692;5589.413111623384;5384.273123128484;5386.919196212139;5397.730113494231;5431.511163995259;5462.262594549685 19:09:41 INFO - PROCESS | 1656 | |5;yui.html;672.9427998620117;773.369703691491;792.9533016418327;802.1216767639435;817.8650522663761;14044.789328160074;14528.491502797404;15086.773698394532;15866.682666346665;16226.675466490664;14407.207603581825;14716.411343546246;14736.778948315758;14738.189399583018;15167.54497365078;692.5083190934417;851.650823162505;867.5835598912525;868.448535180162;868.6264906090379;718.131061415685;856.4261944497201;884.5002573545926;897.3270047464401;899.9145081217274;938.0900526489295;939.6570251858091;941.0260380506178;941.1435593609816;941.4304345870752;721.2246834652717;934.887813462382;937.1893312284825;940.7365937537467;941.2281928818371;674.1606699658956;697.4873468001015;698.0123125777019;699.4509310191526;700.7932659865352;15019.849801501954;15057.774133388008;15074.397024119022;15088.321025553767;15100.093994360373;13817.585472435843;13970.790438143436;14102.435902563886;14205.218712970782;14515.346809393497;10482.528286227138;12593.559225427158;12780.808287875689;12900;13192.73614527704;4518.615917647004;4535.7732113394195;4555.407796986404;4741;4748.19280722278;93586;118672;121168.39415802865;121382.39308803488;122104;10962;11022.906556427546;11202.159838012181;11244;11315.377654228932;22031.559368812534;22034.88982555109;22036.88981555114;22073.779262207172;22077.668834967488;7709.691612335444;7973.362131029504;8047.8330642057745;8121.837563248702;8123;4313.137372525444;4506.098780243964;4557.635389168859;4570.88572785683;4759.333693282943;3393.728501719906;3526.982365088158;3559.1635965548076;3561.9109522261633;3570.6072332043454;615;621.9315875253718;624.7126321891981;634.1565717595587;642.595165046018;573.1202604002835;580.1587697838067;619.3527763487077;627.4008322052429;628.1770880147022;8687.52218627969;8807.559622019002;8996.55017249148;9022.14289642483;9118.908810911938;604.1300527240847;610.4292486525113;615.5106690181317;635.6281575278422;636.9904451433232;4273.722208056529;4289.506706728704;4291.570842915746;4348.304271316574;4398.296272596369;137528;167484.16257918876;167703.39114392814;171770;171843;89821;91000.54499727458;97016.51491742497;136166.95826043488;141894.2905285467;95496.52251738697;96971.99999999859;97767;100817.49591251997;110658.44670776595;8847.20375166238;9366.297527685314;9721.756956076155;9903.752406189902;10007.449590272314;41448.792756036026;59516;60002.699986499785;62137.37862621316;62606.06090908549;93886;97863.51068244613;98075.52886706563;98774.5061274689;100459.49770251101;12829.294388808705;13037.413316400594;13132.277724725232;13541.79687304711;13617.86382136166;14833.851661483679;14938.925305373403;14944.551663450115;15080.849191508383;15586.766198506804;13808.378622961789;13960.232187229394;14080.647983800487;14255.358508867334;14272.873098564369;13614.727705445637;14016.439342426189;14129.222892740998;14268.643283917985;14565.781513277097;13281.734365312834;13885.236312002937;14006.719865602834;14105.09071955116;14254.714905702036;11019.889801101886;11187.049100826196;11246.325220486797;11317.094632429551;11335.03652189573;9854;9862.260330475387;11537.192396532162;11565.479553419947;11587.304761714324 19:09:41 INFO - PROCESS | 1656 | __end_tp_report 19:09:41 INFO - PROCESS | 1656 | __start_cc_report 19:09:41 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1561 19:09:41 INFO - PROCESS | 1656 | __end_cc_report 19:09:41 INFO - PROCESS | 1656 | __startTimestamp1472782181478__endTimestamp 19:09:41 INFO - PROCESS | 1656 | 19:09:41 INFO - PROCESS | 1656 | ------- Summary: start ------- 19:09:41 INFO - PROCESS | 1656 | Number of tests: 6 19:09:41 INFO - PROCESS | 1656 | 19:09:41 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5704.76 Median:5900.68 stddev:633.86 (10.7%) stddev-sans-first:606.89 19:09:41 INFO - PROCESS | 1656 | Values: 3292.3 3319.5 3345.8 3372.4 3411.3 5535.1 5617.8 5630.0 5632.9 5706.3 5637.9 5672.4 5674.0 5683.3 5692.3 5100.6 5150.3 5151.3 5155.8 5196.6 5220.7 5234.1 5238.4 5238.6 5240.1 5157.1 5167.4 5192.2 5202.5 5223.7 5081.1 5239.5 5253.1 5254.9 5268.5 5033.2 5059.4 5069.3 5176.3 5185.5 5632.5 5668.0 5669.5 5709.7 5751.3 6193.2 6194.6 6209.4 6210.1 6213.0 6096.5 6146.5 6196.4 6256.5 6259.4 5732.6 5770.9 5770.9 5785.8 5789.9 6283.4 6293.6 6308.1 6309.4 6310.9 5894.9 5981.6 6003.5 6092.1 6097.8 6161.9 6204.9 6210.6 6228.9 6235.8 6077.0 6141.6 6165.3 6182.3 6188.3 6022.1 6066.8 6086.2 6179.9 6203.1 4887.3 4899.3 4903.2 4909.3 4916.5 4402.3 4430.8 4440.9 4444.4 4449.4 5544.2 5638.1 5664.2 5680.2 5758.0 6220.6 6249.4 6270.2 6277.5 6282.1 5668.0 5669.7 5686.3 5697.2 5716.1 5299.9 5316.7 5341.9 5343.7 5382.0 6269.5 6290.7 6294.9 6315.4 6315.8 6215.9 6217.0 6221.7 6222.6 6235.8 6226.3 6235.1 6248.6 6297.3 6307.1 6133.9 6142.0 6143.8 6154.0 6158.1 6225.5 6227.6 6256.8 6257.3 6272.6 5900.7 5902.2 5948.1 6051.7 6068.2 5991.9 5994.9 6015.5 6046.9 6135.2 4920.4 5924.6 5937.9 5939.8 5947.5 6033.0 6052.7 6052.8 6078.2 6085.1 5752.6 5791.0 5791.5 5856.4 5892.3 19:09:41 INFO - PROCESS | 1656 | 19:09:41 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:65906.03 Median:200006.50 stddev:141257.88 (70.6%) stddev-sans-first:141568.63 19:09:41 INFO - PROCESS | 1656 | Values: 438.8 493.6 505.8 522.5 526.1 30542.9 33845.7 39868.6 41174.8 43513.6 42748.8 43063.9 43140.4 43296.0 43329.1 8854.4 9778.6 9966.6 9970.7 9971.3 12049.4 12327.9 12328.6 12487.4 12488.4 12204.7 12322.4 12323.9 12324.3 12350.6 12188.5 12272.8 12277.9 12286.6 12291.9 12073.5 12142.2 12159.4 12160.9 12177.8 40037.6 40339.4 40386.4 40413.8 40492.6 3539.5 3602.3 3833.6 4014.3 4153.9 30579.8 30912.7 31638.8 31865.4 31898.0 3222.0 3317.4 3338.0 3343.2 3344.9 352598.0 354226.0 355638.0 357367.0 362551.0 57047.4 57688.8 57937.4 58168.7 58280.8 52040.7 59001.1 59982.0 60221.0 60231.4 16036.6 16212.8 16225.7 16229.2 16272.8 11482.0 11825.8 12513.1 12828.2 12895.5 12892.9 15006.8 15701.6 15730.5 15974.3 5844.6 6607.3 6654.5 6683.8 6691.6 847.6 847.8 900.6 927.6 934.9 23045.3 29776.2 29940.6 30009.8 30041.7 1050.1 1061.9 1065.0 1076.4 1078.3 15380.5 15415.9 15486.3 15489.9 15707.4 616575.3 618361.0 619747.0 627610.0 628727.0 56767.7 56790.4 56903.4 56922.4 56937.6 1346.6 1372.0 1372.2 1374.6 1376.5 28774.0 29612.6 29717.3 30228.8 30380.0 48624.8 49175.5 49230.8 49389.8 49409.0 567722.0 569829.0 569852.0 571691.0 572217.0 29704.9 39090.2 39567.0 39735.0 40221.2 35970.5 45382.3 45769.0 45803.0 46994.5 38215.4 38488.1 38935.0 39209.8 39958.8 37884.4 38285.0 38559.3 38829.0 39910.0 37462.0 37905.4 38291.2 38398.4 38407.8 31787.0 33840.2 34032.8 34068.3 34509.0 19823.0 24704.8 25391.0 25635.0 25842.6 19:09:41 INFO - PROCESS | 1656 | 19:09:41 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:32334.69 Median:395.10 stddev:91054.91 (23046.1%) stddev-sans-first:91278.71 19:09:41 INFO - PROCESS | 1656 | Values: 367.1 376.7 388.6 401.6 408.4 19104.5 19906.3 19996.8 20235.3 20245.4 18964.3 19194.6 19702.1 20019.8 20270.0 5281.6 5795.8 5892.9 5983.0 6021.3 5927.4 6007.1 6013.6 6014.4 6018.9 5708.6 5796.9 5895.6 5904.8 5905.8 5583.3 5841.0 5871.8 5878.6 5882.3 5024.1 5067.5 5088.7 5103.0 5110.1 25014.9 25083.4 25090.2 25133.7 25378.7 2248.0 2448.7 2457.1 2593.6 2839.5 9685.8 10076.6 10348.4 14815.8 16024.0 1811.1 1829.6 1833.6 1850.2 1890.8 114454.0 114567.4 118903.0 119415.0 121141.0 10002.8 10540.3 11250.3 11312.5 11395.0 14367.5 14408.1 14414.4 14427.4 14447.3 7403.9 8018.2 8186.5 8503.7 8569.7 8419.2 8428.9 8441.5 8597.0 8621.4 4422.0 4444.8 4446.2 4471.5 4521.4 1776.3 1884.5 1891.0 1903.6 1932.9 847.7 938.5 960.6 964.1 984.7 19141.9 20471.7 20482.6 20548.1 20860.2 761.0 770.6 797.9 937.1 947.4 10495.3 10802.1 10810.5 10876.6 10938.3 528519.0 535810.0 556116.0 556916.0 560208.0 72170.0 74345.9 75172.6 97184.0 126633.0 934.4 946.9 949.6 969.0 970.0 13415.0 15342.9 16652.0 17112.1 17163.4 54686.5 55044.4 55737.7 56151.4 56509.2 59085.7 75263.6 75295.0 76429.0 79272.0 6957.4 7236.2 7753.8 7873.4 8088.8 7581.9 12420.9 12802.7 13247.5 13430.3 11123.5 11460.1 11618.5 11644.6 11689.2 11159.3 11204.2 11493.7 11670.8 11705.8 11128.3 11401.8 11812.9 11844.1 11930.3 7936.4 7940.3 8138.1 8290.0 8411.7 8341.8 10564.7 11304.7 11551.1 11560.1 19:09:41 INFO - PROCESS | 1656 | 19:09:41 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5582.45 Median:4916.70 stddev:3114.66 (63.3%) stddev-sans-first:3106.82 19:09:41 INFO - PROCESS | 1656 | Values: 1307.3 1437.9 1465.1 1490.2 1889.5 7582.6 8331.0 8686.7 8704.3 8779.8 8567.4 8648.9 8680.7 8691.1 8703.6 2370.1 2399.9 2402.0 2403.5 2405.1 2343.6 2368.3 2389.3 2390.6 2393.3 2361.4 2376.9 2388.1 2395.4 2396.8 2339.4 2365.7 2371.5 2373.5 2374.9 2305.9 2336.3 2337.6 2342.6 2345.4 8461.9 8538.1 8707.7 8717.3 8717.4 5159.3 5346.9 5351.2 5352.4 5356.1 3568.8 3573.6 3630.8 3642.3 3644.5 1070.6 1078.6 1080.3 1106.2 1108.7 11411.7 11423.9 11490.0 11513.4 11574.9 3681.6 4318.8 4409.3 4545.3 4593.4 8873.4 8873.7 8892.1 8901.0 8902.6 5174.3 5177.8 5183.8 5185.8 5186.5 4353.3 4374.5 4538.5 4850.6 4860.2 3651.4 3679.5 3759.4 3795.1 3805.2 1000.3 1130.3 1149.7 1168.6 1177.2 761.7 854.3 898.4 900.7 943.0 5240.8 5573.2 5759.0 5853.4 5952.1 978.8 996.6 1007.1 1017.6 1043.9 3713.4 3759.3 3771.6 3888.1 3951.1 10285.6 10495.9 10603.8 10608.9 10628.0 10553.7 10566.5 10574.6 10580.9 10626.3 7594.8 7892.4 7974.2 8162.8 8165.8 5833.7 5860.5 5869.1 5889.2 6008.8 9239.8 9555.6 9683.5 9930.5 10022.3 9825.9 10035.8 10040.7 10178.8 10181.7 6823.9 7083.2 7387.0 7434.6 7533.1 6876.7 8319.7 8328.1 8328.9 8354.0 7250.5 7351.6 7376.7 7451.2 7499.8 6895.3 6946.0 7249.4 7269.2 7284.1 6799.0 6854.4 6925.1 6929.3 6932.5 4299.0 4900.8 4930.1 4933.5 4944.1 4903.3 4943.7 4986.6 5048.5 5070.0 19:09:41 INFO - PROCESS | 1656 | 19:09:41 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4448.69 Median:5076.52 stddev:1373.34 (27.1%) stddev-sans-first:1341.44 19:09:41 INFO - PROCESS | 1656 | Values: 300.4 305.2 309.2 314.2 317.8 4356.8 5201.6 5231.1 5325.1 5330.7 4138.9 4730.5 5054.7 5221.6 5337.8 3155.4 3425.9 3649.6 3683.2 4042.5 3988.3 3998.0 3999.8 4022.6 4029.5 3289.1 3312.8 3622.0 3783.0 3959.4 3926.2 3943.0 3964.8 3970.5 3978.6 2879.3 3179.7 3278.8 3450.0 3469.3 4654.3 4806.6 4954.8 5121.9 5246.3 4543.3 4556.6 4557.8 4587.2 4601.4 5441.3 5459.8 5483.9 5520.3 5551.1 3849.4 3863.4 3869.8 3875.8 3875.8 5198.8 5454.6 5717.3 5728.5 5768.2 3877.3 3881.8 3882.2 3891.4 3893.7 3950.7 3969.9 3970.8 3985.3 3988.7 3884.9 4363.2 4493.6 4520.7 4570.2 4549.8 5460.3 5477.3 5480.8 5485.6 1211.3 1319.8 1344.2 1369.7 1376.1 766.6 826.6 864.6 896.0 923.7 4322.4 4475.0 4765.3 5113.2 5125.7 5592.7 5624.0 5637.4 5710.9 5739.6 4704.5 5058.2 5138.7 5150.9 5198.8 2594.8 2797.6 2819.3 2905.0 2967.8 4848.5 5162.9 5358.4 5375.3 5923.9 4469.5 5691.9 5790.2 5829.6 5847.9 5862.1 5866.2 5874.1 5964.4 6041.9 4982.0 5302.7 5364.0 5648.7 5673.5 4820.5 5101.9 5282.1 5588.4 5603.6 4636.4 4672.3 5465.6 5897.9 5958.6 3570.4 3690.8 3736.0 3744.2 3745.4 5646.2 5691.7 5708.2 5719.3 5767.7 5306.5 5492.3 5542.3 5552.6 5564.2 5182.6 5422.4 5476.5 5537.4 5628.9 4708.2 4876.7 5094.8 5127.3 5154.4 5365.5 5442.5 5511.3 5517.3 5589.4 5384.3 5386.9 5397.7 5431.5 5462.3 19:09:41 INFO - PROCESS | 1656 | 19:09:41 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:24795.89 Median:4290.54 stddev:39972.60 (931.6%) stddev-sans-first:40043.69 19:09:41 INFO - PROCESS | 1656 | Values: 672.9 773.4 793.0 802.1 817.9 14044.8 14528.5 15086.8 15866.7 16226.7 14407.2 14716.4 14736.8 14738.2 15167.5 692.5 851.7 867.6 868.4 868.6 718.1 856.4 884.5 897.3 899.9 938.1 939.7 941.0 941.1 941.4 721.2 934.9 937.2 940.7 941.2 674.2 697.5 698.0 699.5 700.8 15019.8 15057.8 15074.4 15088.3 15100.1 13817.6 13970.8 14102.4 14205.2 14515.3 10482.5 12593.6 12780.8 12900.0 13192.7 4518.6 4535.8 4555.4 4741.0 4748.2 93586.0 118672.0 121168.4 121382.4 122104.0 10962.0 11022.9 11202.2 11244.0 11315.4 22031.6 22034.9 22036.9 22073.8 22077.7 7709.7 7973.4 8047.8 8121.8 8123.0 4313.1 4506.1 4557.6 4570.9 4759.3 3393.7 3527.0 3559.2 3561.9 3570.6 615.0 621.9 624.7 634.2 642.6 573.1 580.2 619.4 627.4 628.2 8687.5 8807.6 8996.6 9022.1 9118.9 604.1 610.4 615.5 635.6 637.0 4273.7 4289.5 4291.6 4348.3 4398.3 137528.0 167484.2 167703.4 171770.0 171843.0 89821.0 91000.5 97016.5 136167.0 141894.3 95496.5 96972.0 97767.0 100817.5 110658.4 8847.2 9366.3 9721.8 9903.8 10007.4 41448.8 59516.0 60002.7 62137.4 62606.1 93886.0 97863.5 98075.5 98774.5 100459.5 12829.3 13037.4 13132.3 13541.8 13617.9 14833.9 14938.9 14944.6 15080.8 15586.8 13808.4 13960.2 14080.6 14255.4 14272.9 13614.7 14016.4 14129.2 14268.6 14565.8 13281.7 13885.2 14006.7 14105.1 14254.7 11019.9 11187.0 11246.3 11317.1 11335.0 9854.0 9862.3 11537.2 11565.5 11587.3 19:09:41 INFO - PROCESS | 1656 | -------- Summary: end -------- 19:09:41 INFO - PROCESS | 1656 | 19:09:42 INFO - TEST-INFO | 1656: exit 0 19:09:42 INFO - TEST-OK | dromaeo_css | took 1118363ms 19:09:42 INFO - TEST-START | kraken 19:09:42 INFO - operating with platform_type : mac_ 19:09:42 INFO - Initialising browser for kraken test... 19:09:42 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpNgM3Zf/profile http://localhost:49279/getInfo.html) 19:09:44 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 19:09:44 INFO - PROCESS | 1665 | colorDepth:24 19:09:44 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 19:09:44 INFO - PROCESS | 1665 | __metrics 19:09:44 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:09:44 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:09:45 INFO - TEST-INFO | 1665: exit 0 19:09:45 INFO - Browser initialized. 19:09:45 INFO - Running cycle 1/1 for kraken test... 19:09:45 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpNgM3Zf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:09:57 INFO - PROCESS | 1666 | RSS: Main: 233705472 19:09:57 INFO - PROCESS | 1666 | 19:09:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 19:10:00 INFO - PROCESS | 1666 | RSS: Main: 292057088 19:10:00 INFO - PROCESS | 1666 | 19:10:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 19:10:04 INFO - PROCESS | 1666 | RSS: Main: 288350208 19:10:04 INFO - PROCESS | 1666 | 19:10:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 19:10:08 INFO - PROCESS | 1666 | RSS: Main: 379248640 19:10:08 INFO - PROCESS | 1666 | 19:10:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 19:10:10 INFO - PROCESS | 1666 | RSS: Main: 282103808 19:10:10 INFO - PROCESS | 1666 | 19:10:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:10:13 INFO - PROCESS | 1666 | RSS: Main: 284512256 19:10:13 INFO - PROCESS | 1666 | 19:10:16 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 19:10:16 INFO - PROCESS | 1666 | RSS: Main: 324472832 19:10:16 INFO - PROCESS | 1666 | 19:10:19 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 19:10:20 INFO - PROCESS | 1666 | RSS: Main: 326213632 19:10:20 INFO - PROCESS | 1666 | 19:10:23 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 19:10:23 INFO - PROCESS | 1666 | RSS: Main: 322306048 19:10:23 INFO - PROCESS | 1666 | 19:10:25 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:10:26 INFO - PROCESS | 1666 | RSS: Main: 297558016 19:10:26 INFO - PROCESS | 1666 | 19:10:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:10:28 INFO - PROCESS | 1666 | RSS: Main: 304009216 19:10:28 INFO - PROCESS | 1666 | 19:10:30 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:10:31 INFO - PROCESS | 1666 | RSS: Main: 301223936 19:10:31 INFO - PROCESS | 1666 | 19:10:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:10:35 INFO - PROCESS | 1666 | RSS: Main: 319492096 19:10:35 INFO - PROCESS | 1666 | 19:10:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:10:38 INFO - PROCESS | 1666 | RSS: Main: 315645952 19:10:38 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 19:10:40 INFO - PROCESS | 1666 | RSS: Main: 359559168 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | __start_tp_report 19:10:40 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 19:10:40 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 19:10:40 INFO - PROCESS | 1666 | |i|pagename|runs| 19:10:40 INFO - PROCESS | 1666 | |0;ai-astar;111;110;114;112;111;110;111;114;110;121 19:10:40 INFO - PROCESS | 1666 | |1;audio-beat-detection;148;109;132;160;109;162;150;101;199;138 19:10:40 INFO - PROCESS | 1666 | |2;audio-dft;182;185;182;180;182;201;184;178;182;182 19:10:40 INFO - PROCESS | 1666 | |3;audio-fft;77;84;75;155;58;57;57;61;143;57 19:10:40 INFO - PROCESS | 1666 | |4;audio-oscillator;83;83;132;70;70;91;121;68;70;118 19:10:40 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;86;83;86;83;85;83;85;83;87;83 19:10:40 INFO - PROCESS | 1666 | |6;imaging-darkroom;93;93;93;94;93;92;93;93;93;93 19:10:40 INFO - PROCESS | 1666 | |7;imaging-desaturate;86;84;85;101;117;134;115;85;85;84 19:10:40 INFO - PROCESS | 1666 | |8;json-parse-financial;52;50;56;52;51;52;50;53;51;52 19:10:40 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;67;72;73;77;74;74;105;71;72;71 19:10:40 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;85;87;85;85;81;86;97;105;95;84 19:10:40 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;137;142;150;144;148;139;139;135;230;151 19:10:40 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;166;167;167;167;161;167;167;167;170;231 19:10:40 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;64;82;85;67;68;68;66;67;67;66 19:10:40 INFO - PROCESS | 1666 | __end_tp_report 19:10:40 INFO - PROCESS | 1666 | __start_cc_report 19:10:40 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2038 19:10:40 INFO - PROCESS | 1666 | __end_cc_report 19:10:40 INFO - PROCESS | 1666 | __startTimestamp1472782240764__endTimestamp 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | ------- Summary: start ------- 19:10:40 INFO - PROCESS | 1666 | Number of tests: 14 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:112.40 Median:111.50 stddev:3.37 (3.0%) stddev-sans-first:3.54 19:10:40 INFO - PROCESS | 1666 | Values: 111.0 110.0 114.0 112.0 111.0 110.0 111.0 114.0 110.0 121.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:149.00 stddev:29.88 (20.1%) stddev-sans-first:31.58 19:10:40 INFO - PROCESS | 1666 | Values: 148.0 109.0 132.0 160.0 109.0 162.0 150.0 101.0 199.0 138.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:183.80 Median:182.00 stddev:6.34 (3.5%) stddev-sans-first:6.69 19:10:40 INFO - PROCESS | 1666 | Values: 182.0 185.0 182.0 180.0 182.0 201.0 184.0 178.0 182.0 182.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:82.40 Median:59.50 stddev:36.54 (61.4%) stddev-sans-first:38.71 19:10:40 INFO - PROCESS | 1666 | Values: 77.0 84.0 75.0 155.0 58.0 57.0 57.0 61.0 143.0 57.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:70.00 stddev:24.24 (34.6%) stddev-sans-first:25.55 19:10:40 INFO - PROCESS | 1666 | Values: 83.0 83.0 132.0 70.0 70.0 91.0 121.0 68.0 70.0 118.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:84.40 Median:85.00 stddev:1.58 (1.9%) stddev-sans-first:1.56 19:10:40 INFO - PROCESS | 1666 | Values: 86.0 83.0 86.0 83.0 85.0 83.0 85.0 83.0 87.0 83.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 19:10:40 INFO - PROCESS | 1666 | Values: 93.0 93.0 93.0 94.0 93.0 92.0 93.0 93.0 93.0 93.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:84.50 stddev:18.25 (21.6%) stddev-sans-first:18.86 19:10:40 INFO - PROCESS | 1666 | Values: 86.0 84.0 85.0 101.0 117.0 134.0 115.0 85.0 85.0 84.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:1.73 (3.3%) stddev-sans-first:1.83 19:10:40 INFO - PROCESS | 1666 | Values: 52.0 50.0 56.0 52.0 51.0 52.0 50.0 53.0 51.0 52.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:75.60 Median:72.50 stddev:10.65 (14.7%) stddev-sans-first:10.83 19:10:40 INFO - PROCESS | 1666 | Values: 67.0 72.0 73.0 77.0 74.0 74.0 105.0 71.0 72.0 71.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:85.50 stddev:7.50 (8.8%) stddev-sans-first:7.81 19:10:40 INFO - PROCESS | 1666 | Values: 85.0 87.0 85.0 85.0 81.0 86.0 97.0 105.0 95.0 84.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:146.00 stddev:28.12 (19.3%) stddev-sans-first:29.34 19:10:40 INFO - PROCESS | 1666 | Values: 137.0 142.0 150.0 144.0 148.0 139.0 139.0 135.0 230.0 151.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:167.00 stddev:20.50 (12.3%) stddev-sans-first:21.59 19:10:40 INFO - PROCESS | 1666 | Values: 166.0 167.0 167.0 167.0 161.0 167.0 167.0 167.0 170.0 231.0 19:10:40 INFO - PROCESS | 1666 | 19:10:40 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:67.50 stddev:7.24 (10.7%) stddev-sans-first:7.35 19:10:40 INFO - PROCESS | 1666 | Values: 64.0 82.0 85.0 67.0 68.0 68.0 66.0 67.0 67.0 66.0 19:10:40 INFO - PROCESS | 1666 | -------- Summary: end -------- 19:10:40 INFO - PROCESS | 1666 | 19:10:41 INFO - TEST-INFO | 1666: exit 0 19:10:41 INFO - TEST-OK | kraken | took 59526ms 19:10:41 INFO - SUITE-END | took 1177s 19:10:41 INFO - Completed test suite (00:19:38) 19:10:41 INFO - javascript benchmark 19:10:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3292.341531693662, 3319.5020746887963, 3345.799252044876, 3372.443546814776, 3411.283630437608, 5535.086710692741, 5617.83146505605, 5629.971850140754, 5632.887342253152, 5706.31524217094, 5637.915431268524, 5672.404397538261, 5673.971630141844, 5683.2895888014, 5692.288463942007, 5100.591952643789, 5150.2532132840715, 5151.330327057477, 5155.7679904404395, 5196.58427325815, 5220.738963051832, 5234.084035293828, 5238.3975842778, 5238.580913526908, 5240.082985477546, 5157.097507936115, 5167.4057483389515, 5192.169252919533, 5202.505761952609, 5223.660462069973, 5081.110805609003, 5239.476052394749, 5253.0544501989625, 5254.949010197975, 5268.525832675077, 5033.245013248005, 5059.367579052619, 5069.315642388267, 5176.275321454999, 5185.455527169668, 5632.549396048306, 5667.971660141714, 5669.461401166883, 5709.714514274311, 5751.338596061465, 6193.225846769154, 6194.628322300631, 6209.410106039919, 6210.068489726532, 6213.03697926822, 6096.542759293026, 6146.477549408306, 6196.4423201911595, 6256.530760192958, 6259.436650701458, 5732.570057245724, 5770.855728606776, 5770.913436298459, 5785.768569257267, 5789.942100578983, 6283.403076707706, 6293.590916590407, 6308.116863639094, 6309.400606942425, 6310.905336419957, 5894.882102358015, 5981.551383646157, 6003.519718422515, 6092.147099406086, 6097.847553811191, 6161.938380616226, 6204.937950620526, 6210.565260431817, 6228.875422491525, 6235.781747638811, 6076.969615151985, 6141.6007959482495, 6165.260168779775, 6182.258129024546, 6188.257409110935, 6022.09668549721, 6066.787662431793, 6086.2087928569, 6179.938200617937, 6203.069539569011, 4887.29134275528, 4899.314096026559, 4903.215485522299, 4909.337239472697, 4916.483769204182, 4402.3396490526675, 4430.778461076998, 4440.911181776346, 4444.400005999149, 4449.377087207794, 5544.16837474374, 5638.126090455986, 5664.20701101846, 5680.204771332017, 5757.971210143923, 6220.595661282002, 6249.406306400884, 6270.2475702915945, 6277.52918531112, 6282.057691346243, 5668.0, 5669.716514174274, 5686.346070201897, 5697.23087383198, 5716.114002329646, 5299.894002120013, 5316.680999140064, 5341.919871201935, 5343.732813359316, 5382.0, 6269.46709529686, 6290.748370065146, 6294.937050629618, 6315.368463153648, 6315.842103947346, 6215.9378406217165, 6216.968915155395, 6221.657808820377, 6222.595531290452, 6235.781747638811, 6226.3151053385, 6235.064740288993, 6248.625082495065, 6297.307296197506, 6307.148534947723, 6133.916185115316, 6142.0, 6143.784967526115, 6153.9845925421805, 6158.137860699416, 6225.501959843292, 6227.595206311576, 6256.812295630956, 6257.311695713377, 6272.560920735505, 5900.675462849413, 5902.173695682522, 5948.078047902582, 6051.667158306335, 6068.241469816273, 5991.88016239664, 5994.910076348945, 6015.4586087252355, 6046.879062418815, 6135.233095863017, 4920.360353154067, 5924.6149000314845, 5937.910931335947, 5939.821805345847, 5947.524198064058, 6032.969835150971, 6052.727627256696, 6052.84867878289, 6078.240219972504, 6085.056816193497, 5752.568557358131, 5790.986577348863, 5791.478766910998, 5856.385079566755, 5892.292924849045], "value": 5663.858756160445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.76745324977753, 493.62237888015676, 505.81386648309723, 522.4566450891074, 526.1187510919211, 30542.930997415096, 33845.66154338459, 39868.60131398689, 41174.79412602933, 43513.56486435135, 42748.78625606876, 43063.923401914886, 43140.35289470653, 43296.0, 43329.133417331635, 8854.424462409956, 9778.559964801583, 9966.601335946554, 9970.700878973643, 9971.252156088287, 12049.397530123502, 12327.876721232808, 12328.630141095782, 12487.438065287039, 12488.438945131858, 12204.69488262796, 12322.383880805968, 12323.876761232408, 12324.260544367367, 12350.567730129447, 12188.451519681592, 12272.815907761302, 12277.877221227764, 12286.569970051005, 12291.877081229162, 12073.45669444877, 12142.150049496538, 12159.392030398445, 12160.939195304056, 12177.807994561064, 40037.59962400368, 40339.394909076385, 40386.39420408696, 40413.79793101045, 40492.595074049175, 3539.50446937429, 3602.3335682898487, 3833.6358045985585, 4014.310412023922, 4153.937690934638, 30579.847100764353, 30912.69087309143, 31638.841805790824, 31865.362692746014, 31898.0, 3222.0494953988627, 3317.369699757063, 3338.0, 3343.21434462901, 3344.866205351783, 352598.0, 354226.0, 355638.0, 357366.9999999974, 362550.9999999974, 57047.42952570504, 57688.84622307531, 57937.4206257932, 58168.709156453944, 58280.83438331295, 52040.739796300775, 59001.114983275285, 59982.0, 60221.0, 60231.397686023454, 16036.599085022734, 16212.75680864788, 16225.675486490203, 16229.188540572924, 16272.837271627131, 11482.0, 11825.763484730258, 12513.061520386007, 12828.166169198972, 12895.548655797189, 12892.871071289166, 15006.849931500763, 15701.607459813367, 15730.528084157493, 15974.281157347947, 5844.649321040752, 6607.273199948002, 6654.467642588581, 6683.7660681875905, 6691.631960242136, 847.6016272351985, 847.7838151271386, 900.5542256583034, 927.5501381829943, 934.8784657994418, 23045.287929510207, 29776.219521318297, 29940.550891736206, 30009.849950750107, 30041.699583004327, 1050.1021626509225, 1061.8991195836538, 1064.9520771565299, 1076.4294923690456, 1078.2613909472036, 15380.538583842503, 15415.920885537904, 15486.303116359792, 15489.922550387402, 15707.371705131894, 616575.3369865156, 618361.0, 619747.0, 627610.0, 628727.0, 56767.71616141893, 56790.432095678516, 56903.43096569064, 56922.43077569254, 56937.59092886169, 1346.6094832498661, 1372.025861638228, 1372.2452651042086, 1374.5944946240884, 1376.5388594820647, 28774.0, 29612.555811663275, 29717.257068573457, 30228.84885575558, 30380.0, 48624.75687621539, 49175.50824491852, 49230.75384623197, 49389.75305123451, 49409.0, 567722.0, 569829.0, 569852.0, 571691.0, 572217.0, 29704.851475742482, 39090.15245528279, 39567.0, 39735.0, 40221.17898799609, 35970.4604430939, 45382.31926521039, 45769.0, 45803.0, 46994.53005469902, 38215.426768597936, 38488.10590226005, 38935.0, 39209.803950980066, 39958.801235962965, 37884.43173352347, 38285.0, 38559.30942104569, 38829.0, 39910.0, 37462.0, 37905.4314185293, 38291.234175316895, 38398.35215654509, 38407.84776456711, 31787.0, 33840.15399615029, 34032.829835850665, 34068.318633626695, 34509.0, 19823.0, 24704.7647617622, 25391.0, 25635.0, 25842.61236081498], "value": 20142.909176411296, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.13356478710244, 376.6647683561629, 388.64439038279966, 401.5542747550217, 408.43023981545747, 19104.522386940316, 19906.303279385225, 19996.800031999675, 20235.291764788253, 20245.39263822084, 18964.336248231313, 19194.616107677837, 19702.11340489678, 20019.799802001977, 20270.0, 5281.603879709018, 5795.768169273224, 5892.852678683024, 5983.0, 6021.337652858182, 5927.407259274081, 6007.128966299884, 6013.609115407507, 6014.428629280212, 6018.8796224075495, 5708.628939118965, 5796.927807980518, 5895.616784908989, 5904.763809447617, 5905.793297234598, 5583.274174357348, 5841.0, 5871.765129394819, 5878.647281163144, 5882.323532793741, 5024.070546948826, 5067.5185857343495, 5088.745562721849, 5103.030424219386, 5110.080185566597, 25014.874925625438, 25083.37291567707, 25090.24729258107, 25133.748662513506, 25378.70076890011, 2247.9662805057937, 2448.681671382709, 2457.127719659513, 2593.5850263957677, 2839.5456726923594, 9685.806283874354, 10076.647317343857, 10348.430836304, 14815.777763333559, 16024.038557686576, 1811.1306572845108, 1829.634073185355, 1833.5507800588941, 1850.1581780289937, 1890.750066784737, 114453.99999999917, 114567.4271628645, 118903.0, 119415.0, 121141.0, 10002.849672287635, 10540.310891188023, 11250.324980501196, 11312.547498100148, 11395.037290304415, 14367.497137600134, 14408.13551186932, 14414.42342306317, 14427.422903083969, 14447.277636118151, 7403.9264306676305, 8018.238267364591, 8186.549739764251, 8503.74488765338, 8569.700060497977, 8419.158084191531, 8428.873566896502, 8441.535715535581, 8596.957015214884, 8621.353398495137, 4422.049259409242, 4444.777761111932, 4446.177457170434, 4471.485779135376, 4521.3896124023495, 1776.3338747969512, 1884.5477085499656, 1890.9788714094234, 1903.5526651236949, 1932.8936908470168, 847.737201467547, 938.5166639180827, 960.6301573894101, 964.0744884910564, 984.6652138273022, 19141.861701863305, 20471.692924606145, 20482.590348192953, 20548.075336609887, 20860.165593376096, 760.9771706848803, 770.603139383218, 797.9202079791975, 937.1378331934636, 947.4220725357526, 10495.265950278716, 10802.135829133651, 10810.516143419945, 10876.603131687598, 10938.289011214245, 528519.0, 535810.0, 556116.0, 556916.0, 560208.0, 72170.0, 74345.88481172787, 75172.62413687896, 97184.0, 126632.99999999815, 934.4113208678625, 946.9431834090115, 949.5774380400655, 968.9757756056155, 969.9806003879883, 13414.956355279288, 15342.922659995902, 16652.0, 17112.058836764096, 17163.399281025104, 54686.45313546814, 55044.44955550393, 55737.72131139319, 56151.43848561626, 56509.15236271377, 59085.70457147687, 75263.62368188125, 75295.0, 76429.0, 79272.0, 6957.373836354753, 7236.167840698387, 7753.844923101619, 7873.448858580074, 8088.757337279891, 7581.886271706039, 12420.875791241971, 12802.743945121232, 13247.536336228572, 13430.328483575977, 11123.499442524944, 11460.083193344679, 11618.477168527268, 11644.592439264585, 11689.181757276909, 11159.27464714791, 11204.215704900578, 11493.712657183638, 11670.766584668429, 11705.765884682089, 11128.276662016942, 11401.828972565252, 11812.940935295612, 11844.052475801744, 11930.343831089027, 7936.404769642185, 7940.28537431634, 8138.145494722969, 8290.046644635944, 8411.663533458837, 8341.83316333682, 10564.735881603021, 11304.717382065513, 11551.133664975328, 11560.13299002595], "value": 9345.134898831177, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.3397934043319, 1437.9065360751551, 1465.069680752722, 1490.1655073159031, 1889.5465088378785, 7582.620868956551, 8331.000279966405, 8686.739397818068, 8704.347173961962, 8779.780505487366, 8567.35744819138, 8648.91351086489, 8680.696175633853, 8691.130886911322, 8703.608337624806, 2370.0519792083132, 2399.9040038398443, 2402.015173778751, 2403.495265994143, 2405.0860672944345, 2343.5781559319316, 2368.3368656776042, 2389.3429306940557, 2390.569697454466, 2393.3298676370646, 2361.445060410802, 2376.940576485584, 2388.092524840567, 2395.3891757601787, 2396.820238482112, 2339.4034521197154, 2365.7161140663143, 2371.5256948610345, 2373.4659701567184, 2374.9168779092647, 2305.8501197422115, 2336.2874323331375, 2337.614293641547, 2342.61346877765, 2345.3784747042046, 8461.915380846174, 8538.146185381474, 8707.651693932236, 8717.259032982203, 8717.389782715212, 5159.277701121846, 5346.893062138735, 5351.197320401932, 5352.411234764173, 5356.062689029443, 3568.7858728476376, 3573.6247693992277, 3630.8366123524506, 3642.271545690846, 3644.5079914211506, 1070.5557193764578, 1078.6278733836814, 1080.330195278932, 1106.2145876427746, 1108.706192858885, 11411.714707132305, 11423.94288028563, 11489.999999999916, 11513.42432878361, 11574.942125289404, 3681.5950245472977, 4318.805653745589, 4409.250427427345, 4545.2954792007295, 4593.402857628487, 8873.423227490193, 8873.73378798637, 8892.110788921056, 8901.020887702347, 8902.643894244158, 5174.301469301671, 5177.8187763428095, 5183.844484665466, 5185.818496352685, 5186.507281808223, 4353.281708518059, 4374.518802931675, 4538.455385353713, 4850.6362022847725, 4860.1737704590305, 3651.397519409321, 3679.4848721179055, 3759.3797023491366, 3795.0891785971166, 3805.162864169878, 1000.3247807729754, 1130.2879186112696, 1149.6661776593528, 1168.5793114478781, 1177.152450235846, 761.6800943603699, 854.3165467625874, 898.3935843305743, 900.7297810656777, 942.9858552121724, 5240.816571419982, 5573.203867977259, 5758.992176369203, 5853.385394533512, 5952.136940143655, 978.7944531648291, 996.6113215845681, 1007.09361574582, 1017.6438246613626, 1043.9217058720474, 3713.405855063177, 3759.323321802102, 3771.585125636178, 3888.0862997195636, 3951.05174758056, 10285.588576456857, 10495.947520262349, 10603.787924241473, 10608.893911060944, 10627.9903409176, 10553.736156595993, 10566.471676416148, 10574.577016919391, 10580.894191058145, 10626.309289896131, 7594.772156835304, 7892.368610511146, 7974.242446967529, 8162.795930101676, 8165.755027349189, 5833.737481813413, 5860.472557469848, 5869.090291004731, 5889.204957330706, 6008.819735407945, 9239.76900577491, 9555.61777528891, 9683.515824208902, 9930.453825039693, 10022.3485473444, 9825.852612210965, 10035.799284014425, 10040.748981275234, 10178.847317290098, 10181.74545636365, 6823.931760682528, 7083.1854336749875, 7387.0, 7434.591097489473, 7533.096028476618, 6876.656167191721, 8319.667213311399, 8328.08391076969, 8328.875066874123, 8353.95823020881, 7250.492465527363, 7351.632418379167, 7376.7418140364825, 7451.217622149813, 7499.775006749806, 6895.310468953065, 6946.0, 7249.42004639617, 7269.164046134762, 7284.089488813898, 6798.966005169942, 6854.383105520527, 6925.065116209449, 6929.272426395156, 6932.514723969273, 4298.957010429981, 4900.754962251945, 4930.087933732271, 4933.457319694759, 4944.085344211332, 4903.31353610502, 4943.728094954668, 4986.626003049858, 5048.469910659474, 5070.0], "value": 4498.569942412524, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.4036986581636, 305.20189703924245, 309.16371215861096, 314.2207325831937, 317.84425631440604, 4356.78216089195, 5201.6098792590565, 5231.110711179099, 5325.121354976434, 5330.706811125391, 4138.937915931256, 4730.499430421489, 5054.671446355985, 5221.5822734181265, 5337.786488540453, 3155.365269999542, 3425.8629654813903, 3649.6350364963555, 3683.1712864605515, 4042.4936539345645, 3988.3419235826054, 3997.9999999999854, 3999.7800120993334, 4022.557518672944, 4029.53660329063, 3289.125198636778, 3312.7846689965077, 3621.963780362189, 3782.9810850945846, 3959.366501359783, 3926.2343842950636, 3942.9605703943025, 3964.8414063437426, 3970.4838371011583, 3978.622030907059, 2879.294572829649, 3179.666135055832, 3278.7868788528667, 3450.0339904826687, 3469.271452994874, 4654.25531914892, 4806.615470762331, 4954.752262386866, 5121.897562048775, 5246.265522826808, 4543.341215523764, 4556.612687921531, 4557.817687292538, 4587.151376995266, 4601.401817763703, 5441.265429167091, 5459.808906688247, 5483.890322193533, 5520.337559492847, 5551.056320425538, 3849.3840985442475, 3863.3625451800417, 3869.7678139311734, 3875.767453952772, 3875.8255878485534, 5198.792048318062, 5454.61817672763, 5717.313922329264, 5728.481952282648, 5768.211854325173, 3877.302085624615, 3881.825317860703, 3882.2235552889533, 3891.3579259422445, 3893.7274390792372, 3950.696270230817, 3969.8610548631236, 3970.8014599269923, 3985.3224951757693, 3988.743545783069, 3884.9291711688898, 4363.2364336241035, 4493.573110554492, 4520.728756274634, 4570.154521413549, 4549.795259213343, 5460.31746031746, 5477.3427188737605, 5480.780768769284, 5485.561155107582, 1211.2913945341897, 1319.8284223050941, 1344.2069179184327, 1369.657585603599, 1376.1192836584594, 766.5592284436472, 826.5949684654476, 864.5590748718199, 896.0, 923.6813299411692, 4322.438083049184, 4474.970756725971, 4765.285207218945, 5113.233015047773, 5125.692458452505, 5592.685718856065, 5623.971880140573, 5637.379888212294, 5710.942890571124, 5739.598228124075, 4704.506026867198, 5058.215976523645, 5138.691678499302, 5150.922736158961, 5198.792048318101, 2594.805389595788, 2797.5523916173315, 2819.2951762059483, 2904.956425653617, 2967.762578993675, 4848.490908454632, 5162.870928226749, 5358.4373640767935, 5375.279910428703, 5923.881522369528, 4469.4636643602325, 5691.857703557444, 5790.191756997852, 5829.571754920072, 5847.9415205848245, 5862.091375836752, 5866.2373891393845, 5874.089516125007, 5964.403559644001, 6041.879162416727, 4981.953789704058, 5302.734863256745, 5363.973180134074, 5648.689322087161, 5673.517750991212, 4820.493848145894, 5101.933716844684, 5282.061272710029, 5588.41321661236, 5603.579731520071, 4636.374089498009, 4672.322513235494, 5465.617406781487, 5897.941020589739, 5958.55699163341, 3570.357335679603, 3690.760100593453, 3735.96264037367, 3744.1544426235714, 3745.400735882246, 5646.160610784706, 5691.7438715257085, 5708.172315014217, 5719.342275638355, 5767.68277744728, 5306.495882891119, 5492.3409190895745, 5542.2795036644975, 5552.639078459887, 5564.221009058654, 5182.585393168462, 5422.376426710979, 5476.507114359568, 5537.446255374431, 5628.85927851807, 4708.208903049337, 4876.683015603974, 5094.8216812412875, 5127.282180494808, 5154.355705536808, 5365.490278423544, 5442.510174084351, 5511.319047690493, 5517.337919449692, 5589.413111623384, 5384.273123128484, 5386.919196212139, 5397.730113494231, 5431.511163995259, 5462.262594549685], "value": 4012.6886990941753, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.9427998620117, 773.369703691491, 792.9533016418327, 802.1216767639435, 817.8650522663761, 14044.789328160074, 14528.491502797404, 15086.773698394532, 15866.682666346665, 16226.675466490664, 14407.207603581825, 14716.411343546246, 14736.778948315758, 14738.189399583018, 15167.54497365078, 692.5083190934417, 851.650823162505, 867.5835598912525, 868.448535180162, 868.6264906090379, 718.131061415685, 856.4261944497201, 884.5002573545926, 897.3270047464401, 899.9145081217274, 938.0900526489295, 939.6570251858091, 941.0260380506178, 941.1435593609816, 941.4304345870752, 721.2246834652717, 934.887813462382, 937.1893312284825, 940.7365937537467, 941.2281928818371, 674.1606699658956, 697.4873468001015, 698.0123125777019, 699.4509310191526, 700.7932659865352, 15019.849801501954, 15057.774133388008, 15074.397024119022, 15088.321025553767, 15100.093994360373, 13817.585472435843, 13970.790438143436, 14102.435902563886, 14205.218712970782, 14515.346809393497, 10482.528286227138, 12593.559225427158, 12780.808287875689, 12900.0, 13192.73614527704, 4518.615917647004, 4535.7732113394195, 4555.407796986404, 4741.0, 4748.19280722278, 93586.0, 118672.0, 121168.39415802865, 121382.39308803488, 122104.0, 10962.0, 11022.906556427546, 11202.159838012181, 11244.0, 11315.377654228932, 22031.559368812534, 22034.88982555109, 22036.88981555114, 22073.779262207172, 22077.668834967488, 7709.691612335444, 7973.362131029504, 8047.8330642057745, 8121.837563248702, 8123.0, 4313.137372525444, 4506.098780243964, 4557.635389168859, 4570.88572785683, 4759.333693282943, 3393.728501719906, 3526.982365088158, 3559.1635965548076, 3561.9109522261633, 3570.6072332043454, 615.0, 621.9315875253718, 624.7126321891981, 634.1565717595587, 642.595165046018, 573.1202604002835, 580.1587697838067, 619.3527763487077, 627.4008322052429, 628.1770880147022, 8687.52218627969, 8807.559622019002, 8996.55017249148, 9022.14289642483, 9118.908810911938, 604.1300527240847, 610.4292486525113, 615.5106690181317, 635.6281575278422, 636.9904451433232, 4273.722208056529, 4289.506706728704, 4291.570842915746, 4348.304271316574, 4398.296272596369, 137528.0, 167484.16257918876, 167703.39114392814, 171770.0, 171843.0, 89821.0, 91000.54499727458, 97016.51491742497, 136166.95826043488, 141894.2905285467, 95496.52251738697, 96971.99999999859, 97767.0, 100817.49591251997, 110658.44670776595, 8847.20375166238, 9366.297527685314, 9721.756956076155, 9903.752406189902, 10007.449590272314, 41448.792756036026, 59516.0, 60002.699986499785, 62137.37862621316, 62606.06090908549, 93886.0, 97863.51068244613, 98075.52886706563, 98774.5061274689, 100459.49770251101, 12829.294388808705, 13037.413316400594, 13132.277724725232, 13541.79687304711, 13617.86382136166, 14833.851661483679, 14938.925305373403, 14944.551663450115, 15080.849191508383, 15586.766198506804, 13808.378622961789, 13960.232187229394, 14080.647983800487, 14255.358508867334, 14272.873098564369, 13614.727705445637, 14016.439342426189, 14129.222892740998, 14268.643283917985, 14565.781513277097, 13281.734365312834, 13885.236312002937, 14006.719865602834, 14105.09071955116, 14254.714905702036, 11019.889801101886, 11187.049100826196, 11246.325220486797, 11317.094632429551, 11335.03652189573, 9854.0, 9862.260330475387, 11537.192396532162, 11565.479553419947, 11587.304761714324], "value": 7881.369965700451, "unit": "score"}], "name": "dromaeo_css", "value": 7302.9209729226795, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 114.0, 112.0, 111.0, 110.0, 111.0, 114.0, 110.0, 121.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 109.0, 132.0, 160.0, 109.0, 162.0, 150.0, 101.0, 199.0, 138.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 182.0, 180.0, 182.0, 201.0, 184.0, 178.0, 182.0, 182.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 84.0, 75.0, 155.0, 58.0, 57.0, 57.0, 61.0, 143.0, 57.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 132.0, 70.0, 70.0, 91.0, 121.0, 68.0, 70.0, 118.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 83.0, 85.0, 83.0, 85.0, 83.0, 87.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 93.0, 92.0, 93.0, 93.0, 93.0, 93.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 101.0, 117.0, 134.0, 115.0, 85.0, 85.0, 84.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 56.0, 52.0, 51.0, 52.0, 50.0, 53.0, 51.0, 52.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 73.0, 77.0, 74.0, 74.0, 105.0, 71.0, 72.0, 71.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 85.0, 85.0, 81.0, 86.0, 97.0, 105.0, 95.0, 84.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 142.0, 150.0, 144.0, 148.0, 139.0, 139.0, 135.0, 230.0, 151.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 167.0, 167.0, 161.0, 167.0, 167.0, 167.0, 170.0, 231.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 82.0, 85.0, 67.0, 68.0, 68.0, 66.0, 67.0, 67.0, 66.0], "value": 70.0, "unit": "score"}], "name": "kraken", "value": 1496.0, "alertThreshold": 2.0}]} 19:10:41 INFO - Return code: 0 19:10:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:10:42 INFO - # TBPL SUCCESS # 19:10:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:10:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:10:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:10:42 INFO - 2016-09-01 19:10:42.081 screenresolution[1670:9840] starting screenresolution argv=screenresolution get 19:10:42 INFO - 2016-09-01 19:10:42.084 screenresolution[1670:9840] Display 0: 1600x1200x32@60 19:10:42 INFO - 2016-09-01 19:10:42.090 screenresolution[1671:9843] starting screenresolution argv=screenresolution list 19:10:42 INFO - Available Modes on Display 0 19:10:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:10:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:10:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:10:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:10:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:10:42 INFO - Intel Iris: 19:10:42 INFO - Chipset Model: Intel Iris 19:10:42 INFO - Type: GPU 19:10:42 INFO - Bus: Built-In 19:10:42 INFO - VRAM (Dynamic, Max): 1536 MB 19:10:42 INFO - Vendor: Intel (0x8086) 19:10:42 INFO - Device ID: 0x0a2e 19:10:42 INFO - Revision ID: 0x0009 19:10:42 INFO - Displays: 19:10:42 INFO - Display: 19:10:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:10:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:10:42 INFO - Display Serial Number: 1600x1200 60 19:10:42 INFO - Main Display: Yes 19:10:42 INFO - Mirror: Off 19:10:42 INFO - Online: Yes 19:10:42 INFO - Rotation: Supported 19:10:42 INFO - Return code: 0 19:10:42 INFO - Running post-action listener: _resource_record_post_action 19:10:42 INFO - [mozharness: 2016-09-02 02:10:42.334922Z] Finished run-tests step (success) 19:10:42 INFO - Running post-run listener: _resource_record_post_run 19:10:42 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 38216704; Write bytes: 704688128; Read time: 372; Write time: 1964 19:10:42 INFO - TinderboxPrint: CPU usage
53.5% 19:10:42 INFO - TinderboxPrint: I/O read bytes / time
38,216,704 / 372 19:10:42 INFO - TinderboxPrint: I/O write bytes / time
704,688,128 / 1,964 19:10:42 INFO - TinderboxPrint: CPU idle
2,256.5 (46.5%) 19:10:42 INFO - TinderboxPrint: CPU system
969.4 (20.0%) 19:10:42 INFO - TinderboxPrint: CPU user
1,627.8 (33.5%) 19:10:42 INFO - TinderboxPrint: Swap in / out
444,121,088 / 0 19:10:42 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 403057152; Write bytes: 397604352; Read time: 29966; Write time: 702 19:10:42 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 36086784; Write bytes: 294341120; Read time: 347; Write time: 1227 19:10:42 INFO - Running post-run listener: _upload_blobber_files 19:10:42 INFO - Blob upload gear active. 19:10:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:10:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:10:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:10:43 INFO - (blobuploader) - INFO - Open directory for files ... 19:10:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:10:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:10:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:10:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:10:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:10:44 INFO - (blobuploader) - INFO - Done attempting. 19:10:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:10:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:10:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:10:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:10:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:10:45 INFO - (blobuploader) - INFO - Done attempting. 19:10:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:10:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:10:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:10:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:10:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:10:47 INFO - (blobuploader) - INFO - Done attempting. 19:10:47 INFO - (blobuploader) - INFO - Iteration through files over. 19:10:47 INFO - Return code: 0 19:10:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:10:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:10:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cbc90cc46e25224c9fa0a752a59ab74711352229951968b4a43ce418632bdf9885f49b531865157b7f229acde150beeade500dd0452fb99c71f8ff5b23d8452", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/450a37e37e3478a1f8f76dc89893517fc5adac0c93b0aed115b47f4228921c0d2d75f5373ebe3f0b52db641a5dae706d8ab5e904ad526460a2b6b6aecd012567", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d60e555debb8c7e049d3bd30b742bbf04f2799118570f38236a20895fd640728411d3be1bfd5cefd5cfdd6c79f9914692e0075f347369ef0e83559e81271b59"} 19:10:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:10:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:10:47 INFO - Contents: 19:10:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cbc90cc46e25224c9fa0a752a59ab74711352229951968b4a43ce418632bdf9885f49b531865157b7f229acde150beeade500dd0452fb99c71f8ff5b23d8452", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/450a37e37e3478a1f8f76dc89893517fc5adac0c93b0aed115b47f4228921c0d2d75f5373ebe3f0b52db641a5dae706d8ab5e904ad526460a2b6b6aecd012567", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d60e555debb8c7e049d3bd30b742bbf04f2799118570f38236a20895fd640728411d3be1bfd5cefd5cfdd6c79f9914692e0075f347369ef0e83559e81271b59"} 19:10:47 INFO - Running post-run listener: copy_logs_to_upload_dir 19:10:47 INFO - Copying logs to upload dir... 19:10:47 INFO - mkdir: /builds/slave/test/build/upload/logs 19:10:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.083533 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-01 19:10:47.959470) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 19:10:47.963143) ========= 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.67SDdTS0O5/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.xqqZBREk3t/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cbc90cc46e25224c9fa0a752a59ab74711352229951968b4a43ce418632bdf9885f49b531865157b7f229acde150beeade500dd0452fb99c71f8ff5b23d8452", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/450a37e37e3478a1f8f76dc89893517fc5adac0c93b0aed115b47f4228921c0d2d75f5373ebe3f0b52db641a5dae706d8ab5e904ad526460a2b6b6aecd012567", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d60e555debb8c7e049d3bd30b742bbf04f2799118570f38236a20895fd640728411d3be1bfd5cefd5cfdd6c79f9914692e0075f347369ef0e83559e81271b59"} build_url:https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010600 build_url: 'https://queue.taskcluster.net/v1/task/JVYKuWobQciKb7BKQJz1gA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cbc90cc46e25224c9fa0a752a59ab74711352229951968b4a43ce418632bdf9885f49b531865157b7f229acde150beeade500dd0452fb99c71f8ff5b23d8452", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/450a37e37e3478a1f8f76dc89893517fc5adac0c93b0aed115b47f4228921c0d2d75f5373ebe3f0b52db641a5dae706d8ab5e904ad526460a2b6b6aecd012567", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d60e555debb8c7e049d3bd30b742bbf04f2799118570f38236a20895fd640728411d3be1bfd5cefd5cfdd6c79f9914692e0075f347369ef0e83559e81271b59"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 19:10:47.998955) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:10:47.999289) ========= 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.67SDdTS0O5/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.xqqZBREk3t/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.005177 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:10:48.048886) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 19:10:48.049418) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 19:10:48.049983) ========= ========= Total master_lag: 0.15 =========