builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0318 starttime: 1472857063.86 results: success (0) buildid: 20160902133750 builduid: 9a1789dd9d844ceba142ec1b05528fd9 revision: 969397f22187cfd25fca153f0d1624dde9490589 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 15:57:43.863940) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 15:57:43.864488) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 15:57:43.864789) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 15:57:43.888983) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 15:57:43.889392) ========= 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.Get7yemWuN/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.9HcnmjwfJN/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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-02 15:57:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.56M=0.001s 2016-09-02 15:57:43 (8.56 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097226 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 15:57:44.009102) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 15:57:44.009443) ========= 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.Get7yemWuN/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.9HcnmjwfJN/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.020807 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 15:57:44.058248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-02 15:57:44.058614) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 969397f22187cfd25fca153f0d1624dde9490589 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 969397f22187cfd25fca153f0d1624dde9490589 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Get7yemWuN/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.9HcnmjwfJN/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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-02 15:57:44,141 truncating revision to first 12 chars 2016-09-02 15:57:44,141 Setting DEBUG logging. 2016-09-02 15:57:44,142 attempt 1/10 2016-09-02 15:57:44,142 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/969397f22187?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-02 15:57:45,212 unpacking tar archive at: fx-team-969397f22187/testing/mozharness/ program finished with exit code 0 elapsedTime=1.337287 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-02 15:57:45.418102) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 15:57:45.418435) ========= script_repo_revision: 969397f22187cfd25fca153f0d1624dde9490589 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 15:57:45.418815) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 15:57:45.419082) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 15:57:45.437397) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-02 15:57:45.437665) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Get7yemWuN/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.9HcnmjwfJN/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 15:57:45 INFO - MultiFileLogger online at 20160902 15:57:45 in /builds/slave/test 15:57:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:57:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:57:45 INFO - {'append_to_log': False, 15:57:45 INFO - 'base_work_dir': '/builds/slave/test', 15:57:45 INFO - 'blob_upload_branch': 'Fx-Team', 15:57:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:57:45 INFO - 'branch': 'Fx-Team', 15:57:45 INFO - 'buildbot_json_path': 'buildprops.json', 15:57:45 INFO - 'config_files': ('talos/mac_config.py',), 15:57:45 INFO - 'default_actions': ('clobber', 15:57:45 INFO - 'read-buildbot-config', 15:57:45 INFO - 'download-and-extract', 15:57:45 INFO - 'populate-webroot', 15:57:45 INFO - 'create-virtualenv', 15:57:45 INFO - 'install', 15:57:45 INFO - 'run-tests'), 15:57:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:57:45 INFO - 'download_minidump_stackwalk': True, 15:57:45 INFO - 'download_symbols': 'ondemand', 15:57:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:57:45 INFO - 'tooltool.py': '/tools/tooltool.py', 15:57:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:57:45 INFO - '/tools/misc-python/virtualenv.py')}, 15:57:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:57:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:57:45 INFO - 'installer_path': 'installer.exe', 15:57:45 INFO - 'log_level': 'info', 15:57:45 INFO - 'log_name': 'talos', 15:57:45 INFO - 'log_to_console': True, 15:57:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:57:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:57:45 INFO - 'opt_config_files': (), 15:57:45 INFO - 'pip_index': False, 15:57:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:57:45 INFO - 'cmd': ('bash', 15:57:45 INFO - '-c', 15:57:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:57:45 INFO - 'enabled': True, 15:57:45 INFO - 'halt_on_failure': False, 15:57:45 INFO - 'name': 'check_screen_resolution'},), 15:57:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:57:45 INFO - 'cmd': ('bash', 15:57:45 INFO - '-c', 15:57:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:57:45 INFO - 'enabled': True, 15:57:45 INFO - 'halt_on_failure': False, 15:57:45 INFO - 'name': 'check_screen_resolution'},), 15:57:45 INFO - 'run_cmd_checks_enabled': True, 15:57:45 INFO - 'sps_profile': False, 15:57:45 INFO - 'sps_profile_interval': 0, 15:57:45 INFO - 'suite': 'dromaeojs', 15:57:45 INFO - 'system_bits': '32', 15:57:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:57:45 INFO - 'title': 't-yosemite-r7-0318', 15:57:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:57:45 INFO - 'use_talos_json': True, 15:57:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:57:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:57:45 INFO - 'work_dir': 'build'} 15:57:45 INFO - [mozharness: 2016-09-02 22:57:45.629100Z] Running clobber step. 15:57:45 INFO - Running pre-action listener: _resource_record_pre_action 15:57:45 INFO - Running main action method: clobber 15:57:45 INFO - rmtree: /builds/slave/test/build 15:57:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:57:47 INFO - Running post-action listener: _resource_record_post_action 15:57:47 INFO - [mozharness: 2016-09-02 22:57:47.115324Z] Finished clobber step (success) 15:57:47 INFO - [mozharness: 2016-09-02 22:57:47.115453Z] Running read-buildbot-config step. 15:57:47 INFO - Running pre-action listener: _resource_record_pre_action 15:57:47 INFO - Running main action method: read_buildbot_config 15:57:47 INFO - Using buildbot properties: 15:57:47 INFO - { 15:57:47 INFO - "project": "", 15:57:47 INFO - "product": "firefox", 15:57:47 INFO - "script_repo_revision": "production", 15:57:47 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:57:47 INFO - "repository": "", 15:57:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:57:47 INFO - "buildid": "20160902133750", 15:57:47 INFO - "pgo_build": "False", 15:57:47 INFO - "basedir": "/builds/slave/test", 15:57:47 INFO - "buildnumber": 931, 15:57:47 INFO - "slavename": "t-yosemite-r7-0318", 15:57:47 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:57:47 INFO - "platform": "yosemite_r7", 15:57:47 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:57:47 INFO - "branch": "fx-team", 15:57:47 INFO - "repo_path": "integration/fx-team", 15:57:47 INFO - "revision": "969397f22187cfd25fca153f0d1624dde9490589", 15:57:47 INFO - "stage_platform": "macosx64", 15:57:47 INFO - "builduid": "9a1789dd9d844ceba142ec1b05528fd9", 15:57:47 INFO - "slavebuilddir": "test" 15:57:47 INFO - } 15:57:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:57:47 INFO - Running post-action listener: _resource_record_post_action 15:57:47 INFO - [mozharness: 2016-09-02 22:57:47.118791Z] Finished read-buildbot-config step (success) 15:57:47 INFO - [mozharness: 2016-09-02 22:57:47.118925Z] Running download-and-extract step. 15:57:47 INFO - Running pre-action listener: _resource_record_pre_action 15:57:47 INFO - Running main action method: download_and_extract 15:57:47 INFO - mkdir: /builds/slave/test/build/tests 15:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:47 INFO - trying https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:57:47 INFO - Downloading https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/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 15:57:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/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 15:57:48 INFO - Downloaded 1199 bytes. 15:57:48 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:57:48 INFO - Using the following test package requirements: 15:57:48 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:57:48 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:57:48 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:57:48 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:57:48 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:57:48 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:57:48 INFO - u'jsshell-mac.zip'], 15:57:48 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:57:48 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:57:48 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:57:48 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:57:48 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:57:48 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:57:48 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:57:48 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:57:48 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:57:48 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:57:48 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:57:48 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:57:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:48 INFO - trying https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:57:48 INFO - Downloading https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/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 15:57:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/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 15:57:49 INFO - Downloaded 16399974 bytes. 15:57:49 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:57:56 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 15:57:56 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')]} 15:57:56 INFO - trying https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:57:56 INFO - Downloading https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/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 15:57:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/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 15:57:57 INFO - Downloaded 16399974 bytes. 15:57:57 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:58: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')]} 15:58:03 INFO - trying https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:58:03 INFO - Downloading https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/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 15:58:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/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 15:58:05 INFO - Downloaded 14413998 bytes. 15:58:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 15:58:05 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')]} 15:58:05 INFO - trying https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:58:05 INFO - Downloading https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:58:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/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 15:58:08 INFO - Downloaded 111490088 bytes. 15:58:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:58:08 INFO - mkdir: /builds/slave/test/properties 15:58:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:58:08 INFO - Writing to file /builds/slave/test/properties/build_url 15:58:08 INFO - Contents: 15:58:08 INFO - build_url:https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:58:08 INFO - Running post-action listener: _resource_record_post_action 15:58:08 INFO - Running post-action listener: set_extra_try_arguments 15:58:08 INFO - [mozharness: 2016-09-02 22:58:08.831931Z] Finished download-and-extract step (success) 15:58:08 INFO - [mozharness: 2016-09-02 22:58:08.832076Z] Running populate-webroot step. 15:58:08 INFO - Running pre-action listener: _resource_record_pre_action 15:58:08 INFO - Running main action method: populate_webroot 15:58:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:58:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:58:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:58:08 INFO - '--tppagecycles', 15:58:08 INFO - '7'], 15:58:08 INFO - 'tests': ['tsvgm']}}, 15:58:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:58:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:58:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:58:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:58:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:58:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:58:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:58:08 INFO - 'talos_options': ['--disable-e10s'], 15:58:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:58:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:58:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:58:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:58:08 INFO - 'talos_options': ['--disable-e10s'], 15:58:08 INFO - 'tests': ['damp', 'tps']}, 15:58:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:58:08 INFO - 'tests': ['damp', 'tps']}, 15:58:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:58:08 INFO - 'tests': ['dromaeo_dom']}, 15:58:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:58:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:58:08 INFO - 'tests': ['basic_compositor_video']}, 15:58:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:58:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:58:08 INFO - 'tests': ['a11yr', 15:58:08 INFO - 'ts_paint', 15:58:08 INFO - 'tpaint', 15:58:08 INFO - 'sessionrestore', 15:58:08 INFO - 'sessionrestore_no_auto_restore', 15:58:08 INFO - 'tabpaint']}, 15:58:08 INFO - 'other-e10s': {'tests': ['a11yr', 15:58:08 INFO - 'ts_paint', 15:58:08 INFO - 'tpaint', 15:58:08 INFO - 'sessionrestore', 15:58:08 INFO - 'sessionrestore_no_auto_restore', 15:58:08 INFO - 'tabpaint']}, 15:58:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:58:08 INFO - 'ts_paint', 15:58:08 INFO - 'tpaint', 15:58:08 INFO - 'sessionrestore', 15:58:08 INFO - 'sessionrestore_no_auto_restore', 15:58:08 INFO - 'tabpaint']}, 15:58:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:58:08 INFO - 'ts_paint', 15:58:08 INFO - 'tpaint', 15:58:08 INFO - 'sessionrestore', 15:58:08 INFO - 'sessionrestore_no_auto_restore', 15:58:08 INFO - 'tabpaint']}, 15:58:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:58:08 INFO - 'tests': ['a11yr', 15:58:08 INFO - 'ts_paint', 15:58:08 INFO - 'tpaint', 15:58:08 INFO - 'sessionrestore', 15:58:08 INFO - 'sessionrestore_no_auto_restore', 15:58:08 INFO - 'tabpaint']}, 15:58:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:58:08 INFO - 'tests': ['a11yr', 15:58:08 INFO - 'ts_paint', 15:58:08 INFO - 'tpaint', 15:58:08 INFO - 'sessionrestore', 15:58:08 INFO - 'sessionrestore_no_auto_restore', 15:58:08 INFO - 'tabpaint']}, 15:58:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:58:08 INFO - 'tests': ['tsvgx', 15:58:08 INFO - 'tsvgr_opacity', 15:58:08 INFO - 'tart', 15:58:08 INFO - 'tscrollx', 15:58:08 INFO - 'cart']}, 15:58:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:58:08 INFO - 'tsvgr_opacity', 15:58:08 INFO - 'tart', 15:58:08 INFO - 'tscrollx', 15:58:08 INFO - 'cart']}, 15:58:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:58:08 INFO - 'talos_options': ['--disable-e10s'], 15:58:08 INFO - 'tests': ['tp5o']}, 15:58:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:58:08 INFO - 'tests': ['tp5o']}, 15:58:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:58:08 INFO - 'talos_options': ['--disable-e10s', 15:58:08 INFO - '--xperf_path', 15:58:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:58:08 INFO - 'tests': ['tp5n']}, 15:58:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:58:08 INFO - 'talos_options': ['--xperf_path', 15:58:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:58:08 INFO - 'tests': ['tp5n']}}, 15:58:08 INFO - 'talos.zip': {'path': '', 15:58:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:58:08 INFO - Running post-action listener: _resource_record_post_action 15:58:08 INFO - [mozharness: 2016-09-02 22:58:08.850618Z] Finished populate-webroot step (success) 15:58:08 INFO - [mozharness: 2016-09-02 22:58:08.850749Z] Running create-virtualenv step. 15:58:08 INFO - Running pre-action listener: _resource_record_pre_action 15:58:08 INFO - Running main action method: create_virtualenv 15:58:08 INFO - Creating virtualenv /builds/slave/test/build/venv 15:58:08 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 15:58:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:58:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:58:08 INFO - Using real prefix '/tools/python27' 15:58:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:58:09 INFO - Installing distribute.............................................................................................................................................................................................done. 15:58:12 INFO - Installing pip.................done. 15:58:12 INFO - Return code: 0 15:58:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:58: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')]} 15:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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')]} 15:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101076a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d14a99f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fdad30>, '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 0x100d3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd2b20>, '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.Get7yemWuN/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.9HcnmjwfJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 15:58: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', 'pip>=1.5'] in /builds/slave/test/build 15:58: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 pip>=1.5 15:58:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Get7yemWuN/Render', 15:58:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:12 INFO - 'HOME': '/Users/cltbld', 15:58:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:12 INFO - 'LOGNAME': 'cltbld', 15:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:12 INFO - 'MOZ_NO_REMOTE': '1', 15:58:12 INFO - 'NO_EM_RESTART': '1', 15:58:12 INFO - 'PAGER': '/bin/cat', 15:58:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:12 INFO - 'PWD': '/builds/slave/test', 15:58:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:12 INFO - 'SHELL': '/bin/bash', 15:58:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HcnmjwfJN/Listeners', 15:58:12 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 15:58:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:12 INFO - 'USER': 'cltbld', 15:58:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:12 INFO - 'XPC_FLAGS': '0x0', 15:58:12 INFO - 'XPC_SERVICE_NAME': '0', 15:58:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:12 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 15:58:12 INFO - Cleaning up... 15:58:12 INFO - Return code: 0 15:58:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:58: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')]} 15:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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')]} 15:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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', '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 0x101076a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d14a99f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fdad30>, '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 0x100d3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd2b20>, '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.Get7yemWuN/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.9HcnmjwfJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 15:58: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', 'psutil>=3.1.1'] in /builds/slave/test/build 15:58: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 psutil>=3.1.1 15:58:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Get7yemWuN/Render', 15:58:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:12 INFO - 'HOME': '/Users/cltbld', 15:58:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:12 INFO - 'LOGNAME': 'cltbld', 15:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:12 INFO - 'MOZ_NO_REMOTE': '1', 15:58:12 INFO - 'NO_EM_RESTART': '1', 15:58:12 INFO - 'PAGER': '/bin/cat', 15:58:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:12 INFO - 'PWD': '/builds/slave/test', 15:58:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:12 INFO - 'SHELL': '/bin/bash', 15:58:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HcnmjwfJN/Listeners', 15:58:12 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 15:58:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:12 INFO - 'USER': 'cltbld', 15:58:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:12 INFO - 'XPC_FLAGS': '0x0', 15:58:12 INFO - 'XPC_SERVICE_NAME': '0', 15:58:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:13 INFO - Downloading/unpacking psutil>=3.1.1 15:58: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 15:58: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 15:58: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 15:58: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 15:58: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 15:58: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 15:58:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:58:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:58:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:58:16 INFO - Installing collected packages: psutil 15:58:16 INFO - Running setup.py install for psutil 15:58:16 INFO - building 'psutil._psutil_osx' extension 15:58:16 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 15:58:16 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 15:58:16 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 15:58:16 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 15:58:16 INFO - building 'psutil._psutil_posix' extension 15:58:16 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 15:58:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:58:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:58:16 INFO - ^ 15:58:16 INFO - 1 warning generated. 15:58:16 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 15:58:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:58:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:58:16 INFO - Successfully installed psutil 15:58:16 INFO - Cleaning up... 15:58:16 INFO - Return code: 0 15:58:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:58:16 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')]} 15:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:16 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')]} 15:58:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:16 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 0x101076a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d14a99f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fdad30>, '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 0x100d3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd2b20>, '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.Get7yemWuN/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.9HcnmjwfJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 15:58:16 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 15:58:16 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 15:58:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Get7yemWuN/Render', 15:58:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:16 INFO - 'HOME': '/Users/cltbld', 15:58:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:16 INFO - 'LOGNAME': 'cltbld', 15:58:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:16 INFO - 'MOZ_NO_REMOTE': '1', 15:58:16 INFO - 'NO_EM_RESTART': '1', 15:58:16 INFO - 'PAGER': '/bin/cat', 15:58:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:16 INFO - 'PWD': '/builds/slave/test', 15:58:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:16 INFO - 'SHELL': '/bin/bash', 15:58:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HcnmjwfJN/Listeners', 15:58:16 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 15:58:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:16 INFO - 'USER': 'cltbld', 15:58:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:16 INFO - 'XPC_FLAGS': '0x0', 15:58:16 INFO - 'XPC_SERVICE_NAME': '0', 15:58:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:58:17 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 15:58:17 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 15:58:17 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 15:58:17 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 15:58:17 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 15:58:17 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 15:58:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:58:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:58:19 INFO - Installing collected packages: mozsystemmonitor 15:58:19 INFO - Running setup.py install for mozsystemmonitor 15:58:19 INFO - Successfully installed mozsystemmonitor 15:58:19 INFO - Cleaning up... 15:58:19 INFO - Return code: 0 15:58:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101076a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d14a99f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fdad30>, '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 0x100d3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd2b20>, '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.Get7yemWuN/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.9HcnmjwfJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 15:58:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:58:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Get7yemWuN/Render', 15:58:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:19 INFO - 'HOME': '/Users/cltbld', 15:58:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:19 INFO - 'LOGNAME': 'cltbld', 15:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:19 INFO - 'MOZ_NO_REMOTE': '1', 15:58:19 INFO - 'NO_EM_RESTART': '1', 15:58:19 INFO - 'PAGER': '/bin/cat', 15:58:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:19 INFO - 'PWD': '/builds/slave/test', 15:58:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:19 INFO - 'SHELL': '/bin/bash', 15:58:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HcnmjwfJN/Listeners', 15:58:19 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 15:58:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:19 INFO - 'USER': 'cltbld', 15:58:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:19 INFO - 'XPC_FLAGS': '0x0', 15:58:19 INFO - 'XPC_SERVICE_NAME': '0', 15:58:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:20 INFO - Downloading/unpacking jsonschema==2.5.1 15:58:20 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 15:58:20 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 15:58:20 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 15:58:20 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 15:58:20 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 15:58:20 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 15:58:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:58:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:58:22 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 15:58:22 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 15:58:22 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 15:58:22 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 15:58:22 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 15:58:22 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 15:58:22 INFO - Downloading functools32-3.2.3-2.tar.gz 15:58:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:58:23 INFO - warning: no files found matching '*.txt' 15:58:23 INFO - no previously-included directories found matching 'build' 15:58:23 INFO - no previously-included directories found matching 'dist' 15:58:23 INFO - no previously-included directories found matching '.git*' 15:58:23 INFO - Installing collected packages: jsonschema, functools32 15:58:23 INFO - Running setup.py install for functools32 15:58:23 INFO - warning: no files found matching '*.txt' 15:58:23 INFO - no previously-included directories found matching 'build' 15:58:23 INFO - no previously-included directories found matching 'dist' 15:58:23 INFO - no previously-included directories found matching '.git*' 15:58:23 INFO - Successfully installed jsonschema functools32 15:58:23 INFO - Cleaning up... 15:58:23 INFO - Return code: 0 15:58:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:58:23 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')]} 15:58:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:23 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')]} 15:58:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:23 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 0x101076a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d14a99f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fdad30>, '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 0x100d3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd2b20>, '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.Get7yemWuN/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.9HcnmjwfJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 15:58:23 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 15:58:23 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 15:58:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Get7yemWuN/Render', 15:58:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:23 INFO - 'HOME': '/Users/cltbld', 15:58:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:23 INFO - 'LOGNAME': 'cltbld', 15:58:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:23 INFO - 'MOZ_NO_REMOTE': '1', 15:58:23 INFO - 'NO_EM_RESTART': '1', 15:58:23 INFO - 'PAGER': '/bin/cat', 15:58:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:23 INFO - 'PWD': '/builds/slave/test', 15:58:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:23 INFO - 'SHELL': '/bin/bash', 15:58:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HcnmjwfJN/Listeners', 15:58:23 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 15:58:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:23 INFO - 'USER': 'cltbld', 15:58:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:23 INFO - 'XPC_FLAGS': '0x0', 15:58:23 INFO - 'XPC_SERVICE_NAME': '0', 15:58:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:58:23 INFO - Cleaning up... 15:58:23 INFO - Return code: 0 15:58:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:58:23 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')]} 15:58:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:23 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')]} 15:58:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:23 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 0x101076a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d14a99f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fdad30>, '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 0x100d3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd2b20>, '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.Get7yemWuN/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.9HcnmjwfJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 15:58:23 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 15:58:23 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 15:58:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Get7yemWuN/Render', 15:58:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:23 INFO - 'HOME': '/Users/cltbld', 15:58:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:23 INFO - 'LOGNAME': 'cltbld', 15:58:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:23 INFO - 'MOZ_NO_REMOTE': '1', 15:58:23 INFO - 'NO_EM_RESTART': '1', 15:58:23 INFO - 'PAGER': '/bin/cat', 15:58:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:23 INFO - 'PWD': '/builds/slave/test', 15:58:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:23 INFO - 'SHELL': '/bin/bash', 15:58:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HcnmjwfJN/Listeners', 15:58:23 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 15:58:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:23 INFO - 'USER': 'cltbld', 15:58:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:23 INFO - 'XPC_FLAGS': '0x0', 15:58:23 INFO - 'XPC_SERVICE_NAME': '0', 15:58:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:23 INFO - Downloading/unpacking blobuploader==1.2.4 15:58:23 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 15:58:23 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 15:58:23 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 15:58:23 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 15:58:23 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 15:58:23 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 15:58:26 INFO - Downloading blobuploader-1.2.4.tar.gz 15:58:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:58:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:58:26 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 15:58:26 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 15:58:26 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 15:58:26 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 15:58:26 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 15:58:26 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 15:58:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:58:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:58:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:58:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:58:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:58:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:58:27 INFO - Downloading docopt-0.6.1.tar.gz 15:58:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:58:27 INFO - Installing collected packages: blobuploader, requests, docopt 15:58:27 INFO - Running setup.py install for blobuploader 15:58:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:58:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:58:27 INFO - Running setup.py install for requests 15:58:28 INFO - Running setup.py install for docopt 15:58:28 INFO - Successfully installed blobuploader requests docopt 15:58:28 INFO - Cleaning up... 15:58:28 INFO - Return code: 0 15:58:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:58: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')]} 15:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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')]} 15:58:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:28 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 0x101076a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d14a99f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fdad30>, '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 0x100d3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd2b20>, '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.Get7yemWuN/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.9HcnmjwfJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 15:58:28 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 15:58:28 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 15:58:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Get7yemWuN/Render', 15:58:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:28 INFO - 'HOME': '/Users/cltbld', 15:58:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:28 INFO - 'LOGNAME': 'cltbld', 15:58:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:28 INFO - 'MOZ_NO_REMOTE': '1', 15:58:28 INFO - 'NO_EM_RESTART': '1', 15:58:28 INFO - 'PAGER': '/bin/cat', 15:58:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:28 INFO - 'PWD': '/builds/slave/test', 15:58:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:28 INFO - 'SHELL': '/bin/bash', 15:58:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HcnmjwfJN/Listeners', 15:58:28 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 15:58:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:28 INFO - 'USER': 'cltbld', 15:58:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:28 INFO - 'XPC_FLAGS': '0x0', 15:58:28 INFO - 'XPC_SERVICE_NAME': '0', 15:58:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:58:28 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-6Z3uRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:58:28 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-M41qo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:58:28 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-IZkiY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:58:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:58:28 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-hjPwfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:58:29 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-XL4aIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:58:29 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-O19mCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:58:29 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-c1b7Rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:58:29 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-oDFeXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:58:29 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-dEcfvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:58:29 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-RP1_0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:58:29 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-bekZAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:58:29 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-n5GB4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:58:29 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-wY1LEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:58:29 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-xyBNAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:58:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:58:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-upJMq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:58:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:58:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-QkrpuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:58:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:58:30 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-al262y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:58:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:58:30 INFO - Running setup.py install for manifestparser 15:58:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:58:30 INFO - Running setup.py install for mozcrash 15:58:30 INFO - Running setup.py install for mozdebug 15:58:30 INFO - Running setup.py install for mozdevice 15:58:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:58:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:58:31 INFO - Running setup.py install for mozfile 15:58:31 INFO - Running setup.py install for mozhttpd 15:58:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:58:31 INFO - Running setup.py install for mozinfo 15:58:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:58:31 INFO - Running setup.py install for mozInstall 15:58:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:58:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:58:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:58:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:58:31 INFO - Running setup.py install for mozleak 15:58:31 INFO - Running setup.py install for mozlog 15:58:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:58:31 INFO - Running setup.py install for moznetwork 15:58:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:58:32 INFO - Running setup.py install for mozprocess 15:58:32 INFO - Running setup.py install for mozprofile 15:58:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:58:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:58:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:58:32 INFO - Running setup.py install for mozrunner 15:58:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:58:32 INFO - Running setup.py install for mozscreenshot 15:58:32 INFO - Running setup.py install for moztest 15:58:32 INFO - Running setup.py install for mozversion 15:58:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:58:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:58:32 INFO - Cleaning up... 15:58:33 INFO - Return code: 0 15:58:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:58:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:33 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 0x101076a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d14a99f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fdad30>, '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 0x100d3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd2b20>, '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.Get7yemWuN/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.9HcnmjwfJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 15:58:33 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 15:58:33 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 15:58:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Get7yemWuN/Render', 15:58:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:33 INFO - 'HOME': '/Users/cltbld', 15:58:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:33 INFO - 'LOGNAME': 'cltbld', 15:58:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:33 INFO - 'MOZ_NO_REMOTE': '1', 15:58:33 INFO - 'NO_EM_RESTART': '1', 15:58:33 INFO - 'PAGER': '/bin/cat', 15:58:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:33 INFO - 'PWD': '/builds/slave/test', 15:58:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:33 INFO - 'SHELL': '/bin/bash', 15:58:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HcnmjwfJN/Listeners', 15:58:33 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 15:58:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:33 INFO - 'USER': 'cltbld', 15:58:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:33 INFO - 'XPC_FLAGS': '0x0', 15:58:33 INFO - 'XPC_SERVICE_NAME': '0', 15:58:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:58:33 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-rvOdqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:58:33 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)) 15:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:58:33 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-3avoJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:58:33 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)) 15:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:58:33 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ygIbdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:58:33 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)) 15:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:58:33 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-rHblcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:58:33 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)) 15:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:58:33 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-wXKMom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:58:33 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)) 15:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:58:33 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-S2lTol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:58:33 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)) 15:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:58:33 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-7L5ZRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:58:34 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)) 15:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:58:34 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-XnoEUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:58:34 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)) 15:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:58:34 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-0HXLMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:58:34 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)) 15:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:58:34 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-yxPZkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:58:34 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)) 15:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:58:34 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-MZLkR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:58:34 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)) 15:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:58:34 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-1luBka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:58:34 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)) 15:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:58:34 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-fqnDHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:58:34 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)) 15:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:58:34 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ghPS1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:58:34 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)) 15:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:58:34 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-FKooFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:58:34 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)) 15:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:58:34 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-zjweLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:58:34 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)) 15:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:58:35 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-dTckmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:58:35 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)) 15:58:35 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)) 15:58:35 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)) 15:58:35 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)) 15:58:35 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)) 15:58:35 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)) 15:58:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:58:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:58:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:58:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:58:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:58:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:58:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:58:37 INFO - Downloading blessings-1.6.tar.gz 15:58:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:58:37 INFO - Installing collected packages: blessings 15:58:37 INFO - Running setup.py install for blessings 15:58:37 INFO - Successfully installed blessings 15:58:37 INFO - Cleaning up... 15:58:37 INFO - Return code: 0 15:58:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:58:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:58:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:58:38 INFO - Reading from file tmpfile_stdout 15:58:38 INFO - Current package versions: 15:58:38 INFO - blessings == 1.6 15:58:38 INFO - blobuploader == 1.2.4 15:58:38 INFO - docopt == 0.6.1 15:58:38 INFO - functools32 == 3.2.3-2 15:58:38 INFO - jsonschema == 2.5.1 15:58:38 INFO - manifestparser == 1.1 15:58:38 INFO - mozInstall == 1.12 15:58:38 INFO - mozcrash == 0.17 15:58:38 INFO - mozdebug == 0.1 15:58:38 INFO - mozdevice == 0.48 15:58:38 INFO - mozfile == 1.2 15:58:38 INFO - mozhttpd == 0.7 15:58:38 INFO - mozinfo == 0.9 15:58:38 INFO - mozleak == 0.1 15:58:38 INFO - mozlog == 3.3 15:58:38 INFO - moznetwork == 0.27 15:58:38 INFO - mozprocess == 0.23 15:58:38 INFO - mozprofile == 0.28 15:58:38 INFO - mozrunner == 6.12 15:58:38 INFO - mozscreenshot == 0.1 15:58:38 INFO - mozsystemmonitor == 0.3 15:58:38 INFO - moztest == 0.7 15:58:38 INFO - mozversion == 1.4 15:58:38 INFO - psutil == 3.1.1 15:58:38 INFO - requests == 1.2.3 15:58:38 INFO - wsgiref == 0.1.2 15:58:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:38 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 0x101076a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d14a99f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fdad30>, '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 0x100d3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd2b20>, '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.Get7yemWuN/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.9HcnmjwfJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 15:58:38 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 15:58:38 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 15:58:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Get7yemWuN/Render', 15:58:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:38 INFO - 'HOME': '/Users/cltbld', 15:58:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:38 INFO - 'LOGNAME': 'cltbld', 15:58:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:38 INFO - 'MOZ_NO_REMOTE': '1', 15:58:38 INFO - 'NO_EM_RESTART': '1', 15:58:38 INFO - 'PAGER': '/bin/cat', 15:58:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:38 INFO - 'PWD': '/builds/slave/test', 15:58:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:38 INFO - 'SHELL': '/bin/bash', 15:58:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HcnmjwfJN/Listeners', 15:58:38 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 15:58:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:38 INFO - 'USER': 'cltbld', 15:58:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:38 INFO - 'XPC_FLAGS': '0x0', 15:58:38 INFO - 'XPC_SERVICE_NAME': '0', 15:58:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:38 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)) 15:58:38 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)) 15:58:38 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)) 15:58:38 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)) 15:58:38 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)) 15:58:38 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)) 15:58:38 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)) 15:58:38 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)) 15:58:38 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)) 15:58:38 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)) 15:58:38 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)) 15:58:38 INFO - Cleaning up... 15:58:38 INFO - Return code: 0 15:58:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101076a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d14a99f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fdad30>, '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 0x100d3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd2b20>, '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.Get7yemWuN/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.9HcnmjwfJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 15:58:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:58:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:58:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Get7yemWuN/Render', 15:58:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:38 INFO - 'HOME': '/Users/cltbld', 15:58:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:38 INFO - 'LOGNAME': 'cltbld', 15:58:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:38 INFO - 'MOZ_NO_REMOTE': '1', 15:58:38 INFO - 'NO_EM_RESTART': '1', 15:58:38 INFO - 'PAGER': '/bin/cat', 15:58:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:38 INFO - 'PWD': '/builds/slave/test', 15:58:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:38 INFO - 'SHELL': '/bin/bash', 15:58:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HcnmjwfJN/Listeners', 15:58:38 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 15:58:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:38 INFO - 'USER': 'cltbld', 15:58:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:38 INFO - 'XPC_FLAGS': '0x0', 15:58:38 INFO - 'XPC_SERVICE_NAME': '0', 15:58:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:58:38 INFO - Cleaning up... 15:58:38 INFO - Return code: 0 15:58:38 INFO - Running post-action listener: _resource_record_post_action 15:58:38 INFO - Running post-action listener: _start_resource_monitoring 15:58:38 INFO - Starting resource monitoring. 15:58:38 INFO - [mozharness: 2016-09-02 22:58:38.795209Z] Finished create-virtualenv step (success) 15:58:38 INFO - [mozharness: 2016-09-02 22:58:38.796275Z] Running install step. 15:58:38 INFO - Running pre-action listener: _resource_record_pre_action 15:58:38 INFO - Running main action method: install 15:58:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:58:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:58:39 INFO - Reading from file tmpfile_stdout 15:58:39 INFO - Detecting whether we're running mozinstall >=1.0... 15:58:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:58:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:58:39 INFO - Reading from file tmpfile_stdout 15:58:39 INFO - Output received: 15:58:39 INFO - Usage: mozinstall [options] installer 15:58:39 INFO - Options: 15:58:39 INFO - -h, --help show this help message and exit 15:58:39 INFO - -d DEST, --destination=DEST 15:58:39 INFO - Directory to install application into. [default: 15:58:39 INFO - "/builds/slave/test"] 15:58:39 INFO - --app=APP Application being installed. [default: firefox] 15:58:39 INFO - mkdir: /builds/slave/test/build/application 15:58:39 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'] 15:58:39 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 15:59:13 INFO - Reading from file tmpfile_stdout 15:59:13 INFO - Output received: 15:59:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:59:13 INFO - Running post-action listener: _resource_record_post_action 15:59:13 INFO - [mozharness: 2016-09-02 22:59:13.214183Z] Finished install step (success) 15:59:13 INFO - [mozharness: 2016-09-02 22:59:13.214306Z] Running run-tests step. 15:59:13 INFO - Running pre-action listener: _resource_record_pre_action 15:59:13 INFO - Running main action method: run_tests 15:59:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:59:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:59:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:59:13 INFO - 2016-09-02 15:59:13.229 screenresolution[1932:18391] starting screenresolution argv=screenresolution get 15:59:13 INFO - 2016-09-02 15:59:13.231 screenresolution[1932:18391] Display 0: 1600x1200x32@60 15:59:13 INFO - 2016-09-02 15:59:13.237 screenresolution[1933:18393] starting screenresolution argv=screenresolution list 15:59:13 INFO - Available Modes on Display 0 15:59:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:59:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:59:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:59:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:59:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:59:13 INFO - Intel Iris: 15:59:13 INFO - Chipset Model: Intel Iris 15:59:13 INFO - Type: GPU 15:59:13 INFO - Bus: Built-In 15:59:13 INFO - VRAM (Dynamic, Max): 1536 MB 15:59:13 INFO - Vendor: Intel (0x8086) 15:59:13 INFO - Device ID: 0x0a2e 15:59:13 INFO - Revision ID: 0x0009 15:59:13 INFO - Displays: 15:59:13 INFO - Display: 15:59:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:59:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:59:13 INFO - Display Serial Number: 1600x1200 60 15:59:13 INFO - Main Display: Yes 15:59:13 INFO - Mirror: Off 15:59:13 INFO - Online: Yes 15:59:13 INFO - Rotation: Supported 15:59:13 INFO - Return code: 0 15:59:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:59:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:59:13 INFO - Python 2.7.3 15:59:13 INFO - Return code: 0 15:59:13 INFO - grabbing minidump binary from tooltool 15:59:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:13 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 0x100fdad30>, '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 0x100d3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:59:13 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 15:59:13 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 15:59:13 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 15:59:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:59:13 INFO - Return code: 0 15:59:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:59:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:59:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:59:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:59:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:59:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 15:59:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0318 --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 15:59:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Get7yemWuN/Render', 15:59:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:13 INFO - 'HOME': '/Users/cltbld', 15:59:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:13 INFO - 'LOGNAME': 'cltbld', 15:59:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:59:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:13 INFO - 'MOZ_NO_REMOTE': '1', 15:59:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:59:13 INFO - 'NO_EM_RESTART': '1', 15:59:13 INFO - 'PAGER': '/bin/cat', 15:59:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:13 INFO - 'PWD': '/builds/slave/test', 15:59:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:59:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:13 INFO - 'SHELL': '/bin/bash', 15:59:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HcnmjwfJN/Listeners', 15:59:13 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 15:59:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:13 INFO - 'USER': 'cltbld', 15:59:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:13 INFO - 'XPC_FLAGS': '0x0', 15:59:13 INFO - 'XPC_SERVICE_NAME': '0', 15:59:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 15:59:13 INFO - mozversion application_buildid: 20160902133750 15:59:13 INFO - mozversion application_changeset: 969397f22187cfd25fca153f0d1624dde9490589 15:59:13 INFO - mozversion application_display_name: Nightly 15:59:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:59:13 INFO - mozversion application_name: Firefox 15:59:13 INFO - mozversion application_remotingname: firefox 15:59:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:59:13 INFO - mozversion application_vendor: Mozilla 15:59:13 INFO - mozversion application_version: 51.0a1 15:59:13 INFO - mozversion platform_buildid: 20160902133750 15:59:13 INFO - mozversion platform_changeset: 969397f22187cfd25fca153f0d1624dde9490589 15:59:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:59:13 INFO - mozversion platform_version: 51.0a1 15:59:13 INFO - using testdate: 1472857153 15:59:13 INFO - actual date: 1472857153 15:59:13 INFO - starting webserver on 'localhost:54339' 15:59:13 INFO - SUITE-START | Running 2 tests 15:59:13 INFO - TEST-START | dromaeo_css 15:59:13 INFO - operating with platform_type : mac_ 15:59:13 INFO - Initialising browser for dromaeo_css test... 15:59:13 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmp_Cs4X6/profile http://localhost:54339/getInfo.html) 15:59:16 INFO - PROCESS | 1943 | __metrics Screen width/height:1600/1200 15:59:16 INFO - PROCESS | 1943 | colorDepth:24 15:59:16 INFO - PROCESS | 1943 | Browser inner width/height: 1024/689 15:59:16 INFO - PROCESS | 1943 | __metrics 15:59:16 INFO - PROCESS | 1943 | 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" 15:59:16 INFO - PROCESS | 1943 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:59:17 INFO - TEST-INFO | 1943: exit 0 15:59:17 INFO - Browser initialized. 15:59:17 INFO - Running cycle 1/1 for dromaeo_css test... 15:59:17 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmp_Cs4X6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:59:28 INFO - PROCESS | 1945 | RSS: Main: 233791488 15:59:28 INFO - PROCESS | 1945 | 16:00:20 INFO - PROCESS | 1945 | 1472857220450 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] 16:02:32 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54339/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54339/tests/dromaeo/cssquery-ext.html) 16:02:33 INFO - PROCESS | 1945 | RSS: Main: 350588928 16:02:33 INFO - PROCESS | 1945 | 16:05:36 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54339/tests/dromaeo/cssquery-ext.html (next: http://localhost:54339/tests/dromaeo/cssquery-jquery.html) 16:05:36 INFO - PROCESS | 1945 | RSS: Main: 373063680 16:05:36 INFO - PROCESS | 1945 | 16:08:39 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54339/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54339/tests/dromaeo/cssquery-mootools.html) 16:08:39 INFO - PROCESS | 1945 | RSS: Main: 337842176 16:08:39 INFO - PROCESS | 1945 | 16:11:42 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54339/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54339/tests/dromaeo/cssquery-prototype.html) 16:11:42 INFO - PROCESS | 1945 | RSS: Main: 342745088 16:11:42 INFO - PROCESS | 1945 | 16:14:46 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54339/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54339/tests/dromaeo/cssquery-yui.html) 16:14:46 INFO - PROCESS | 1945 | RSS: Main: 327884800 16:14:46 INFO - PROCESS | 1945 | 16:17:50 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:54339/tests/dromaeo/cssquery-yui.html (next: http://localhost:54339/tests/dromaeo/cssquery-dojo.html) 16:17:50 INFO - PROCESS | 1945 | RSS: Main: 442335232 16:17:50 INFO - PROCESS | 1945 | 16:17:50 INFO - PROCESS | 1945 | __start_tp_report 16:17:50 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 16:17:50 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 16:17:50 INFO - PROCESS | 1945 | |i|pagename|runs| 16:17:50 INFO - PROCESS | 1945 | |0;dojo.html;3289.9506507402384;3295.3574053059656;3302.273499830038;3355.5973283206017;3374.595048594169;5514.834554963358;5580.218769372286;5586.972065139668;5677.602567820254;5698.0883058710615;5582.385937546866;5607.57943154263;5629.71851407429;5634.887302253953;5641.548676105912;5098.796048158069;5134.435212126682;5141.922871156936;5145.716985565792;5174.172132458807;4264.402983582301;5231.816886408977;5234.42421333653;5236.607254455912;5252.36971563411;4350.499692535352;4957.677750946177;5155.072087024353;5235.502627250424;5247.632665713401;4342.283523218697;5252.947470525303;5267.104592219332;5271.525562699337;5280.735963201824;5066.645334826564;5075.923861142086;5130.692158470466;5169.844904652867;5211.478852114758;5553.583481238923;5642.746076426571;5671.6880571568545;5673.659580425187;5712.200291959129;5254.4220135785035;6132.601380910227;6135.355787642323;6164.84587885302;6171.012637977902;5402.459754024605;6049.736967957913;6131;6134.355892631254;6135.141080248769;5769.971150144264;5880.852978675567;5891.381404952418;5907.025340818803;5919.200907877382;6156.251089018208;6193.318734939153;6203.100550420164;6210.347913469111;6216.564840461125;5910.556708246899;6100.267967843887;6112.541559383064;6115.1744514490865;6117.051856962178;6123;6126.969365153235;6147;6158.04550294705;6173.166622505904;6135.11040899076;6138.1099740537375;6144.78493252734;6151;6159.1069294951985;6119.082137679384;6125.9693701531205;6140.785072522441;6191.752329906753;6203.751849925952;4789.473157952622;4862.489438608965;4893.388326459192;4894.045661096052;4919.7540122993705;4337.9132417351475;4381.539938306496;4383.211022016004;4402.84590039347;4446.466424029137;5607.102863541813;5611.579131565149;5635.774569017193;5643.238162847963;5691.4877661010705;6077.3314935357075;6139.355367686418;6159.969200153971;6160.630362178284;6167.074938759132;5719.8570035749435;5781.479666830005;5790.392008839095;5852.970735146297;5858.062709966385;5415.783368665288;5423.484768946943;5429.809956651498;5431.402545719968;5463.69949652765;6272.153259310117;6303.495720342454;6322.841928951813;6325.715342809584;6352;6126.212334160775;6129.756146212572;6141.324454309933;6145.371476558805;6198.907016394667;6313.873722525615;6329.082283069021;6342.492600592034;6345.96827015862;6351.555391122577;6063.878722425438;6114.327423983447;6128.540359473147;6131.601445906002;6137.1101190328045;6064.605800622945;6097.524678803442;6103.084537319438;6121.392004143408;6145.6927153641245;6047.485963693135;6056.333803281724;6072.635641861502;6079.696015199311;6129.2032035835055;6072.635641861326;6075.574709770273;6078.3921607838865;6101.084837274444;6116.3272040076445;5803.071508558611;5916.792912248051;5933.199018132497;5941.881162376815;5962.5528085394635;5960.791372301948;6067.059605761114;6100.702285530011;6101.145839582551;6116.6096904373135;5893.145493903275;5927.110933360031;5943.346231914399;5949.464548190684;5953.434423729739 16:17:50 INFO - PROCESS | 1945 | |1;ext.html;491.9188333924903;500.02495134487754;526.0084740264601;529.0186703664705;534.7647035304468;28479.145625631212;39596.80201598992;40455.79772101135;40573.95695322852;42394.364084538756;41559.58440415603;41918.95202619936;41922.16155676885;41960.37059444103;41971.16057678845;9613.567389467491;9820.656277030306;9822.116009559139;9825;9830.164436022947;9594.992525784797;9684.951575242114;9715.854262186072;9716.174125199366;9725.756856078584;9664;9734.17259532937;9737.707868763948;9743.707688769347;9750.36622619531;9657.372270802376;9694.36986595869;9701.07839755222;9705.61177552897;9718.173955213815;9635.13283804454;9698.127168554864;9713;9740;9741.31810773246;39987.00032499211;40264.194716105805;40392.79803600963;40464.1907161858;40574.39138412926;3032.2419395151214;3232.450483417801;3772.792496412696;4021.477207962975;4098.774567398792;30851.07446776578;30851.845740771376;30867.845660771778;30879.536806947915;30940.845295773375;3137.5607414961923;3233.708966193054;3234.7735658503916;3316.1709572606846;3329.6836800504157;340382;340808.9999999975;341907;342444;349107;55114.72442637761;55648.887022259325;55736.88526229453;55749.442505575236;55896.6025849357;58150.41849581535;58201.70899145477;58223;58280;58282;15307.15810630426;15523.301451434712;15536.067835929654;15540.84459155394;15541.922290388477;12154.027677785756;12188.14682972201;12212.633620991384;12219.816702749466;12228.327441990597;12798.488060477663;15076.095434273759;15089.245537723289;15127.243637818065;15305.00517466361;5801.752623185951;6062.151298818169;6183.96908015466;6238.812835614939;6255.718492667841;715.3168723868692;988.0625706559355;1019.6023550815188;1042.890496497872;1086.093112251279;24188.395290117536;29738.702612973593;29856;29890.40219195604;30159.547606785916;959.7264779537799;962.302330810171;1030.3611760708409;1051.9579216831394;1158.44394690549;14985.32566034531;15194.240287985556;15430.382784688487;15439.919205655497;15906.52280431589;517808;645075;648479;649398;835785;51684.22473662898;52205;52236.21645675318;52297.7385113072;52328.999999999236;1245.1346314311465;1291.8062290656665;1347.9663008424673;1386.0644065255913;1399.7760358342616;29499.410011799213;30787.846060770447;30962.53556196704;31464.68535314709;31711.841440792647;50871.23693144532;53520.464795351545;54612;54653.45346546484;56880.71559642341;562876.2274263557;570521.1473942604;571004;572229;574633;22867.885660572258;24918.750812491642;25163.119290825467;25302.49395012126;25462.23613291604;38108.61891381162;42676.146477070906;45751.542484574726;46229.537704622526;46305;38154;38205.80897095497;38329.23341533209;39240.82277531679;39460.21079578335;38386.61613383942;38748.64379746695;39269.803650981565;39401.80299098486;39611.60388396079;37258.254834903695;37301.81349093237;37552;38130.046748831504;38413.04597787347;29991.950281740035;32891.671083288864;33732.83133584316;34157.829210853786;34630.82684586561;20554;25099.74900250974;25188;25328.74671253264;25698.65917430722 16:17:50 INFO - PROCESS | 1945 | |2;jquery.html;321.5786224886004;352.5997992278763;373.1138545953361;377.6808596735758;403.243917654398;15380.846191538081;17428;20831.895840520814;21305;21416.678749818766;20220.09009594568;21014.789852101476;21041.894790526065;21457.785422145775;22280.88859555704;6828.180618325808;6902.482313826458;7039.788806335792;7103.85792284154;7150.713971441136;4629.444466664005;6949.027136200936;7071.752488662898;7089.1493020837315;7092.929070709279;4601.838935637254;7060.752873649423;7903.209679032108;7994.160613135624;8063.1130575636635;7730.922690773076;8032.437729358947;8069.556174410405;8073.192680731939;8094.514329140212;7142.14294284684;7519.398448124191;7803.619895931739;7805.804854878616;7822.726204582869;24226;24578.508429831305;27846.3038424039;27856.442871142463;28774.424511509653;2268.534950335177;2430.8541487510806;2434.989479366061;2468.655361143141;2524.3941454050896;15710.68578628421;16098.436554720645;16169.353225870951;16449.917750411292;17096.230669619898;1872.7565416495768;1958.4026871804094;2056.4139220322136;2059.4851287178208;2123.4054464749893;97320.0267997318;100185.4990725049;129711;136131.31934340365;136419;19988.700169497468;20342.694859577117;20601.278955236496;21041.368758937257;21216.772238915;33219.50170747441;34972;34999.12502187415;35049.82475087608;35094.47358289628;10423.270371073952;10441.164706823436;10532.052115309505;10670.466476676134;10782.73043173927;8604.655813767504;8631.194861669983;8638.705590718908;8771.692990745294;8784.472931624123;9253.629854805733;9368.063193680577;9490.810183796424;9498.620055197853;9543.236541076698;3572.5534308211472;3665.2852693724467;3671.2840996006057;3675.191457879262;3681.9815900920325;702.5819637315789;800.7677773445752;869.1916517638657;874.8862647855865;888.8755574219614;20042.997850107437;21021;21076.05157767904;21103.788962110182;21120.47198820011;912.5710915869531;921.15714121578;927.8469052606245;966.463612694947;1024.4519182237468;10333.63832265882;10804.24370294072;10880.510377033053;10892.891071089347;10894.945525272324;432096;532904;535692;546379;550133;86626.56686716527;109806;118215;118635;132970.33514832557;814.8673344051773;966.0773960867496;1017.6336518853207;1017.9515099447581;1021.1065317846884;18200.36298729591;19255;19732.802671973095;19754.012299384685;19797.505062373555;46259.84350391267;48369;54527.72736136294;54964.45035549593;55912.720436397554;80870;80909;80947.19052809397;81333.18666813256;82334.76497852657;7860.882086768817;8096.676132954852;8097.716579919675;8215.917840821516;8358.414910956419;10775.461226938778;11140.832887506533;12019.699507512381;12893.613191604267;13127.015473839308;9684.612615495302;10395.648565686715;11519.424028798694;11548;11587.826182607436;9481.66814161501;10304.948475257575;10560;10950.178736594946;11349.54601815918;11321.943390282995;11447.484863181344;11786.174967752175;11899.286042837457;11908.880911190778;7966;8135.593220339078;8236.423450358418;8271.13153118938;8422.789430264293;10085.546150423103;10533.631322903662;11351.205415620827;11378.43107844588;11410.429478525875 16:17:50 INFO - PROCESS | 1945 | |3;mootools.html;1387.8612138786116;1555.1913005237273;1570.827209007009;1790.8746387752853;1952.5606738483834;7913.762587122381;8703.173198546136;8962.327825413104;8982.416142950704;9076.319276054306;8832.293416526678;8911.242544383718;8918.331125165621;8919.464832110078;8920.553972301392;2381.118985975187;2415.685960825099;2416.6858308419883;2416.7704068113503;2417.8065754739578;2348.1311914591624;2370.146747171017;2402.8678422686744;2403.0267741564608;2404.5671779079757;1796.8203179682057;2157.6912703407284;2277.567262220173;2331.8484298520625;2342.7305859826083;1886.7830199527025;2292.5300313435705;2296.816254699637;2303.0557471436796;2325.104834638659;2349.894254758523;2355.5642206191737;2362.9763702363102;2364.4798144408373;2370.3718514593647;8767.736967890973;8803;8873;8940.105989401072;8982.056884027148;5264.210368444726;5445.292112025451;5465.617406781487;5468.808591699271;5469.753861076261;3001.9099427017222;3697.574778900434;3704.833282502294;3757.3236817372863;3760.435934609803;1071.2287153249727;1078.0351585331164;1085.3939402983658;1101.218135124055;1108.7228192951761;9970.800583988279;11717.882821171765;11786.705332366759;11799.88200118005;11807.291562506278;4429.291313389842;4480.977595112004;4682.578567928903;4687.460941991646;4688.742119183477;9123.178913897782;9129.634814607341;9131.360804743605;9142.3600347975;9147.131022552847;5272.130098533776;5290.65610735301;5296.92054619181;5298.496642818926;5301.787928482895;4344.978275108604;4458.5987261146;4601.286800545854;4867.148249056401;4970.577500912391;3723.162288485069;3747.5315585551807;3827.961720382816;3838.9040273992814;3848.8652897148463;1146.7763786061637;1174.1546086817477;1174.424531979324;1181.539199712113;1192.2965450384315;903.8024617381996;915.1671978499534;933.0902370189011;933.3865177502964;967.1247520993512;5681.204631351531;5857.991417338199;5986.132010858401;6115.082737589309;6200.844978875474;427.65573713161206;441.9756913369731;445.72587858466807;447.7873010320137;447.88131145246535;3887.1059656279213;3900.317444447267;3979.6617287530885;4016.2369149861433;4036.2936486114813;10245.795084098276;10596.417197054081;10771.784564308826;10922.126229901589;10957.890421095846;10878.619248326271;10879.184061195441;10882.347059176474;10893;10897.455127243606;8167.469114507538;8196.959015204886;8199.385046121564;8314.168583141638;8321.542315172723;5755.827325180084;5902.793402230901;5928.644281343133;5974.61165024272;5984.551158663205;10110.69667909964;10164.491775411348;10208.795824083329;10224.028717271847;10239.385636861813;9406.670766523139;9771.071748183911;9865.704028879145;10336.844947325646;10401.58393664245;6024.638521688713;6779.830504237433;6829.829254268683;7049.224585295716;7056.858862822817;8097.919020809953;8244.464109832841;8315.459495132796;8357.289630381243;8360.372972027244;6354.936450635434;7445.664945077593;7501.474896757175;7502.437317201123;7585.393168546393;7338.633068346455;7349.853002940018;7351.154617219088;7357.117145942521;7407.370373518312;6457.7094030767785;6537.967310163419;6562.048502967138;6614.933850661432;6619.0071489277;5015.749212539431;5038.445770965264;5038.647294689483;5083.110455670317;5167.58659307247;4318.265894797829;5000.799968001239;5076.720780356968;5130.948690513047;5154.690718556899 16:17:50 INFO - PROCESS | 1945 | |4;prototype.html;262.59822471618423;265.41740878771094;305.22016248485113;313.2841457270138;317.35101716988754;4313.870583882481;4659.860204193871;5012.624053196007;5081.958198569294;5216.504432078956;4998.350214472122;5164.147915593932;5166.380034395868;5202.375714914207;5231.953609278141;3512.4545200111997;4046.5751096134923;4052.49343832021;4064.309067458532;4088.4684990951137;3916.1188732535093;4003.4195041718935;4036.899077523056;4060.573639767826;4081.2857749893797;3586.6234045425117;3785.337565925966;4010.7393019453643;4040.353543433051;4053.574374690659;3498.9475157872653;3909.354956432185;4005.4178599453344;4007.9799601001955;4026.5570787213383;3072.7080927311868;3104.30153215527;3533.9646603533893;3534.717222622184;3541.628129046439;4763.49983251757;4947.678400903966;5028.893643398446;5146.404614569496;5186.651470617629;3822.6047492664975;4611.501262089801;4632.72203667781;4657.5575320344515;4660.4174478190225;5518.392976772552;5546.168074788774;5553.4724201200825;5554.111342185231;5611;3781.9243615127543;3883.359245724452;3903.668188204006;3923.470331505239;3928.489296391451;5799.9130013049835;5808.012637851575;5815.418458154235;5826;5833.533317334645;3973.7019723520852;3977.423273625308;3983.342748446532;4001.779902105354;4007.198560287954;3152.0386282183695;3879.0884142226555;4026.7382620129597;4028.576999415078;4035.6367926886132;3797.886063418102;4613.815447382134;4616.28447590624;4637.907241855211;4695.600873925686;4512.548745125461;5249.658772179796;5367.75845086972;5610.943890561124;5617.241672374259;1083.766990097133;1130.5760339872547;1141.4863311509687;1225.945686709429;1346.764316244673;915.9129882661136;931.9487428191514;949.2500924269749;956.3592393096642;961.1253759079204;4179.516271723555;5155.639105262596;5200.869978250574;5224.947750522446;5237.214417837278;4605.296040464982;4834.492378300298;5633.887292265401;5672.376038635747;5686.715664216773;4977.651564390458;5169.172932330809;5179.928028788439;5185.351831021122;5210.639137756126;2093.110428068065;2132.413586263759;2243.8429309948147;2454.6072628379375;2516.16320086338;4668.692766025519;4765.9523404766205;4776;4778.880527986828;4975.5522003019905;4333.891652708645;4482.596566309048;4530.88672783183;4626.930596041062;4680.672352935262;4973.35346404965;4978.850634480971;4989.827390563142;4990;4999.375078115236;4260.659147268273;4425.336199570091;4741.336212930261;4766.61867050642;4840.6127509798425;4049.1432781433737;4309.9353509698;4391.692581519263;4440.444944381952;4789.4971028041555;4120.979395103005;4327.415798867239;4431.667624928208;4627.616777359595;4711.599514041345;2881.884724610992;2949.867255973443;3203.359328134336;3434.492820241288;3449.6377880322207;4339.153864996296;4363.956360436482;4477.485089214782;4673.228917228756;4721.504242054672;4520.615747661485;4625.3579979106935;4864.781084851261;4933.629977751755;5321.5476684482255;4626.421697287839;4834.936314010912;5148.944466384307;5305.230741542532;5574.442555744556;4861.975690121527;5058.468860769566;5447.700376479332;5456.135528847387;5541.833744987657;4451.375248034509;4634.258518637137;5360.758765855624;5509.752061157337;5567.10926251814;5410.729463526729;5413.404525502113;5424.2406063151975;5433.8369848904595;5477.315335583052 16:17:50 INFO - PROCESS | 1945 | |5;yui.html;697.1842943755805;725.9346658800707;764.3884892086329;796.1520980156133;799.7041094794927;15757.05457672539;16320.510384688463;16440.260188291526;16444.83555164448;16457.50627481176;14331.925105617069;14442.42230310789;14444.855551444482;14458.277086145703;14872.256387180652;679.5243329669252;703.5391818358983;838.5681374092347;844.4342290665269;868.6308318964446;854.598338780772;858.7938894665298;866.7919699272161;867.865480850466;869.1091631078164;919.0258326174243;919.8942121656029;920.742192186189;921.8617207418873;922.7001224601997;730.7077169132336;748.5658318175446;758.6055251269364;914.9176574108329;915.3363811236833;556.8969740597962;665.411111166621;676.7293082766884;685.4036987820627;685.9519833611649;14961.925190374088;15011.924940375338;15012.624684382869;15014.849851501564;15029;11745.765084698343;13856;14199.07705999117;14214.786778198335;14282.64293392663;12547.74904501914;12760.744785104338;12831.230126192459;12944.676383090404;13160.210387376788;4474.731516109011;4478.686491945565;4537.478190008125;4571.108633816407;4587.082583483317;86598.13401865964;117599;117681.05797355049;125245;125669.74330256671;11091.889081109248;11121.777564448666;11264.057579846987;11350.829737553944;11474;22662;22691.978860951298;22698.773012269998;22702.205422810122;22714.886425567765;7470;7785.066419616565;7912.169222231698;8065.314448271963;8068.8386232275025;4281.871543853689;4362.956370436255;4373.234683930299;4536.047430039728;4562.954370456253;3511.701505372021;3610.5125808016105;3614.493970844084;3616.0959760059986;3616.5479315085613;624.8344188789974;632.9113924050637;650.7201903181677;654.502578040683;663.8174502011985;528.2630730131445;551.3411473289381;555.6554935939663;567.9346875109333;596.7821745063108;7638.541687498768;8683.869741953877;9001.324900632479;9116.81766364669;9230.815383692423;517.8161752577856;551.1484756051909;556.5742207211487;627.4603840696997;636.5321488705797;4369.820148559871;4387.539308372575;4438.622717069085;4475.395821564112;4534.342520334533;160371.19814400852;162914;165279.513663223;165795;167791.16104419643;110516.99999999838;114120.42939785248;114136.42931785453;121167.78832211741;144272;34277.65722342745;97956.53065204028;105350.4732476348;109322;110142.34786478209;9625.374350667184;9933.850992234979;10115.949420252851;10135.79728405413;10181.440020798927;58308.70845645745;61814;62070.379296208266;63771.681141593996;64509;67378;70426;88562;92842;94161.5291923536;11355.48900299472;11545.422728863356;11743.295402275891;11768.94079532846;12738.617841464771;12888.806667900177;13111.475540978254;15268.9311748181;15380.769288460906;15542.300596473411;14194.43222271139;14211.28943552839;14251.714965700421;14395.42418303256;14508.419663213353;13871;13992.650183745487;14054.648633784238;14191.929040354733;14590.197539135024;13408;13623.455061797416;13945.860541394457;14182.290885455892;14221.573352799018;10052.195824333889;11219.49512271966;11298.943505282421;11337.546498139982;11475.483603237708;9584.329096963425;11289.204859882057;11463.254888432559;11644.417779110841;11669.474873630537 16:17:50 INFO - PROCESS | 1945 | __end_tp_report 16:17:50 INFO - PROCESS | 1945 | __start_cc_report 16:17:50 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,1166 16:17:50 INFO - PROCESS | 1945 | __end_cc_report 16:17:50 INFO - PROCESS | 1945 | __startTimestamp1472858270299__endTimestamp 16:17:50 INFO - PROCESS | 1945 | 16:17:50 INFO - PROCESS | 1945 | ------- Summary: start ------- 16:17:50 INFO - PROCESS | 1945 | Number of tests: 6 16:17:50 INFO - PROCESS | 1945 | 16:17:50 INFO - PROCESS | 1945 | [#0] dojo.html Cycles:165 Average:5681.74 Median:5933.20 stddev:652.43 (11.0%) stddev-sans-first:626.87 16:17:50 INFO - PROCESS | 1945 | Values: 3290.0 3295.4 3302.3 3355.6 3374.6 5514.8 5580.2 5587.0 5677.6 5698.1 5582.4 5607.6 5629.7 5634.9 5641.5 5098.8 5134.4 5141.9 5145.7 5174.2 4264.4 5231.8 5234.4 5236.6 5252.4 4350.5 4957.7 5155.1 5235.5 5247.6 4342.3 5252.9 5267.1 5271.5 5280.7 5066.6 5075.9 5130.7 5169.8 5211.5 5553.6 5642.7 5671.7 5673.7 5712.2 5254.4 6132.6 6135.4 6164.8 6171.0 5402.5 6049.7 6131.0 6134.4 6135.1 5770.0 5880.9 5891.4 5907.0 5919.2 6156.3 6193.3 6203.1 6210.3 6216.6 5910.6 6100.3 6112.5 6115.2 6117.1 6123.0 6127.0 6147.0 6158.0 6173.2 6135.1 6138.1 6144.8 6151.0 6159.1 6119.1 6126.0 6140.8 6191.8 6203.8 4789.5 4862.5 4893.4 4894.0 4919.8 4337.9 4381.5 4383.2 4402.8 4446.5 5607.1 5611.6 5635.8 5643.2 5691.5 6077.3 6139.4 6160.0 6160.6 6167.1 5719.9 5781.5 5790.4 5853.0 5858.1 5415.8 5423.5 5429.8 5431.4 5463.7 6272.2 6303.5 6322.8 6325.7 6352.0 6126.2 6129.8 6141.3 6145.4 6198.9 6313.9 6329.1 6342.5 6346.0 6351.6 6063.9 6114.3 6128.5 6131.6 6137.1 6064.6 6097.5 6103.1 6121.4 6145.7 6047.5 6056.3 6072.6 6079.7 6129.2 6072.6 6075.6 6078.4 6101.1 6116.3 5803.1 5916.8 5933.2 5941.9 5962.6 5960.8 6067.1 6100.7 6101.1 6116.6 5893.1 5927.1 5943.3 5949.5 5953.4 16:17:50 INFO - PROCESS | 1945 | 16:17:50 INFO - PROCESS | 1945 | [#1] ext.html Cycles:180 Average:65787.37 Median:39604.20 stddev:145876.93 (368.3%) stddev-sans-first:146203.77 16:17:50 INFO - PROCESS | 1945 | Values: 491.9 500.0 526.0 529.0 534.8 28479.1 39596.8 40455.8 40574.0 42394.4 41559.6 41919.0 41922.2 41960.4 41971.2 9613.6 9820.7 9822.1 9825.0 9830.2 9595.0 9685.0 9715.9 9716.2 9725.8 9664.0 9734.2 9737.7 9743.7 9750.4 9657.4 9694.4 9701.1 9705.6 9718.2 9635.1 9698.1 9713.0 9740.0 9741.3 39987.0 40264.2 40392.8 40464.2 40574.4 3032.2 3232.5 3772.8 4021.5 4098.8 30851.1 30851.8 30867.8 30879.5 30940.8 3137.6 3233.7 3234.8 3316.2 3329.7 340382.0 340809.0 341907.0 342444.0 349107.0 55114.7 55648.9 55736.9 55749.4 55896.6 58150.4 58201.7 58223.0 58280.0 58282.0 15307.2 15523.3 15536.1 15540.8 15541.9 12154.0 12188.1 12212.6 12219.8 12228.3 12798.5 15076.1 15089.2 15127.2 15305.0 5801.8 6062.2 6184.0 6238.8 6255.7 715.3 988.1 1019.6 1042.9 1086.1 24188.4 29738.7 29856.0 29890.4 30159.5 959.7 962.3 1030.4 1052.0 1158.4 14985.3 15194.2 15430.4 15439.9 15906.5 517808.0 645075.0 648479.0 649398.0 835785.0 51684.2 52205.0 52236.2 52297.7 52329.0 1245.1 1291.8 1348.0 1386.1 1399.8 29499.4 30787.8 30962.5 31464.7 31711.8 50871.2 53520.5 54612.0 54653.5 56880.7 562876.2 570521.1 571004.0 572229.0 574633.0 22867.9 24918.8 25163.1 25302.5 25462.2 38108.6 42676.1 45751.5 46229.5 46305.0 38154.0 38205.8 38329.2 39240.8 39460.2 38386.6 38748.6 39269.8 39401.8 39611.6 37258.3 37301.8 37552.0 38130.0 38413.0 29992.0 32891.7 33732.8 34157.8 34630.8 20554.0 25099.7 25188.0 25328.7 25698.7 16:17:50 INFO - PROCESS | 1945 | 16:17:50 INFO - PROCESS | 1945 | [#2] jquery.html Cycles:180 Average:34007.91 Median:16770.54 stddev:87310.91 (520.6%) stddev-sans-first:87519.21 16:17:50 INFO - PROCESS | 1945 | Values: 321.6 352.6 373.1 377.7 403.2 15380.8 17428.0 20831.9 21305.0 21416.7 20220.1 21014.8 21041.9 21457.8 22280.9 6828.2 6902.5 7039.8 7103.9 7150.7 4629.4 6949.0 7071.8 7089.1 7092.9 4601.8 7060.8 7903.2 7994.2 8063.1 7730.9 8032.4 8069.6 8073.2 8094.5 7142.1 7519.4 7803.6 7805.8 7822.7 24226.0 24578.5 27846.3 27856.4 28774.4 2268.5 2430.9 2435.0 2468.7 2524.4 15710.7 16098.4 16169.4 16449.9 17096.2 1872.8 1958.4 2056.4 2059.5 2123.4 97320.0 100185.5 129711.0 136131.3 136419.0 19988.7 20342.7 20601.3 21041.4 21216.8 33219.5 34972.0 34999.1 35049.8 35094.5 10423.3 10441.2 10532.1 10670.5 10782.7 8604.7 8631.2 8638.7 8771.7 8784.5 9253.6 9368.1 9490.8 9498.6 9543.2 3572.6 3665.3 3671.3 3675.2 3682.0 702.6 800.8 869.2 874.9 888.9 20043.0 21021.0 21076.1 21103.8 21120.5 912.6 921.2 927.8 966.5 1024.5 10333.6 10804.2 10880.5 10892.9 10894.9 432096.0 532904.0 535692.0 546379.0 550133.0 86626.6 109806.0 118215.0 118635.0 132970.3 814.9 966.1 1017.6 1018.0 1021.1 18200.4 19255.0 19732.8 19754.0 19797.5 46259.8 48369.0 54527.7 54964.5 55912.7 80870.0 80909.0 80947.2 81333.2 82334.8 7860.9 8096.7 8097.7 8215.9 8358.4 10775.5 11140.8 12019.7 12893.6 13127.0 9684.6 10395.6 11519.4 11548.0 11587.8 9481.7 10304.9 10560.0 10950.2 11349.5 11321.9 11447.5 11786.2 11899.3 11908.9 7966.0 8135.6 8236.4 8271.1 8422.8 10085.5 10533.6 11351.2 11378.4 11410.4 16:17:50 INFO - PROCESS | 1945 | 16:17:50 INFO - PROCESS | 1945 | [#3] mootools.html Cycles:180 Average:5646.49 Median:4918.86 stddev:3200.24 (65.1%) stddev-sans-first:3193.22 16:17:50 INFO - PROCESS | 1945 | Values: 1387.9 1555.2 1570.8 1790.9 1952.6 7913.8 8703.2 8962.3 8982.4 9076.3 8832.3 8911.2 8918.3 8919.5 8920.6 2381.1 2415.7 2416.7 2416.8 2417.8 2348.1 2370.1 2402.9 2403.0 2404.6 1796.8 2157.7 2277.6 2331.8 2342.7 1886.8 2292.5 2296.8 2303.1 2325.1 2349.9 2355.6 2363.0 2364.5 2370.4 8767.7 8803.0 8873.0 8940.1 8982.1 5264.2 5445.3 5465.6 5468.8 5469.8 3001.9 3697.6 3704.8 3757.3 3760.4 1071.2 1078.0 1085.4 1101.2 1108.7 9970.8 11717.9 11786.7 11799.9 11807.3 4429.3 4481.0 4682.6 4687.5 4688.7 9123.2 9129.6 9131.4 9142.4 9147.1 5272.1 5290.7 5296.9 5298.5 5301.8 4345.0 4458.6 4601.3 4867.1 4970.6 3723.2 3747.5 3828.0 3838.9 3848.9 1146.8 1174.2 1174.4 1181.5 1192.3 903.8 915.2 933.1 933.4 967.1 5681.2 5858.0 5986.1 6115.1 6200.8 427.7 442.0 445.7 447.8 447.9 3887.1 3900.3 3979.7 4016.2 4036.3 10245.8 10596.4 10771.8 10922.1 10957.9 10878.6 10879.2 10882.3 10893.0 10897.5 8167.5 8197.0 8199.4 8314.2 8321.5 5755.8 5902.8 5928.6 5974.6 5984.6 10110.7 10164.5 10208.8 10224.0 10239.4 9406.7 9771.1 9865.7 10336.8 10401.6 6024.6 6779.8 6829.8 7049.2 7056.9 8097.9 8244.5 8315.5 8357.3 8360.4 6354.9 7445.7 7501.5 7502.4 7585.4 7338.6 7349.9 7351.2 7357.1 7407.4 6457.7 6538.0 6562.0 6614.9 6619.0 5015.7 5038.4 5038.6 5083.1 5167.6 4318.3 5000.8 5076.7 5130.9 5154.7 16:17:50 INFO - PROCESS | 1945 | 16:17:50 INFO - PROCESS | 1945 | [#4] prototype.html Cycles:180 Average:4244.11 Median:4633.49 stddev:1288.93 (27.8%) stddev-sans-first:1257.43 16:17:50 INFO - PROCESS | 1945 | Values: 262.6 265.4 305.2 313.3 317.4 4313.9 4659.9 5012.6 5082.0 5216.5 4998.4 5164.1 5166.4 5202.4 5232.0 3512.5 4046.6 4052.5 4064.3 4088.5 3916.1 4003.4 4036.9 4060.6 4081.3 3586.6 3785.3 4010.7 4040.4 4053.6 3498.9 3909.4 4005.4 4008.0 4026.6 3072.7 3104.3 3534.0 3534.7 3541.6 4763.5 4947.7 5028.9 5146.4 5186.7 3822.6 4611.5 4632.7 4657.6 4660.4 5518.4 5546.2 5553.5 5554.1 5611.0 3781.9 3883.4 3903.7 3923.5 3928.5 5799.9 5808.0 5815.4 5826.0 5833.5 3973.7 3977.4 3983.3 4001.8 4007.2 3152.0 3879.1 4026.7 4028.6 4035.6 3797.9 4613.8 4616.3 4637.9 4695.6 4512.5 5249.7 5367.8 5610.9 5617.2 1083.8 1130.6 1141.5 1225.9 1346.8 915.9 931.9 949.3 956.4 961.1 4179.5 5155.6 5200.9 5224.9 5237.2 4605.3 4834.5 5633.9 5672.4 5686.7 4977.7 5169.2 5179.9 5185.4 5210.6 2093.1 2132.4 2243.8 2454.6 2516.2 4668.7 4766.0 4776.0 4778.9 4975.6 4333.9 4482.6 4530.9 4626.9 4680.7 4973.4 4978.9 4989.8 4990.0 4999.4 4260.7 4425.3 4741.3 4766.6 4840.6 4049.1 4309.9 4391.7 4440.4 4789.5 4121.0 4327.4 4431.7 4627.6 4711.6 2881.9 2949.9 3203.4 3434.5 3449.6 4339.2 4364.0 4477.5 4673.2 4721.5 4520.6 4625.4 4864.8 4933.6 5321.5 4626.4 4834.9 5148.9 5305.2 5574.4 4862.0 5058.5 5447.7 5456.1 5541.8 4451.4 4634.3 5360.8 5509.8 5567.1 5410.7 5413.4 5424.2 5433.8 5477.3 16:17:50 INFO - PROCESS | 1945 | 16:17:50 INFO - PROCESS | 1945 | [#5] yui.html Cycles:180 Average:24534.37 Median:3561.11 stddev:39790.23 (1117.4%) stddev-sans-first:39861.60 16:17:50 INFO - PROCESS | 1945 | Values: 697.2 725.9 764.4 796.2 799.7 15757.1 16320.5 16440.3 16444.8 16457.5 14331.9 14442.4 14444.9 14458.3 14872.3 679.5 703.5 838.6 844.4 868.6 854.6 858.8 866.8 867.9 869.1 919.0 919.9 920.7 921.9 922.7 730.7 748.6 758.6 914.9 915.3 556.9 665.4 676.7 685.4 686.0 14961.9 15011.9 15012.6 15014.8 15029.0 11745.8 13856.0 14199.1 14214.8 14282.6 12547.7 12760.7 12831.2 12944.7 13160.2 4474.7 4478.7 4537.5 4571.1 4587.1 86598.1 117599.0 117681.1 125245.0 125669.7 11091.9 11121.8 11264.1 11350.8 11474.0 22662.0 22692.0 22698.8 22702.2 22714.9 7470.0 7785.1 7912.2 8065.3 8068.8 4281.9 4363.0 4373.2 4536.0 4563.0 3511.7 3610.5 3614.5 3616.1 3616.5 624.8 632.9 650.7 654.5 663.8 528.3 551.3 555.7 567.9 596.8 7638.5 8683.9 9001.3 9116.8 9230.8 517.8 551.1 556.6 627.5 636.5 4369.8 4387.5 4438.6 4475.4 4534.3 160371.2 162914.0 165279.5 165795.0 167791.2 110517.0 114120.4 114136.4 121167.8 144272.0 34277.7 97956.5 105350.5 109322.0 110142.3 9625.4 9933.9 10115.9 10135.8 10181.4 58308.7 61814.0 62070.4 63771.7 64509.0 67378.0 70426.0 88562.0 92842.0 94161.5 11355.5 11545.4 11743.3 11768.9 12738.6 12888.8 13111.5 15268.9 15380.8 15542.3 14194.4 14211.3 14251.7 14395.4 14508.4 13871.0 13992.7 14054.6 14191.9 14590.2 13408.0 13623.5 13945.9 14182.3 14221.6 10052.2 11219.5 11298.9 11337.5 11475.5 9584.3 11289.2 11463.3 11644.4 11669.5 16:17:50 INFO - PROCESS | 1945 | -------- Summary: end -------- 16:17:50 INFO - PROCESS | 1945 | 16:17:50 INFO - TEST-INFO | 1945: exit 0 16:17:50 INFO - TEST-OK | dromaeo_css | took 1117076ms 16:17:50 INFO - TEST-START | kraken 16:17:50 INFO - operating with platform_type : mac_ 16:17:50 INFO - Initialising browser for kraken test... 16:17:50 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmppgA2sU/profile http://localhost:54339/getInfo.html) 16:17:53 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 16:17:53 INFO - PROCESS | 1948 | colorDepth:24 16:17:53 INFO - PROCESS | 1948 | Browser inner width/height: 1024/689 16:17:53 INFO - PROCESS | 1948 | __metrics 16:17:53 INFO - PROCESS | 1948 | 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" 16:17:53 INFO - PROCESS | 1948 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:17:54 INFO - TEST-INFO | 1948: exit 0 16:17:54 INFO - Browser initialized. 16:17:54 INFO - Running cycle 1/1 for kraken test... 16:17:54 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmppgA2sU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:18:06 INFO - PROCESS | 1949 | RSS: Main: 234016768 16:18:06 INFO - PROCESS | 1949 | 16:18:08 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54339/tests/kraken/driver.html?testName=audio-beat-detection) 16:18:09 INFO - PROCESS | 1949 | RSS: Main: 292798464 16:18:09 INFO - PROCESS | 1949 | 16:18:12 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54339/tests/kraken/driver.html?testName=audio-dft) 16:18:12 INFO - PROCESS | 1949 | RSS: Main: 290160640 16:18:12 INFO - PROCESS | 1949 | 16:18:16 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54339/tests/kraken/driver.html?testName=audio-fft) 16:18:16 INFO - PROCESS | 1949 | RSS: Main: 380452864 16:18:16 INFO - PROCESS | 1949 | 16:18:18 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54339/tests/kraken/driver.html?testName=audio-oscillator) 16:18:19 INFO - PROCESS | 1949 | RSS: Main: 282566656 16:18:19 INFO - PROCESS | 1949 | 16:18:21 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54339/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:18:22 INFO - PROCESS | 1949 | RSS: Main: 285659136 16:18:22 INFO - PROCESS | 1949 | 16:18:25 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54339/tests/kraken/driver.html?testName=imaging-darkroom) 16:18:25 INFO - PROCESS | 1949 | RSS: Main: 322830336 16:18:25 INFO - PROCESS | 1949 | 16:18:28 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54339/tests/kraken/driver.html?testName=imaging-desaturate) 16:18:29 INFO - PROCESS | 1949 | RSS: Main: 328863744 16:18:29 INFO - PROCESS | 1949 | 16:18:31 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54339/tests/kraken/driver.html?testName=json-parse-financial) 16:18:32 INFO - PROCESS | 1949 | RSS: Main: 325058560 16:18:32 INFO - PROCESS | 1949 | 16:18:34 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54339/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:18:34 INFO - PROCESS | 1949 | RSS: Main: 299393024 16:18:34 INFO - PROCESS | 1949 | 16:18:36 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54339/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:18:37 INFO - PROCESS | 1949 | RSS: Main: 306044928 16:18:37 INFO - PROCESS | 1949 | 16:18:39 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54339/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:18:40 INFO - PROCESS | 1949 | RSS: Main: 308703232 16:18:40 INFO - PROCESS | 1949 | 16:18:43 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54339/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:18:43 INFO - PROCESS | 1949 | RSS: Main: 323559424 16:18:43 INFO - PROCESS | 1949 | 16:18:46 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54339/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:18:47 INFO - PROCESS | 1949 | RSS: Main: 319733760 16:18:47 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:54339/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54339/tests/kraken/driver.html?testName=ai-astar) 16:18:49 INFO - PROCESS | 1949 | RSS: Main: 363200512 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | __start_tp_report 16:18:49 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 16:18:49 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 16:18:49 INFO - PROCESS | 1949 | |i|pagename|runs| 16:18:49 INFO - PROCESS | 1949 | |0;ai-astar;113;112;116;113;114;114;113;112;112;123 16:18:49 INFO - PROCESS | 1949 | |1;audio-beat-detection;150;116;131;163;112;132;127;108;155;108 16:18:49 INFO - PROCESS | 1949 | |2;audio-dft;187;183;190;185;183;184;186;183;182;184 16:18:49 INFO - PROCESS | 1949 | |3;audio-fft;74;77;78;156;58;57;58;70;140;58 16:18:49 INFO - PROCESS | 1949 | |4;audio-oscillator;82;84;133;70;79;84;124;67;69;118 16:18:49 INFO - PROCESS | 1949 | |5;imaging-gaussian-blur;90;86;90;87;88;86;89;87;90;85 16:18:49 INFO - PROCESS | 1949 | |6;imaging-darkroom;95;94;94;96;95;94;95;96;95;95 16:18:49 INFO - PROCESS | 1949 | |7;imaging-desaturate;86;86;85;83;86;84;84;86;85;86 16:18:49 INFO - PROCESS | 1949 | |8;json-parse-financial;54;54;55;53;52;56;53;55;52;53 16:18:49 INFO - PROCESS | 1949 | |9;json-stringify-tinderbox;67;67;68;76;73;81;73;71;73;72 16:18:49 INFO - PROCESS | 1949 | |10;stanford-crypto-aes;84;89;86;88;86;86;89;89;86;90 16:18:49 INFO - PROCESS | 1949 | |11;stanford-crypto-ccm;140;129;138;153;154;132;139;138;184;141 16:18:49 INFO - PROCESS | 1949 | |12;stanford-crypto-pbkdf2;169;174;177;239;190;169;171;171;171;172 16:18:49 INFO - PROCESS | 1949 | |13;stanford-crypto-sha256-iterative;67;67;68;67;67;67;65;65;66;68 16:18:49 INFO - PROCESS | 1949 | __end_tp_report 16:18:49 INFO - PROCESS | 1949 | __start_cc_report 16:18:49 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,2113 16:18:49 INFO - PROCESS | 1949 | __end_cc_report 16:18:49 INFO - PROCESS | 1949 | __startTimestamp1472858329359__endTimestamp 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | ------- Summary: start ------- 16:18:49 INFO - PROCESS | 1949 | Number of tests: 14 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#0] ai-astar Cycles:10 Average:114.20 Median:113.50 stddev:3.33 (2.9%) stddev-sans-first:3.50 16:18:49 INFO - PROCESS | 1949 | Values: 113.0 112.0 116.0 113.0 114.0 114.0 113.0 112.0 112.0 123.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:131.50 stddev:20.04 (15.2%) stddev-sans-first:19.94 16:18:49 INFO - PROCESS | 1949 | Values: 150.0 116.0 131.0 163.0 112.0 132.0 127.0 108.0 155.0 108.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#2] audio-dft Cycles:10 Average:184.70 Median:184.50 stddev:2.41 (1.3%) stddev-sans-first:2.40 16:18:49 INFO - PROCESS | 1949 | Values: 187.0 183.0 190.0 185.0 183.0 184.0 186.0 183.0 182.0 184.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#3] audio-fft Cycles:10 Average:82.60 Median:64.00 stddev:35.65 (55.7%) stddev-sans-first:37.68 16:18:49 INFO - PROCESS | 1949 | Values: 74.0 77.0 78.0 156.0 58.0 57.0 58.0 70.0 140.0 58.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:74.50 stddev:24.51 (32.9%) stddev-sans-first:25.78 16:18:49 INFO - PROCESS | 1949 | Values: 82.0 84.0 133.0 70.0 79.0 84.0 124.0 67.0 69.0 118.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:88.50 stddev:1.87 (2.1%) stddev-sans-first:1.81 16:18:49 INFO - PROCESS | 1949 | Values: 90.0 86.0 90.0 87.0 88.0 86.0 89.0 87.0 90.0 85.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 16:18:49 INFO - PROCESS | 1949 | Values: 95.0 94.0 94.0 96.0 95.0 94.0 95.0 96.0 95.0 95.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:86.00 stddev:1.10 (1.3%) stddev-sans-first:1.12 16:18:49 INFO - PROCESS | 1949 | Values: 86.0 86.0 85.0 83.0 86.0 84.0 84.0 86.0 85.0 86.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:1.34 (2.5%) stddev-sans-first:1.41 16:18:49 INFO - PROCESS | 1949 | Values: 54.0 54.0 55.0 53.0 52.0 56.0 53.0 55.0 52.0 53.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:73.00 stddev:4.31 (5.9%) stddev-sans-first:4.15 16:18:49 INFO - PROCESS | 1949 | Values: 67.0 67.0 68.0 76.0 73.0 81.0 73.0 71.0 73.0 72.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:88.50 stddev:1.95 (2.2%) stddev-sans-first:1.66 16:18:49 INFO - PROCESS | 1949 | Values: 84.0 89.0 86.0 88.0 86.0 86.0 89.0 89.0 86.0 90.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:140.50 stddev:15.87 (11.3%) stddev-sans-first:16.73 16:18:49 INFO - PROCESS | 1949 | Values: 140.0 129.0 138.0 153.0 154.0 132.0 139.0 138.0 184.0 141.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.30 Median:173.00 stddev:21.54 (12.4%) stddev-sans-first:22.45 16:18:49 INFO - PROCESS | 1949 | Values: 169.0 174.0 177.0 239.0 190.0 169.0 171.0 171.0 171.0 172.0 16:18:49 INFO - PROCESS | 1949 | 16:18:49 INFO - PROCESS | 1949 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.06 (1.6%) stddev-sans-first:1.12 16:18:49 INFO - PROCESS | 1949 | Values: 67.0 67.0 68.0 67.0 67.0 67.0 65.0 65.0 66.0 68.0 16:18:49 INFO - PROCESS | 1949 | -------- Summary: end -------- 16:18:49 INFO - PROCESS | 1949 | 16:18:50 INFO - TEST-INFO | 1949: exit 0 16:18:50 INFO - TEST-OK | kraken | took 59280ms 16:18:50 INFO - SUITE-END | took 1176s 16:18:50 INFO - Completed test suite (00:19:36) 16:18:50 INFO - javascript benchmark 16:18:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3289.9506507402384, 3295.3574053059656, 3302.273499830038, 3355.5973283206017, 3374.595048594169, 5514.834554963358, 5580.218769372286, 5586.972065139668, 5677.602567820254, 5698.0883058710615, 5582.385937546866, 5607.57943154263, 5629.71851407429, 5634.887302253953, 5641.548676105912, 5098.796048158069, 5134.435212126682, 5141.922871156936, 5145.716985565792, 5174.172132458807, 4264.402983582301, 5231.816886408977, 5234.42421333653, 5236.607254455912, 5252.36971563411, 4350.499692535352, 4957.677750946177, 5155.072087024353, 5235.502627250424, 5247.632665713401, 4342.283523218697, 5252.947470525303, 5267.104592219332, 5271.525562699337, 5280.735963201824, 5066.645334826564, 5075.923861142086, 5130.692158470466, 5169.844904652867, 5211.478852114758, 5553.583481238923, 5642.746076426571, 5671.6880571568545, 5673.659580425187, 5712.200291959129, 5254.4220135785035, 6132.601380910227, 6135.355787642323, 6164.84587885302, 6171.012637977902, 5402.459754024605, 6049.736967957913, 6131.0, 6134.355892631254, 6135.141080248769, 5769.971150144264, 5880.852978675567, 5891.381404952418, 5907.025340818803, 5919.200907877382, 6156.251089018208, 6193.318734939153, 6203.100550420164, 6210.347913469111, 6216.564840461125, 5910.556708246899, 6100.267967843887, 6112.541559383064, 6115.1744514490865, 6117.051856962178, 6123.0, 6126.969365153235, 6147.0, 6158.04550294705, 6173.166622505904, 6135.11040899076, 6138.1099740537375, 6144.78493252734, 6151.0, 6159.1069294951985, 6119.082137679384, 6125.9693701531205, 6140.785072522441, 6191.752329906753, 6203.751849925952, 4789.473157952622, 4862.489438608965, 4893.388326459192, 4894.045661096052, 4919.7540122993705, 4337.9132417351475, 4381.539938306496, 4383.211022016004, 4402.84590039347, 4446.466424029137, 5607.102863541813, 5611.579131565149, 5635.774569017193, 5643.238162847963, 5691.4877661010705, 6077.3314935357075, 6139.355367686418, 6159.969200153971, 6160.630362178284, 6167.074938759132, 5719.8570035749435, 5781.479666830005, 5790.392008839095, 5852.970735146297, 5858.062709966385, 5415.783368665288, 5423.484768946943, 5429.809956651498, 5431.402545719968, 5463.69949652765, 6272.153259310117, 6303.495720342454, 6322.841928951813, 6325.715342809584, 6352.0, 6126.212334160775, 6129.756146212572, 6141.324454309933, 6145.371476558805, 6198.907016394667, 6313.873722525615, 6329.082283069021, 6342.492600592034, 6345.96827015862, 6351.555391122577, 6063.878722425438, 6114.327423983447, 6128.540359473147, 6131.601445906002, 6137.1101190328045, 6064.605800622945, 6097.524678803442, 6103.084537319438, 6121.392004143408, 6145.6927153641245, 6047.485963693135, 6056.333803281724, 6072.635641861502, 6079.696015199311, 6129.2032035835055, 6072.635641861326, 6075.574709770273, 6078.3921607838865, 6101.084837274444, 6116.3272040076445, 5803.071508558611, 5916.792912248051, 5933.199018132497, 5941.881162376815, 5962.5528085394635, 5960.791372301948, 6067.059605761114, 6100.702285530011, 6101.145839582551, 6116.6096904373135, 5893.145493903275, 5927.110933360031, 5943.346231914399, 5949.464548190684, 5953.434423729739], "value": 5639.489933078774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.9188333924903, 500.02495134487754, 526.0084740264601, 529.0186703664705, 534.7647035304468, 28479.145625631212, 39596.80201598992, 40455.79772101135, 40573.95695322852, 42394.364084538756, 41559.58440415603, 41918.95202619936, 41922.16155676885, 41960.37059444103, 41971.16057678845, 9613.567389467491, 9820.656277030306, 9822.116009559139, 9825.0, 9830.164436022947, 9594.992525784797, 9684.951575242114, 9715.854262186072, 9716.174125199366, 9725.756856078584, 9664.0, 9734.17259532937, 9737.707868763948, 9743.707688769347, 9750.36622619531, 9657.372270802376, 9694.36986595869, 9701.07839755222, 9705.61177552897, 9718.173955213815, 9635.13283804454, 9698.127168554864, 9713.0, 9740.0, 9741.31810773246, 39987.00032499211, 40264.194716105805, 40392.79803600963, 40464.1907161858, 40574.39138412926, 3032.2419395151214, 3232.450483417801, 3772.792496412696, 4021.477207962975, 4098.774567398792, 30851.07446776578, 30851.845740771376, 30867.845660771778, 30879.536806947915, 30940.845295773375, 3137.5607414961923, 3233.708966193054, 3234.7735658503916, 3316.1709572606846, 3329.6836800504157, 340382.0, 340808.9999999975, 341907.0, 342444.0, 349107.0, 55114.72442637761, 55648.887022259325, 55736.88526229453, 55749.442505575236, 55896.6025849357, 58150.41849581535, 58201.70899145477, 58223.0, 58280.0, 58282.0, 15307.15810630426, 15523.301451434712, 15536.067835929654, 15540.84459155394, 15541.922290388477, 12154.027677785756, 12188.14682972201, 12212.633620991384, 12219.816702749466, 12228.327441990597, 12798.488060477663, 15076.095434273759, 15089.245537723289, 15127.243637818065, 15305.00517466361, 5801.752623185951, 6062.151298818169, 6183.96908015466, 6238.812835614939, 6255.718492667841, 715.3168723868692, 988.0625706559355, 1019.6023550815188, 1042.890496497872, 1086.093112251279, 24188.395290117536, 29738.702612973593, 29856.0, 29890.40219195604, 30159.547606785916, 959.7264779537799, 962.302330810171, 1030.3611760708409, 1051.9579216831394, 1158.44394690549, 14985.32566034531, 15194.240287985556, 15430.382784688487, 15439.919205655497, 15906.52280431589, 517808.0, 645075.0, 648479.0, 649398.0, 835785.0, 51684.22473662898, 52205.0, 52236.21645675318, 52297.7385113072, 52328.999999999236, 1245.1346314311465, 1291.8062290656665, 1347.9663008424673, 1386.0644065255913, 1399.7760358342616, 29499.410011799213, 30787.846060770447, 30962.53556196704, 31464.68535314709, 31711.841440792647, 50871.23693144532, 53520.464795351545, 54612.0, 54653.45346546484, 56880.71559642341, 562876.2274263557, 570521.1473942604, 571004.0, 572229.0, 574633.0, 22867.885660572258, 24918.750812491642, 25163.119290825467, 25302.49395012126, 25462.23613291604, 38108.61891381162, 42676.146477070906, 45751.542484574726, 46229.537704622526, 46305.0, 38154.0, 38205.80897095497, 38329.23341533209, 39240.82277531679, 39460.21079578335, 38386.61613383942, 38748.64379746695, 39269.803650981565, 39401.80299098486, 39611.60388396079, 37258.254834903695, 37301.81349093237, 37552.0, 38130.046748831504, 38413.04597787347, 29991.950281740035, 32891.671083288864, 33732.83133584316, 34157.829210853786, 34630.82684586561, 20554.0, 25099.74900250974, 25188.0, 25328.74671253264, 25698.65917430722], "value": 19306.995182921863, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.5786224886004, 352.5997992278763, 373.1138545953361, 377.6808596735758, 403.243917654398, 15380.846191538081, 17428.0, 20831.895840520814, 21305.0, 21416.678749818766, 20220.09009594568, 21014.789852101476, 21041.894790526065, 21457.785422145775, 22280.88859555704, 6828.180618325808, 6902.482313826458, 7039.788806335792, 7103.85792284154, 7150.713971441136, 4629.444466664005, 6949.027136200936, 7071.752488662898, 7089.1493020837315, 7092.929070709279, 4601.838935637254, 7060.752873649423, 7903.209679032108, 7994.160613135624, 8063.1130575636635, 7730.922690773076, 8032.437729358947, 8069.556174410405, 8073.192680731939, 8094.514329140212, 7142.14294284684, 7519.398448124191, 7803.619895931739, 7805.804854878616, 7822.726204582869, 24226.0, 24578.508429831305, 27846.3038424039, 27856.442871142463, 28774.424511509653, 2268.534950335177, 2430.8541487510806, 2434.989479366061, 2468.655361143141, 2524.3941454050896, 15710.68578628421, 16098.436554720645, 16169.353225870951, 16449.917750411292, 17096.230669619898, 1872.7565416495768, 1958.4026871804094, 2056.4139220322136, 2059.4851287178208, 2123.4054464749893, 97320.0267997318, 100185.4990725049, 129711.0, 136131.31934340365, 136419.0, 19988.700169497468, 20342.694859577117, 20601.278955236496, 21041.368758937257, 21216.772238915, 33219.50170747441, 34972.0, 34999.12502187415, 35049.82475087608, 35094.47358289628, 10423.270371073952, 10441.164706823436, 10532.052115309505, 10670.466476676134, 10782.73043173927, 8604.655813767504, 8631.194861669983, 8638.705590718908, 8771.692990745294, 8784.472931624123, 9253.629854805733, 9368.063193680577, 9490.810183796424, 9498.620055197853, 9543.236541076698, 3572.5534308211472, 3665.2852693724467, 3671.2840996006057, 3675.191457879262, 3681.9815900920325, 702.5819637315789, 800.7677773445752, 869.1916517638657, 874.8862647855865, 888.8755574219614, 20042.997850107437, 21021.0, 21076.05157767904, 21103.788962110182, 21120.47198820011, 912.5710915869531, 921.15714121578, 927.8469052606245, 966.463612694947, 1024.4519182237468, 10333.63832265882, 10804.24370294072, 10880.510377033053, 10892.891071089347, 10894.945525272324, 432096.0, 532904.0, 535692.0, 546379.0, 550133.0, 86626.56686716527, 109806.0, 118215.0, 118635.0, 132970.33514832557, 814.8673344051773, 966.0773960867496, 1017.6336518853207, 1017.9515099447581, 1021.1065317846884, 18200.36298729591, 19255.0, 19732.802671973095, 19754.012299384685, 19797.505062373555, 46259.84350391267, 48369.0, 54527.72736136294, 54964.45035549593, 55912.720436397554, 80870.0, 80909.0, 80947.19052809397, 81333.18666813256, 82334.76497852657, 7860.882086768817, 8096.676132954852, 8097.716579919675, 8215.917840821516, 8358.414910956419, 10775.461226938778, 11140.832887506533, 12019.699507512381, 12893.613191604267, 13127.015473839308, 9684.612615495302, 10395.648565686715, 11519.424028798694, 11548.0, 11587.826182607436, 9481.66814161501, 10304.948475257575, 10560.0, 10950.178736594946, 11349.54601815918, 11321.943390282995, 11447.484863181344, 11786.174967752175, 11899.286042837457, 11908.880911190778, 7966.0, 8135.593220339078, 8236.423450358418, 8271.13153118938, 8422.789430264293, 10085.546150423103, 10533.631322903662, 11351.205415620827, 11378.43107844588, 11410.429478525875], "value": 10814.651375677075, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.8612138786116, 1555.1913005237273, 1570.827209007009, 1790.8746387752853, 1952.5606738483834, 7913.762587122381, 8703.173198546136, 8962.327825413104, 8982.416142950704, 9076.319276054306, 8832.293416526678, 8911.242544383718, 8918.331125165621, 8919.464832110078, 8920.553972301392, 2381.118985975187, 2415.685960825099, 2416.6858308419883, 2416.7704068113503, 2417.8065754739578, 2348.1311914591624, 2370.146747171017, 2402.8678422686744, 2403.0267741564608, 2404.5671779079757, 1796.8203179682057, 2157.6912703407284, 2277.567262220173, 2331.8484298520625, 2342.7305859826083, 1886.7830199527025, 2292.5300313435705, 2296.816254699637, 2303.0557471436796, 2325.104834638659, 2349.894254758523, 2355.5642206191737, 2362.9763702363102, 2364.4798144408373, 2370.3718514593647, 8767.736967890973, 8803.0, 8873.0, 8940.105989401072, 8982.056884027148, 5264.210368444726, 5445.292112025451, 5465.617406781487, 5468.808591699271, 5469.753861076261, 3001.9099427017222, 3697.574778900434, 3704.833282502294, 3757.3236817372863, 3760.435934609803, 1071.2287153249727, 1078.0351585331164, 1085.3939402983658, 1101.218135124055, 1108.7228192951761, 9970.800583988279, 11717.882821171765, 11786.705332366759, 11799.88200118005, 11807.291562506278, 4429.291313389842, 4480.977595112004, 4682.578567928903, 4687.460941991646, 4688.742119183477, 9123.178913897782, 9129.634814607341, 9131.360804743605, 9142.3600347975, 9147.131022552847, 5272.130098533776, 5290.65610735301, 5296.92054619181, 5298.496642818926, 5301.787928482895, 4344.978275108604, 4458.5987261146, 4601.286800545854, 4867.148249056401, 4970.577500912391, 3723.162288485069, 3747.5315585551807, 3827.961720382816, 3838.9040273992814, 3848.8652897148463, 1146.7763786061637, 1174.1546086817477, 1174.424531979324, 1181.539199712113, 1192.2965450384315, 903.8024617381996, 915.1671978499534, 933.0902370189011, 933.3865177502964, 967.1247520993512, 5681.204631351531, 5857.991417338199, 5986.132010858401, 6115.082737589309, 6200.844978875474, 427.65573713161206, 441.9756913369731, 445.72587858466807, 447.7873010320137, 447.88131145246535, 3887.1059656279213, 3900.317444447267, 3979.6617287530885, 4016.2369149861433, 4036.2936486114813, 10245.795084098276, 10596.417197054081, 10771.784564308826, 10922.126229901589, 10957.890421095846, 10878.619248326271, 10879.184061195441, 10882.347059176474, 10893.0, 10897.455127243606, 8167.469114507538, 8196.959015204886, 8199.385046121564, 8314.168583141638, 8321.542315172723, 5755.827325180084, 5902.793402230901, 5928.644281343133, 5974.61165024272, 5984.551158663205, 10110.69667909964, 10164.491775411348, 10208.795824083329, 10224.028717271847, 10239.385636861813, 9406.670766523139, 9771.071748183911, 9865.704028879145, 10336.844947325646, 10401.58393664245, 6024.638521688713, 6779.830504237433, 6829.829254268683, 7049.224585295716, 7056.858862822817, 8097.919020809953, 8244.464109832841, 8315.459495132796, 8357.289630381243, 8360.372972027244, 6354.936450635434, 7445.664945077593, 7501.474896757175, 7502.437317201123, 7585.393168546393, 7338.633068346455, 7349.853002940018, 7351.154617219088, 7357.117145942521, 7407.370373518312, 6457.7094030767785, 6537.967310163419, 6562.048502967138, 6614.933850661432, 6619.0071489277, 5015.749212539431, 5038.445770965264, 5038.647294689483, 5083.110455670317, 5167.58659307247, 4318.265894797829, 5000.799968001239, 5076.720780356968, 5130.948690513047, 5154.690718556899], "value": 4455.721521493449, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [262.59822471618423, 265.41740878771094, 305.22016248485113, 313.2841457270138, 317.35101716988754, 4313.870583882481, 4659.860204193871, 5012.624053196007, 5081.958198569294, 5216.504432078956, 4998.350214472122, 5164.147915593932, 5166.380034395868, 5202.375714914207, 5231.953609278141, 3512.4545200111997, 4046.5751096134923, 4052.49343832021, 4064.309067458532, 4088.4684990951137, 3916.1188732535093, 4003.4195041718935, 4036.899077523056, 4060.573639767826, 4081.2857749893797, 3586.6234045425117, 3785.337565925966, 4010.7393019453643, 4040.353543433051, 4053.574374690659, 3498.9475157872653, 3909.354956432185, 4005.4178599453344, 4007.9799601001955, 4026.5570787213383, 3072.7080927311868, 3104.30153215527, 3533.9646603533893, 3534.717222622184, 3541.628129046439, 4763.49983251757, 4947.678400903966, 5028.893643398446, 5146.404614569496, 5186.651470617629, 3822.6047492664975, 4611.501262089801, 4632.72203667781, 4657.5575320344515, 4660.4174478190225, 5518.392976772552, 5546.168074788774, 5553.4724201200825, 5554.111342185231, 5611.0, 3781.9243615127543, 3883.359245724452, 3903.668188204006, 3923.470331505239, 3928.489296391451, 5799.9130013049835, 5808.012637851575, 5815.418458154235, 5826.0, 5833.533317334645, 3973.7019723520852, 3977.423273625308, 3983.342748446532, 4001.779902105354, 4007.198560287954, 3152.0386282183695, 3879.0884142226555, 4026.7382620129597, 4028.576999415078, 4035.6367926886132, 3797.886063418102, 4613.815447382134, 4616.28447590624, 4637.907241855211, 4695.600873925686, 4512.548745125461, 5249.658772179796, 5367.75845086972, 5610.943890561124, 5617.241672374259, 1083.766990097133, 1130.5760339872547, 1141.4863311509687, 1225.945686709429, 1346.764316244673, 915.9129882661136, 931.9487428191514, 949.2500924269749, 956.3592393096642, 961.1253759079204, 4179.516271723555, 5155.639105262596, 5200.869978250574, 5224.947750522446, 5237.214417837278, 4605.296040464982, 4834.492378300298, 5633.887292265401, 5672.376038635747, 5686.715664216773, 4977.651564390458, 5169.172932330809, 5179.928028788439, 5185.351831021122, 5210.639137756126, 2093.110428068065, 2132.413586263759, 2243.8429309948147, 2454.6072628379375, 2516.16320086338, 4668.692766025519, 4765.9523404766205, 4776.0, 4778.880527986828, 4975.5522003019905, 4333.891652708645, 4482.596566309048, 4530.88672783183, 4626.930596041062, 4680.672352935262, 4973.35346404965, 4978.850634480971, 4989.827390563142, 4990.0, 4999.375078115236, 4260.659147268273, 4425.336199570091, 4741.336212930261, 4766.61867050642, 4840.6127509798425, 4049.1432781433737, 4309.9353509698, 4391.692581519263, 4440.444944381952, 4789.4971028041555, 4120.979395103005, 4327.415798867239, 4431.667624928208, 4627.616777359595, 4711.599514041345, 2881.884724610992, 2949.867255973443, 3203.359328134336, 3434.492820241288, 3449.6377880322207, 4339.153864996296, 4363.956360436482, 4477.485089214782, 4673.228917228756, 4721.504242054672, 4520.615747661485, 4625.3579979106935, 4864.781084851261, 4933.629977751755, 5321.5476684482255, 4626.421697287839, 4834.936314010912, 5148.944466384307, 5305.230741542532, 5574.442555744556, 4861.975690121527, 5058.468860769566, 5447.700376479332, 5456.135528847387, 5541.833744987657, 4451.375248034509, 4634.258518637137, 5360.758765855624, 5509.752061157337, 5567.10926251814, 5410.729463526729, 5413.404525502113, 5424.2406063151975, 5433.8369848904595, 5477.315335583052], "value": 3837.5842615763645, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.1842943755805, 725.9346658800707, 764.3884892086329, 796.1520980156133, 799.7041094794927, 15757.05457672539, 16320.510384688463, 16440.260188291526, 16444.83555164448, 16457.50627481176, 14331.925105617069, 14442.42230310789, 14444.855551444482, 14458.277086145703, 14872.256387180652, 679.5243329669252, 703.5391818358983, 838.5681374092347, 844.4342290665269, 868.6308318964446, 854.598338780772, 858.7938894665298, 866.7919699272161, 867.865480850466, 869.1091631078164, 919.0258326174243, 919.8942121656029, 920.742192186189, 921.8617207418873, 922.7001224601997, 730.7077169132336, 748.5658318175446, 758.6055251269364, 914.9176574108329, 915.3363811236833, 556.8969740597962, 665.411111166621, 676.7293082766884, 685.4036987820627, 685.9519833611649, 14961.925190374088, 15011.924940375338, 15012.624684382869, 15014.849851501564, 15029.0, 11745.765084698343, 13856.0, 14199.07705999117, 14214.786778198335, 14282.64293392663, 12547.74904501914, 12760.744785104338, 12831.230126192459, 12944.676383090404, 13160.210387376788, 4474.731516109011, 4478.686491945565, 4537.478190008125, 4571.108633816407, 4587.082583483317, 86598.13401865964, 117599.0, 117681.05797355049, 125245.0, 125669.74330256671, 11091.889081109248, 11121.777564448666, 11264.057579846987, 11350.829737553944, 11474.0, 22662.0, 22691.978860951298, 22698.773012269998, 22702.205422810122, 22714.886425567765, 7470.0, 7785.066419616565, 7912.169222231698, 8065.314448271963, 8068.8386232275025, 4281.871543853689, 4362.956370436255, 4373.234683930299, 4536.047430039728, 4562.954370456253, 3511.701505372021, 3610.5125808016105, 3614.493970844084, 3616.0959760059986, 3616.5479315085613, 624.8344188789974, 632.9113924050637, 650.7201903181677, 654.502578040683, 663.8174502011985, 528.2630730131445, 551.3411473289381, 555.6554935939663, 567.9346875109333, 596.7821745063108, 7638.541687498768, 8683.869741953877, 9001.324900632479, 9116.81766364669, 9230.815383692423, 517.8161752577856, 551.1484756051909, 556.5742207211487, 627.4603840696997, 636.5321488705797, 4369.820148559871, 4387.539308372575, 4438.622717069085, 4475.395821564112, 4534.342520334533, 160371.19814400852, 162914.0, 165279.513663223, 165795.0, 167791.16104419643, 110516.99999999838, 114120.42939785248, 114136.42931785453, 121167.78832211741, 144272.0, 34277.65722342745, 97956.53065204028, 105350.4732476348, 109322.0, 110142.34786478209, 9625.374350667184, 9933.850992234979, 10115.949420252851, 10135.79728405413, 10181.440020798927, 58308.70845645745, 61814.0, 62070.379296208266, 63771.681141593996, 64509.0, 67378.0, 70426.0, 88562.0, 92842.0, 94161.5291923536, 11355.48900299472, 11545.422728863356, 11743.295402275891, 11768.94079532846, 12738.617841464771, 12888.806667900177, 13111.475540978254, 15268.9311748181, 15380.769288460906, 15542.300596473411, 14194.43222271139, 14211.28943552839, 14251.714965700421, 14395.42418303256, 14508.419663213353, 13871.0, 13992.650183745487, 14054.648633784238, 14191.929040354733, 14590.197539135024, 13408.0, 13623.455061797416, 13945.860541394457, 14182.290885455892, 14221.573352799018, 10052.195824333889, 11219.49512271966, 11298.943505282421, 11337.546498139982, 11475.483603237708, 9584.329096963425, 11289.204859882057, 11463.254888432559, 11644.417779110841, 11669.474873630537], "value": 7776.958043316288, "unit": "score"}], "name": "dromaeo_css", "value": 7341.768065634664, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 116.0, 113.0, 114.0, 114.0, 113.0, 112.0, 112.0, 123.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 131.0, 163.0, 112.0, 132.0, 127.0, 108.0, 155.0, 108.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 190.0, 185.0, 183.0, 184.0, 186.0, 183.0, 182.0, 184.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 78.0, 156.0, 58.0, 57.0, 58.0, 70.0, 140.0, 58.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 133.0, 70.0, 79.0, 84.0, 124.0, 67.0, 69.0, 118.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 87.0, 88.0, 86.0, 89.0, 87.0, 90.0, 85.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 96.0, 95.0, 94.0, 95.0, 96.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 83.0, 86.0, 84.0, 84.0, 86.0, 85.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 53.0, 52.0, 56.0, 53.0, 55.0, 52.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 76.0, 73.0, 81.0, 73.0, 71.0, 73.0, 72.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 86.0, 88.0, 86.0, 86.0, 89.0, 89.0, 86.0, 90.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 129.0, 138.0, 153.0, 154.0, 132.0, 139.0, 138.0, 184.0, 141.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 174.0, 177.0, 239.0, 190.0, 169.0, 171.0, 171.0, 171.0, 172.0], "value": 180.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 67.0, 67.0, 65.0, 65.0, 66.0, 68.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1475.3999999999999, "alertThreshold": 2.0}]} 16:18:50 INFO - Return code: 0 16:18:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:18:50 INFO - # TBPL SUCCESS # 16:18:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:18:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:18:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:18:50 INFO - 2016-09-02 16:18:50.675 screenresolution[1953:20399] starting screenresolution argv=screenresolution get 16:18:50 INFO - 2016-09-02 16:18:50.678 screenresolution[1953:20399] Display 0: 1600x1200x32@60 16:18:50 INFO - 2016-09-02 16:18:50.684 screenresolution[1954:20402] starting screenresolution argv=screenresolution list 16:18:50 INFO - Available Modes on Display 0 16:18:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:18:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:18:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:18:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:18:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:18:50 INFO - Intel Iris: 16:18:50 INFO - Chipset Model: Intel Iris 16:18:50 INFO - Type: GPU 16:18:50 INFO - Bus: Built-In 16:18:50 INFO - VRAM (Dynamic, Max): 1536 MB 16:18:50 INFO - Vendor: Intel (0x8086) 16:18:50 INFO - Device ID: 0x0a2e 16:18:50 INFO - Revision ID: 0x0009 16:18:50 INFO - Displays: 16:18:50 INFO - Display: 16:18:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:18:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:18:50 INFO - Display Serial Number: 1600x1200 60 16:18:50 INFO - Main Display: Yes 16:18:50 INFO - Mirror: Off 16:18:50 INFO - Online: Yes 16:18:50 INFO - Rotation: Supported 16:18:50 INFO - Return code: 0 16:18:50 INFO - Running post-action listener: _resource_record_post_action 16:18:50 INFO - [mozharness: 2016-09-02 23:18:50.918992Z] Finished run-tests step (success) 16:18:50 INFO - Running post-run listener: _resource_record_post_run 16:18:50 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 606208; Write bytes: 705553408; Read time: 6; Write time: 1997 16:18:50 INFO - TinderboxPrint: CPU usage
53.6% 16:18:50 INFO - TinderboxPrint: I/O read bytes / time
606,208 / 6 16:18:50 INFO - TinderboxPrint: I/O write bytes / time
705,553,408 / 1,997 16:18:50 INFO - TinderboxPrint: CPU idle
2,249.2 (46.4%) 16:18:50 INFO - TinderboxPrint: CPU system
969.7 (20.0%) 16:18:50 INFO - TinderboxPrint: CPU user
1,626.7 (33.6%) 16:18:50 INFO - TinderboxPrint: Swap in / out
405,757,952 / 0 16:18:50 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400034304; Write bytes: 396938752; Read time: 29613; Write time: 866 16:18:50 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 606208; Write bytes: 292894208; Read time: 6; Write time: 1088 16:18:51 INFO - Running post-run listener: _upload_blobber_files 16:18:51 INFO - Blob upload gear active. 16:18:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:18:51 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 16:18:51 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:18:51 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:18:51 INFO - (blobuploader) - INFO - Open directory for files ... 16:18:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:18:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:18:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:18:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:18:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:18:53 INFO - (blobuploader) - INFO - Done attempting. 16:18:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:18:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:18:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:18:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:18:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:18:54 INFO - (blobuploader) - INFO - Done attempting. 16:18:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:18:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:18:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:18:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:18:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:18:56 INFO - (blobuploader) - INFO - Done attempting. 16:18:56 INFO - (blobuploader) - INFO - Iteration through files over. 16:18:56 INFO - Return code: 0 16:18:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:18:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:18:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/570ff68bb61b77abecd53d553b425b7dfa3274f4f198e52cd5e2773404f748f5129d46206f86dfba6bf0b5d6fcddce7bfabfeb2d8d4d37295b761519ea7ad2ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0140d6edf86d242bfd289b88a179cfc026369922beb10c33a843f8b455cec291e88a2a9ef6634a0c494b9b8a194c62eeb8162ced7252cbf4ad7f99e4f4d3748a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2553173891de6052611faa9657a26e41a9832f16d78bcb25922870a1b4a6ac8fbb252264a23edb695d15bff5f0d40fb4e8ea71a3d6ecd673d02c99164692c36"} 16:18:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:18:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:18:56 INFO - Contents: 16:18:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/570ff68bb61b77abecd53d553b425b7dfa3274f4f198e52cd5e2773404f748f5129d46206f86dfba6bf0b5d6fcddce7bfabfeb2d8d4d37295b761519ea7ad2ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0140d6edf86d242bfd289b88a179cfc026369922beb10c33a843f8b455cec291e88a2a9ef6634a0c494b9b8a194c62eeb8162ced7252cbf4ad7f99e4f4d3748a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2553173891de6052611faa9657a26e41a9832f16d78bcb25922870a1b4a6ac8fbb252264a23edb695d15bff5f0d40fb4e8ea71a3d6ecd673d02c99164692c36"} 16:18:56 INFO - Running post-run listener: copy_logs_to_upload_dir 16:18:56 INFO - Copying logs to upload dir... 16:18:56 INFO - mkdir: /builds/slave/test/build/upload/logs 16:18:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.508149 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-02 16:18:56.982957) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 16:18:56.986743) ========= 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.Get7yemWuN/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.9HcnmjwfJN/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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/Fx-Team/sha512/570ff68bb61b77abecd53d553b425b7dfa3274f4f198e52cd5e2773404f748f5129d46206f86dfba6bf0b5d6fcddce7bfabfeb2d8d4d37295b761519ea7ad2ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0140d6edf86d242bfd289b88a179cfc026369922beb10c33a843f8b455cec291e88a2a9ef6634a0c494b9b8a194c62eeb8162ced7252cbf4ad7f99e4f4d3748a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2553173891de6052611faa9657a26e41a9832f16d78bcb25922870a1b4a6ac8fbb252264a23edb695d15bff5f0d40fb4e8ea71a3d6ecd673d02c99164692c36"} build_url:https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010639 build_url: 'https://queue.taskcluster.net/v1/task/JY_8oXNbRmS2aBBFL2GMMA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/570ff68bb61b77abecd53d553b425b7dfa3274f4f198e52cd5e2773404f748f5129d46206f86dfba6bf0b5d6fcddce7bfabfeb2d8d4d37295b761519ea7ad2ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0140d6edf86d242bfd289b88a179cfc026369922beb10c33a843f8b455cec291e88a2a9ef6634a0c494b9b8a194c62eeb8162ced7252cbf4ad7f99e4f4d3748a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2553173891de6052611faa9657a26e41a9832f16d78bcb25922870a1b4a6ac8fbb252264a23edb695d15bff5f0d40fb4e8ea71a3d6ecd673d02c99164692c36"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 16:18:57.023112) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 16:18:57.023824) ========= 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.Get7yemWuN/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.9HcnmjwfJN/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.004472 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 16:18:57.076070) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 16:18:57.076689) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 16:18:57.077511) ========= ========= Total master_lag: 0.18 =========