builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0343 starttime: 1473391929.96 results: success (0) buildid: 20160908185019 builduid: 51ee48dc79864783b7a3468cda29110f revision: 76ded056a9dec866e29ba13b4248e4e7e16547f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 20:32:09.956160) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 20:32:09.956547) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 20:32:09.956813) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 20:32:09.996642) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:32:09.996902) ========= 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.6tinEH3tNo/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.Xc08ATsmjF/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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-08 20:32:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2016-09-08 20:32:10 (18.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110273 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:32:10.130815) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:32:10.131113) ========= 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.6tinEH3tNo/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.Xc08ATsmjF/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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.025760 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:32:10.179833) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 20:32:10.180154) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 76ded056a9dec866e29ba13b4248e4e7e16547f5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 76ded056a9dec866e29ba13b4248e4e7e16547f5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6tinEH3tNo/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.Xc08ATsmjF/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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-08 20:32:10,266 truncating revision to first 12 chars 2016-09-08 20:32:10,267 Setting DEBUG logging. 2016-09-08 20:32:10,267 attempt 1/10 2016-09-08 20:32:10,267 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/76ded056a9de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 20:32:11,131 unpacking tar archive at: autoland-76ded056a9de/testing/mozharness/ program finished with exit code 0 elapsedTime=1.136718 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 20:32:11.339285) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 20:32:11.339584) ========= script_repo_revision: 76ded056a9dec866e29ba13b4248e4e7e16547f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 20:32:11.339956) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 20:32:11.340233) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 20:32:11.358498) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-08 20:32:11.358803) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6tinEH3tNo/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.Xc08ATsmjF/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 20:32:11 INFO - MultiFileLogger online at 20160908 20:32:11 in /builds/slave/test 20:32:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 20:32:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:32:11 INFO - {'append_to_log': False, 20:32:11 INFO - 'base_work_dir': '/builds/slave/test', 20:32:11 INFO - 'blob_upload_branch': 'Autoland', 20:32:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:32:11 INFO - 'branch': 'Autoland', 20:32:11 INFO - 'buildbot_json_path': 'buildprops.json', 20:32:11 INFO - 'config_files': ('talos/mac_config.py',), 20:32:11 INFO - 'default_actions': ('clobber', 20:32:11 INFO - 'read-buildbot-config', 20:32:11 INFO - 'download-and-extract', 20:32:11 INFO - 'populate-webroot', 20:32:11 INFO - 'create-virtualenv', 20:32:11 INFO - 'install', 20:32:11 INFO - 'run-tests'), 20:32:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:32:11 INFO - 'download_minidump_stackwalk': True, 20:32:11 INFO - 'download_symbols': 'ondemand', 20:32:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:32:11 INFO - 'tooltool.py': '/tools/tooltool.py', 20:32:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:32:11 INFO - '/tools/misc-python/virtualenv.py')}, 20:32:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:32:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:32:11 INFO - 'installer_path': 'installer.exe', 20:32:11 INFO - 'log_level': 'info', 20:32:11 INFO - 'log_name': 'talos', 20:32:11 INFO - 'log_to_console': True, 20:32:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:32:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:32:11 INFO - 'opt_config_files': (), 20:32:11 INFO - 'pip_index': False, 20:32:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:32:11 INFO - 'cmd': ('bash', 20:32:11 INFO - '-c', 20:32:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:32:11 INFO - 'enabled': True, 20:32:11 INFO - 'halt_on_failure': False, 20:32:11 INFO - 'name': 'check_screen_resolution'},), 20:32:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:32:11 INFO - 'cmd': ('bash', 20:32:11 INFO - '-c', 20:32:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:32:11 INFO - 'enabled': True, 20:32:11 INFO - 'halt_on_failure': False, 20:32:11 INFO - 'name': 'check_screen_resolution'},), 20:32:11 INFO - 'run_cmd_checks_enabled': True, 20:32:11 INFO - 'sps_profile': False, 20:32:11 INFO - 'sps_profile_interval': 0, 20:32:11 INFO - 'suite': 'dromaeojs', 20:32:11 INFO - 'system_bits': '32', 20:32:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:32:11 INFO - 'title': 't-yosemite-r7-0343', 20:32:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:32:11 INFO - 'use_talos_json': True, 20:32:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:32:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:32:11 INFO - 'work_dir': 'build'} 20:32:11 INFO - [mozharness: 2016-09-09 03:32:11.557609Z] Running clobber step. 20:32:11 INFO - Running pre-action listener: _resource_record_pre_action 20:32:11 INFO - Running main action method: clobber 20:32:11 INFO - rmtree: /builds/slave/test/build 20:32:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:32:13 INFO - Running post-action listener: _resource_record_post_action 20:32:13 INFO - [mozharness: 2016-09-09 03:32:13.584173Z] Finished clobber step (success) 20:32:13 INFO - [mozharness: 2016-09-09 03:32:13.584299Z] Running read-buildbot-config step. 20:32:13 INFO - Running pre-action listener: _resource_record_pre_action 20:32:13 INFO - Running main action method: read_buildbot_config 20:32:13 INFO - Using buildbot properties: 20:32:13 INFO - { 20:32:13 INFO - "project": "", 20:32:13 INFO - "product": "firefox", 20:32:13 INFO - "script_repo_revision": "production", 20:32:13 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 20:32:13 INFO - "repository": "", 20:32:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 20:32:13 INFO - "buildid": "20160908185019", 20:32:13 INFO - "pgo_build": "False", 20:32:13 INFO - "basedir": "/builds/slave/test", 20:32:13 INFO - "buildnumber": 190, 20:32:13 INFO - "slavename": "t-yosemite-r7-0343", 20:32:13 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 20:32:13 INFO - "platform": "yosemite_r7", 20:32:13 INFO - "scheduler": "tests-autoland-macosx64-talos", 20:32:13 INFO - "branch": "autoland", 20:32:13 INFO - "repo_path": "integration/autoland", 20:32:13 INFO - "revision": "76ded056a9dec866e29ba13b4248e4e7e16547f5", 20:32:13 INFO - "stage_platform": "macosx64", 20:32:13 INFO - "builduid": "51ee48dc79864783b7a3468cda29110f", 20:32:13 INFO - "slavebuilddir": "test" 20:32:13 INFO - } 20:32:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 20:32:13 INFO - Running post-action listener: _resource_record_post_action 20:32:13 INFO - [mozharness: 2016-09-09 03:32:13.587195Z] Finished read-buildbot-config step (success) 20:32:13 INFO - [mozharness: 2016-09-09 03:32:13.587306Z] Running download-and-extract step. 20:32:13 INFO - Running pre-action listener: _resource_record_pre_action 20:32:13 INFO - Running main action method: download_and_extract 20:32:13 INFO - mkdir: /builds/slave/test/build/tests 20:32: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')]} 20:32:13 INFO - trying https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 20:32:13 INFO - Downloading https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/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 20:32:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/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 20:32:14 INFO - Downloaded 1199 bytes. 20:32:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 20:32:14 INFO - Using the following test package requirements: 20:32:14 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:32:14 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:32:14 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 20:32:14 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:32:14 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 20:32:14 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:32:14 INFO - u'jsshell-mac.zip'], 20:32:14 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:32:14 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 20:32:14 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:32:14 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:32:14 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 20:32:14 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:32:14 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 20:32:14 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:32:14 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 20:32:14 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:32:14 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 20:32:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:32:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:32:14 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 20:32:21 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 20:32:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:32:22 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 20:32:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 20:32:32 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 20:32:32 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')]} 20:32:32 INFO - trying https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:32:32 INFO - Downloading https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 20:32:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/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 20:32:36 INFO - Downloaded 111545287 bytes. 20:32:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:32:36 INFO - mkdir: /builds/slave/test/properties 20:32:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:32:36 INFO - Writing to file /builds/slave/test/properties/build_url 20:32:36 INFO - Contents: 20:32:36 INFO - build_url:https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:32:38 INFO - Running post-action listener: _resource_record_post_action 20:32:38 INFO - Running post-action listener: set_extra_try_arguments 20:32:38 INFO - [mozharness: 2016-09-09 03:32:38.282182Z] Finished download-and-extract step (success) 20:32:38 INFO - [mozharness: 2016-09-09 03:32:38.282333Z] Running populate-webroot step. 20:32:38 INFO - Running pre-action listener: _resource_record_pre_action 20:32:38 INFO - Running main action method: populate_webroot 20:32:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:32:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:32:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:32:38 INFO - '--tppagecycles', 20:32:38 INFO - '7'], 20:32:38 INFO - 'tests': ['tsvgm']}}, 20:32:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:32:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:32:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:32:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:32:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:32:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:32:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:38 INFO - 'talos_options': ['--disable-e10s'], 20:32:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:32:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:32:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:38 INFO - 'talos_options': ['--disable-e10s'], 20:32:38 INFO - 'tests': ['damp', 'tps']}, 20:32:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:38 INFO - 'tests': ['damp', 'tps']}, 20:32:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:32:38 INFO - 'tests': ['dromaeo_dom']}, 20:32:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:32:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:32:38 INFO - 'tests': ['basic_compositor_video']}, 20:32:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:32:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:32:38 INFO - 'tests': ['a11yr', 20:32:38 INFO - 'ts_paint', 20:32:38 INFO - 'tpaint', 20:32:38 INFO - 'sessionrestore', 20:32:38 INFO - 'sessionrestore_no_auto_restore', 20:32:38 INFO - 'tabpaint']}, 20:32:38 INFO - 'other-e10s': {'tests': ['a11yr', 20:32:38 INFO - 'ts_paint', 20:32:38 INFO - 'tpaint', 20:32:38 INFO - 'sessionrestore', 20:32:38 INFO - 'sessionrestore_no_auto_restore', 20:32:38 INFO - 'tabpaint']}, 20:32:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:32:38 INFO - 'ts_paint', 20:32:38 INFO - 'tpaint', 20:32:38 INFO - 'sessionrestore', 20:32:38 INFO - 'sessionrestore_no_auto_restore', 20:32:38 INFO - 'tabpaint']}, 20:32:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:32:38 INFO - 'ts_paint', 20:32:38 INFO - 'tpaint', 20:32:38 INFO - 'sessionrestore', 20:32:38 INFO - 'sessionrestore_no_auto_restore', 20:32:38 INFO - 'tabpaint']}, 20:32:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:32:38 INFO - 'tests': ['a11yr', 20:32:38 INFO - 'ts_paint', 20:32:38 INFO - 'tpaint', 20:32:38 INFO - 'sessionrestore', 20:32:38 INFO - 'sessionrestore_no_auto_restore', 20:32:38 INFO - 'tabpaint']}, 20:32:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:32:38 INFO - 'tests': ['a11yr', 20:32:38 INFO - 'ts_paint', 20:32:38 INFO - 'tpaint', 20:32:38 INFO - 'sessionrestore', 20:32:38 INFO - 'sessionrestore_no_auto_restore', 20:32:38 INFO - 'tabpaint']}, 20:32:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:32:38 INFO - 'tests': ['tsvgx', 20:32:38 INFO - 'tsvgr_opacity', 20:32:38 INFO - 'tart', 20:32:38 INFO - 'tscrollx', 20:32:38 INFO - 'cart']}, 20:32:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:32:38 INFO - 'tsvgr_opacity', 20:32:38 INFO - 'tart', 20:32:38 INFO - 'tscrollx', 20:32:38 INFO - 'cart']}, 20:32:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:38 INFO - 'talos_options': ['--disable-e10s'], 20:32:38 INFO - 'tests': ['tp5o']}, 20:32:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:38 INFO - 'tests': ['tp5o']}, 20:32:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:38 INFO - 'talos_options': ['--disable-e10s', 20:32:38 INFO - '--xperf_path', 20:32:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:32:38 INFO - 'tests': ['tp5n']}, 20:32:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:38 INFO - 'talos_options': ['--xperf_path', 20:32:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:32:38 INFO - 'tests': ['tp5n']}}, 20:32:38 INFO - 'talos.zip': {'path': '', 20:32:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:32:38 INFO - Running post-action listener: _resource_record_post_action 20:32:38 INFO - [mozharness: 2016-09-09 03:32:38.300959Z] Finished populate-webroot step (success) 20:32:38 INFO - [mozharness: 2016-09-09 03:32:38.301090Z] Running create-virtualenv step. 20:32:38 INFO - Running pre-action listener: _resource_record_pre_action 20:32:38 INFO - Running main action method: create_virtualenv 20:32:38 INFO - Creating virtualenv /builds/slave/test/build/venv 20:32:38 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 20:32:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:32:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:32:38 INFO - Using real prefix '/tools/python27' 20:32:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:32:39 INFO - Installing distribute.............................................................................................................................................................................................done. 20:32:41 INFO - Installing pip.................done. 20:32:41 INFO - Return code: 0 20:32:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab9473ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c48dd30>, '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 0x10c1f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c485b20>, '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.6tinEH3tNo/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.Xc08ATsmjF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 20:32:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:32:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6tinEH3tNo/Render', 20:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:42 INFO - 'HOME': '/Users/cltbld', 20:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:42 INFO - 'LOGNAME': 'cltbld', 20:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:42 INFO - 'MOZ_NO_REMOTE': '1', 20:32:42 INFO - 'NO_EM_RESTART': '1', 20:32:42 INFO - 'PAGER': '/bin/cat', 20:32:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:42 INFO - 'PWD': '/builds/slave/test', 20:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:42 INFO - 'SHELL': '/bin/bash', 20:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xc08ATsmjF/Listeners', 20:32:42 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 20:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:42 INFO - 'USER': 'cltbld', 20:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:42 INFO - 'XPC_FLAGS': '0x0', 20:32:42 INFO - 'XPC_SERVICE_NAME': '0', 20:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:42 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 20:32:42 INFO - Cleaning up... 20:32:42 INFO - Return code: 0 20:32:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab9473ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c48dd30>, '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 0x10c1f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c485b20>, '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.6tinEH3tNo/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.Xc08ATsmjF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 20:32:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:32:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6tinEH3tNo/Render', 20:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:42 INFO - 'HOME': '/Users/cltbld', 20:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:42 INFO - 'LOGNAME': 'cltbld', 20:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:42 INFO - 'MOZ_NO_REMOTE': '1', 20:32:42 INFO - 'NO_EM_RESTART': '1', 20:32:42 INFO - 'PAGER': '/bin/cat', 20:32:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:42 INFO - 'PWD': '/builds/slave/test', 20:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:42 INFO - 'SHELL': '/bin/bash', 20:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xc08ATsmjF/Listeners', 20:32:42 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 20:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:42 INFO - 'USER': 'cltbld', 20:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:42 INFO - 'XPC_FLAGS': '0x0', 20:32:42 INFO - 'XPC_SERVICE_NAME': '0', 20:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:42 INFO - Downloading/unpacking psutil>=3.1.1 20:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:32:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:32:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:32:45 INFO - Installing collected packages: psutil 20:32:45 INFO - Running setup.py install for psutil 20:32:45 INFO - building 'psutil._psutil_osx' extension 20:32:45 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 20:32:46 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 20:32:46 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 20:32:46 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 20:32:46 INFO - building 'psutil._psutil_posix' extension 20:32:46 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 20:32:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:32:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:32:46 INFO - ^ 20:32:46 INFO - 1 warning generated. 20:32:46 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 20:32:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:32:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:32:46 INFO - Successfully installed psutil 20:32:46 INFO - Cleaning up... 20:32:46 INFO - Return code: 0 20:32:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:32:46 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')]} 20:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:46 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')]} 20:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:46 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 0x10c529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab9473ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c48dd30>, '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 0x10c1f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c485b20>, '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.6tinEH3tNo/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.Xc08ATsmjF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 20:32:46 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 20:32:46 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 20:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6tinEH3tNo/Render', 20:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:46 INFO - 'HOME': '/Users/cltbld', 20:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:46 INFO - 'LOGNAME': 'cltbld', 20:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:46 INFO - 'MOZ_NO_REMOTE': '1', 20:32:46 INFO - 'NO_EM_RESTART': '1', 20:32:46 INFO - 'PAGER': '/bin/cat', 20:32:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:46 INFO - 'PWD': '/builds/slave/test', 20:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:46 INFO - 'SHELL': '/bin/bash', 20:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xc08ATsmjF/Listeners', 20:32:46 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 20:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:46 INFO - 'USER': 'cltbld', 20:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:46 INFO - 'XPC_FLAGS': '0x0', 20:32:46 INFO - 'XPC_SERVICE_NAME': '0', 20:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:32:50 INFO - Installing collected packages: mozsystemmonitor 20:32:50 INFO - Running setup.py install for mozsystemmonitor 20:32:50 INFO - Successfully installed mozsystemmonitor 20:32:50 INFO - Cleaning up... 20:32:50 INFO - Return code: 0 20:32:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab9473ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c48dd30>, '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 0x10c1f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c485b20>, '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.6tinEH3tNo/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.Xc08ATsmjF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 20:32:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:32:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6tinEH3tNo/Render', 20:32:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:50 INFO - 'HOME': '/Users/cltbld', 20:32:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:50 INFO - 'LOGNAME': 'cltbld', 20:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:50 INFO - 'MOZ_NO_REMOTE': '1', 20:32:50 INFO - 'NO_EM_RESTART': '1', 20:32:50 INFO - 'PAGER': '/bin/cat', 20:32:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:50 INFO - 'PWD': '/builds/slave/test', 20:32:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:50 INFO - 'SHELL': '/bin/bash', 20:32:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xc08ATsmjF/Listeners', 20:32:50 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 20:32:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:50 INFO - 'USER': 'cltbld', 20:32:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:50 INFO - 'XPC_FLAGS': '0x0', 20:32:50 INFO - 'XPC_SERVICE_NAME': '0', 20:32:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:50 INFO - Downloading/unpacking jsonschema==2.5.1 20:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:32:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:32:53 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 20:32:53 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 20:32:53 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 20:32:53 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 20:32:53 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 20:32:53 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 20:32:53 INFO - Downloading functools32-3.2.3-2.tar.gz 20:32:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:32:53 INFO - warning: no files found matching '*.txt' 20:32:53 INFO - no previously-included directories found matching 'build' 20:32:53 INFO - no previously-included directories found matching 'dist' 20:32:53 INFO - no previously-included directories found matching '.git*' 20:32:53 INFO - Installing collected packages: jsonschema, functools32 20:32:53 INFO - Running setup.py install for functools32 20:32:53 INFO - warning: no files found matching '*.txt' 20:32:53 INFO - no previously-included directories found matching 'build' 20:32:53 INFO - no previously-included directories found matching 'dist' 20:32:53 INFO - no previously-included directories found matching '.git*' 20:32:53 INFO - Successfully installed jsonschema functools32 20:32:53 INFO - Cleaning up... 20:32:54 INFO - Return code: 0 20:32:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:54 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 0x10c529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab9473ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c48dd30>, '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 0x10c1f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c485b20>, '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.6tinEH3tNo/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.Xc08ATsmjF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 20:32:54 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 20:32:54 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 20:32:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6tinEH3tNo/Render', 20:32:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:54 INFO - 'HOME': '/Users/cltbld', 20:32:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:54 INFO - 'LOGNAME': 'cltbld', 20:32:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:54 INFO - 'MOZ_NO_REMOTE': '1', 20:32:54 INFO - 'NO_EM_RESTART': '1', 20:32:54 INFO - 'PAGER': '/bin/cat', 20:32:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:54 INFO - 'PWD': '/builds/slave/test', 20:32:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:54 INFO - 'SHELL': '/bin/bash', 20:32:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xc08ATsmjF/Listeners', 20:32:54 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 20:32:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:54 INFO - 'USER': 'cltbld', 20:32:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:54 INFO - 'XPC_FLAGS': '0x0', 20:32:54 INFO - 'XPC_SERVICE_NAME': '0', 20:32:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:32:54 INFO - Cleaning up... 20:32:54 INFO - Return code: 0 20:32:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:54 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 0x10c529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab9473ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c48dd30>, '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 0x10c1f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c485b20>, '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.6tinEH3tNo/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.Xc08ATsmjF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 20:32:54 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 20:32:54 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 20:32:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6tinEH3tNo/Render', 20:32:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:54 INFO - 'HOME': '/Users/cltbld', 20:32:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:54 INFO - 'LOGNAME': 'cltbld', 20:32:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:54 INFO - 'MOZ_NO_REMOTE': '1', 20:32:54 INFO - 'NO_EM_RESTART': '1', 20:32:54 INFO - 'PAGER': '/bin/cat', 20:32:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:54 INFO - 'PWD': '/builds/slave/test', 20:32:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:54 INFO - 'SHELL': '/bin/bash', 20:32:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xc08ATsmjF/Listeners', 20:32:54 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 20:32:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:54 INFO - 'USER': 'cltbld', 20:32:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:54 INFO - 'XPC_FLAGS': '0x0', 20:32:54 INFO - 'XPC_SERVICE_NAME': '0', 20:32:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:54 INFO - Downloading/unpacking blobuploader==1.2.4 20:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:56 INFO - Downloading blobuploader-1.2.4.tar.gz 20:32:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:32:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:32:57 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 20:32:57 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 20:32:57 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 20:32:57 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 20:32:57 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 20:32:57 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 20:32:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:32:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:32:57 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 20:32:57 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 20:32:57 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 20:32:57 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 20:32:57 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 20:32:57 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 20:32:58 INFO - Downloading docopt-0.6.1.tar.gz 20:32:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:32:58 INFO - Installing collected packages: blobuploader, requests, docopt 20:32:58 INFO - Running setup.py install for blobuploader 20:32:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:32:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:32:58 INFO - Running setup.py install for requests 20:32:58 INFO - Running setup.py install for docopt 20:32:59 INFO - Successfully installed blobuploader requests docopt 20:32:59 INFO - Cleaning up... 20:32:59 INFO - Return code: 0 20:32:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:59 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 0x10c529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab9473ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c48dd30>, '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 0x10c1f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c485b20>, '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.6tinEH3tNo/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.Xc08ATsmjF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 20:32:59 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 20:32:59 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 20:32:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6tinEH3tNo/Render', 20:32:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:59 INFO - 'HOME': '/Users/cltbld', 20:32:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:59 INFO - 'LOGNAME': 'cltbld', 20:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:59 INFO - 'MOZ_NO_REMOTE': '1', 20:32:59 INFO - 'NO_EM_RESTART': '1', 20:32:59 INFO - 'PAGER': '/bin/cat', 20:32:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:59 INFO - 'PWD': '/builds/slave/test', 20:32:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:59 INFO - 'SHELL': '/bin/bash', 20:32:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xc08ATsmjF/Listeners', 20:32:59 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 20:32:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:59 INFO - 'USER': 'cltbld', 20:32:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:59 INFO - 'XPC_FLAGS': '0x0', 20:32:59 INFO - 'XPC_SERVICE_NAME': '0', 20:32:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:32:59 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-Bx1JJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:32:59 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-Pr_AmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:32:59 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-jNqjJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:32:59 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-7G4Uk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:32:59 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-TmfEmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:32:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:32:59 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-5QBX9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:33:00 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-LV5GcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:33:00 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-JNWJZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:33:00 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-PKOB1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:33:00 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-BHKyyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:33:00 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-HRmOyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:33:00 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-I0hPTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:33:00 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-oqlev3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:33:00 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-EFaqqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:33:00 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-eRJy3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:33:00 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-GUxSTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:33:01 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-h4Fl6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:33:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:33:01 INFO - Running setup.py install for manifestparser 20:33:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:33:01 INFO - Running setup.py install for mozcrash 20:33:01 INFO - Running setup.py install for mozdebug 20:33:01 INFO - Running setup.py install for mozdevice 20:33:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:33:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:33:01 INFO - Running setup.py install for mozfile 20:33:01 INFO - Running setup.py install for mozhttpd 20:33:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:33:02 INFO - Running setup.py install for mozinfo 20:33:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:33:02 INFO - Running setup.py install for mozInstall 20:33:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:33:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:33:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:33:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:33:02 INFO - Running setup.py install for mozleak 20:33:02 INFO - Running setup.py install for mozlog 20:33:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:33:02 INFO - Running setup.py install for moznetwork 20:33:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:33:02 INFO - Running setup.py install for mozprocess 20:33:02 INFO - Running setup.py install for mozprofile 20:33:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:33:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:33:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:33:03 INFO - Running setup.py install for mozrunner 20:33:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:33:03 INFO - Running setup.py install for mozscreenshot 20:33:03 INFO - Running setup.py install for moztest 20:33:03 INFO - Running setup.py install for mozversion 20:33:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:33:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:33:03 INFO - Cleaning up... 20:33:03 INFO - Return code: 0 20:33:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:33: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')]} 20:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:33:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33: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')]} 20:33:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:33:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:03 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 0x10c529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab9473ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c48dd30>, '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 0x10c1f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c485b20>, '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.6tinEH3tNo/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.Xc08ATsmjF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 20:33:03 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 20:33:03 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 20:33:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6tinEH3tNo/Render', 20:33:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:03 INFO - 'HOME': '/Users/cltbld', 20:33:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:03 INFO - 'LOGNAME': 'cltbld', 20:33:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:03 INFO - 'MOZ_NO_REMOTE': '1', 20:33:03 INFO - 'NO_EM_RESTART': '1', 20:33:03 INFO - 'PAGER': '/bin/cat', 20:33:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:03 INFO - 'PWD': '/builds/slave/test', 20:33:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:03 INFO - 'SHELL': '/bin/bash', 20:33:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xc08ATsmjF/Listeners', 20:33:03 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 20:33:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:03 INFO - 'USER': 'cltbld', 20:33:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:03 INFO - 'XPC_FLAGS': '0x0', 20:33:03 INFO - 'XPC_SERVICE_NAME': '0', 20:33:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:33:03 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-f0jUvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:33:04 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)) 20:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:33:04 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-fGBhJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:33:04 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)) 20:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:33:04 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-2baWF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:33:04 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)) 20:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:33:04 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-5mhB_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:33:04 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)) 20:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:33:04 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-cmltmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:33:04 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)) 20:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:33:04 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-DZkErE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:33:04 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)) 20:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:33:04 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-GRO3sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:33:04 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)) 20:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:33:04 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-vD083J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:33:04 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)) 20:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:33:04 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-kw9NSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:33:04 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)) 20:33:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:33:04 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-gZ_Oyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:33:05 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)) 20:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:33:05 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-jxoLlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:33:05 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)) 20:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:33:05 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-jb6vlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:33:05 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)) 20:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:33:05 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-rqj3n9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:33:05 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)) 20:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:33:05 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-3N8x9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:33:05 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)) 20:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:33:05 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-SLDHSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:33:05 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)) 20:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:33:05 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-4Vf4bY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:33:05 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)) 20:33:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:33:05 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-xy7zht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:33:05 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)) 20:33:05 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)) 20:33:05 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)) 20:33:05 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)) 20:33:05 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)) 20:33:05 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)) 20:33:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:33:05 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 20:33:05 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 20:33:05 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 20:33:05 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 20:33:05 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 20:33:05 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 20:33:08 INFO - Downloading blessings-1.6.tar.gz 20:33:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:33:08 INFO - Installing collected packages: blessings 20:33:08 INFO - Running setup.py install for blessings 20:33:08 INFO - Successfully installed blessings 20:33:08 INFO - Cleaning up... 20:33:08 INFO - Return code: 0 20:33:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:33:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:33:08 INFO - Reading from file tmpfile_stdout 20:33:08 INFO - Current package versions: 20:33:08 INFO - blessings == 1.6 20:33:08 INFO - blobuploader == 1.2.4 20:33:08 INFO - docopt == 0.6.1 20:33:08 INFO - functools32 == 3.2.3-2 20:33:08 INFO - jsonschema == 2.5.1 20:33:08 INFO - manifestparser == 1.1 20:33:08 INFO - mozInstall == 1.12 20:33:08 INFO - mozcrash == 0.17 20:33:08 INFO - mozdebug == 0.1 20:33:08 INFO - mozdevice == 0.48 20:33:08 INFO - mozfile == 1.2 20:33:08 INFO - mozhttpd == 0.7 20:33:08 INFO - mozinfo == 0.9 20:33:08 INFO - mozleak == 0.1 20:33:08 INFO - mozlog == 3.3 20:33:08 INFO - moznetwork == 0.27 20:33:08 INFO - mozprocess == 0.23 20:33:08 INFO - mozprofile == 0.28 20:33:08 INFO - mozrunner == 6.12 20:33:08 INFO - mozscreenshot == 0.1 20:33:08 INFO - mozsystemmonitor == 0.3 20:33:08 INFO - moztest == 0.7 20:33:08 INFO - mozversion == 1.4 20:33:08 INFO - psutil == 3.1.1 20:33:08 INFO - requests == 1.2.3 20:33:08 INFO - wsgiref == 0.1.2 20:33:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:33:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:33:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:33:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:08 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 0x10c529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab9473ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c48dd30>, '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 0x10c1f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c485b20>, '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.6tinEH3tNo/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.Xc08ATsmjF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 20:33:08 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 20:33:08 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 20:33:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6tinEH3tNo/Render', 20:33:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:08 INFO - 'HOME': '/Users/cltbld', 20:33:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:08 INFO - 'LOGNAME': 'cltbld', 20:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:08 INFO - 'MOZ_NO_REMOTE': '1', 20:33:08 INFO - 'NO_EM_RESTART': '1', 20:33:08 INFO - 'PAGER': '/bin/cat', 20:33:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:08 INFO - 'PWD': '/builds/slave/test', 20:33:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:08 INFO - 'SHELL': '/bin/bash', 20:33:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xc08ATsmjF/Listeners', 20:33:08 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 20:33:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:08 INFO - 'USER': 'cltbld', 20:33:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:08 INFO - 'XPC_FLAGS': '0x0', 20:33:08 INFO - 'XPC_SERVICE_NAME': '0', 20:33:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:33:09 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)) 20:33:09 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)) 20:33:09 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)) 20:33:09 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)) 20:33:09 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)) 20:33:09 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)) 20:33:09 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)) 20:33:09 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)) 20:33:09 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)) 20:33:09 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)) 20:33:09 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)) 20:33:09 INFO - Cleaning up... 20:33:09 INFO - Return code: 0 20:33:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:33:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:33:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:33:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:33:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:09 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 0x10c529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab9473ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c48dd30>, '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 0x10c1f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c485b20>, '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.6tinEH3tNo/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.Xc08ATsmjF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 20:33:09 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 20:33:09 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 20:33:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6tinEH3tNo/Render', 20:33:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:09 INFO - 'HOME': '/Users/cltbld', 20:33:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:09 INFO - 'LOGNAME': 'cltbld', 20:33:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:09 INFO - 'MOZ_NO_REMOTE': '1', 20:33:09 INFO - 'NO_EM_RESTART': '1', 20:33:09 INFO - 'PAGER': '/bin/cat', 20:33:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:09 INFO - 'PWD': '/builds/slave/test', 20:33:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:09 INFO - 'SHELL': '/bin/bash', 20:33:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xc08ATsmjF/Listeners', 20:33:09 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 20:33:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:09 INFO - 'USER': 'cltbld', 20:33:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:09 INFO - 'XPC_FLAGS': '0x0', 20:33:09 INFO - 'XPC_SERVICE_NAME': '0', 20:33:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:33:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:33:09 INFO - Cleaning up... 20:33:09 INFO - Return code: 0 20:33:09 INFO - Running post-action listener: _resource_record_post_action 20:33:09 INFO - Running post-action listener: _start_resource_monitoring 20:33:09 INFO - Starting resource monitoring. 20:33:09 INFO - [mozharness: 2016-09-09 03:33:09.480627Z] Finished create-virtualenv step (success) 20:33:09 INFO - [mozharness: 2016-09-09 03:33:09.481643Z] Running install step. 20:33:09 INFO - Running pre-action listener: _resource_record_pre_action 20:33:09 INFO - Running main action method: install 20:33:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:33:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:33:09 INFO - Reading from file tmpfile_stdout 20:33:09 INFO - Detecting whether we're running mozinstall >=1.0... 20:33:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:33:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:33:09 INFO - Reading from file tmpfile_stdout 20:33:09 INFO - Output received: 20:33:09 INFO - Usage: mozinstall [options] installer 20:33:09 INFO - Options: 20:33:09 INFO - -h, --help show this help message and exit 20:33:09 INFO - -d DEST, --destination=DEST 20:33:09 INFO - Directory to install application into. [default: 20:33:09 INFO - "/builds/slave/test"] 20:33:09 INFO - --app=APP Application being installed. [default: firefox] 20:33:09 INFO - mkdir: /builds/slave/test/build/application 20:33:09 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'] 20:33:09 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 20:33:44 INFO - Reading from file tmpfile_stdout 20:33:44 INFO - Output received: 20:33:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:33:44 INFO - Running post-action listener: _resource_record_post_action 20:33:44 INFO - [mozharness: 2016-09-09 03:33:44.056801Z] Finished install step (success) 20:33:44 INFO - [mozharness: 2016-09-09 03:33:44.056961Z] Running run-tests step. 20:33:44 INFO - Running pre-action listener: _resource_record_pre_action 20:33:44 INFO - Running main action method: run_tests 20:33:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:33:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:33:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:33:44 INFO - 2016-09-08 20:33:44.071 screenresolution[1646:7640] starting screenresolution argv=screenresolution get 20:33:44 INFO - 2016-09-08 20:33:44.074 screenresolution[1646:7640] Display 0: 1600x1200x32@60 20:33:44 INFO - 2016-09-08 20:33:44.080 screenresolution[1647:7642] starting screenresolution argv=screenresolution list 20:33:44 INFO - Available Modes on Display 0 20:33:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:33:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:33:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:33:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:33:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:33:44 INFO - Intel Iris: 20:33:44 INFO - Chipset Model: Intel Iris 20:33:44 INFO - Type: GPU 20:33:44 INFO - Bus: Built-In 20:33:44 INFO - VRAM (Dynamic, Max): 1536 MB 20:33:44 INFO - Vendor: Intel (0x8086) 20:33:44 INFO - Device ID: 0x0a2e 20:33:44 INFO - Revision ID: 0x0009 20:33:44 INFO - Displays: 20:33:44 INFO - Display: 20:33:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:33:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:33:44 INFO - Display Serial Number: 1600x1200 60 20:33:44 INFO - Main Display: Yes 20:33:44 INFO - Mirror: Off 20:33:44 INFO - Online: Yes 20:33:44 INFO - Rotation: Supported 20:33:44 INFO - Return code: 0 20:33:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:33:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:33:44 INFO - Python 2.7.3 20:33:44 INFO - Return code: 0 20:33:44 INFO - grabbing minidump binary from tooltool 20:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:33:44 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 0x10c48dd30>, '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 0x10c1f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c485b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:33:44 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 20:33:44 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 20:33:44 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 20:33:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:33:44 INFO - Return code: 0 20:33:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:33:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:33:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:33:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:33:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:33:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0343', '--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 20:33:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0343 --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 20:33:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6tinEH3tNo/Render', 20:33:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:44 INFO - 'HOME': '/Users/cltbld', 20:33:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:44 INFO - 'LOGNAME': 'cltbld', 20:33:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:33:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:33:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:44 INFO - 'MOZ_NO_REMOTE': '1', 20:33:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:33:44 INFO - 'NO_EM_RESTART': '1', 20:33:44 INFO - 'PAGER': '/bin/cat', 20:33:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:44 INFO - 'PWD': '/builds/slave/test', 20:33:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:33:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:44 INFO - 'SHELL': '/bin/bash', 20:33:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xc08ATsmjF/Listeners', 20:33:44 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 20:33:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:44 INFO - 'USER': 'cltbld', 20:33:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:44 INFO - 'XPC_FLAGS': '0x0', 20:33:44 INFO - 'XPC_SERVICE_NAME': '0', 20:33:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0343', '--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 20:33:44 INFO - mozversion application_buildid: 20160908185019 20:33:44 INFO - mozversion application_changeset: 76ded056a9dec866e29ba13b4248e4e7e16547f5 20:33:44 INFO - mozversion application_display_name: Nightly 20:33:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:33:44 INFO - mozversion application_name: Firefox 20:33:44 INFO - mozversion application_remotingname: firefox 20:33:44 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:33:44 INFO - mozversion application_vendor: Mozilla 20:33:44 INFO - mozversion application_version: 51.0a1 20:33:44 INFO - mozversion platform_buildid: 20160908185019 20:33:44 INFO - mozversion platform_changeset: 76ded056a9dec866e29ba13b4248e4e7e16547f5 20:33:44 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:33:44 INFO - mozversion platform_version: 51.0a1 20:33:44 INFO - using testdate: 1473392024 20:33:44 INFO - actual date: 1473392024 20:33:44 INFO - starting webserver on 'localhost:49279' 20:33:44 INFO - SUITE-START | Running 2 tests 20:33:44 INFO - TEST-START | dromaeo_css 20:33:44 INFO - operating with platform_type : mac_ 20:33:44 INFO - Initialising browser for dromaeo_css test... 20:33:44 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/tmpc9PhaY/profile http://localhost:49279/getInfo.html) 20:33:47 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 20:33:47 INFO - PROCESS | 1657 | colorDepth:24 20:33:47 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 20:33:47 INFO - PROCESS | 1657 | __metrics 20:33:47 INFO - PROCESS | 1657 | 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" 20:33:47 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:33:47 INFO - TEST-INFO | 1657: exit 0 20:33:47 INFO - Browser initialized. 20:33:47 INFO - Running cycle 1/1 for dromaeo_css test... 20:33:47 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/tmpc9PhaY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:33:59 INFO - PROCESS | 1659 | RSS: Main: 232316928 20:33:59 INFO - PROCESS | 1659 | 20:34:50 INFO - PROCESS | 1659 | 1473392090803 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] 20:37:01 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 20:37:04 INFO - PROCESS | 1659 | RSS: Main: 386048000 20:37:04 INFO - PROCESS | 1659 | 20:40:07 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 20:40:07 INFO - PROCESS | 1659 | RSS: Main: 393297920 20:40:07 INFO - PROCESS | 1659 | 20:43:10 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 20:43:10 INFO - PROCESS | 1659 | RSS: Main: 339582976 20:43:10 INFO - PROCESS | 1659 | 20:46:12 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 20:46:13 INFO - PROCESS | 1659 | RSS: Main: 341114880 20:46:13 INFO - PROCESS | 1659 | 20:49:17 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 20:49:17 INFO - PROCESS | 1659 | RSS: Main: 332251136 20:49:17 INFO - PROCESS | 1659 | 20:52:20 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 20:52:21 INFO - PROCESS | 1659 | RSS: Main: 424546304 20:52:21 INFO - PROCESS | 1659 | 20:52:21 INFO - PROCESS | 1659 | __start_tp_report 20:52:21 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 20:52:21 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 20:52:21 INFO - PROCESS | 1659 | |i|pagename|runs| 20:52:21 INFO - PROCESS | 1659 | |0;dojo.html;2963.836988965607;3222.838858057098;3282.556854824598;3311.519829624703;3340.16495876031;5379.166229234465;5442.782288708452;5478.04134276502;5487.725613719313;5536.3909969903325;4358.171947330013;5488.4237155098635;5545.195946587743;5552.805651802188;5573.08044172711;4988.152014157602;5020.0963826511215;5069.214271787879;5075.720835354073;5218.112920803473;5147.974260128694;5149.691018538881;5174.560162386183;5175.275461435402;5186.118359878829;5015.1975683890405;5016.49835016499;5061.949380506185;5093.006863661588;5100.566451851597;4180.707350485467;5034.798608055673;5050.924236136461;5119.001794650045;5175.560077393426;5015.924761128586;5062.012907483042;5081.390233172043;5082.79668813247;5108.693478391308;5372.462753724635;5461.5357694596005;5477.808276710336;5505.476979686923;5517.779288828482;5927.407259274081;6003.789867354665;6015.187949626788;6048.334683184846;6053.213082299317;6080.209572755513;6087.4825639820665;6110.847228819271;6115.877682446326;6131.47882429994;5464.863378415572;5518.144687573433;5619.157126431027;5661.7735290588325;5665.915011274834;6115.057807494006;6149.985252433388;6164.4143806338325;6225.595336303126;6235.158253635701;5969.432903874125;5975.940240597538;5977.562901331665;5989.8203053908455;6031.457168854824;5843.2403787507355;6113.266408031065;6117.449429551273;6124.234470691164;6131.233595800525;5903.793367232212;5940.940590594125;6022.096685497123;6029.095635654687;6141.785037523755;6067;6100.511959043266;6108.450239478469;6111.602745821597;6114.57197996136;4837.32277481153;4840.903181936342;4845;4854.368932038883;4907.607391408679;4258.701890867609;4296.914061718748;4345.630621397154;4384.101259241847;4399.318105693623;5473.972630136824;5545;5549.6115271931385;5551.472610102034;5563.304586926634;5951.4750210011025;6102.640737559664;6173.96913015441;6185.721642526097;6201.379862013762;4954.603631709455;5506.889862202814;5519.089350257163;5560.332760068818;5593.664380137205;5189.974050129726;5256.894862102813;5331.493508116722;5332.466753324637;5345.35855697311;6224.346443623445;6231.750729970931;6244.968775156186;6245.75016999324;6253.499720022296;6143.017117261216;6148.292946311231;6158.230221222348;6171.475424588781;6177.135201071944;6107.114468401968;6123.252118380239;6153.600015998945;6238.095476155841;6256.937430625636;6009.008513595208;6062.545309101747;6071.635701857903;6086.6652334122045;6129.509639228941;6107.480864126599;6108.267007959073;6108.511319094373;6115.755369785159;6118.5411094169;5691.373948865705;5713.200151978644;5916.26046744157;5923.585349025527;5965.642061476326;5769.884602307847;5832.854178645568;5835.766569337179;5838.474537291664;5841.1530328101335;5126.384833819965;5642.322921249476;5687.800926967537;5691.630044047124;5695.031844586348;4962.354893863775;5798.304203495608;5875.941240587539;5942.078977758454;6003.519718422428;5758.913616295675;5763.7406316715005;5769.624974376652;5783.624064435799;5785.710714464176 20:52:21 INFO - PROCESS | 1659 | |1;ext.html;505.8634168774431;515.4510446374611;521.6687403498779;529.8410476856947;552.2434265056872;31795.68204317956;41430.171396572085;41464.37803432951;41581.376279355834;43772.56227437725;42632.786836065774;42795.14409711804;42845.785771071176;42846;43109.78445107778;8623.482591044525;9659.178969787576;9806.705798826046;9814.165795907356;9814.214862810994;8603.741887743377;10152.03555662211;11799.469023893946;11926.701832454171;12291.323977181251;12104.878951210507;12176.878231217708;12184.573539926105;12187.953011747064;12196.329201893892;12038.69903252417;12122.333271670099;12141.514339426501;12148.57479988205;12148.635540933787;11863.52545898163;11952.282863028246;11953.223040502426;11953.701157471045;11954.701132471671;33522.1619459513;40840.79579602083;40870.591294086975;40873.795631021654;40936.79531602353;3194.1375828526166;3204.295055087877;3248.3340915112344;3254.381667483194;3317.402867483852;30603.387932241232;31560.526592101138;31613;31671.891483797957;31859.84070079658;3262.869485220588;3265.3182717923596;3372.19067423819;3398.2014226656715;3400.1669590950128;509915;510559.4472027653;511079.4446027783;511969;514490;58581.70709146427;59095.70452147798;59318.406815932154;59467.702661486415;59517.70241148853;62174;62185.067223991675;62321.702655273904;62351.752964940446;62375.6881215591;15617.68764624724;15830.52508424749;15834.920825395799;15849.128297943724;15891.28489217988;11122.332660040423;11375.943120284512;12314.953228975637;12374.938125309316;12391.442385092692;16002;16397;16408.589785255463;16580.9222400546;16582.585435364213;6722.731090756315;6748.257691653914;6775.864482710318;6798.320167983162;6875.518713690094;848.5969164646743;919.7470695558641;952.48565774481;973.6884197056874;987.2990176974429;28492.572611410844;29459.86744109333;29505.557416638767;30151.793928243063;30303.69696303053;993.9403635781877;1049.1606714628267;1056.0917610854658;1109.7724966381872;1120.915931305139;12213.824405654594;13383.665408364674;15384.769228461582;15443;15780.526584202493;663689.3631063724;670136;670221.6488917525;672005;672046;56705;56786;56907.71546142243;56959;56999.14501282484;1076.7523469601879;1181.639599922032;1307.4181989014796;1326.2838067443665;1338.7590233757821;26707.33231669224;28738.85630571834;29197.41605167842;30780.53829192519;31613.683863161074;49126;49614.25578616252;49704.75147624239;49761.25358119704;49841.753956151384;515650;517193.2421013763;517802.41098794265;519359.66083222034;519940;38750.80624596859;39204;39307.410388844764;39418.40872386974;39479;46523.53476465192;46920.530794691615;47890.760546197045;48309.5169048305;48449;37809.96269475501;38237.617623823404;38702.6129738699;39703.007424813455;40513;36927.63072369242;38354;38745;39178.02099620236;40199.79900100481;37183;37820.243595127395;38282;38325.808370957966;38422.423663644506;27479;31570.526442103845;34495.827520862236;34547;34657.1335716599;21635.891820540797;24851.25446236616;25378.873105634353;25492.107776227742;25682.61476077823 20:52:21 INFO - PROCESS | 1659 | |2;jquery.html;328.6910304313946;349.481020684284;368.6939839932856;381.2185020707549;403.9919201615968;14810.25948702566;16166.272517736701;19258.22967081315;19302.903485482588;19356.709649355234;19685.901570492162;20125.39623811288;20721.481962950933;20787.480312992182;20936.848473333997;5330.626856120073;5806.796762113327;6061.090836374535;6068.180795592582;6068.393160683919;5885.676287804169;5977.043673012319;6002.519798416116;6014.939850601481;6025.789097381593;5755.107958266475;5962.135490353896;5974.133750606159;5977.910331345033;6063.787767428141;5605.710686542118;6071.848203794918;6105.481034112106;6123.234595675541;6134.355892631254;5453.034743194903;5780;5871.1193321001765;5938.524918006549;5942.61373010753;12880.742385152338;13130.86869131306;13137.540186093442;20395.388138355724;24820.627690584657;2089.0286017002013;2250.718660167479;2506.736792636765;2673.2381271337576;2730.7269273072534;9212.907870921272;9738.318317717763;9804.166645835112;10210.693679189562;10290.228232882564;1797.218210078607;1848.5471059590352;1859.739636450898;1864.077281745531;1879;125526.11710824344;132594.3370283152;134776.9783453249;134829;138939;10559.894401056044;10605.204609654153;11267.774644507228;11278.830817537744;11505.252158609663;14233.64415889611;14274.928625356806;14286.499972500913;14289.285535723173;14308.928455357656;8006.71976480832;8057.556834374049;8308.29980256419;8350.958245208734;8440.11378805229;7240.674169662378;8281.29608983231;8292.709755158541;8494.872576911352;8514.276286515591;4385.912281754411;4394.604485596247;4399.670024748157;4407.713498622579;4459.7547134907245;1819.2268285978407;1868.6078871241014;1872.550587858904;1895.3176856331709;1903.4765439504247;828.8508068547719;856.8800367948493;865.4936861935712;886.663068034143;960.673371053845;19757.308494202633;20291.086901089486;20419.79580204209;20431.87624680657;20543.4864128398;875.9882335901916;909.8134882349102;915.1717695485596;939.7133874168444;955.3933252384786;10260.612200305895;10884.564617415215;10896.848480019213;10903.890961090447;10912.61805836792;598201;599666;606733;609766;613734;121770;123050;130988;131870.68129318583;133100;948.7865230323122;966.7196513011148;993.8310487217191;996.8685541909985;1001.159026417798;13927.23400212998;17532.649347013245;18277.908610456863;18381.448556543324;18449.354272600394;44150.77085176392;56394.7180264096;56697;57271.713641431525;57304.14043789263;81872.18127818646;82243;82588.3482330362;82822.5459708629;82861.58569207115;7647.88528172089;7852.450328476952;7856.764297071097;8110.351171906115;8136.471129376809;10308.896911030793;12137.21108127969;12485.188462749893;12872.420741066488;13161.934190328988;10919;10996.28524145928;11366.829497557377;11415.885841141482;11641.359725214856;9563.856542151734;11082.833757493483;11197.832032519356;11255.887441125484;11682.182247242612;10272.845907311246;10514.894851051698;11481.196316257781;11633.825492617449;11789.174757767218;7806.92193078062;7828.765137045898;8048.476849004796;8255.463394879554;8282.917170828456;9955.701328959853;11172.441377931233;11430.25703329281;11635.243709158878;11724.531018759155 20:52:21 INFO - PROCESS | 1659 | |3;mootools.html;1343.1403901503033;1508.7284288828014;1539.738244498435;1732.6967780638386;1894.2517705506325;7766.57283849388;8633.179847914445;8885.511296878669;8917;9020.097990200993;8823.205911467967;8882.911170888305;8887.155720206587;8901.376903616749;8920.375573709827;2360.551495215908;2391.3184742348435;2391.497785465054;2395.628677554973;2398.7481314461907;2325.3140323604575;2365.550545396369;2370.419247284418;2371.5494056129282;2378.262738551046;2336.813054955608;2361.657559653849;2365.6924599802;2374.620060790274;2381.666566680666;2334.124703236286;2356.9764302357016;2361.2562042956415;2368.3605426534828;2376.097083108425;2318.13070098713;2342.566625174331;2343.1564636730764;2347.0377145370485;2352.7059117610297;8758.518281494515;8935.776605584848;8938.284937205008;8951.641934322619;8981.281497480186;5241.580673546107;5420.403755586883;5431.131019036935;5474.671519708831;5482.17767334899;3680.907977300562;3690.225052738928;3752.699784017272;3761.811909404546;3772;1080.5515710980012;1090.1278976818514;1091.5251865438481;1112.104755671684;1114.8717897441818;11682.182247242696;11713.121515886343;11786.292822430682;11804.527818887234;11815.232009919242;4394.450693663292;4491.865244042684;4640.095181439654;4649.139909116824;4662.044280922402;9132.771680708036;9135.314851386172;9139.131782480654;9142.908570914338;9154.130357616015;5230.92153617696;5232.895342093137;5243.292155559027;5247.396549396792;5249.632525723239;4225.873223803229;4285.46431696038;4368.934465983013;4511.616512596466;4902.411710594752;3712.9628703712615;3784.981075094662;3792.563855156692;3830.3871380578976;3836.5204349456317;1163.4357336691698;1165.4405885175163;1170.4089434835523;1172.1267655596464;1174.8531433570804;748.0948052855996;825.6325934959006;888.7200531832452;895.3463971300987;896.1934259166671;5747.597668163188;5817.7963771267805;5857.740585774079;5917.349091599921;6116.296625887991;405.1888060370141;428.2783509786021;439.45946485822617;442.82508409178195;455.794892298467;3950.634548823566;3959.148783011666;3997.8066895967186;4101.569335219819;4120.876373708794;10294.3823370598;10823.971722686332;10840.837387439195;10893.400862952456;10909;10750.516226769814;10765.515551800188;10778.89221107779;10779.461026948778;10801.351918884891;8234.300084492885;8293.668253269921;8303.294219991369;8303.924018995373;8325.377506085684;4965.865286317741;5608.803691870765;5889.381614930542;5898.764049437975;5910.852228694145;10058.34620749649;10068.39589624625;10105.39367637944;10132.138768204784;10166.085052345325;9301.767455813659;9830.705078847646;10092.091711745978;10265.2301077418;10305.690829275134;6322.873542529215;6833.658317084225;6948.478864085315;7059.71761129549;7097.219305876452;8260.586970651564;8266.173382661686;8288;8381.203785640599;8382.077971423261;6811.761588344384;7400.888986665312;7537.321641052332;7570.886436703344;7639.732609358646;7227.2772722729405;7323.707051717872;7365.079365079365;7432.033835601337;7485.737999170221;6947.513674042769;6972.965135174291;7027.648617568999;7049.541779784297;7067.222605513287;4921.680090794087;5036.244563315386;5074.6194035448225;5084.2881996521255;5243.947560524499;4999.77501012448;5042.168042272984;5104.132297509506;5212.791488340424;5291.3121294231505 20:52:21 INFO - PROCESS | 1659 | |4;prototype.html;280.7108678061597;295.58913110776024;311.58403531285734;311.65873368661323;312.1478364066098;4375.993521490059;4460.799264033114;4693.507181745918;5031.1698569736;5088.643794934347;4830.574980380788;4939.209346719102;4993.400791904976;5141.562967147797;5145.948540514593;3426;3893.792924193502;3986.621270979252;4009.939850902239;4011.2178125265586;3315.4529502632035;3986.986571781261;4014.0767623446623;4049.372347286175;4070.4097905803787;3298.9670103299018;3773.905652358699;3779.7165212609166;3847.172593018308;4016.5983401659746;3314.96685033149;3992.7604343739467;3996.4205190247253;4029.2747305485;4036.8898552897895;3496.8251587420527;3512.525809015776;3522.3659741246565;3533.2050288685095;3540.203454222805;4683.976580117112;5057.367829021372;5098.847034588968;5106.157484015133;5110.897782044375;4062.8014735653055;4142.751434913885;4193.014641559231;4315.892102697427;4414.713043652152;4847.418309802846;5031.496850314939;5118.872028199287;5415.13357862744;5464.644798088151;3421.3328400961823;3571.8571257149683;3718.51659284294;3733.8133093345496;3767.6232376762377;5032.446430892599;5321.669582604349;5499.560035197174;5654.293213348373;5718.855429214044;3560.376934036585;3638.199596088856;3791.810409479515;3798.8100594970692;3821.5987321331418;3260.5653512454446;3574.5353104096303;3864.265789499986;3879.243547508209;3881.4177873318663;4318.784060796947;4460.598546130864;4477.888052798641;4491.71985983993;4513.729176249435;5435.701036442954;5448.346198456132;5478.45215478457;5480.561555075585;5504.697241651667;1285.9262515799226;1309.2668105860748;1309.6594885329691;1314.3362601885985;1315.4540865540885;675.1796567170911;785.5757890739051;789.3251270163927;811.8213992921548;838.6393850644159;3971.72197946141;4622.029373831533;4946.925796113062;5078.21287700407;5164;4839.41926968766;4866.659333846667;5320.042392369333;5346.732663366816;5628.549716022709;4687.250039993584;5065.189569668835;5070.847874563769;5109.846704598868;5137.357830271216;2462.630605409167;2778.6943436222;2906.8837246510325;2907.9418411632073;2909.2145120817686;5643.802466913638;5715.514181294637;5726.656400615893;5755.877603867206;5835.883282334415;5302.814401496006;5687.260656114679;5712.487135163175;5715.914261286084;5729.510327314845;5793.942060579425;5797.913031304533;5816.185733997159;5816.592838501264;5836.270466191726;4690.15577196108;4949.257611358325;5572.582056345871;5595.5803314752375;5639.88720225585;4825.638077144158;4901.362822833009;5536.861578460571;5566.332040155207;5584.46947539983;4911.484294149063;5134.845954621367;5795.565332600157;5946.837213950339;6038.486728628116;3146.716795488417;3410.403179443637;3562.8955023942663;3592.2456284180616;3748.6626203641804;4705.6000239979985;5449.782008719607;5529.889402212014;5540.252065971043;5556.277683901229;4922.72924989129;4979.261406226633;5128.58971282304;5389.622726409114;5390.703511306916;4341.848035318749;5033.546980771748;5169.880349056869;5380.950714610613;5423.4034256231;5310.230016647642;5338.3593968724;5347.379783905781;5350.491703288181;5350.598705097055;3940.3402703175816;5184.703824043989;5410.458954104558;5496.780128794963;5521.641093328921;5236.319278493771;5279.102552565996;5295.073362161529;5301.708406037705;5305.787768489372 20:52:21 INFO - PROCESS | 1659 | |5;yui.html;709.5458906299968;713.5861200503705;725.0719079578139;804.9254245582148;807.100083407001;15129.24353782312;16068.437604683824;16072.598185045379;16075.598110047255;16078.27647755851;14439.494617688382;14600.488982885601;14618.780718289234;14621.634459138528;14648.194349310783;647.4367300448592;665.6771465839064;814.7433558429076;821.5112008355019;839.7858546070773;802.1256830055239;814.1166833985134;814.771863878115;815.2621877201112;818.8280461103176;878.6178012564523;878.8945326560789;879.1208791208791;880.6961598248625;882.2104216726059;689.8068540808531;709.2659097833717;713.1798431803415;873.8820259264982;874.0341922176;527.8574784808119;636.11897521932;652.9347065293433;658.2364457229638;659.6437923521293;14366.784498232535;14393;14441.133531988009;14451.78322325166;14483.058601190889;13354.332283385791;13508.121972071784;13718.039737218398;13801.309934503335;14076.92961535196;11933.582324618683;12273.447694853752;12313.787559789533;12337.321447320393;12811;4418.664758042073;4505.662075344329;4543.204939135671;4544.818207271705;4558.908821823545;121576.78423215832;125266.37366813199;127116;127961;128824;9465.479398633004;10735.89264107349;10764.354138751543;10836;10908.945455272673;20558.69161962572;20779.584408311748;20796.064177112064;20817.895910520652;20823.791762082186;7476.738314158978;7513.624318784039;7551.622418879144;7557.168711441737;7727.111382191008;4221.050263690707;4225.154969006211;4235.830566777356;4254.532001479834;4380.058287468146;3265.9020229393154;3352.3295340931913;3357.496375543688;3358.3115461330367;3361.058903507022;565.4825834361595;704.8202708309349;708.0158579574396;715.2454160860342;715.4240836126863;611.5566214494545;612.7212118486078;641.9293877673452;650.2814390098968;652.2010537092119;9726.854097188549;9738.610455581698;9771.560279787285;9793.804123917482;9804.509774511245;647.9910293431799;652.4975768658106;678.1218322272571;687.5702685821361;693.1543516909362;4542.205987453337;4614.123316569841;4624.768761561909;4675.298705194248;4721.362616046789;88839;124302;148499;156879;161374;134807;134957.92111331818;139963;141125.58874411124;141442;92097;102425.34235274832;109296.17463388744;109764;118664;8486.915130848613;8582.699605513777;8598.097199793932;8753.737387878375;8838.690645827364;48861.75569122132;49143.262851057974;53768.731156343965;60744;63383.6830815843;92909;94285;94706;97040;97326;11401.828972565583;11609;11615.245009074382;11669.88330116688;11954.163208575315;12129.272243665408;12377.443015064164;12466.626001219978;12715.491380345053;12882;9836;12122.27266364021;12241.265524068584;12391.690207744878;12427.975362606723;9771.267154963549;11816.054715622902;11942.462589183333;11997.520099195934;12422.565210217599;11736.185739634482;11752.360965372589;11761.480056035563;11947.163698541019;11954;8123.5532045738055;9235.353525253167;9769.706908792748;9916.950415248166;10325.9483702581;9497.28770342241;9926.801463970825;10064.647737329158;10082.294239403465;10092.646757363456 20:52:21 INFO - PROCESS | 1659 | __end_tp_report 20:52:21 INFO - PROCESS | 1659 | __start_cc_report 20:52:21 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2052 20:52:21 INFO - PROCESS | 1659 | __end_cc_report 20:52:21 INFO - PROCESS | 1659 | __startTimestamp1473393141201__endTimestamp 20:52:21 INFO - PROCESS | 1659 | 20:52:21 INFO - PROCESS | 1659 | ------- Summary: start ------- 20:52:21 INFO - PROCESS | 1659 | Number of tests: 6 20:52:21 INFO - PROCESS | 1659 | 20:52:21 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5570.78 Median:5713.20 stddev:647.45 (11.3%) stddev-sans-first:616.29 20:52:21 INFO - PROCESS | 1659 | Values: 2963.8 3222.8 3282.6 3311.5 3340.2 5379.2 5442.8 5478.0 5487.7 5536.4 4358.2 5488.4 5545.2 5552.8 5573.1 4988.2 5020.1 5069.2 5075.7 5218.1 5148.0 5149.7 5174.6 5175.3 5186.1 5015.2 5016.5 5061.9 5093.0 5100.6 4180.7 5034.8 5050.9 5119.0 5175.6 5015.9 5062.0 5081.4 5082.8 5108.7 5372.5 5461.5 5477.8 5505.5 5517.8 5927.4 6003.8 6015.2 6048.3 6053.2 6080.2 6087.5 6110.8 6115.9 6131.5 5464.9 5518.1 5619.2 5661.8 5665.9 6115.1 6150.0 6164.4 6225.6 6235.2 5969.4 5975.9 5977.6 5989.8 6031.5 5843.2 6113.3 6117.4 6124.2 6131.2 5903.8 5940.9 6022.1 6029.1 6141.8 6067.0 6100.5 6108.5 6111.6 6114.6 4837.3 4840.9 4845.0 4854.4 4907.6 4258.7 4296.9 4345.6 4384.1 4399.3 5474.0 5545.0 5549.6 5551.5 5563.3 5951.5 6102.6 6174.0 6185.7 6201.4 4954.6 5506.9 5519.1 5560.3 5593.7 5190.0 5256.9 5331.5 5332.5 5345.4 6224.3 6231.8 6245.0 6245.8 6253.5 6143.0 6148.3 6158.2 6171.5 6177.1 6107.1 6123.3 6153.6 6238.1 6256.9 6009.0 6062.5 6071.6 6086.7 6129.5 6107.5 6108.3 6108.5 6115.8 6118.5 5691.4 5713.2 5916.3 5923.6 5965.6 5769.9 5832.9 5835.8 5838.5 5841.2 5126.4 5642.3 5687.8 5691.6 5695.0 4962.4 5798.3 5875.9 5942.1 6003.5 5758.9 5763.7 5769.6 5783.6 5785.7 20:52:21 INFO - PROCESS | 1659 | 20:52:21 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:70333.22 Median:38303.90 stddev:152515.12 (398.2%) stddev-sans-first:152852.86 20:52:21 INFO - PROCESS | 1659 | Values: 505.9 515.5 521.7 529.8 552.2 31795.7 41430.2 41464.4 41581.4 43772.6 42632.8 42795.1 42845.8 42846.0 43109.8 8623.5 9659.2 9806.7 9814.2 9814.2 8603.7 10152.0 11799.5 11926.7 12291.3 12104.9 12176.9 12184.6 12188.0 12196.3 12038.7 12122.3 12141.5 12148.6 12148.6 11863.5 11952.3 11953.2 11953.7 11954.7 33522.2 40840.8 40870.6 40873.8 40936.8 3194.1 3204.3 3248.3 3254.4 3317.4 30603.4 31560.5 31613.0 31671.9 31859.8 3262.9 3265.3 3372.2 3398.2 3400.2 509915.0 510559.4 511079.4 511969.0 514490.0 58581.7 59095.7 59318.4 59467.7 59517.7 62174.0 62185.1 62321.7 62351.8 62375.7 15617.7 15830.5 15834.9 15849.1 15891.3 11122.3 11375.9 12315.0 12374.9 12391.4 16002.0 16397.0 16408.6 16580.9 16582.6 6722.7 6748.3 6775.9 6798.3 6875.5 848.6 919.7 952.5 973.7 987.3 28492.6 29459.9 29505.6 30151.8 30303.7 993.9 1049.2 1056.1 1109.8 1120.9 12213.8 13383.7 15384.8 15443.0 15780.5 663689.4 670136.0 670221.6 672005.0 672046.0 56705.0 56786.0 56907.7 56959.0 56999.1 1076.8 1181.6 1307.4 1326.3 1338.8 26707.3 28738.9 29197.4 30780.5 31613.7 49126.0 49614.3 49704.8 49761.3 49841.8 515650.0 517193.2 517802.4 519359.7 519940.0 38750.8 39204.0 39307.4 39418.4 39479.0 46523.5 46920.5 47890.8 48309.5 48449.0 37810.0 38237.6 38702.6 39703.0 40513.0 36927.6 38354.0 38745.0 39178.0 40199.8 37183.0 37820.2 38282.0 38325.8 38422.4 27479.0 31570.5 34495.8 34547.0 34657.1 21635.9 24851.3 25378.9 25492.1 25682.6 20:52:21 INFO - PROCESS | 1659 | 20:52:21 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35417.53 Median:1529.71 stddev:101538.53 (6637.8%) stddev-sans-first:101789.19 20:52:21 INFO - PROCESS | 1659 | Values: 328.7 349.5 368.7 381.2 404.0 14810.3 16166.3 19258.2 19302.9 19356.7 19685.9 20125.4 20721.5 20787.5 20936.8 5330.6 5806.8 6061.1 6068.2 6068.4 5885.7 5977.0 6002.5 6014.9 6025.8 5755.1 5962.1 5974.1 5977.9 6063.8 5605.7 6071.8 6105.5 6123.2 6134.4 5453.0 5780.0 5871.1 5938.5 5942.6 12880.7 13130.9 13137.5 20395.4 24820.6 2089.0 2250.7 2506.7 2673.2 2730.7 9212.9 9738.3 9804.2 10210.7 10290.2 1797.2 1848.5 1859.7 1864.1 1879.0 125526.1 132594.3 134777.0 134829.0 138939.0 10559.9 10605.2 11267.8 11278.8 11505.3 14233.6 14274.9 14286.5 14289.3 14308.9 8006.7 8057.6 8308.3 8351.0 8440.1 7240.7 8281.3 8292.7 8494.9 8514.3 4385.9 4394.6 4399.7 4407.7 4459.8 1819.2 1868.6 1872.6 1895.3 1903.5 828.9 856.9 865.5 886.7 960.7 19757.3 20291.1 20419.8 20431.9 20543.5 876.0 909.8 915.2 939.7 955.4 10260.6 10884.6 10896.8 10903.9 10912.6 598201.0 599666.0 606733.0 609766.0 613734.0 121770.0 123050.0 130988.0 131870.7 133100.0 948.8 966.7 993.8 996.9 1001.2 13927.2 17532.6 18277.9 18381.4 18449.4 44150.8 56394.7 56697.0 57271.7 57304.1 81872.2 82243.0 82588.3 82822.5 82861.6 7647.9 7852.5 7856.8 8110.4 8136.5 10308.9 12137.2 12485.2 12872.4 13161.9 10919.0 10996.3 11366.8 11415.9 11641.4 9563.9 11082.8 11197.8 11255.9 11682.2 10272.8 10514.9 11481.2 11633.8 11789.2 7806.9 7828.8 8048.5 8255.5 8282.9 9955.7 11172.4 11430.3 11635.2 11724.5 20:52:21 INFO - PROCESS | 1659 | 20:52:21 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5669.74 Median:4655.59 stddev:3211.58 (69.0%) stddev-sans-first:3204.13 20:52:21 INFO - PROCESS | 1659 | Values: 1343.1 1508.7 1539.7 1732.7 1894.3 7766.6 8633.2 8885.5 8917.0 9020.1 8823.2 8882.9 8887.2 8901.4 8920.4 2360.6 2391.3 2391.5 2395.6 2398.7 2325.3 2365.6 2370.4 2371.5 2378.3 2336.8 2361.7 2365.7 2374.6 2381.7 2334.1 2357.0 2361.3 2368.4 2376.1 2318.1 2342.6 2343.2 2347.0 2352.7 8758.5 8935.8 8938.3 8951.6 8981.3 5241.6 5420.4 5431.1 5474.7 5482.2 3680.9 3690.2 3752.7 3761.8 3772.0 1080.6 1090.1 1091.5 1112.1 1114.9 11682.2 11713.1 11786.3 11804.5 11815.2 4394.5 4491.9 4640.1 4649.1 4662.0 9132.8 9135.3 9139.1 9142.9 9154.1 5230.9 5232.9 5243.3 5247.4 5249.6 4225.9 4285.5 4368.9 4511.6 4902.4 3713.0 3785.0 3792.6 3830.4 3836.5 1163.4 1165.4 1170.4 1172.1 1174.9 748.1 825.6 888.7 895.3 896.2 5747.6 5817.8 5857.7 5917.3 6116.3 405.2 428.3 439.5 442.8 455.8 3950.6 3959.1 3997.8 4101.6 4120.9 10294.4 10824.0 10840.8 10893.4 10909.0 10750.5 10765.5 10778.9 10779.5 10801.4 8234.3 8293.7 8303.3 8303.9 8325.4 4965.9 5608.8 5889.4 5898.8 5910.9 10058.3 10068.4 10105.4 10132.1 10166.1 9301.8 9830.7 10092.1 10265.2 10305.7 6322.9 6833.7 6948.5 7059.7 7097.2 8260.6 8266.2 8288.0 8381.2 8382.1 6811.8 7400.9 7537.3 7570.9 7639.7 7227.3 7323.7 7365.1 7432.0 7485.7 6947.5 6973.0 7027.6 7049.5 7067.2 4921.7 5036.2 5074.6 5084.3 5243.9 4999.8 5042.2 5104.1 5212.8 5291.3 20:52:21 INFO - PROCESS | 1659 | 20:52:21 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4381.98 Median:4948.09 stddev:1355.62 (27.4%) stddev-sans-first:1324.01 20:52:21 INFO - PROCESS | 1659 | Values: 280.7 295.6 311.6 311.7 312.1 4376.0 4460.8 4693.5 5031.2 5088.6 4830.6 4939.2 4993.4 5141.6 5145.9 3426.0 3893.8 3986.6 4009.9 4011.2 3315.5 3987.0 4014.1 4049.4 4070.4 3299.0 3773.9 3779.7 3847.2 4016.6 3315.0 3992.8 3996.4 4029.3 4036.9 3496.8 3512.5 3522.4 3533.2 3540.2 4684.0 5057.4 5098.8 5106.2 5110.9 4062.8 4142.8 4193.0 4315.9 4414.7 4847.4 5031.5 5118.9 5415.1 5464.6 3421.3 3571.9 3718.5 3733.8 3767.6 5032.4 5321.7 5499.6 5654.3 5718.9 3560.4 3638.2 3791.8 3798.8 3821.6 3260.6 3574.5 3864.3 3879.2 3881.4 4318.8 4460.6 4477.9 4491.7 4513.7 5435.7 5448.3 5478.5 5480.6 5504.7 1285.9 1309.3 1309.7 1314.3 1315.5 675.2 785.6 789.3 811.8 838.6 3971.7 4622.0 4946.9 5078.2 5164.0 4839.4 4866.7 5320.0 5346.7 5628.5 4687.3 5065.2 5070.8 5109.8 5137.4 2462.6 2778.7 2906.9 2907.9 2909.2 5643.8 5715.5 5726.7 5755.9 5835.9 5302.8 5687.3 5712.5 5715.9 5729.5 5793.9 5797.9 5816.2 5816.6 5836.3 4690.2 4949.3 5572.6 5595.6 5639.9 4825.6 4901.4 5536.9 5566.3 5584.5 4911.5 5134.8 5795.6 5946.8 6038.5 3146.7 3410.4 3562.9 3592.2 3748.7 4705.6 5449.8 5529.9 5540.3 5556.3 4922.7 4979.3 5128.6 5389.6 5390.7 4341.8 5033.5 5169.9 5381.0 5423.4 5310.2 5338.4 5347.4 5350.5 5350.6 3940.3 5184.7 5410.5 5496.8 5521.6 5236.3 5279.1 5295.1 5301.7 5305.8 20:52:21 INFO - PROCESS | 1659 | 20:52:21 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:24661.41 Median:4523.93 stddev:40505.32 (895.4%) stddev-sans-first:40579.02 20:52:21 INFO - PROCESS | 1659 | Values: 709.5 713.6 725.1 804.9 807.1 15129.2 16068.4 16072.6 16075.6 16078.3 14439.5 14600.5 14618.8 14621.6 14648.2 647.4 665.7 814.7 821.5 839.8 802.1 814.1 814.8 815.3 818.8 878.6 878.9 879.1 880.7 882.2 689.8 709.3 713.2 873.9 874.0 527.9 636.1 652.9 658.2 659.6 14366.8 14393.0 14441.1 14451.8 14483.1 13354.3 13508.1 13718.0 13801.3 14076.9 11933.6 12273.4 12313.8 12337.3 12811.0 4418.7 4505.7 4543.2 4544.8 4558.9 121576.8 125266.4 127116.0 127961.0 128824.0 9465.5 10735.9 10764.4 10836.0 10908.9 20558.7 20779.6 20796.1 20817.9 20823.8 7476.7 7513.6 7551.6 7557.2 7727.1 4221.1 4225.2 4235.8 4254.5 4380.1 3265.9 3352.3 3357.5 3358.3 3361.1 565.5 704.8 708.0 715.2 715.4 611.6 612.7 641.9 650.3 652.2 9726.9 9738.6 9771.6 9793.8 9804.5 648.0 652.5 678.1 687.6 693.2 4542.2 4614.1 4624.8 4675.3 4721.4 88839.0 124302.0 148499.0 156879.0 161374.0 134807.0 134957.9 139963.0 141125.6 141442.0 92097.0 102425.3 109296.2 109764.0 118664.0 8486.9 8582.7 8598.1 8753.7 8838.7 48861.8 49143.3 53768.7 60744.0 63383.7 92909.0 94285.0 94706.0 97040.0 97326.0 11401.8 11609.0 11615.2 11669.9 11954.2 12129.3 12377.4 12466.6 12715.5 12882.0 9836.0 12122.3 12241.3 12391.7 12428.0 9771.3 11816.1 11942.5 11997.5 12422.6 11736.2 11752.4 11761.5 11947.2 11954.0 8123.6 9235.4 9769.7 9917.0 10325.9 9497.3 9926.8 10064.6 10082.3 10092.6 20:52:21 INFO - PROCESS | 1659 | -------- Summary: end -------- 20:52:21 INFO - PROCESS | 1659 | 20:52:21 INFO - TEST-INFO | 1659: exit 0 20:52:21 INFO - TEST-OK | dromaeo_css | took 1117125ms 20:52:21 INFO - TEST-START | kraken 20:52:21 INFO - operating with platform_type : mac_ 20:52:21 INFO - Initialising browser for kraken test... 20:52:21 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/tmp6eGTKS/profile http://localhost:49279/getInfo.html) 20:52:24 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 20:52:24 INFO - PROCESS | 1666 | colorDepth:24 20:52:24 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 20:52:24 INFO - PROCESS | 1666 | __metrics 20:52:24 INFO - PROCESS | 1666 | 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" 20:52:24 INFO - PROCESS | 1666 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:52:25 INFO - TEST-INFO | 1666: exit 0 20:52:25 INFO - Browser initialized. 20:52:25 INFO - Running cycle 1/1 for kraken test... 20:52:25 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/tmp6eGTKS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:52:36 INFO - PROCESS | 1667 | RSS: Main: 232427520 20:52:36 INFO - PROCESS | 1667 | 20:52:39 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 20:52:40 INFO - PROCESS | 1667 | RSS: Main: 292777984 20:52:40 INFO - PROCESS | 1667 | 20:52:43 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 20:52:43 INFO - PROCESS | 1667 | RSS: Main: 287182848 20:52:43 INFO - PROCESS | 1667 | 20:52:47 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 20:52:47 INFO - PROCESS | 1667 | RSS: Main: 378482688 20:52:47 INFO - PROCESS | 1667 | 20:52:49 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 20:52:50 INFO - PROCESS | 1667 | RSS: Main: 279826432 20:52:50 INFO - PROCESS | 1667 | 20:52:52 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:52:53 INFO - PROCESS | 1667 | RSS: Main: 283684864 20:52:53 INFO - PROCESS | 1667 | 20:52:55 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 20:52:56 INFO - PROCESS | 1667 | RSS: Main: 320536576 20:52:56 INFO - PROCESS | 1667 | 20:52:59 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 20:52:59 INFO - PROCESS | 1667 | RSS: Main: 321052672 20:52:59 INFO - PROCESS | 1667 | 20:53:02 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 20:53:03 INFO - PROCESS | 1667 | RSS: Main: 320770048 20:53:03 INFO - PROCESS | 1667 | 20:53:04 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:53:05 INFO - PROCESS | 1667 | RSS: Main: 297742336 20:53:05 INFO - PROCESS | 1667 | 20:53:07 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:53:08 INFO - PROCESS | 1667 | RSS: Main: 302030848 20:53:08 INFO - PROCESS | 1667 | 20:53:10 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:53:10 INFO - PROCESS | 1667 | RSS: Main: 305717248 20:53:10 INFO - PROCESS | 1667 | 20:53:13 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:53:14 INFO - PROCESS | 1667 | RSS: Main: 314380288 20:53:14 INFO - PROCESS | 1667 | 20:53:17 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:53:17 INFO - PROCESS | 1667 | RSS: Main: 315711488 20:53:17 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 20:53:19 INFO - PROCESS | 1667 | RSS: Main: 359763968 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | __start_tp_report 20:53:19 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 20:53:19 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 20:53:19 INFO - PROCESS | 1667 | |i|pagename|runs| 20:53:19 INFO - PROCESS | 1667 | |0;ai-astar;111;112;116;116;122;116;113;111;110;124 20:53:19 INFO - PROCESS | 1667 | |1;audio-beat-detection;145;114;128;161;111;128;148;137;198;93 20:53:19 INFO - PROCESS | 1667 | |2;audio-dft;186;186;184;182;184;181;180;181;184;182 20:53:19 INFO - PROCESS | 1667 | |3;audio-fft;78;73;77;156;58;57;62;64;140;58 20:53:19 INFO - PROCESS | 1667 | |4;audio-oscillator;81;83;136;69;72;70;118;69;70;117 20:53:19 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;87;84;86;84;86;83;87;83;87;84 20:53:19 INFO - PROCESS | 1667 | |6;imaging-darkroom;94;94;94;101;95;95;96;94;94;95 20:53:19 INFO - PROCESS | 1667 | |7;imaging-desaturate;84;84;83;84;84;83;84;85;83;84 20:53:19 INFO - PROCESS | 1667 | |8;json-parse-financial;56;56;56;56;51;55;73;80;80;80 20:53:19 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;63;66;70;75;74;71;69;70;70;70 20:53:19 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;86;87;82;85;84;83;87;83;89;86 20:53:19 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;138;138;132;138;145;148;145;138;189;143 20:53:19 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;169;167;166;173;166;169;168;166;167;169 20:53:19 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;66;67;68;68;66;67;66;67;68;66 20:53:19 INFO - PROCESS | 1667 | __end_tp_report 20:53:19 INFO - PROCESS | 1667 | __start_cc_report 20:53:19 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1995 20:53:19 INFO - PROCESS | 1667 | __end_cc_report 20:53:19 INFO - PROCESS | 1667 | __startTimestamp1473393199815__endTimestamp 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | ------- Summary: start ------- 20:53:19 INFO - PROCESS | 1667 | Number of tests: 14 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:115.10 Median:116.00 stddev:4.75 (4.1%) stddev-sans-first:4.80 20:53:19 INFO - PROCESS | 1667 | Values: 111.0 112.0 116.0 116.0 122.0 116.0 113.0 111.0 110.0 124.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:136.30 Median:146.50 stddev:29.40 (20.1%) stddev-sans-first:31.02 20:53:19 INFO - PROCESS | 1667 | Values: 145.0 114.0 128.0 161.0 111.0 128.0 148.0 137.0 198.0 93.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:183.00 Median:184.00 stddev:2.11 (1.1%) stddev-sans-first:1.94 20:53:19 INFO - PROCESS | 1667 | Values: 186.0 186.0 184.0 182.0 184.0 181.0 180.0 181.0 184.0 182.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:82.30 Median:63.00 stddev:35.69 (56.6%) stddev-sans-first:37.82 20:53:19 INFO - PROCESS | 1667 | Values: 78.0 73.0 77.0 156.0 58.0 57.0 62.0 64.0 140.0 58.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:88.50 Median:70.00 stddev:25.26 (36.1%) stddev-sans-first:26.65 20:53:19 INFO - PROCESS | 1667 | Values: 81.0 83.0 136.0 69.0 72.0 70.0 118.0 69.0 70.0 117.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:85.10 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.62 20:53:19 INFO - PROCESS | 1667 | Values: 87.0 84.0 86.0 84.0 86.0 83.0 87.0 83.0 87.0 84.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:94.50 stddev:2.15 (2.3%) stddev-sans-first:2.24 20:53:19 INFO - PROCESS | 1667 | Values: 94.0 94.0 94.0 101.0 95.0 95.0 96.0 94.0 94.0 95.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:0.63 (0.8%) stddev-sans-first:0.67 20:53:19 INFO - PROCESS | 1667 | Values: 84.0 84.0 83.0 84.0 84.0 83.0 84.0 85.0 83.0 84.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.50 stddev:12.27 (19.0%) stddev-sans-first:12.64 20:53:19 INFO - PROCESS | 1667 | Values: 56.0 56.0 56.0 56.0 51.0 55.0 73.0 80.0 80.0 80.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:3.46 (4.9%) stddev-sans-first:2.65 20:53:19 INFO - PROCESS | 1667 | Values: 63.0 66.0 70.0 75.0 74.0 71.0 69.0 70.0 70.0 70.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:86.00 stddev:2.20 (2.6%) stddev-sans-first:2.32 20:53:19 INFO - PROCESS | 1667 | Values: 86.0 87.0 82.0 85.0 84.0 83.0 87.0 83.0 89.0 86.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:144.00 stddev:16.03 (11.1%) stddev-sans-first:16.78 20:53:19 INFO - PROCESS | 1667 | Values: 138.0 138.0 132.0 138.0 145.0 148.0 145.0 138.0 189.0 143.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:168.50 stddev:2.16 (1.3%) stddev-sans-first:2.26 20:53:19 INFO - PROCESS | 1667 | Values: 169.0 167.0 166.0 173.0 166.0 169.0 168.0 166.0 167.0 169.0 20:53:19 INFO - PROCESS | 1667 | 20:53:19 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.87 20:53:19 INFO - PROCESS | 1667 | Values: 66.0 67.0 68.0 68.0 66.0 67.0 66.0 67.0 68.0 66.0 20:53:19 INFO - PROCESS | 1667 | -------- Summary: end -------- 20:53:19 INFO - PROCESS | 1667 | 20:53:20 INFO - TEST-INFO | 1667: exit 0 20:53:20 INFO - TEST-OK | kraken | took 58896ms 20:53:20 INFO - SUITE-END | took 1176s 20:53:20 INFO - Completed test suite (00:19:36) 20:53:20 INFO - javascript benchmark 20:53:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2963.836988965607, 3222.838858057098, 3282.556854824598, 3311.519829624703, 3340.16495876031, 5379.166229234465, 5442.782288708452, 5478.04134276502, 5487.725613719313, 5536.3909969903325, 4358.171947330013, 5488.4237155098635, 5545.195946587743, 5552.805651802188, 5573.08044172711, 4988.152014157602, 5020.0963826511215, 5069.214271787879, 5075.720835354073, 5218.112920803473, 5147.974260128694, 5149.691018538881, 5174.560162386183, 5175.275461435402, 5186.118359878829, 5015.1975683890405, 5016.49835016499, 5061.949380506185, 5093.006863661588, 5100.566451851597, 4180.707350485467, 5034.798608055673, 5050.924236136461, 5119.001794650045, 5175.560077393426, 5015.924761128586, 5062.012907483042, 5081.390233172043, 5082.79668813247, 5108.693478391308, 5372.462753724635, 5461.5357694596005, 5477.808276710336, 5505.476979686923, 5517.779288828482, 5927.407259274081, 6003.789867354665, 6015.187949626788, 6048.334683184846, 6053.213082299317, 6080.209572755513, 6087.4825639820665, 6110.847228819271, 6115.877682446326, 6131.47882429994, 5464.863378415572, 5518.144687573433, 5619.157126431027, 5661.7735290588325, 5665.915011274834, 6115.057807494006, 6149.985252433388, 6164.4143806338325, 6225.595336303126, 6235.158253635701, 5969.432903874125, 5975.940240597538, 5977.562901331665, 5989.8203053908455, 6031.457168854824, 5843.2403787507355, 6113.266408031065, 6117.449429551273, 6124.234470691164, 6131.233595800525, 5903.793367232212, 5940.940590594125, 6022.096685497123, 6029.095635654687, 6141.785037523755, 6067.0, 6100.511959043266, 6108.450239478469, 6111.602745821597, 6114.57197996136, 4837.32277481153, 4840.903181936342, 4845.0, 4854.368932038883, 4907.607391408679, 4258.701890867609, 4296.914061718748, 4345.630621397154, 4384.101259241847, 4399.318105693623, 5473.972630136824, 5545.0, 5549.6115271931385, 5551.472610102034, 5563.304586926634, 5951.4750210011025, 6102.640737559664, 6173.96913015441, 6185.721642526097, 6201.379862013762, 4954.603631709455, 5506.889862202814, 5519.089350257163, 5560.332760068818, 5593.664380137205, 5189.974050129726, 5256.894862102813, 5331.493508116722, 5332.466753324637, 5345.35855697311, 6224.346443623445, 6231.750729970931, 6244.968775156186, 6245.75016999324, 6253.499720022296, 6143.017117261216, 6148.292946311231, 6158.230221222348, 6171.475424588781, 6177.135201071944, 6107.114468401968, 6123.252118380239, 6153.600015998945, 6238.095476155841, 6256.937430625636, 6009.008513595208, 6062.545309101747, 6071.635701857903, 6086.6652334122045, 6129.509639228941, 6107.480864126599, 6108.267007959073, 6108.511319094373, 6115.755369785159, 6118.5411094169, 5691.373948865705, 5713.200151978644, 5916.26046744157, 5923.585349025527, 5965.642061476326, 5769.884602307847, 5832.854178645568, 5835.766569337179, 5838.474537291664, 5841.1530328101335, 5126.384833819965, 5642.322921249476, 5687.800926967537, 5691.630044047124, 5695.031844586348, 4962.354893863775, 5798.304203495608, 5875.941240587539, 5942.078977758454, 6003.519718422428, 5758.913616295675, 5763.7406316715005, 5769.624974376652, 5783.624064435799, 5785.710714464176], "value": 5528.76281808662, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.8634168774431, 515.4510446374611, 521.6687403498779, 529.8410476856947, 552.2434265056872, 31795.68204317956, 41430.171396572085, 41464.37803432951, 41581.376279355834, 43772.56227437725, 42632.786836065774, 42795.14409711804, 42845.785771071176, 42846.0, 43109.78445107778, 8623.482591044525, 9659.178969787576, 9806.705798826046, 9814.165795907356, 9814.214862810994, 8603.741887743377, 10152.03555662211, 11799.469023893946, 11926.701832454171, 12291.323977181251, 12104.878951210507, 12176.878231217708, 12184.573539926105, 12187.953011747064, 12196.329201893892, 12038.69903252417, 12122.333271670099, 12141.514339426501, 12148.57479988205, 12148.635540933787, 11863.52545898163, 11952.282863028246, 11953.223040502426, 11953.701157471045, 11954.701132471671, 33522.1619459513, 40840.79579602083, 40870.591294086975, 40873.795631021654, 40936.79531602353, 3194.1375828526166, 3204.295055087877, 3248.3340915112344, 3254.381667483194, 3317.402867483852, 30603.387932241232, 31560.526592101138, 31613.0, 31671.891483797957, 31859.84070079658, 3262.869485220588, 3265.3182717923596, 3372.19067423819, 3398.2014226656715, 3400.1669590950128, 509915.0, 510559.4472027653, 511079.4446027783, 511969.0, 514490.0, 58581.70709146427, 59095.70452147798, 59318.406815932154, 59467.702661486415, 59517.70241148853, 62174.0, 62185.067223991675, 62321.702655273904, 62351.752964940446, 62375.6881215591, 15617.68764624724, 15830.52508424749, 15834.920825395799, 15849.128297943724, 15891.28489217988, 11122.332660040423, 11375.943120284512, 12314.953228975637, 12374.938125309316, 12391.442385092692, 16002.0, 16397.0, 16408.589785255463, 16580.9222400546, 16582.585435364213, 6722.731090756315, 6748.257691653914, 6775.864482710318, 6798.320167983162, 6875.518713690094, 848.5969164646743, 919.7470695558641, 952.48565774481, 973.6884197056874, 987.2990176974429, 28492.572611410844, 29459.86744109333, 29505.557416638767, 30151.793928243063, 30303.69696303053, 993.9403635781877, 1049.1606714628267, 1056.0917610854658, 1109.7724966381872, 1120.915931305139, 12213.824405654594, 13383.665408364674, 15384.769228461582, 15443.0, 15780.526584202493, 663689.3631063724, 670136.0, 670221.6488917525, 672005.0, 672046.0, 56705.0, 56786.0, 56907.71546142243, 56959.0, 56999.14501282484, 1076.7523469601879, 1181.639599922032, 1307.4181989014796, 1326.2838067443665, 1338.7590233757821, 26707.33231669224, 28738.85630571834, 29197.41605167842, 30780.53829192519, 31613.683863161074, 49126.0, 49614.25578616252, 49704.75147624239, 49761.25358119704, 49841.753956151384, 515650.0, 517193.2421013763, 517802.41098794265, 519359.66083222034, 519940.0, 38750.80624596859, 39204.0, 39307.410388844764, 39418.40872386974, 39479.0, 46523.53476465192, 46920.530794691615, 47890.760546197045, 48309.5169048305, 48449.0, 37809.96269475501, 38237.617623823404, 38702.6129738699, 39703.007424813455, 40513.0, 36927.63072369242, 38354.0, 38745.0, 39178.02099620236, 40199.79900100481, 37183.0, 37820.243595127395, 38282.0, 38325.808370957966, 38422.423663644506, 27479.0, 31570.526442103845, 34495.827520862236, 34547.0, 34657.1335716599, 21635.891820540797, 24851.25446236616, 25378.873105634353, 25492.107776227742, 25682.61476077823], "value": 20292.365690652157, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.6910304313946, 349.481020684284, 368.6939839932856, 381.2185020707549, 403.9919201615968, 14810.25948702566, 16166.272517736701, 19258.22967081315, 19302.903485482588, 19356.709649355234, 19685.901570492162, 20125.39623811288, 20721.481962950933, 20787.480312992182, 20936.848473333997, 5330.626856120073, 5806.796762113327, 6061.090836374535, 6068.180795592582, 6068.393160683919, 5885.676287804169, 5977.043673012319, 6002.519798416116, 6014.939850601481, 6025.789097381593, 5755.107958266475, 5962.135490353896, 5974.133750606159, 5977.910331345033, 6063.787767428141, 5605.710686542118, 6071.848203794918, 6105.481034112106, 6123.234595675541, 6134.355892631254, 5453.034743194903, 5780.0, 5871.1193321001765, 5938.524918006549, 5942.61373010753, 12880.742385152338, 13130.86869131306, 13137.540186093442, 20395.388138355724, 24820.627690584657, 2089.0286017002013, 2250.718660167479, 2506.736792636765, 2673.2381271337576, 2730.7269273072534, 9212.907870921272, 9738.318317717763, 9804.166645835112, 10210.693679189562, 10290.228232882564, 1797.218210078607, 1848.5471059590352, 1859.739636450898, 1864.077281745531, 1879.0, 125526.11710824344, 132594.3370283152, 134776.9783453249, 134829.0, 138939.0, 10559.894401056044, 10605.204609654153, 11267.774644507228, 11278.830817537744, 11505.252158609663, 14233.64415889611, 14274.928625356806, 14286.499972500913, 14289.285535723173, 14308.928455357656, 8006.71976480832, 8057.556834374049, 8308.29980256419, 8350.958245208734, 8440.11378805229, 7240.674169662378, 8281.29608983231, 8292.709755158541, 8494.872576911352, 8514.276286515591, 4385.912281754411, 4394.604485596247, 4399.670024748157, 4407.713498622579, 4459.7547134907245, 1819.2268285978407, 1868.6078871241014, 1872.550587858904, 1895.3176856331709, 1903.4765439504247, 828.8508068547719, 856.8800367948493, 865.4936861935712, 886.663068034143, 960.673371053845, 19757.308494202633, 20291.086901089486, 20419.79580204209, 20431.87624680657, 20543.4864128398, 875.9882335901916, 909.8134882349102, 915.1717695485596, 939.7133874168444, 955.3933252384786, 10260.612200305895, 10884.564617415215, 10896.848480019213, 10903.890961090447, 10912.61805836792, 598201.0, 599666.0, 606733.0, 609766.0, 613734.0, 121770.0, 123050.0, 130988.0, 131870.68129318583, 133100.0, 948.7865230323122, 966.7196513011148, 993.8310487217191, 996.8685541909985, 1001.159026417798, 13927.23400212998, 17532.649347013245, 18277.908610456863, 18381.448556543324, 18449.354272600394, 44150.77085176392, 56394.7180264096, 56697.0, 57271.713641431525, 57304.14043789263, 81872.18127818646, 82243.0, 82588.3482330362, 82822.5459708629, 82861.58569207115, 7647.88528172089, 7852.450328476952, 7856.764297071097, 8110.351171906115, 8136.471129376809, 10308.896911030793, 12137.21108127969, 12485.188462749893, 12872.420741066488, 13161.934190328988, 10919.0, 10996.28524145928, 11366.829497557377, 11415.885841141482, 11641.359725214856, 9563.856542151734, 11082.833757493483, 11197.832032519356, 11255.887441125484, 11682.182247242612, 10272.845907311246, 10514.894851051698, 11481.196316257781, 11633.825492617449, 11789.174757767218, 7806.92193078062, 7828.765137045898, 8048.476849004796, 8255.463394879554, 8282.917170828456, 9955.701328959853, 11172.441377931233, 11430.25703329281, 11635.243709158878, 11724.531018759155], "value": 9397.86929681501, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1343.1403901503033, 1508.7284288828014, 1539.738244498435, 1732.6967780638386, 1894.2517705506325, 7766.57283849388, 8633.179847914445, 8885.511296878669, 8917.0, 9020.097990200993, 8823.205911467967, 8882.911170888305, 8887.155720206587, 8901.376903616749, 8920.375573709827, 2360.551495215908, 2391.3184742348435, 2391.497785465054, 2395.628677554973, 2398.7481314461907, 2325.3140323604575, 2365.550545396369, 2370.419247284418, 2371.5494056129282, 2378.262738551046, 2336.813054955608, 2361.657559653849, 2365.6924599802, 2374.620060790274, 2381.666566680666, 2334.124703236286, 2356.9764302357016, 2361.2562042956415, 2368.3605426534828, 2376.097083108425, 2318.13070098713, 2342.566625174331, 2343.1564636730764, 2347.0377145370485, 2352.7059117610297, 8758.518281494515, 8935.776605584848, 8938.284937205008, 8951.641934322619, 8981.281497480186, 5241.580673546107, 5420.403755586883, 5431.131019036935, 5474.671519708831, 5482.17767334899, 3680.907977300562, 3690.225052738928, 3752.699784017272, 3761.811909404546, 3772.0, 1080.5515710980012, 1090.1278976818514, 1091.5251865438481, 1112.104755671684, 1114.8717897441818, 11682.182247242696, 11713.121515886343, 11786.292822430682, 11804.527818887234, 11815.232009919242, 4394.450693663292, 4491.865244042684, 4640.095181439654, 4649.139909116824, 4662.044280922402, 9132.771680708036, 9135.314851386172, 9139.131782480654, 9142.908570914338, 9154.130357616015, 5230.92153617696, 5232.895342093137, 5243.292155559027, 5247.396549396792, 5249.632525723239, 4225.873223803229, 4285.46431696038, 4368.934465983013, 4511.616512596466, 4902.411710594752, 3712.9628703712615, 3784.981075094662, 3792.563855156692, 3830.3871380578976, 3836.5204349456317, 1163.4357336691698, 1165.4405885175163, 1170.4089434835523, 1172.1267655596464, 1174.8531433570804, 748.0948052855996, 825.6325934959006, 888.7200531832452, 895.3463971300987, 896.1934259166671, 5747.597668163188, 5817.7963771267805, 5857.740585774079, 5917.349091599921, 6116.296625887991, 405.1888060370141, 428.2783509786021, 439.45946485822617, 442.82508409178195, 455.794892298467, 3950.634548823566, 3959.148783011666, 3997.8066895967186, 4101.569335219819, 4120.876373708794, 10294.3823370598, 10823.971722686332, 10840.837387439195, 10893.400862952456, 10909.0, 10750.516226769814, 10765.515551800188, 10778.89221107779, 10779.461026948778, 10801.351918884891, 8234.300084492885, 8293.668253269921, 8303.294219991369, 8303.924018995373, 8325.377506085684, 4965.865286317741, 5608.803691870765, 5889.381614930542, 5898.764049437975, 5910.852228694145, 10058.34620749649, 10068.39589624625, 10105.39367637944, 10132.138768204784, 10166.085052345325, 9301.767455813659, 9830.705078847646, 10092.091711745978, 10265.2301077418, 10305.690829275134, 6322.873542529215, 6833.658317084225, 6948.478864085315, 7059.71761129549, 7097.219305876452, 8260.586970651564, 8266.173382661686, 8288.0, 8381.203785640599, 8382.077971423261, 6811.761588344384, 7400.888986665312, 7537.321641052332, 7570.886436703344, 7639.732609358646, 7227.2772722729405, 7323.707051717872, 7365.079365079365, 7432.033835601337, 7485.737999170221, 6947.513674042769, 6972.965135174291, 7027.648617568999, 7049.541779784297, 7067.222605513287, 4921.680090794087, 5036.244563315386, 5074.6194035448225, 5084.2881996521255, 5243.947560524499, 4999.77501012448, 5042.168042272984, 5104.132297509506, 5212.791488340424, 5291.3121294231505], "value": 4465.357629389155, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.7108678061597, 295.58913110776024, 311.58403531285734, 311.65873368661323, 312.1478364066098, 4375.993521490059, 4460.799264033114, 4693.507181745918, 5031.1698569736, 5088.643794934347, 4830.574980380788, 4939.209346719102, 4993.400791904976, 5141.562967147797, 5145.948540514593, 3426.0, 3893.792924193502, 3986.621270979252, 4009.939850902239, 4011.2178125265586, 3315.4529502632035, 3986.986571781261, 4014.0767623446623, 4049.372347286175, 4070.4097905803787, 3298.9670103299018, 3773.905652358699, 3779.7165212609166, 3847.172593018308, 4016.5983401659746, 3314.96685033149, 3992.7604343739467, 3996.4205190247253, 4029.2747305485, 4036.8898552897895, 3496.8251587420527, 3512.525809015776, 3522.3659741246565, 3533.2050288685095, 3540.203454222805, 4683.976580117112, 5057.367829021372, 5098.847034588968, 5106.157484015133, 5110.897782044375, 4062.8014735653055, 4142.751434913885, 4193.014641559231, 4315.892102697427, 4414.713043652152, 4847.418309802846, 5031.496850314939, 5118.872028199287, 5415.13357862744, 5464.644798088151, 3421.3328400961823, 3571.8571257149683, 3718.51659284294, 3733.8133093345496, 3767.6232376762377, 5032.446430892599, 5321.669582604349, 5499.560035197174, 5654.293213348373, 5718.855429214044, 3560.376934036585, 3638.199596088856, 3791.810409479515, 3798.8100594970692, 3821.5987321331418, 3260.5653512454446, 3574.5353104096303, 3864.265789499986, 3879.243547508209, 3881.4177873318663, 4318.784060796947, 4460.598546130864, 4477.888052798641, 4491.71985983993, 4513.729176249435, 5435.701036442954, 5448.346198456132, 5478.45215478457, 5480.561555075585, 5504.697241651667, 1285.9262515799226, 1309.2668105860748, 1309.6594885329691, 1314.3362601885985, 1315.4540865540885, 675.1796567170911, 785.5757890739051, 789.3251270163927, 811.8213992921548, 838.6393850644159, 3971.72197946141, 4622.029373831533, 4946.925796113062, 5078.21287700407, 5164.0, 4839.41926968766, 4866.659333846667, 5320.042392369333, 5346.732663366816, 5628.549716022709, 4687.250039993584, 5065.189569668835, 5070.847874563769, 5109.846704598868, 5137.357830271216, 2462.630605409167, 2778.6943436222, 2906.8837246510325, 2907.9418411632073, 2909.2145120817686, 5643.802466913638, 5715.514181294637, 5726.656400615893, 5755.877603867206, 5835.883282334415, 5302.814401496006, 5687.260656114679, 5712.487135163175, 5715.914261286084, 5729.510327314845, 5793.942060579425, 5797.913031304533, 5816.185733997159, 5816.592838501264, 5836.270466191726, 4690.15577196108, 4949.257611358325, 5572.582056345871, 5595.5803314752375, 5639.88720225585, 4825.638077144158, 4901.362822833009, 5536.861578460571, 5566.332040155207, 5584.46947539983, 4911.484294149063, 5134.845954621367, 5795.565332600157, 5946.837213950339, 6038.486728628116, 3146.716795488417, 3410.403179443637, 3562.8955023942663, 3592.2456284180616, 3748.6626203641804, 4705.6000239979985, 5449.782008719607, 5529.889402212014, 5540.252065971043, 5556.277683901229, 4922.72924989129, 4979.261406226633, 5128.58971282304, 5389.622726409114, 5390.703511306916, 4341.848035318749, 5033.546980771748, 5169.880349056869, 5380.950714610613, 5423.4034256231, 5310.230016647642, 5338.3593968724, 5347.379783905781, 5350.491703288181, 5350.598705097055, 3940.3402703175816, 5184.703824043989, 5410.458954104558, 5496.780128794963, 5521.641093328921, 5236.319278493771, 5279.102552565996, 5295.073362161529, 5301.708406037705, 5305.787768489372], "value": 3946.9549757839236, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.5458906299968, 713.5861200503705, 725.0719079578139, 804.9254245582148, 807.100083407001, 15129.24353782312, 16068.437604683824, 16072.598185045379, 16075.598110047255, 16078.27647755851, 14439.494617688382, 14600.488982885601, 14618.780718289234, 14621.634459138528, 14648.194349310783, 647.4367300448592, 665.6771465839064, 814.7433558429076, 821.5112008355019, 839.7858546070773, 802.1256830055239, 814.1166833985134, 814.771863878115, 815.2621877201112, 818.8280461103176, 878.6178012564523, 878.8945326560789, 879.1208791208791, 880.6961598248625, 882.2104216726059, 689.8068540808531, 709.2659097833717, 713.1798431803415, 873.8820259264982, 874.0341922176, 527.8574784808119, 636.11897521932, 652.9347065293433, 658.2364457229638, 659.6437923521293, 14366.784498232535, 14393.0, 14441.133531988009, 14451.78322325166, 14483.058601190889, 13354.332283385791, 13508.121972071784, 13718.039737218398, 13801.309934503335, 14076.92961535196, 11933.582324618683, 12273.447694853752, 12313.787559789533, 12337.321447320393, 12811.0, 4418.664758042073, 4505.662075344329, 4543.204939135671, 4544.818207271705, 4558.908821823545, 121576.78423215832, 125266.37366813199, 127116.0, 127961.0, 128824.0, 9465.479398633004, 10735.89264107349, 10764.354138751543, 10836.0, 10908.945455272673, 20558.69161962572, 20779.584408311748, 20796.064177112064, 20817.895910520652, 20823.791762082186, 7476.738314158978, 7513.624318784039, 7551.622418879144, 7557.168711441737, 7727.111382191008, 4221.050263690707, 4225.154969006211, 4235.830566777356, 4254.532001479834, 4380.058287468146, 3265.9020229393154, 3352.3295340931913, 3357.496375543688, 3358.3115461330367, 3361.058903507022, 565.4825834361595, 704.8202708309349, 708.0158579574396, 715.2454160860342, 715.4240836126863, 611.5566214494545, 612.7212118486078, 641.9293877673452, 650.2814390098968, 652.2010537092119, 9726.854097188549, 9738.610455581698, 9771.560279787285, 9793.804123917482, 9804.509774511245, 647.9910293431799, 652.4975768658106, 678.1218322272571, 687.5702685821361, 693.1543516909362, 4542.205987453337, 4614.123316569841, 4624.768761561909, 4675.298705194248, 4721.362616046789, 88839.0, 124302.0, 148499.0, 156879.0, 161374.0, 134807.0, 134957.92111331818, 139963.0, 141125.58874411124, 141442.0, 92097.0, 102425.34235274832, 109296.17463388744, 109764.0, 118664.0, 8486.915130848613, 8582.699605513777, 8598.097199793932, 8753.737387878375, 8838.690645827364, 48861.75569122132, 49143.262851057974, 53768.731156343965, 60744.0, 63383.6830815843, 92909.0, 94285.0, 94706.0, 97040.0, 97326.0, 11401.828972565583, 11609.0, 11615.245009074382, 11669.88330116688, 11954.163208575315, 12129.272243665408, 12377.443015064164, 12466.626001219978, 12715.491380345053, 12882.0, 9836.0, 12122.27266364021, 12241.265524068584, 12391.690207744878, 12427.975362606723, 9771.267154963549, 11816.054715622902, 11942.462589183333, 11997.520099195934, 12422.565210217599, 11736.185739634482, 11752.360965372589, 11761.480056035563, 11947.163698541019, 11954.0, 8123.5532045738055, 9235.353525253167, 9769.706908792748, 9916.950415248166, 10325.9483702581, 9497.28770342241, 9926.801463970825, 10064.647737329158, 10082.294239403465, 10092.646757363456], "value": 7548.935054087481, "unit": "score"}], "name": "dromaeo_css", "value": 7208.428675060887, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 116.0, 116.0, 122.0, 116.0, 113.0, 111.0, 110.0, 124.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 114.0, 128.0, 161.0, 111.0, 128.0, 148.0, 137.0, 198.0, 93.0], "value": 136.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 184.0, 182.0, 184.0, 181.0, 180.0, 181.0, 184.0, 182.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 77.0, 156.0, 58.0, 57.0, 62.0, 64.0, 140.0, 58.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 136.0, 69.0, 72.0, 70.0, 118.0, 69.0, 70.0, 117.0], "value": 88.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 84.0, 86.0, 83.0, 87.0, 83.0, 87.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 101.0, 95.0, 95.0, 96.0, 94.0, 94.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 84.0, 84.0, 83.0, 84.0, 85.0, 83.0, 84.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 56.0, 51.0, 55.0, 73.0, 80.0, 80.0, 80.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 70.0, 75.0, 74.0, 71.0, 69.0, 70.0, 70.0, 70.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 82.0, 85.0, 84.0, 83.0, 87.0, 83.0, 89.0, 86.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 132.0, 138.0, 145.0, 148.0, 145.0, 138.0, 189.0, 143.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 166.0, 173.0, 166.0, 169.0, 168.0, 166.0, 167.0, 169.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 68.0, 66.0, 67.0, 66.0, 67.0, 68.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1468.9, "alertThreshold": 2.0}]} 20:53:21 INFO - Return code: 0 20:53:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:53:21 INFO - # TBPL SUCCESS # 20:53:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:53:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:53:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:53:21 INFO - 2016-09-08 20:53:21.126 screenresolution[1671:9890] starting screenresolution argv=screenresolution get 20:53:21 INFO - 2016-09-08 20:53:21.128 screenresolution[1671:9890] Display 0: 1600x1200x32@60 20:53:21 INFO - 2016-09-08 20:53:21.134 screenresolution[1672:9893] starting screenresolution argv=screenresolution list 20:53:21 INFO - Available Modes on Display 0 20:53:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:53:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:53:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:53:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:53:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:53:21 INFO - Intel Iris: 20:53:21 INFO - Chipset Model: Intel Iris 20:53:21 INFO - Type: GPU 20:53:21 INFO - Bus: Built-In 20:53:21 INFO - VRAM (Dynamic, Max): 1536 MB 20:53:21 INFO - Vendor: Intel (0x8086) 20:53:21 INFO - Device ID: 0x0a2e 20:53:21 INFO - Revision ID: 0x0009 20:53:21 INFO - Displays: 20:53:21 INFO - Display: 20:53:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:53:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:53:21 INFO - Display Serial Number: 1600x1200 60 20:53:21 INFO - Main Display: Yes 20:53:21 INFO - Mirror: Off 20:53:21 INFO - Online: Yes 20:53:21 INFO - Rotation: Supported 20:53:21 INFO - Return code: 0 20:53:21 INFO - Running post-action listener: _resource_record_post_action 20:53:21 INFO - [mozharness: 2016-09-09 03:53:21.370023Z] Finished run-tests step (success) 20:53:21 INFO - Running post-run listener: _resource_record_post_run 20:53:21 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37311488; Write bytes: 704607232; Read time: 237; Write time: 1953 20:53:21 INFO - TinderboxPrint: CPU usage
53.3% 20:53:21 INFO - TinderboxPrint: I/O read bytes / time
37,311,488 / 237 20:53:21 INFO - TinderboxPrint: I/O write bytes / time
704,607,232 / 1,953 20:53:21 INFO - TinderboxPrint: CPU idle
2,262.3 (46.7%) 20:53:21 INFO - TinderboxPrint: CPU system
965.2 (19.9%) 20:53:21 INFO - TinderboxPrint: CPU user
1,618.2 (33.4%) 20:53:21 INFO - TinderboxPrint: Swap in / out
443,195,392 / 0 20:53:21 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 399639040; Write bytes: 387831296; Read time: 29352; Write time: 800 20:53:21 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30112768; Write bytes: 295996928; Read time: 179; Write time: 1101 20:53:21 INFO - Running post-run listener: _upload_blobber_files 20:53:21 INFO - Blob upload gear active. 20:53:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:53:21 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 20:53:21 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:53:21 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:53:22 INFO - (blobuploader) - INFO - Open directory for files ... 20:53:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:53:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:53:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:53:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:53:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:53:23 INFO - (blobuploader) - INFO - Done attempting. 20:53:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:53:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:53:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:53:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:53:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:53:23 INFO - (blobuploader) - INFO - Done attempting. 20:53:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:53:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:53:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:53:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:53:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:53:26 INFO - (blobuploader) - INFO - Done attempting. 20:53:26 INFO - (blobuploader) - INFO - Iteration through files over. 20:53:26 INFO - Return code: 0 20:53:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:53:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:53:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4f60525d9f4416b76e59c51f96c51418203b84ffabe08c6d64fbe843824253b759dbe4190b560523e7dea321bc275e2a5da8aa134fe4a8b2456584147c24dcb5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/31192229671f36d28bf91b1c62a11190d4ce674dfa6c6fccc663c73b3511d14f5d328e8cf544212245d315c672da726ceaebefd43ff557aae8e33f8f93641190", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/310ad7f4910e4430ec9fc7151783237bce55c1eeb9f88cb95d4517ab9b0f6cd06490ff5fb09a7e7b1601597cbf9f038a4e5ab4151fde94a252ad7c846092e4d1"} 20:53:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:53:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:53:26 INFO - Contents: 20:53:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4f60525d9f4416b76e59c51f96c51418203b84ffabe08c6d64fbe843824253b759dbe4190b560523e7dea321bc275e2a5da8aa134fe4a8b2456584147c24dcb5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/31192229671f36d28bf91b1c62a11190d4ce674dfa6c6fccc663c73b3511d14f5d328e8cf544212245d315c672da726ceaebefd43ff557aae8e33f8f93641190", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/310ad7f4910e4430ec9fc7151783237bce55c1eeb9f88cb95d4517ab9b0f6cd06490ff5fb09a7e7b1601597cbf9f038a4e5ab4151fde94a252ad7c846092e4d1"} 20:53:26 INFO - Running post-run listener: copy_logs_to_upload_dir 20:53:26 INFO - Copying logs to upload dir... 20:53:26 INFO - mkdir: /builds/slave/test/build/upload/logs 20:53:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.912934 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-08 20:53:26.307554) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 20:53:26.311519) ========= 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.6tinEH3tNo/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.Xc08ATsmjF/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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/Autoland/sha512/4f60525d9f4416b76e59c51f96c51418203b84ffabe08c6d64fbe843824253b759dbe4190b560523e7dea321bc275e2a5da8aa134fe4a8b2456584147c24dcb5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/31192229671f36d28bf91b1c62a11190d4ce674dfa6c6fccc663c73b3511d14f5d328e8cf544212245d315c672da726ceaebefd43ff557aae8e33f8f93641190", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/310ad7f4910e4430ec9fc7151783237bce55c1eeb9f88cb95d4517ab9b0f6cd06490ff5fb09a7e7b1601597cbf9f038a4e5ab4151fde94a252ad7c846092e4d1"} build_url:https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011030 build_url: 'https://queue.taskcluster.net/v1/task/IJUjjdomQxWX5661nnnU9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4f60525d9f4416b76e59c51f96c51418203b84ffabe08c6d64fbe843824253b759dbe4190b560523e7dea321bc275e2a5da8aa134fe4a8b2456584147c24dcb5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/31192229671f36d28bf91b1c62a11190d4ce674dfa6c6fccc663c73b3511d14f5d328e8cf544212245d315c672da726ceaebefd43ff557aae8e33f8f93641190", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/310ad7f4910e4430ec9fc7151783237bce55c1eeb9f88cb95d4517ab9b0f6cd06490ff5fb09a7e7b1601597cbf9f038a4e5ab4151fde94a252ad7c846092e4d1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 20:53:26.345517) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:53:26.345835) ========= 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.6tinEH3tNo/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.Xc08ATsmjF/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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.005084 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:53:26.394036) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 20:53:26.394455) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 20:53:26.394882) ========= ========= Total master_lag: 0.17 =========