builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0388 starttime: 1473390502.36 results: success (0) buildid: 20160908182319 builduid: 3be97246cec8409487ae69774c3ad2f5 revision: c82d3d5d08864a2d437db3c1ae9a669249d36575 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 20:08:22.357006) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 20:08:22.357743) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 20:08:22.358437) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 20:08:22.386386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:08:22.387061) ========= 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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:08:22-- 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% 10.2M=0.001s 2016-09-08 20:08:22 (10.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161372 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:08:22.576649) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:08:22.577311) ========= 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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.020590 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:08:22.630072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 20:08:22.630868) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev c82d3d5d08864a2d437db3c1ae9a669249d36575 --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 c82d3d5d08864a2d437db3c1ae9a669249d36575 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q9BdoxPKH4/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.WA26eQW5cf/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:08:22,720 truncating revision to first 12 chars 2016-09-08 20:08:22,720 Setting DEBUG logging. 2016-09-08 20:08:22,720 attempt 1/10 2016-09-08 20:08:22,721 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/c82d3d5d0886?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 20:08:23,527 unpacking tar archive at: autoland-c82d3d5d0886/testing/mozharness/ program finished with exit code 0 elapsedTime=1.069423 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 20:08:23.727597) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 20:08:23.728378) ========= script_repo_revision: c82d3d5d08864a2d437db3c1ae9a669249d36575 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 20:08:23.729339) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 20:08:23.729854) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 20:08:23.750412) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-09-08 20:08:23.751122) ========= /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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:08:23 INFO - MultiFileLogger online at 20160908 20:08:23 in /builds/slave/test 20:08:23 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:08:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:08:23 INFO - {'append_to_log': False, 20:08:23 INFO - 'base_work_dir': '/builds/slave/test', 20:08:23 INFO - 'blob_upload_branch': 'Autoland', 20:08:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:08:23 INFO - 'branch': 'Autoland', 20:08:23 INFO - 'buildbot_json_path': 'buildprops.json', 20:08:23 INFO - 'config_files': ('talos/mac_config.py',), 20:08:23 INFO - 'default_actions': ('clobber', 20:08:23 INFO - 'read-buildbot-config', 20:08:23 INFO - 'download-and-extract', 20:08:23 INFO - 'populate-webroot', 20:08:23 INFO - 'create-virtualenv', 20:08:23 INFO - 'install', 20:08:23 INFO - 'run-tests'), 20:08:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:08:23 INFO - 'download_minidump_stackwalk': True, 20:08:23 INFO - 'download_symbols': 'ondemand', 20:08:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:08:23 INFO - 'tooltool.py': '/tools/tooltool.py', 20:08:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:08:23 INFO - '/tools/misc-python/virtualenv.py')}, 20:08:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:08:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:08:23 INFO - 'installer_path': 'installer.exe', 20:08:23 INFO - 'log_level': 'info', 20:08:23 INFO - 'log_name': 'talos', 20:08:23 INFO - 'log_to_console': True, 20:08:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:08:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:08:23 INFO - 'opt_config_files': (), 20:08:23 INFO - 'pip_index': False, 20:08:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:08:23 INFO - 'cmd': ('bash', 20:08:23 INFO - '-c', 20:08:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:08:23 INFO - 'enabled': True, 20:08:23 INFO - 'halt_on_failure': False, 20:08:23 INFO - 'name': 'check_screen_resolution'},), 20:08:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:08:23 INFO - 'cmd': ('bash', 20:08:23 INFO - '-c', 20:08:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:08:23 INFO - 'enabled': True, 20:08:23 INFO - 'halt_on_failure': False, 20:08:23 INFO - 'name': 'check_screen_resolution'},), 20:08:23 INFO - 'run_cmd_checks_enabled': True, 20:08:23 INFO - 'sps_profile': False, 20:08:23 INFO - 'sps_profile_interval': 0, 20:08:23 INFO - 'suite': 'dromaeojs', 20:08:23 INFO - 'system_bits': '32', 20:08:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:08:23 INFO - 'title': 't-yosemite-r7-0388', 20:08:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:08:23 INFO - 'use_talos_json': True, 20:08:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:08:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:08:23 INFO - 'work_dir': 'build'} 20:08:23 INFO - [mozharness: 2016-09-09 03:08:23.946206Z] Running clobber step. 20:08:23 INFO - Running pre-action listener: _resource_record_pre_action 20:08:23 INFO - Running main action method: clobber 20:08:23 INFO - rmtree: /builds/slave/test/build 20:08:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:08:24 INFO - Running post-action listener: _resource_record_post_action 20:08:24 INFO - [mozharness: 2016-09-09 03:08:24.825787Z] Finished clobber step (success) 20:08:24 INFO - [mozharness: 2016-09-09 03:08:24.825902Z] Running read-buildbot-config step. 20:08:24 INFO - Running pre-action listener: _resource_record_pre_action 20:08:24 INFO - Running main action method: read_buildbot_config 20:08:24 INFO - Using buildbot properties: 20:08:24 INFO - { 20:08:24 INFO - "project": "", 20:08:24 INFO - "product": "firefox", 20:08:24 INFO - "script_repo_revision": "production", 20:08:24 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 20:08:24 INFO - "repository": "", 20:08:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 20:08:24 INFO - "buildid": "20160908182319", 20:08:24 INFO - "pgo_build": "False", 20:08:24 INFO - "basedir": "/builds/slave/test", 20:08:24 INFO - "buildnumber": 240, 20:08:24 INFO - "slavename": "t-yosemite-r7-0388", 20:08:24 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:08:24 INFO - "platform": "yosemite_r7", 20:08:24 INFO - "scheduler": "tests-autoland-macosx64-talos", 20:08:24 INFO - "branch": "autoland", 20:08:24 INFO - "repo_path": "integration/autoland", 20:08:24 INFO - "revision": "c82d3d5d08864a2d437db3c1ae9a669249d36575", 20:08:24 INFO - "stage_platform": "macosx64", 20:08:24 INFO - "builduid": "3be97246cec8409487ae69774c3ad2f5", 20:08:24 INFO - "slavebuilddir": "test" 20:08:24 INFO - } 20:08:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 20:08:24 INFO - Running post-action listener: _resource_record_post_action 20:08:24 INFO - [mozharness: 2016-09-09 03:08:24.828713Z] Finished read-buildbot-config step (success) 20:08:24 INFO - [mozharness: 2016-09-09 03:08:24.828816Z] Running download-and-extract step. 20:08:24 INFO - Running pre-action listener: _resource_record_pre_action 20:08:24 INFO - Running main action method: download_and_extract 20:08:24 INFO - mkdir: /builds/slave/test/build/tests 20:08:24 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:08:24 INFO - trying https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 20:08:24 INFO - Downloading https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/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:08:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/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:08:25 INFO - Downloaded 1199 bytes. 20:08:25 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 20:08:25 INFO - Using the following test package requirements: 20:08:25 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:08:25 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:25 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 20:08:25 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:25 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 20:08:25 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:25 INFO - u'jsshell-mac.zip'], 20:08:25 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:25 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 20:08:25 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:08:25 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:25 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 20:08:25 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:25 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 20:08:25 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:25 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 20:08:25 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:25 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 20:08:25 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:08:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:08:26 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 20:08:33 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:08:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:08:33 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 20:08:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 20:08:41 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 20:08: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:08:42 INFO - trying https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:08:42 INFO - Downloading https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 20:08:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/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:08:45 INFO - Downloaded 111537687 bytes. 20:08:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:08:45 INFO - mkdir: /builds/slave/test/properties 20:08:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:08:45 INFO - Writing to file /builds/slave/test/properties/build_url 20:08:45 INFO - Contents: 20:08:45 INFO - build_url:https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:08:46 INFO - Running post-action listener: _resource_record_post_action 20:08:46 INFO - Running post-action listener: set_extra_try_arguments 20:08:46 INFO - [mozharness: 2016-09-09 03:08:46.063324Z] Finished download-and-extract step (success) 20:08:46 INFO - [mozharness: 2016-09-09 03:08:46.063428Z] Running populate-webroot step. 20:08:46 INFO - Running pre-action listener: _resource_record_pre_action 20:08:46 INFO - Running main action method: populate_webroot 20:08:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:08:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:08:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:08:46 INFO - '--tppagecycles', 20:08:46 INFO - '7'], 20:08:46 INFO - 'tests': ['tsvgm']}}, 20:08:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:08:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:08:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:08:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:08:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:08:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:08:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:46 INFO - 'talos_options': ['--disable-e10s'], 20:08:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:08:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:08:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:46 INFO - 'talos_options': ['--disable-e10s'], 20:08:46 INFO - 'tests': ['damp', 'tps']}, 20:08:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:46 INFO - 'tests': ['damp', 'tps']}, 20:08:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:08:46 INFO - 'tests': ['dromaeo_dom']}, 20:08:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:08:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:08:46 INFO - 'tests': ['basic_compositor_video']}, 20:08:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:08:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:08:46 INFO - 'tests': ['a11yr', 20:08:46 INFO - 'ts_paint', 20:08:46 INFO - 'tpaint', 20:08:46 INFO - 'sessionrestore', 20:08:46 INFO - 'sessionrestore_no_auto_restore', 20:08:46 INFO - 'tabpaint']}, 20:08:46 INFO - 'other-e10s': {'tests': ['a11yr', 20:08:46 INFO - 'ts_paint', 20:08:46 INFO - 'tpaint', 20:08:46 INFO - 'sessionrestore', 20:08:46 INFO - 'sessionrestore_no_auto_restore', 20:08:46 INFO - 'tabpaint']}, 20:08:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:08:46 INFO - 'ts_paint', 20:08:46 INFO - 'tpaint', 20:08:46 INFO - 'sessionrestore', 20:08:46 INFO - 'sessionrestore_no_auto_restore', 20:08:46 INFO - 'tabpaint']}, 20:08:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:08:46 INFO - 'ts_paint', 20:08:46 INFO - 'tpaint', 20:08:46 INFO - 'sessionrestore', 20:08:46 INFO - 'sessionrestore_no_auto_restore', 20:08:46 INFO - 'tabpaint']}, 20:08:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:08:46 INFO - 'tests': ['a11yr', 20:08:46 INFO - 'ts_paint', 20:08:46 INFO - 'tpaint', 20:08:46 INFO - 'sessionrestore', 20:08:46 INFO - 'sessionrestore_no_auto_restore', 20:08:46 INFO - 'tabpaint']}, 20:08:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:08:46 INFO - 'tests': ['a11yr', 20:08:46 INFO - 'ts_paint', 20:08:46 INFO - 'tpaint', 20:08:46 INFO - 'sessionrestore', 20:08:46 INFO - 'sessionrestore_no_auto_restore', 20:08:46 INFO - 'tabpaint']}, 20:08:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:08:46 INFO - 'tests': ['tsvgx', 20:08:46 INFO - 'tsvgr_opacity', 20:08:46 INFO - 'tart', 20:08:46 INFO - 'tscrollx', 20:08:46 INFO - 'cart']}, 20:08:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:08:46 INFO - 'tsvgr_opacity', 20:08:46 INFO - 'tart', 20:08:46 INFO - 'tscrollx', 20:08:46 INFO - 'cart']}, 20:08:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:46 INFO - 'talos_options': ['--disable-e10s'], 20:08:46 INFO - 'tests': ['tp5o']}, 20:08:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:46 INFO - 'tests': ['tp5o']}, 20:08:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:46 INFO - 'talos_options': ['--disable-e10s', 20:08:46 INFO - '--xperf_path', 20:08:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:08:46 INFO - 'tests': ['tp5n']}, 20:08:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:46 INFO - 'talos_options': ['--xperf_path', 20:08:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:08:46 INFO - 'tests': ['tp5n']}}, 20:08:46 INFO - 'talos.zip': {'path': '', 20:08:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:08:46 INFO - Running post-action listener: _resource_record_post_action 20:08:46 INFO - [mozharness: 2016-09-09 03:08:46.082457Z] Finished populate-webroot step (success) 20:08:46 INFO - [mozharness: 2016-09-09 03:08:46.082588Z] Running create-virtualenv step. 20:08:46 INFO - Running pre-action listener: _resource_record_pre_action 20:08:46 INFO - Running main action method: create_virtualenv 20:08:46 INFO - Creating virtualenv /builds/slave/test/build/venv 20:08:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:08:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:08:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:08:46 INFO - Using real prefix '/tools/python27' 20:08:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:08:46 INFO - Installing distribute.............................................................................................................................................................................................done. 20:08:49 INFO - Installing pip.................done. 20:08:49 INFO - Return code: 0 20:08:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:08:49 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:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:49 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:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:49 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 0x105dd9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e6e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb900490c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3dd30>, '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 0x105aa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d35b20>, '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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:08:49 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:08:49 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:08:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q9BdoxPKH4/Render', 20:08:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:49 INFO - 'HOME': '/Users/cltbld', 20:08:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:49 INFO - 'LOGNAME': 'cltbld', 20:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:49 INFO - 'MOZ_NO_REMOTE': '1', 20:08:49 INFO - 'NO_EM_RESTART': '1', 20:08:49 INFO - 'PAGER': '/bin/cat', 20:08:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:49 INFO - 'PWD': '/builds/slave/test', 20:08:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:49 INFO - 'SHELL': '/bin/bash', 20:08:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WA26eQW5cf/Listeners', 20:08:49 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 20:08:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:49 INFO - 'USER': 'cltbld', 20:08:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:49 INFO - 'XPC_FLAGS': '0x0', 20:08:49 INFO - 'XPC_SERVICE_NAME': '0', 20:08:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:49 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:08:49 INFO - Cleaning up... 20:08:49 INFO - Return code: 0 20:08:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:08:49 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:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105dd9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e6e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb900490c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3dd30>, '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 0x105aa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d35b20>, '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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:08:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q9BdoxPKH4/Render', 20:08:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:50 INFO - 'HOME': '/Users/cltbld', 20:08:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:50 INFO - 'LOGNAME': 'cltbld', 20:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:50 INFO - 'MOZ_NO_REMOTE': '1', 20:08:50 INFO - 'NO_EM_RESTART': '1', 20:08:50 INFO - 'PAGER': '/bin/cat', 20:08:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:50 INFO - 'PWD': '/builds/slave/test', 20:08:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:50 INFO - 'SHELL': '/bin/bash', 20:08:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WA26eQW5cf/Listeners', 20:08:50 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 20:08:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:50 INFO - 'USER': 'cltbld', 20:08:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:50 INFO - 'XPC_FLAGS': '0x0', 20:08:50 INFO - 'XPC_SERVICE_NAME': '0', 20:08:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:50 INFO - Downloading/unpacking psutil>=3.1.1 20:08: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:08: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:08: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:08: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:08: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:08: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:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:08:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:08:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:08:53 INFO - Installing collected packages: psutil 20:08:53 INFO - Running setup.py install for psutil 20:08:53 INFO - building 'psutil._psutil_osx' extension 20:08:53 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:08:53 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:08:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:08:54 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:08:54 INFO - building 'psutil._psutil_posix' extension 20:08:54 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:08:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:08:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:08:54 INFO - ^ 20:08:54 INFO - 1 warning generated. 20:08:54 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:08:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:08:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:08:54 INFO - Successfully installed psutil 20:08:54 INFO - Cleaning up... 20:08:54 INFO - Return code: 0 20:08:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:08: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:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105dd9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e6e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb900490c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3dd30>, '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 0x105aa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d35b20>, '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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:08: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:08: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 mozsystemmonitor==0.3 20:08:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q9BdoxPKH4/Render', 20:08:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:54 INFO - 'HOME': '/Users/cltbld', 20:08:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:54 INFO - 'LOGNAME': 'cltbld', 20:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:54 INFO - 'MOZ_NO_REMOTE': '1', 20:08:54 INFO - 'NO_EM_RESTART': '1', 20:08:54 INFO - 'PAGER': '/bin/cat', 20:08:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:54 INFO - 'PWD': '/builds/slave/test', 20:08:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:54 INFO - 'SHELL': '/bin/bash', 20:08:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WA26eQW5cf/Listeners', 20:08:54 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 20:08:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:54 INFO - 'USER': 'cltbld', 20:08:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:54 INFO - 'XPC_FLAGS': '0x0', 20:08:54 INFO - 'XPC_SERVICE_NAME': '0', 20:08:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:08: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:08: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:08: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:08: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:08: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:08: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:08:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:08:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:08:57 INFO - Installing collected packages: mozsystemmonitor 20:08:57 INFO - Running setup.py install for mozsystemmonitor 20:08:57 INFO - Successfully installed mozsystemmonitor 20:08:57 INFO - Cleaning up... 20:08:57 INFO - Return code: 0 20:08:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:08:57 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:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:57 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:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:57 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 0x105dd9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e6e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb900490c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3dd30>, '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 0x105aa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d35b20>, '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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:08:57 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:08:57 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:08:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q9BdoxPKH4/Render', 20:08:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:57 INFO - 'HOME': '/Users/cltbld', 20:08:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:57 INFO - 'LOGNAME': 'cltbld', 20:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:57 INFO - 'MOZ_NO_REMOTE': '1', 20:08:57 INFO - 'NO_EM_RESTART': '1', 20:08:57 INFO - 'PAGER': '/bin/cat', 20:08:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:57 INFO - 'PWD': '/builds/slave/test', 20:08:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:57 INFO - 'SHELL': '/bin/bash', 20:08:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WA26eQW5cf/Listeners', 20:08:57 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 20:08:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:57 INFO - 'USER': 'cltbld', 20:08:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:57 INFO - 'XPC_FLAGS': '0x0', 20:08:57 INFO - 'XPC_SERVICE_NAME': '0', 20:08:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:57 INFO - Downloading/unpacking jsonschema==2.5.1 20:08: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:08: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:08: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:08: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:08: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:08: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:09:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:09:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:00 INFO - Downloading functools32-3.2.3-2.tar.gz 20:09:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:09:00 INFO - warning: no files found matching '*.txt' 20:09:00 INFO - no previously-included directories found matching 'build' 20:09:00 INFO - no previously-included directories found matching 'dist' 20:09:00 INFO - no previously-included directories found matching '.git*' 20:09:00 INFO - Installing collected packages: jsonschema, functools32 20:09:00 INFO - Running setup.py install for functools32 20:09:00 INFO - warning: no files found matching '*.txt' 20:09:00 INFO - no previously-included directories found matching 'build' 20:09:00 INFO - no previously-included directories found matching 'dist' 20:09:00 INFO - no previously-included directories found matching '.git*' 20:09:00 INFO - Successfully installed jsonschema functools32 20:09:00 INFO - Cleaning up... 20:09:01 INFO - Return code: 0 20:09:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105dd9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e6e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb900490c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3dd30>, '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 0x105aa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d35b20>, '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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:09:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q9BdoxPKH4/Render', 20:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:01 INFO - 'HOME': '/Users/cltbld', 20:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:01 INFO - 'LOGNAME': 'cltbld', 20:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:01 INFO - 'MOZ_NO_REMOTE': '1', 20:09:01 INFO - 'NO_EM_RESTART': '1', 20:09:01 INFO - 'PAGER': '/bin/cat', 20:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:01 INFO - 'PWD': '/builds/slave/test', 20:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:01 INFO - 'SHELL': '/bin/bash', 20:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WA26eQW5cf/Listeners', 20:09:01 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 20:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:01 INFO - 'USER': 'cltbld', 20:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:01 INFO - 'XPC_FLAGS': '0x0', 20:09:01 INFO - 'XPC_SERVICE_NAME': '0', 20:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:09:01 INFO - Cleaning up... 20:09:01 INFO - Return code: 0 20:09:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105dd9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e6e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb900490c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3dd30>, '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 0x105aa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d35b20>, '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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:09:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q9BdoxPKH4/Render', 20:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:01 INFO - 'HOME': '/Users/cltbld', 20:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:01 INFO - 'LOGNAME': 'cltbld', 20:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:01 INFO - 'MOZ_NO_REMOTE': '1', 20:09:01 INFO - 'NO_EM_RESTART': '1', 20:09:01 INFO - 'PAGER': '/bin/cat', 20:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:01 INFO - 'PWD': '/builds/slave/test', 20:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:01 INFO - 'SHELL': '/bin/bash', 20:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WA26eQW5cf/Listeners', 20:09:01 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 20:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:01 INFO - 'USER': 'cltbld', 20:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:01 INFO - 'XPC_FLAGS': '0x0', 20:09:01 INFO - 'XPC_SERVICE_NAME': '0', 20:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:01 INFO - Downloading/unpacking blobuploader==1.2.4 20:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:04 INFO - Downloading blobuploader-1.2.4.tar.gz 20:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:09:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:09:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:05 INFO - Downloading docopt-0.6.1.tar.gz 20:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:09:05 INFO - Installing collected packages: blobuploader, requests, docopt 20:09:05 INFO - Running setup.py install for blobuploader 20:09:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:09:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:09:05 INFO - Running setup.py install for requests 20:09:05 INFO - Running setup.py install for docopt 20:09:06 INFO - Successfully installed blobuploader requests docopt 20:09:06 INFO - Cleaning up... 20:09:06 INFO - Return code: 0 20:09:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:06 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 0x105dd9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e6e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb900490c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3dd30>, '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 0x105aa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d35b20>, '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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:09:06 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:09:06 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:09:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q9BdoxPKH4/Render', 20:09:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:06 INFO - 'HOME': '/Users/cltbld', 20:09:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:06 INFO - 'LOGNAME': 'cltbld', 20:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:06 INFO - 'MOZ_NO_REMOTE': '1', 20:09:06 INFO - 'NO_EM_RESTART': '1', 20:09:06 INFO - 'PAGER': '/bin/cat', 20:09:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:06 INFO - 'PWD': '/builds/slave/test', 20:09:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:06 INFO - 'SHELL': '/bin/bash', 20:09:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WA26eQW5cf/Listeners', 20:09:06 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 20:09:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:06 INFO - 'USER': 'cltbld', 20:09:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:06 INFO - 'XPC_FLAGS': '0x0', 20:09:06 INFO - 'XPC_SERVICE_NAME': '0', 20:09:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:09:06 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-4h2YhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:09:06 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-WxY9Jh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:09:06 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-nHTyLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:09:06 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-dEyros-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:09:06 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-rK3gn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:09:06 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-GMFMgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:09:07 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-gXhfij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:09:07 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-0vzR94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:09:07 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-ycayFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:09:07 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-13WmLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:09:07 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-uT39mT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:09:07 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-VfkQNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:09:07 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-NTOUt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:09:07 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-PPR56m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:07 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-MXk3LC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:09:07 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-werXso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:09:08 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-kzbufP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:09:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:09:08 INFO - Running setup.py install for manifestparser 20:09:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:09:08 INFO - Running setup.py install for mozcrash 20:09:08 INFO - Running setup.py install for mozdebug 20:09:08 INFO - Running setup.py install for mozdevice 20:09:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:09:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:09:08 INFO - Running setup.py install for mozfile 20:09:08 INFO - Running setup.py install for mozhttpd 20:09:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:09:09 INFO - Running setup.py install for mozinfo 20:09:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:09:09 INFO - Running setup.py install for mozInstall 20:09:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:09:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:09:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:09:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:09:09 INFO - Running setup.py install for mozleak 20:09:09 INFO - Running setup.py install for mozlog 20:09:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:09:09 INFO - Running setup.py install for moznetwork 20:09:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:09:09 INFO - Running setup.py install for mozprocess 20:09:09 INFO - Running setup.py install for mozprofile 20:09:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:09:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:09:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:09:10 INFO - Running setup.py install for mozrunner 20:09:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:09:10 INFO - Running setup.py install for mozscreenshot 20:09:10 INFO - Running setup.py install for moztest 20:09:10 INFO - Running setup.py install for mozversion 20:09:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:09:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:09:10 INFO - Cleaning up... 20:09:10 INFO - Return code: 0 20:09:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09:10 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:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:10 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:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:10 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 0x105dd9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e6e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb900490c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3dd30>, '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 0x105aa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d35b20>, '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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:09:10 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:09:10 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:09:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q9BdoxPKH4/Render', 20:09:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:10 INFO - 'HOME': '/Users/cltbld', 20:09:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:10 INFO - 'LOGNAME': 'cltbld', 20:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:10 INFO - 'MOZ_NO_REMOTE': '1', 20:09:10 INFO - 'NO_EM_RESTART': '1', 20:09:10 INFO - 'PAGER': '/bin/cat', 20:09:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:10 INFO - 'PWD': '/builds/slave/test', 20:09:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:10 INFO - 'SHELL': '/bin/bash', 20:09:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WA26eQW5cf/Listeners', 20:09:10 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 20:09:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:10 INFO - 'USER': 'cltbld', 20:09:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:10 INFO - 'XPC_FLAGS': '0x0', 20:09:10 INFO - 'XPC_SERVICE_NAME': '0', 20:09:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:09:11 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-GhELr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:09:11 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:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:09:11 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-I2tyob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:09:11 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:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:09:11 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-10Jogm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:09:11 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:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:09:11 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-5zMpyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:09:11 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:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:09:11 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-IPHE98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:09:11 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:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:09:11 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-886KRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:09:11 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:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:09:11 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-argU8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:09:11 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:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:09:11 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-YKAAJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:09:11 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:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:09:11 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-dgKmmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:09:11 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:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:09:12 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-LzdRrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:09:12 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:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:09:12 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-I4BNA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:09:12 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:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:09:12 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-IpVfSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:09:12 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:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:09:12 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-yfp4Sj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:09:12 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:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:09:12 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Z8KN8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:09:12 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:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:12 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-c1UH3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:12 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:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:09:12 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-ZsJxl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:09:12 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:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:09:12 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-lgcVld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:09:12 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:09:12 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:09:12 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:09:12 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:09:12 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:09:12 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:09:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:09:12 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:09:12 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:09:12 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:09:12 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:09:12 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:09:12 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:09:15 INFO - Downloading blessings-1.6.tar.gz 20:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:09:15 INFO - Installing collected packages: blessings 20:09:15 INFO - Running setup.py install for blessings 20:09:15 INFO - Successfully installed blessings 20:09:15 INFO - Cleaning up... 20:09:15 INFO - Return code: 0 20:09:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:09:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:09:15 INFO - Reading from file tmpfile_stdout 20:09:15 INFO - Current package versions: 20:09:15 INFO - blessings == 1.6 20:09:15 INFO - blobuploader == 1.2.4 20:09:15 INFO - docopt == 0.6.1 20:09:15 INFO - functools32 == 3.2.3-2 20:09:15 INFO - jsonschema == 2.5.1 20:09:15 INFO - manifestparser == 1.1 20:09:15 INFO - mozInstall == 1.12 20:09:15 INFO - mozcrash == 0.17 20:09:15 INFO - mozdebug == 0.1 20:09:15 INFO - mozdevice == 0.48 20:09:15 INFO - mozfile == 1.2 20:09:15 INFO - mozhttpd == 0.7 20:09:15 INFO - mozinfo == 0.9 20:09:15 INFO - mozleak == 0.1 20:09:15 INFO - mozlog == 3.3 20:09:15 INFO - moznetwork == 0.27 20:09:15 INFO - mozprocess == 0.23 20:09:15 INFO - mozprofile == 0.28 20:09:15 INFO - mozrunner == 6.12 20:09:15 INFO - mozscreenshot == 0.1 20:09:15 INFO - mozsystemmonitor == 0.3 20:09:15 INFO - moztest == 0.7 20:09:15 INFO - mozversion == 1.4 20:09:15 INFO - psutil == 3.1.1 20:09:15 INFO - requests == 1.2.3 20:09:15 INFO - wsgiref == 0.1.2 20:09:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09:15 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:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:15 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:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:15 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 0x105dd9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e6e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb900490c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3dd30>, '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 0x105aa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d35b20>, '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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:09:15 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:09:15 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:09:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q9BdoxPKH4/Render', 20:09:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:15 INFO - 'HOME': '/Users/cltbld', 20:09:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:15 INFO - 'LOGNAME': 'cltbld', 20:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:15 INFO - 'MOZ_NO_REMOTE': '1', 20:09:15 INFO - 'NO_EM_RESTART': '1', 20:09:15 INFO - 'PAGER': '/bin/cat', 20:09:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:15 INFO - 'PWD': '/builds/slave/test', 20:09:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:15 INFO - 'SHELL': '/bin/bash', 20:09:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WA26eQW5cf/Listeners', 20:09:15 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 20:09:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:15 INFO - 'USER': 'cltbld', 20:09:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:15 INFO - 'XPC_FLAGS': '0x0', 20:09:15 INFO - 'XPC_SERVICE_NAME': '0', 20:09:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:16 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:09:16 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:09:16 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:09:16 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:09:16 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:09:16 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:09:16 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:09:16 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:09:16 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:09:16 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:09:16 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:09:16 INFO - Cleaning up... 20:09:16 INFO - Return code: 0 20:09:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105dd9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e6e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb900490c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3dd30>, '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 0x105aa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d35b20>, '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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:09:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q9BdoxPKH4/Render', 20:09:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:16 INFO - 'HOME': '/Users/cltbld', 20:09:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:16 INFO - 'LOGNAME': 'cltbld', 20:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:16 INFO - 'MOZ_NO_REMOTE': '1', 20:09:16 INFO - 'NO_EM_RESTART': '1', 20:09:16 INFO - 'PAGER': '/bin/cat', 20:09:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:16 INFO - 'PWD': '/builds/slave/test', 20:09:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:16 INFO - 'SHELL': '/bin/bash', 20:09:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WA26eQW5cf/Listeners', 20:09:16 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 20:09:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:16 INFO - 'USER': 'cltbld', 20:09:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:16 INFO - 'XPC_FLAGS': '0x0', 20:09:16 INFO - 'XPC_SERVICE_NAME': '0', 20:09:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:09:16 INFO - Cleaning up... 20:09:16 INFO - Return code: 0 20:09:16 INFO - Running post-action listener: _resource_record_post_action 20:09:16 INFO - Running post-action listener: _start_resource_monitoring 20:09:16 INFO - Starting resource monitoring. 20:09:16 INFO - [mozharness: 2016-09-09 03:09:16.532101Z] Finished create-virtualenv step (success) 20:09:16 INFO - [mozharness: 2016-09-09 03:09:16.533096Z] Running install step. 20:09:16 INFO - Running pre-action listener: _resource_record_pre_action 20:09:16 INFO - Running main action method: install 20:09:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:09:16 INFO - Reading from file tmpfile_stdout 20:09:16 INFO - Detecting whether we're running mozinstall >=1.0... 20:09:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:09:16 INFO - Reading from file tmpfile_stdout 20:09:16 INFO - Output received: 20:09:16 INFO - Usage: mozinstall [options] installer 20:09:16 INFO - Options: 20:09:16 INFO - -h, --help show this help message and exit 20:09:16 INFO - -d DEST, --destination=DEST 20:09:16 INFO - Directory to install application into. [default: 20:09:16 INFO - "/builds/slave/test"] 20:09:16 INFO - --app=APP Application being installed. [default: firefox] 20:09:16 INFO - mkdir: /builds/slave/test/build/application 20:09:16 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:09:16 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:09:51 INFO - Reading from file tmpfile_stdout 20:09:51 INFO - Output received: 20:09:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:09:51 INFO - Running post-action listener: _resource_record_post_action 20:09:51 INFO - [mozharness: 2016-09-09 03:09:51.492785Z] Finished install step (success) 20:09:51 INFO - [mozharness: 2016-09-09 03:09:51.492903Z] Running run-tests step. 20:09:51 INFO - Running pre-action listener: _resource_record_pre_action 20:09:51 INFO - Running main action method: run_tests 20:09:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:09:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:09:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:09:51 INFO - 2016-09-08 20:09:51.507 screenresolution[1947:12453] starting screenresolution argv=screenresolution get 20:09:51 INFO - 2016-09-08 20:09:51.510 screenresolution[1947:12453] Display 0: 1600x1200x32@60 20:09:51 INFO - 2016-09-08 20:09:51.516 screenresolution[1948:12455] starting screenresolution argv=screenresolution list 20:09:51 INFO - Available Modes on Display 0 20:09:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:09:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:09:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:09:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:09:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:09:51 INFO - Intel Iris: 20:09:51 INFO - Chipset Model: Intel Iris 20:09:51 INFO - Type: GPU 20:09:51 INFO - Bus: Built-In 20:09:51 INFO - VRAM (Dynamic, Max): 1536 MB 20:09:51 INFO - Vendor: Intel (0x8086) 20:09:51 INFO - Device ID: 0x0a2e 20:09:51 INFO - Revision ID: 0x0009 20:09:51 INFO - Displays: 20:09:51 INFO - Display: 20:09:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:09:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:09:51 INFO - Display Serial Number: 1600x1200 60 20:09:51 INFO - Main Display: Yes 20:09:51 INFO - Mirror: Off 20:09:51 INFO - Online: Yes 20:09:51 INFO - Rotation: Supported 20:09:51 INFO - Return code: 0 20:09:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:09:51 INFO - Python 2.7.3 20:09:51 INFO - Return code: 0 20:09:51 INFO - grabbing minidump binary from tooltool 20:09:51 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:09:51 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 0x105d3dd30>, '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 0x105aa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d35b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:09:51 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:09:51 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:09:51 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:09:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:09:51 INFO - Return code: 0 20:09:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:09:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:09:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:09:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:09:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:09:51 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/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--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:09:51 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/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0388 --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:09:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q9BdoxPKH4/Render', 20:09:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:51 INFO - 'HOME': '/Users/cltbld', 20:09:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:51 INFO - 'LOGNAME': 'cltbld', 20:09:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:09:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:51 INFO - 'MOZ_NO_REMOTE': '1', 20:09:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:09:51 INFO - 'NO_EM_RESTART': '1', 20:09:51 INFO - 'PAGER': '/bin/cat', 20:09:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:51 INFO - 'PWD': '/builds/slave/test', 20:09:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:09:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:51 INFO - 'SHELL': '/bin/bash', 20:09:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WA26eQW5cf/Listeners', 20:09:51 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 20:09:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:51 INFO - 'USER': 'cltbld', 20:09:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:51 INFO - 'XPC_FLAGS': '0x0', 20:09:51 INFO - 'XPC_SERVICE_NAME': '0', 20:09:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:51 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/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--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:09:52 INFO - mozversion application_buildid: 20160908182319 20:09:52 INFO - mozversion application_changeset: c82d3d5d08864a2d437db3c1ae9a669249d36575 20:09:52 INFO - mozversion application_display_name: Nightly 20:09:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:09:52 INFO - mozversion application_name: Firefox 20:09:52 INFO - mozversion application_remotingname: firefox 20:09:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:09:52 INFO - mozversion application_vendor: Mozilla 20:09:52 INFO - mozversion application_version: 51.0a1 20:09:52 INFO - mozversion platform_buildid: 20160908182319 20:09:52 INFO - mozversion platform_changeset: c82d3d5d08864a2d437db3c1ae9a669249d36575 20:09:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:09:52 INFO - mozversion platform_version: 51.0a1 20:09:52 INFO - using testdate: 1473390592 20:09:52 INFO - actual date: 1473390592 20:09:52 INFO - starting webserver on 'localhost:51978' 20:09:52 INFO - SUITE-START | Running 2 tests 20:09:52 INFO - TEST-START | dromaeo_css 20:09:52 INFO - operating with platform_type : mac_ 20:09:52 INFO - Initialising browser for dromaeo_css test... 20:09:52 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpDDgVic/profile http://localhost:51978/getInfo.html) 20:09:54 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 20:09:54 INFO - PROCESS | 1958 | colorDepth:24 20:09:54 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 20:09:54 INFO - PROCESS | 1958 | __metrics 20:09:54 INFO - PROCESS | 1958 | 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:09:54 INFO - PROCESS | 1958 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:09:55 INFO - TEST-INFO | 1958: exit 0 20:09:55 INFO - Browser initialized. 20:09:55 INFO - Running cycle 1/1 for dromaeo_css test... 20:09:55 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpDDgVic/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:10:07 INFO - PROCESS | 1960 | RSS: Main: 232730624 20:10:07 INFO - PROCESS | 1960 | 20:10:58 INFO - PROCESS | 1960 | 1473390658805 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:13:10 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51978/tests/dromaeo/cssquery-ext.html) 20:13:11 INFO - PROCESS | 1960 | RSS: Main: 355442688 20:13:11 INFO - PROCESS | 1960 | 20:16:14 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-ext.html (next: http://localhost:51978/tests/dromaeo/cssquery-jquery.html) 20:16:15 INFO - PROCESS | 1960 | RSS: Main: 368599040 20:16:15 INFO - PROCESS | 1960 | 20:19:17 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51978/tests/dromaeo/cssquery-mootools.html) 20:19:18 INFO - PROCESS | 1960 | RSS: Main: 343183360 20:19:18 INFO - PROCESS | 1960 | 20:22:20 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51978/tests/dromaeo/cssquery-prototype.html) 20:22:21 INFO - PROCESS | 1960 | RSS: Main: 334454784 20:22:21 INFO - PROCESS | 1960 | 20:25:24 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51978/tests/dromaeo/cssquery-yui.html) 20:25:25 INFO - PROCESS | 1960 | RSS: Main: 330362880 20:25:25 INFO - PROCESS | 1960 | 20:28:28 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-yui.html (next: http://localhost:51978/tests/dromaeo/cssquery-dojo.html) 20:28:29 INFO - PROCESS | 1960 | RSS: Main: 485187584 20:28:29 INFO - PROCESS | 1960 | 20:28:29 INFO - PROCESS | 1960 | __start_tp_report 20:28:29 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 20:28:29 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 20:28:29 INFO - PROCESS | 1960 | |i|pagename|runs| 20:28:29 INFO - PROCESS | 1960 | |0;dojo.html;3260.3316320154363;3288.6218084920233;3297.126261540692;3342.9498557521656;3362.9742928406827;5581.748821303041;5665.263515742953;5677.659340439576;5756.913646295304;5763.481286684203;5638.984982703112;5660.490555849972;5691.715414229293;5692.800751973692;5730.79942202023;5076.289319495274;5089.083964886319;5158.896822063575;5172.146595811687;5257.789688412459;5264.42091369949;5282.762275697583;5297.337832770903;5305.283786688786;5328.254044433782;4618.099470603233;4809.278608208761;5107.438181799999;5174.50842169997;5223.634345595829;5151.536361727425;5169.663971841818;5177.016366890316;5177.119889618773;5186.118359878829;5119.38567371914;5141.0232055909255;5146.099432599281;5158.69047857126;5169.276301317818;5584.972075139639;5608.270924779753;5687.544996400278;5713.3429655589725;5726.7995620153515;6241.9375806241815;6254.15568898202;6279.434850863398;6280.968595157041;6286.371362863722;5708.71456427177;6071.44993122146;6198.6280823150755;6243.781467648656;6282.4974002079725;5780.335261444903;5783;5783.768649254067;5786.739596718074;5843.328017278068;6174.228221472316;6322.873542529123;6326.620402775849;6333.334999825044;6337.7781777637565;6081.239845019372;6105;6117.388261173847;6167.475764559973;6176.752929882754;6287.434130928238;6295.559310848288;6296.275928268308;6301.338359472189;6311.621302721851;5964.31410387811;6223.066540019034;6233.34549872266;6237.002079667232;6238.812835614848;6066.848328791727;6131.7176395431725;6151.661658608819;6165.815025549241;6199.783007594712;4913.828016019422;4917.237828136645;4917.754112294371;4927.926081108786;4951.7276549789385;4411.867643970686;4424.911501769947;4438.156750217513;4448.666350023761;4460.107978404268;5539.861503462365;5584.888302233932;5594.384617692051;5609.80365687199;5650.85872853182;6151.415615516519;6224.564280500322;6229.719662615194;6230.6573138476915;6236.781712640127;5590.5248053915875;5615.943840561542;5637.6617402955135;5676.517496012885;5681.289838770153;5321.840344789663;5327.227552005015;5328.307320048447;5338.599605029638;5341.973290133525;6311.96844015777;6318.936810631927;6323.652199129092;6327.272363678052;6343;6128.319797384489;6206.534509911684;6215.65813880241;6248.031555108965;6317.147185129948;6281.59169653971;6292.402221788922;6299.212598425197;6302.1492098566105;6314.589551679126;6169.753209871735;6197.566170368212;6197.752089916533;6210.192674952227;6215.036669316263;6122.733612273255;6132.724027418866;6199.225096862892;6213.285472170758;6224.688765561795;6137.499563105042;6144.723487443165;6148.692565371624;6151.876962460637;6153.692315384303;6106.491003209229;6127.181496511655;6135.110179431366;6142.897836592492;6143.143438301211;5653.886922261449;5716.771329146787;5901.996660567628;5948.315943666361;6018;6130.551011451207;6134.191681014119;6147.477464415396;6167.660778657217;6179.567430280017;5997.3402925677265;5997.520198384032;6022.247219097613;6028.638281702936;6071.5446341523675 20:28:29 INFO - PROCESS | 1960 | |1;ext.html;476.16908494676795;514.8790034341929;523.1551045062223;523.8376103407943;553.1951011278591;30672.233194170156;40037;41354;41381.96545086375;43663.563364366346;42513.36229956553;42865;43008.569914300846;43039.92400189997;43051.569484305146;9681.370710903786;9858.112769850712;9859.753506162331;9868.654597089104;9877.851832222523;8684.348673849456;8984.966728826203;11844.881551184508;12013.978811801007;12055.45750441228;12086.214396064228;12175.93912030443;12189.024878009783;12207.328596927166;12207.938960305186;12051.15641905067;12102.697432564168;12127.4234349534;12149.028077753846;12150.817737733985;11995.040396768238;12067;12072.818907716392;12091.879081209252;12092.395380230953;39995.80002099971;40215;40227.39658905119;40242.79878600618;40444.595554044376;3268.297316077055;3305.2179275497906;3315.933681326384;3320.7177389921885;3383.5770528683915;30672.846635766902;30872.228194295098;30921.536176957365;31311.84344078288;31749.84125079383;3205.6954589313978;3218.4887268692923;3222.726068284199;3251.739860811153;3318.6515415881468;293187.53406233044;342557;345104;348476;350316.719911608;59488.40511594915;59874.70062649659;60028.197744181234;60093.39906600878;60096.69951650301;34336.141596460286;62994;63204;63205.68397157985;63277.050844237376;16102.919485402497;16889.408870689465;16966.066866322224;16966.8971516851;16991.06549139809;11730;11940.164188506722;12846.229226246454;12992.805107923388;13007.349632518337;14417.062890912057;16737.665246694996;17008.829911700726;19012.049397530067;19499.220031198878;6764.763233286812;7839.686412543549;7903.841923161504;7932.722354717557;7967.760967170994;723.392350425645;732.8900664900308;741.5773009384707;849.3714651158099;990.0297708245815;28238;30118.795248190265;30210.24474388158;30749.077527674206;30907.536386954213;1035.8860525342207;1045.6706137566794;1071.2929466552039;1118.5805323003874;1156.5200441816721;15298;15765.842341576667;15812.60468488297;15851.682966340839;16247.67504649924;185808.21287680697;186737;187332;187517;187620;58235.12647310294;58781.415765502206;58868;58875;59026.40973590209;1217.598192596441;1331.1547167548676;1346.3335648853881;1363.5977386670756;1364.6861221919212;30546.389072218877;31738.523922140725;32048.5192722114;32165;32481.675183247866;50079.74960125176;50682.23976640427;50717.49282507128;50730;50746;496003;497100;497418.51290743315;500631;744728;39847;39946.80026599965;40109.79945100256;40175;40361.74392969094;44840;45000.77499612481;45328;45497.317540237585;45519.77240113778;37142.628573714996;37971.43042854304;39174.82475525739;39240.803795980835;40909.590904090575;38824.41763373495;39266.41100383439;39370.2125957485;40435.39346909858;41072.383914240716;38321.85034448971;38583.03542411462;38946.02634934149;39089;40726.59273407228;27846.21275791566;29108;33137.171570710925;34694.95915122551;35062.29875402427;22306.77693223047;24270;25125.87437062803;25216.74783252144;25811.870940645178 20:28:29 INFO - PROCESS | 1960 | |2;jquery.html;327.97540184486155;346.91500582357327;363.53467561521256;364.31145135693527;378.9280036793009;15442.305096270667;18012.459626211203;20555.794442055576;20650.89674551629;20660.380188594347;18185.54536136594;20293.797062029375;20496.487587810312;20606.381808545768;20835.479113022182;4743.62051035918;5226.451222621627;5462.863428414282;5477.233187353773;5490.396056433789;3878;4101.712880098395;5113.437521872591;6009.75960961561;6069.30203026651;6379.266384365811;6642.070110184553;6667.266600673946;6696.129503164606;6711.563748356366;6034.1853849730605;6067.484263837579;6075.726592303357;6122.357152498968;6672.733090676367;5798.694130517643;5832.329282132524;5846.883062338729;5848.122781582754;5850;12751.808722869164;12982.15615984958;13076;13090.541831035867;13104.934475327562;2234.340869443502;2258.559580881729;2269.126386341254;2305.4697419593585;2816.8028587850285;8618.913810861937;9839.55721992512;9884.703458896172;9975.800483990351;10291.845622315672;1820.4902627264255;1850.2506484873647;1851.389041616277;1910.149983257451;1946.8539859510452;79305;124106.75893241042;126519;128568;130970.3451482746;10224.670792797038;10991.450427478594;11297.49161287744;11453.59912403062;11479.71300717472;13749.518766843112;14598.781018284733;14610.050346727428;14618;14643.194624295553;7175.425965922714;8251.174882511701;8497.532635704973;8545.31637469001;8679;8444.366672499464;8450.323974082059;8453.365997550209;8635.870461943075;8641.04948455682;4056.7829651104553;4285.400043993844;4414.757188354607;4486.730796152241;4604.608608268335;1573.2762929052535;1588.5075626555804;1744.6249056452923;1841.1254654039224;1916.377177417345;683.1392445518683;688.6625553478759;841.6885752271576;874.3398733955822;943.7074506902882;20026;21655.133794648038;21660.133594656036;21742.35596958826;21788.782112178993;856.4518708026868;873.2795443758924;881.1012766977647;911.252772726358;928.4893308680199;10538.683839484827;11338.886611133948;11348.943255283672;11380.089592832719;11420.972112509913;454586;457453;460463;523888;531653;71193.2880671197;112358.43820780843;134848.32575837057;142298;145416;424.52241228617805;450.38297532380847;922.7693076730817;960.500539719356;964.5563041001218;8971.820563588823;9305.674301399418;9796.755081123029;10376.377417354983;11428.371439570903;51078.744606276734;55840.720796395755;56393.30820075404;56811.33047038762;56826.29521114373;74561.62719186369;77097.61451192708;77879.61060194662;78257;78428.21571784437;8077.555734434427;8097.39269554774;8394.328453723809;8563.871541926752;8564.700235491977;12006.63980080599;12908.870911290767;12998.480060797463;13423.194608323533;13468.79796803029;11372.31766094037;11450.770984580427;11589.420528973686;11722.355270460206;11848.822267666164;10560.788784224425;11286.20996530235;11602.18784685064;11630.244034137753;11681.299122052704;11554.94222528882;11602.245854019462;11941;11961.64115076549;11985.64043078709;7209.6034718091005;8264.83470330578;8282.50304981703;8283.58582070882;8496.28187852468;8411.495310281403;10948.781024379627;11437.486336625501;11556.94221528887;11710.824337635113 20:28:29 INFO - PROCESS | 1960 | |3;mootools.html;1478.0318891126317;1524.6950609878024;1716.9484915452533;1834.2112891456668;1906.7139929010655;8436.367272454567;8940.59767310471;8944.373893827435;8957.059508751578;8959.865602015967;7847.332976696974;8060;8565;8768.123187681229;8803;2376.6316220985773;2409.373562873648;2410.4455975125734;2410.4696966667307;2418.069043418288;2376.275236053003;2395.73646898841;2421.079989603948;2422.818288628351;2423.5879900416885;2399.472116134448;2414.6498757680124;2416.32342943976;2426.7694569016003;2429.5019520998153;2335.050233055401;2350.059976009593;2353.846999944998;2360.78752912237;2365.207655435414;2359.1035406545575;2373.169390713254;2380.47629521505;2386.9522609547885;2391.641253811925;8703.825923481558;8928.553572321358;8933.508657023862;8938.776530586721;8982.236509896667;5246.449122842085;5407.675539467645;5418.91871621926;5421.1326187810155;5448.318960129984;3644.9271014579826;3664;3708.2027364116575;3730.850765969358;3740.682042026431;1093.2128867215592;1097.9890201097967;1099.3623698255074;1107.4186052322514;1119.9944000279947;11471.942640286828;11602.651920442317;11608.825867611993;11613.941930290379;11615.767684646344;4459.464864216315;4565.840195593139;4748.620110391161;4750.07373562152;4751.408278226832;8327.708530201415;9612.951935240279;9615.663451779154;9618.124465729115;9633.662821801203;5356.598255130881;5364.9731751341;5365.248865158881;5366.6243362964215;5370.248165256867;4293.3130699088;4427.468703755506;4571.405717256735;4936.777844996984;4973.701577905337;3866.6326698964117;3884.8446062157195;3987.342088555356;4002.15954649521;4036.65688416482;1252.1047451072527;1253.2793643654752;1254.4793910527028;1260.8991280697521;1268.8858002779793;785.681798871458;890.9599068041954;904.0959040959041;917.0096296000437;953.5613617735919;5670.914936275959;5784.566157538202;5890.3815099414805;6033.5173186145;6101.481374083164;387.4594940058602;422.75057715947736;428.7363271587951;431.39819951168573;456.6894511732054;3796.544414670257;3920.176762879827;4100.753954762724;4199.874003779892;4284.935725964113;10724;10849.620263290746;10937.671869843918;10942.4528773561;10951.34291942486;10733.087687546646;10800.891991080145;10836.620718274822;11014.118870490342;11030.28303160292;7208.675609597581;8151.144129866398;8285.461445006054;8328.750137496007;8393.328533717287;5923.466887980103;6025.969870150797;6116.3272040076445;6140.631562106288;6186.938130618636;9953.850692239477;10338.793224135625;10345.586176552853;10355.223358248011;10387.792244155225;8711.257748450209;10079.596816127272;10441.477926103817;10494.527746251284;10518.894811052098;6932.757353492603;7152.177499587614;7189.532680375759;7322.890156647761;7438.62806859644;8283.917160828556;8386.203310685476;8499.150084991452;8503.319734421355;8503.447275927045;7332.413406927326;7505.4746167767735;7520.849583008418;7577.242275772379;7599.506032107895;7280.460470154652;7360.815979600553;7396.852062958818;7451.850962980819;7551.924480755122;6898.448124150156;7037.401820845285;7041.823954401181;7053.047838541731;7063.71745130189;4285.957140428681;4890.975545122252;5053.191489361684;5090.898182036264;5112.718800465861;4883.194272945067;5006.599472042155;5030.320906677552;5031.119554077948;5268.525832675077 20:28:29 INFO - PROCESS | 1960 | |4;prototype.html;251.98992040318387;296.8130078050828;303.60644641097366;304.51277955271553;308.04352485532405;4532.501424843264;5262.605409566463;5350.35795704515;5369.174480676569;5392.487713667205;4486.618637415815;5328.9874923764555;5371.221172929932;5378.515933565978;5401.621886467949;3400.982995085027;3431.3137372525593;3921.450996860442;4085.2646523625735;4189.622933935945;3320.667933206684;3598.118460977051;3953.8418463261437;4131.8967025824295;4165.958340416587;3276.0826968448873;3996.4604778354988;4063.4311196432523;4068.8841802262955;4108.52751933527;3822.2355528894054;3872.7676339419586;4028.939565906514;4065.4308396824467;4074.7351422157762;3192.74458043356;3293.419158803764;3507.3511400390753;3516.7515531986287;3535.9116022099397;5154.278401023859;5155.736844473128;5187.896242075138;5224.660397074178;5251.422343542207;4454.77726113693;4652.162610730067;4658.836940707059;4691;4856.68431551948;4676.8363107291425;5588.3573389060375;5593.720313984284;5619.381867994517;5647.322321321427;3929.5088113985753;3933;3933.704972127102;3934.4885164928664;3939.7833119178435;5581.939431508001;5772.191893134923;5796.652200867877;5932.466078052953;5984.102384642339;3816;3943.211357728466;3949.9802500987885;3985.4221137934837;4015.8996025099023;3989.2420440116284;4000.4599379084034;4001.599840015975;4003.159336539301;4037.7779222143063;4183.288840897055;4646.395968524071;4687.5781179693995;4690.835820746326;4702.4592171899985;5276.868078298074;5654.971725141348;5657.264555607745;5672.404397538282;5714.514266287412;1327.4889167670324;1338.9866101339057;1340.8860246879124;1346.1922846292146;1346.7979803029427;828.3207769628847;832.5878690048344;870.9956450217709;874.2471344953328;894.9418287811271;5174.8965020700125;5203.583713303003;5307.911878064988;5358.91961620576;5363.436839131913;5730.484256416859;5820.941790582125;5820.970895145582;5821.039528477754;5909.674967876808;4204.096119334296;4833.589144922651;5101.387833460009;5322.695939494849;5347.973260133752;2766.7509924106926;2990.908318463745;3011.3676128012926;3042.209025653346;3072.5391191321482;5109.821156259513;6017.819465416044;6066.846269574326;6082;6105.053716673922;5229.790808367623;5716.628419152742;5875.676837773964;6002.639841609517;6004;5776.480116789425;5983.880322393527;6002.909956350658;6067.575269731076;6091.42131497507;5115.488451154854;5382.654336415896;5454.318210223722;5837.270341207349;5845.327787304344;4450.821967121279;5142.460041695568;5700;5720.799772007959;5746.195532625519;5626.690532020778;6008.819735407945;6067.873845784732;6137.514306705792;6170.722317495633;2855.1434569628614;3201.967980320167;3309.435187729529;3310.0925370491927;3396.1509622594353;4705.341252224623;4778.92116929137;4796.409938633238;4798.424189097331;4812.614990800797;4320.703788863266;4406.250937340596;4757;4773.641976851681;4798.49615790337;4340.631046360968;4386.951069007009;4475.530069342803;4597.011642496879;4853.98559597763;4589.4722106958125;4600.88497787558;4614.884627884329;4685.406961633061;4690.788914498788;5299.364076310867;5512.751926163252;5592.350256674209;5636.661800291996;5637.718114094361;5513.972430137824;5523.723813809213;5553.278073850373;5586.720663966867;5632.070708333079 20:28:29 INFO - PROCESS | 1960 | |5;yui.html;711.6655172069127;724.0695706017765;736.5507040705172;761.657254235594;808.8907997420354;15458.458953936615;16339.26473308701;16343.673126537462;16350.182490875453;16361.83638163618;14667.339969701363;14821.110733356007;14822.777658335106;14824.925875370634;14826.925865370658;654.5974225851091;672.8284287506679;776.4409625069969;829.0714399872137;856.6744637037948;839.5927974932154;849.7493239494364;862.4307956748547;864.9654013839439;865.7143142762905;921.4885738415177;926.6895589977332;926.735880274122;926.9814603707955;926.9814603707955;607.6506009044817;719.1118968074423;911.4804561400005;916.8533034714447;917.2661870503604;533.5758072332466;534.3881255961902;534.4708738349045;641.4066988036049;664.2626684380334;11561.710957226052;14498.202598857059;14591.708165836624;14592.489262875748;14634.560963171121;13703.794443083361;13939.442422303096;14084.436622535086;14155.858441415558;14315.245478036113;12326.445309961073;12684.30236337001;12849.67875803103;13072;13202.207867527979;4548.5906268436;4591.494935557086;4594.1271158479785;4778.880527986794;4799;113160;113492.86507134905;115047;115401.4229928845;116595;11038.061764749926;11042.779144417067;11118.61084862026;11143.16426268017;11359.432028398547;16494.75257871133;20962.266320678704;22117.33647990563;22256.777432225794;22276.331710048726;6985.825354366181;8068.193180682002;8235.670573177005;8237.423380363318;8251.854517825408;4414.668899832525;4467.12890986261;4519.683622146484;4619.584237418649;4680.321353403738;3413.573303337083;3559.928801423957;3560.5549306336707;3561.5191949086543;3565.6612621800887;649.7022198159143;657.4116166031375;662.3674390956625;662.3806740697463;663.8539521305305;523.4686792905204;538.0771976060996;541.1071731642821;560.1849309255024;578.2973686970267;7817.335526480199;7845.411594130349;8470.89878315815;9255.814883702289;9289.674861379819;594.4501336263938;606.6238931862274;613.3682307223567;616.722474886303;618.9907151392732;4200.138971510833;4456.3538286948215;4470.530594287618;4489.5959363656775;4528.5245049270015;143700;146074.69626518633;151245.24377378263;153455;155398;100181;133974;134917;135120.9731854023;136952;111026.3346049826;114413.4279328598;116587.41706291583;117604;119527;7568.621568921642;8566.657333706582;8685.174908383693;8778;8807.647694092164;64567.677161613894;65305;65486.67256663686;66930;67123;89945;96695;96716.91038620172;97827;98025.01974980159;10593.258471906893;10636.255462117577;10682.145428365868;10686.25196236256;11232.3260604364;10260.897391026292;12450.00799872016;13931.094478858842;14428.495002674856;14542.317588906284;11471.254368466023;13376;13756.724865502834;13815.861841381458;14135;13156.868431315565;13299.933500332436;13373.197608143542;13708.17750934947;13781.931090344484;12943.417546210254;13005.544805931748;13033.413496392495;13188.175429125446;13331.933340333237;10543.394949283644;11108.44457777124;11192.38441885704;11206.887931120584;11443.198976071595;10333.328333658288;11278.379689117177;11358.25840037874;11378.943105284421;11579.942100289782 20:28:29 INFO - PROCESS | 1960 | __end_tp_report 20:28:29 INFO - PROCESS | 1960 | __start_cc_report 20:28:29 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,1590 20:28:29 INFO - PROCESS | 1960 | __end_cc_report 20:28:29 INFO - PROCESS | 1960 | __startTimestamp1473391709050__endTimestamp 20:28:29 INFO - PROCESS | 1960 | 20:28:29 INFO - PROCESS | 1960 | ------- Summary: start ------- 20:28:29 INFO - PROCESS | 1960 | Number of tests: 6 20:28:29 INFO - PROCESS | 1960 | 20:28:29 INFO - PROCESS | 1960 | [#0] dojo.html Cycles:165 Average:5726.74 Median:5997.52 stddev:654.65 (10.9%) stddev-sans-first:627.42 20:28:29 INFO - PROCESS | 1960 | Values: 3260.3 3288.6 3297.1 3342.9 3363.0 5581.7 5665.3 5677.7 5756.9 5763.5 5639.0 5660.5 5691.7 5692.8 5730.8 5076.3 5089.1 5158.9 5172.1 5257.8 5264.4 5282.8 5297.3 5305.3 5328.3 4618.1 4809.3 5107.4 5174.5 5223.6 5151.5 5169.7 5177.0 5177.1 5186.1 5119.4 5141.0 5146.1 5158.7 5169.3 5585.0 5608.3 5687.5 5713.3 5726.8 6241.9 6254.2 6279.4 6281.0 6286.4 5708.7 6071.4 6198.6 6243.8 6282.5 5780.3 5783.0 5783.8 5786.7 5843.3 6174.2 6322.9 6326.6 6333.3 6337.8 6081.2 6105.0 6117.4 6167.5 6176.8 6287.4 6295.6 6296.3 6301.3 6311.6 5964.3 6223.1 6233.3 6237.0 6238.8 6066.8 6131.7 6151.7 6165.8 6199.8 4913.8 4917.2 4917.8 4927.9 4951.7 4411.9 4424.9 4438.2 4448.7 4460.1 5539.9 5584.9 5594.4 5609.8 5650.9 6151.4 6224.6 6229.7 6230.7 6236.8 5590.5 5615.9 5637.7 5676.5 5681.3 5321.8 5327.2 5328.3 5338.6 5342.0 6312.0 6318.9 6323.7 6327.3 6343.0 6128.3 6206.5 6215.7 6248.0 6317.1 6281.6 6292.4 6299.2 6302.1 6314.6 6169.8 6197.6 6197.8 6210.2 6215.0 6122.7 6132.7 6199.2 6213.3 6224.7 6137.5 6144.7 6148.7 6151.9 6153.7 6106.5 6127.2 6135.1 6142.9 6143.1 5653.9 5716.8 5902.0 5948.3 6018.0 6130.6 6134.2 6147.5 6167.7 6179.6 5997.3 5997.5 6022.2 6028.6 6071.5 20:28:29 INFO - PROCESS | 1960 | 20:28:29 INFO - PROCESS | 1960 | [#1] ext.html Cycles:180 Average:53033.78 Median:189899.48 stddev:104210.93 (54.9%) stddev-sans-first:104428.56 20:28:29 INFO - PROCESS | 1960 | Values: 476.2 514.9 523.2 523.8 553.2 30672.2 40037.0 41354.0 41382.0 43663.6 42513.4 42865.0 43008.6 43039.9 43051.6 9681.4 9858.1 9859.8 9868.7 9877.9 8684.3 8985.0 11844.9 12014.0 12055.5 12086.2 12175.9 12189.0 12207.3 12207.9 12051.2 12102.7 12127.4 12149.0 12150.8 11995.0 12067.0 12072.8 12091.9 12092.4 39995.8 40215.0 40227.4 40242.8 40444.6 3268.3 3305.2 3315.9 3320.7 3383.6 30672.8 30872.2 30921.5 31311.8 31749.8 3205.7 3218.5 3222.7 3251.7 3318.7 293187.5 342557.0 345104.0 348476.0 350316.7 59488.4 59874.7 60028.2 60093.4 60096.7 34336.1 62994.0 63204.0 63205.7 63277.1 16102.9 16889.4 16966.1 16966.9 16991.1 11730.0 11940.2 12846.2 12992.8 13007.3 14417.1 16737.7 17008.8 19012.0 19499.2 6764.8 7839.7 7903.8 7932.7 7967.8 723.4 732.9 741.6 849.4 990.0 28238.0 30118.8 30210.2 30749.1 30907.5 1035.9 1045.7 1071.3 1118.6 1156.5 15298.0 15765.8 15812.6 15851.7 16247.7 185808.2 186737.0 187332.0 187517.0 187620.0 58235.1 58781.4 58868.0 58875.0 59026.4 1217.6 1331.2 1346.3 1363.6 1364.7 30546.4 31738.5 32048.5 32165.0 32481.7 50079.7 50682.2 50717.5 50730.0 50746.0 496003.0 497100.0 497418.5 500631.0 744728.0 39847.0 39946.8 40109.8 40175.0 40361.7 44840.0 45000.8 45328.0 45497.3 45519.8 37142.6 37971.4 39174.8 39240.8 40909.6 38824.4 39266.4 39370.2 40435.4 41072.4 38321.9 38583.0 38946.0 39089.0 40726.6 27846.2 29108.0 33137.2 34695.0 35062.3 22306.8 24270.0 25125.9 25216.7 25811.9 20:28:29 INFO - PROCESS | 1960 | 20:28:29 INFO - PROCESS | 1960 | [#2] jquery.html Cycles:180 Average:31143.62 Median:3967.39 stddev:82571.52 (2081.3%) stddev-sans-first:82770.74 20:28:29 INFO - PROCESS | 1960 | Values: 328.0 346.9 363.5 364.3 378.9 15442.3 18012.5 20555.8 20650.9 20660.4 18185.5 20293.8 20496.5 20606.4 20835.5 4743.6 5226.5 5462.9 5477.2 5490.4 3878.0 4101.7 5113.4 6009.8 6069.3 6379.3 6642.1 6667.3 6696.1 6711.6 6034.2 6067.5 6075.7 6122.4 6672.7 5798.7 5832.3 5846.9 5848.1 5850.0 12751.8 12982.2 13076.0 13090.5 13104.9 2234.3 2258.6 2269.1 2305.5 2816.8 8618.9 9839.6 9884.7 9975.8 10291.8 1820.5 1850.3 1851.4 1910.1 1946.9 79305.0 124106.8 126519.0 128568.0 130970.3 10224.7 10991.5 11297.5 11453.6 11479.7 13749.5 14598.8 14610.1 14618.0 14643.2 7175.4 8251.2 8497.5 8545.3 8679.0 8444.4 8450.3 8453.4 8635.9 8641.0 4056.8 4285.4 4414.8 4486.7 4604.6 1573.3 1588.5 1744.6 1841.1 1916.4 683.1 688.7 841.7 874.3 943.7 20026.0 21655.1 21660.1 21742.4 21788.8 856.5 873.3 881.1 911.3 928.5 10538.7 11338.9 11348.9 11380.1 11421.0 454586.0 457453.0 460463.0 523888.0 531653.0 71193.3 112358.4 134848.3 142298.0 145416.0 424.5 450.4 922.8 960.5 964.6 8971.8 9305.7 9796.8 10376.4 11428.4 51078.7 55840.7 56393.3 56811.3 56826.3 74561.6 77097.6 77879.6 78257.0 78428.2 8077.6 8097.4 8394.3 8563.9 8564.7 12006.6 12908.9 12998.5 13423.2 13468.8 11372.3 11450.8 11589.4 11722.4 11848.8 10560.8 11286.2 11602.2 11630.2 11681.3 11554.9 11602.2 11941.0 11961.6 11985.6 7209.6 8264.8 8282.5 8283.6 8496.3 8411.5 10948.8 11437.5 11556.9 11710.8 20:28:29 INFO - PROCESS | 1960 | 20:28:29 INFO - PROCESS | 1960 | [#3] mootools.html Cycles:180 Average:5717.68 Median:4750.74 stddev:3214.49 (67.7%) stddev-sans-first:3207.71 20:28:29 INFO - PROCESS | 1960 | Values: 1478.0 1524.7 1716.9 1834.2 1906.7 8436.4 8940.6 8944.4 8957.1 8959.9 7847.3 8060.0 8565.0 8768.1 8803.0 2376.6 2409.4 2410.4 2410.5 2418.1 2376.3 2395.7 2421.1 2422.8 2423.6 2399.5 2414.6 2416.3 2426.8 2429.5 2335.1 2350.1 2353.8 2360.8 2365.2 2359.1 2373.2 2380.5 2387.0 2391.6 8703.8 8928.6 8933.5 8938.8 8982.2 5246.4 5407.7 5418.9 5421.1 5448.3 3644.9 3664.0 3708.2 3730.9 3740.7 1093.2 1098.0 1099.4 1107.4 1120.0 11471.9 11602.7 11608.8 11613.9 11615.8 4459.5 4565.8 4748.6 4750.1 4751.4 8327.7 9613.0 9615.7 9618.1 9633.7 5356.6 5365.0 5365.2 5366.6 5370.2 4293.3 4427.5 4571.4 4936.8 4973.7 3866.6 3884.8 3987.3 4002.2 4036.7 1252.1 1253.3 1254.5 1260.9 1268.9 785.7 891.0 904.1 917.0 953.6 5670.9 5784.6 5890.4 6033.5 6101.5 387.5 422.8 428.7 431.4 456.7 3796.5 3920.2 4100.8 4199.9 4284.9 10724.0 10849.6 10937.7 10942.5 10951.3 10733.1 10800.9 10836.6 11014.1 11030.3 7208.7 8151.1 8285.5 8328.8 8393.3 5923.5 6026.0 6116.3 6140.6 6186.9 9953.9 10338.8 10345.6 10355.2 10387.8 8711.3 10079.6 10441.5 10494.5 10518.9 6932.8 7152.2 7189.5 7322.9 7438.6 8283.9 8386.2 8499.2 8503.3 8503.4 7332.4 7505.5 7520.8 7577.2 7599.5 7280.5 7360.8 7396.9 7451.9 7551.9 6898.4 7037.4 7041.8 7053.0 7063.7 4286.0 4891.0 5053.2 5090.9 5112.7 4883.2 5006.6 5030.3 5031.1 5268.5 20:28:29 INFO - PROCESS | 1960 | 20:28:29 INFO - PROCESS | 1960 | [#4] prototype.html Cycles:180 Average:4482.41 Median:4798.46 stddev:1399.82 (29.2%) stddev-sans-first:1367.26 20:28:29 INFO - PROCESS | 1960 | Values: 252.0 296.8 303.6 304.5 308.0 4532.5 5262.6 5350.4 5369.2 5392.5 4486.6 5329.0 5371.2 5378.5 5401.6 3401.0 3431.3 3921.5 4085.3 4189.6 3320.7 3598.1 3953.8 4131.9 4166.0 3276.1 3996.5 4063.4 4068.9 4108.5 3822.2 3872.8 4028.9 4065.4 4074.7 3192.7 3293.4 3507.4 3516.8 3535.9 5154.3 5155.7 5187.9 5224.7 5251.4 4454.8 4652.2 4658.8 4691.0 4856.7 4676.8 5588.4 5593.7 5619.4 5647.3 3929.5 3933.0 3933.7 3934.5 3939.8 5581.9 5772.2 5796.7 5932.5 5984.1 3816.0 3943.2 3950.0 3985.4 4015.9 3989.2 4000.5 4001.6 4003.2 4037.8 4183.3 4646.4 4687.6 4690.8 4702.5 5276.9 5655.0 5657.3 5672.4 5714.5 1327.5 1339.0 1340.9 1346.2 1346.8 828.3 832.6 871.0 874.2 894.9 5174.9 5203.6 5307.9 5358.9 5363.4 5730.5 5820.9 5821.0 5821.0 5909.7 4204.1 4833.6 5101.4 5322.7 5348.0 2766.8 2990.9 3011.4 3042.2 3072.5 5109.8 6017.8 6066.8 6082.0 6105.1 5229.8 5716.6 5875.7 6002.6 6004.0 5776.5 5983.9 6002.9 6067.6 6091.4 5115.5 5382.7 5454.3 5837.3 5845.3 4450.8 5142.5 5700.0 5720.8 5746.2 5626.7 6008.8 6067.9 6137.5 6170.7 2855.1 3202.0 3309.4 3310.1 3396.2 4705.3 4778.9 4796.4 4798.4 4812.6 4320.7 4406.3 4757.0 4773.6 4798.5 4340.6 4387.0 4475.5 4597.0 4854.0 4589.5 4600.9 4614.9 4685.4 4690.8 5299.4 5512.8 5592.4 5636.7 5637.7 5514.0 5523.7 5553.3 5586.7 5632.1 20:28:29 INFO - PROCESS | 1960 | 20:28:29 INFO - PROCESS | 1960 | [#5] yui.html Cycles:180 Average:25248.73 Median:3486.75 stddev:40696.06 (1167.2%) stddev-sans-first:40768.52 20:28:29 INFO - PROCESS | 1960 | Values: 711.7 724.1 736.6 761.7 808.9 15458.5 16339.3 16343.7 16350.2 16361.8 14667.3 14821.1 14822.8 14824.9 14826.9 654.6 672.8 776.4 829.1 856.7 839.6 849.7 862.4 865.0 865.7 921.5 926.7 926.7 927.0 927.0 607.7 719.1 911.5 916.9 917.3 533.6 534.4 534.5 641.4 664.3 11561.7 14498.2 14591.7 14592.5 14634.6 13703.8 13939.4 14084.4 14155.9 14315.2 12326.4 12684.3 12849.7 13072.0 13202.2 4548.6 4591.5 4594.1 4778.9 4799.0 113160.0 113492.9 115047.0 115401.4 116595.0 11038.1 11042.8 11118.6 11143.2 11359.4 16494.8 20962.3 22117.3 22256.8 22276.3 6985.8 8068.2 8235.7 8237.4 8251.9 4414.7 4467.1 4519.7 4619.6 4680.3 3413.6 3559.9 3560.6 3561.5 3565.7 649.7 657.4 662.4 662.4 663.9 523.5 538.1 541.1 560.2 578.3 7817.3 7845.4 8470.9 9255.8 9289.7 594.5 606.6 613.4 616.7 619.0 4200.1 4456.4 4470.5 4489.6 4528.5 143700.0 146074.7 151245.2 153455.0 155398.0 100181.0 133974.0 134917.0 135121.0 136952.0 111026.3 114413.4 116587.4 117604.0 119527.0 7568.6 8566.7 8685.2 8778.0 8807.6 64567.7 65305.0 65486.7 66930.0 67123.0 89945.0 96695.0 96716.9 97827.0 98025.0 10593.3 10636.3 10682.1 10686.3 11232.3 10260.9 12450.0 13931.1 14428.5 14542.3 11471.3 13376.0 13756.7 13815.9 14135.0 13156.9 13299.9 13373.2 13708.2 13781.9 12943.4 13005.5 13033.4 13188.2 13331.9 10543.4 11108.4 11192.4 11206.9 11443.2 10333.3 11278.4 11358.3 11378.9 11579.9 20:28:29 INFO - PROCESS | 1960 | -------- Summary: end -------- 20:28:29 INFO - PROCESS | 1960 | 20:28:29 INFO - TEST-INFO | 1960: exit 0 20:28:29 INFO - TEST-OK | dromaeo_css | took 1117548ms 20:28:29 INFO - TEST-START | kraken 20:28:29 INFO - operating with platform_type : mac_ 20:28:29 INFO - Initialising browser for kraken test... 20:28:29 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpOVydTN/profile http://localhost:51978/getInfo.html) 20:28:31 INFO - PROCESS | 1964 | __metrics Screen width/height:1600/1200 20:28:31 INFO - PROCESS | 1964 | colorDepth:24 20:28:31 INFO - PROCESS | 1964 | Browser inner width/height: 1024/689 20:28:31 INFO - PROCESS | 1964 | __metrics 20:28:32 INFO - PROCESS | 1964 | 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:28:32 INFO - PROCESS | 1964 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:28:32 INFO - TEST-INFO | 1964: exit 0 20:28:32 INFO - Browser initialized. 20:28:32 INFO - Running cycle 1/1 for kraken test... 20:28:32 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpOVydTN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:28:44 INFO - PROCESS | 1965 | RSS: Main: 232734720 20:28:44 INFO - PROCESS | 1965 | 20:28:47 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51978/tests/kraken/driver.html?testName=audio-beat-detection) 20:28:48 INFO - PROCESS | 1965 | RSS: Main: 292859904 20:28:48 INFO - PROCESS | 1965 | 20:28:50 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51978/tests/kraken/driver.html?testName=audio-dft) 20:28:51 INFO - PROCESS | 1965 | RSS: Main: 289230848 20:28:51 INFO - PROCESS | 1965 | 20:28:54 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51978/tests/kraken/driver.html?testName=audio-fft) 20:28:55 INFO - PROCESS | 1965 | RSS: Main: 376815616 20:28:55 INFO - PROCESS | 1965 | 20:28:57 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51978/tests/kraken/driver.html?testName=audio-oscillator) 20:28:58 INFO - PROCESS | 1965 | RSS: Main: 279040000 20:28:58 INFO - PROCESS | 1965 | 20:29:00 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51978/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:29:00 INFO - PROCESS | 1965 | RSS: Main: 282939392 20:29:00 INFO - PROCESS | 1965 | 20:29:03 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51978/tests/kraken/driver.html?testName=imaging-darkroom) 20:29:04 INFO - PROCESS | 1965 | RSS: Main: 322580480 20:29:04 INFO - PROCESS | 1965 | 20:29:07 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51978/tests/kraken/driver.html?testName=imaging-desaturate) 20:29:07 INFO - PROCESS | 1965 | RSS: Main: 321941504 20:29:07 INFO - PROCESS | 1965 | 20:29:10 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51978/tests/kraken/driver.html?testName=json-parse-financial) 20:29:11 INFO - PROCESS | 1965 | RSS: Main: 320188416 20:29:11 INFO - PROCESS | 1965 | 20:29:12 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51978/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:29:13 INFO - PROCESS | 1965 | RSS: Main: 297615360 20:29:13 INFO - PROCESS | 1965 | 20:29:15 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:29:15 INFO - PROCESS | 1965 | RSS: Main: 303296512 20:29:15 INFO - PROCESS | 1965 | 20:29:17 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:29:18 INFO - PROCESS | 1965 | RSS: Main: 309092352 20:29:18 INFO - PROCESS | 1965 | 20:29:21 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:29:22 INFO - PROCESS | 1965 | RSS: Main: 319115264 20:29:22 INFO - PROCESS | 1965 | 20:29:25 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:29:25 INFO - PROCESS | 1965 | RSS: Main: 315117568 20:29:25 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51978/tests/kraken/driver.html?testName=ai-astar) 20:29:27 INFO - PROCESS | 1965 | RSS: Main: 358842368 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | __start_tp_report 20:29:27 INFO - PROCESS | 1965 | _x_x_mozilla_page_load 20:29:27 INFO - PROCESS | 1965 | _x_x_mozilla_page_load_details 20:29:27 INFO - PROCESS | 1965 | |i|pagename|runs| 20:29:27 INFO - PROCESS | 1965 | |0;ai-astar;114;114;115;114;114;115;113;113;114;124 20:29:27 INFO - PROCESS | 1965 | |1;audio-beat-detection;149;115;129;164;109;124;159;136;182;109 20:29:27 INFO - PROCESS | 1965 | |2;audio-dft;184;186;186;185;183;187;181;182;183;185 20:29:27 INFO - PROCESS | 1965 | |3;audio-fft;75;77;76;155;58;58;59;65;140;58 20:29:27 INFO - PROCESS | 1965 | |4;audio-oscillator;81;82;133;70;71;76;119;68;72;116 20:29:27 INFO - PROCESS | 1965 | |5;imaging-gaussian-blur;88;88;89;87;89;86;90;86;89;86 20:29:27 INFO - PROCESS | 1965 | |6;imaging-darkroom;98;97;97;99;98;97;98;101;96;98 20:29:27 INFO - PROCESS | 1965 | |7;imaging-desaturate;124;134;89;85;85;85;86;85;84;95 20:29:27 INFO - PROCESS | 1965 | |8;json-parse-financial;55;55;55;54;53;54;56;54;54;53 20:29:27 INFO - PROCESS | 1965 | |9;json-stringify-tinderbox;64;63;77;74;72;70;69;69;69;70 20:29:27 INFO - PROCESS | 1965 | |10;stanford-crypto-aes;87;85;88;88;87;87;84;85;89;86 20:29:27 INFO - PROCESS | 1965 | |11;stanford-crypto-ccm;141;138;137;152;129;145;146;155;194;139 20:29:27 INFO - PROCESS | 1965 | |12;stanford-crypto-pbkdf2;170;179;204;169;169;167;168;173;170;169 20:29:27 INFO - PROCESS | 1965 | |13;stanford-crypto-sha256-iterative;68;67;69;69;69;67;67;68;67;68 20:29:27 INFO - PROCESS | 1965 | __end_tp_report 20:29:27 INFO - PROCESS | 1965 | __start_cc_report 20:29:27 INFO - PROCESS | 1965 | _x_x_mozilla_cycle_collect,2021 20:29:27 INFO - PROCESS | 1965 | __end_cc_report 20:29:27 INFO - PROCESS | 1965 | __startTimestamp1473391767744__endTimestamp 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | ------- Summary: start ------- 20:29:27 INFO - PROCESS | 1965 | Number of tests: 14 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#0] ai-astar Cycles:10 Average:115.00 Median:114.00 stddev:3.23 (2.8%) stddev-sans-first:3.41 20:29:27 INFO - PROCESS | 1965 | Values: 114.0 114.0 115.0 114.0 114.0 115.0 113.0 113.0 114.0 124.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#1] audio-beat-detection Cycles:10 Average:137.60 Median:142.50 stddev:25.09 (17.6%) stddev-sans-first:26.27 20:29:27 INFO - PROCESS | 1965 | Values: 149.0 115.0 129.0 164.0 109.0 124.0 159.0 136.0 182.0 109.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:1.93 (1.0%) stddev-sans-first:2.05 20:29:27 INFO - PROCESS | 1965 | Values: 184.0 186.0 186.0 185.0 183.0 187.0 181.0 182.0 183.0 185.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#3] audio-fft Cycles:10 Average:82.10 Median:62.00 stddev:35.51 (57.3%) stddev-sans-first:37.57 20:29:27 INFO - PROCESS | 1965 | Values: 75.0 77.0 76.0 155.0 58.0 58.0 59.0 65.0 140.0 58.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#4] audio-oscillator Cycles:10 Average:88.80 Median:71.50 stddev:24.18 (33.8%) stddev-sans-first:25.48 20:29:27 INFO - PROCESS | 1965 | Values: 81.0 82.0 133.0 70.0 71.0 76.0 119.0 68.0 72.0 116.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:88.50 stddev:1.48 (1.7%) stddev-sans-first:1.56 20:29:27 INFO - PROCESS | 1965 | Values: 88.0 88.0 89.0 87.0 89.0 86.0 90.0 86.0 89.0 86.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#6] imaging-darkroom Cycles:10 Average:97.90 Median:98.00 stddev:1.37 (1.4%) stddev-sans-first:1.45 20:29:27 INFO - PROCESS | 1965 | Values: 98.0 97.0 97.0 99.0 98.0 97.0 98.0 101.0 96.0 98.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:85.00 stddev:18.26 (21.5%) stddev-sans-first:16.12 20:29:27 INFO - PROCESS | 1965 | Values: 124.0 134.0 89.0 85.0 85.0 85.0 86.0 85.0 84.0 95.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.50 stddev:0.95 (1.7%) stddev-sans-first:0.97 20:29:27 INFO - PROCESS | 1965 | Values: 55.0 55.0 55.0 54.0 53.0 54.0 56.0 54.0 54.0 53.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:70.00 stddev:4.16 (5.9%) stddev-sans-first:3.87 20:29:27 INFO - PROCESS | 1965 | Values: 64.0 63.0 77.0 74.0 72.0 70.0 69.0 69.0 69.0 70.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:87.00 stddev:1.58 (1.8%) stddev-sans-first:1.67 20:29:27 INFO - PROCESS | 1965 | Values: 87.0 85.0 88.0 88.0 87.0 87.0 84.0 85.0 89.0 86.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:145.50 stddev:17.96 (12.3%) stddev-sans-first:18.89 20:29:27 INFO - PROCESS | 1965 | Values: 141.0 138.0 137.0 152.0 129.0 145.0 146.0 155.0 194.0 139.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:170.00 stddev:11.14 (6.6%) stddev-sans-first:11.73 20:29:27 INFO - PROCESS | 1965 | Values: 170.0 179.0 204.0 169.0 169.0 167.0 168.0 173.0 170.0 169.0 20:29:27 INFO - PROCESS | 1965 | 20:29:27 INFO - PROCESS | 1965 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 20:29:27 INFO - PROCESS | 1965 | Values: 68.0 67.0 69.0 69.0 69.0 67.0 67.0 68.0 67.0 68.0 20:29:27 INFO - PROCESS | 1965 | -------- Summary: end -------- 20:29:27 INFO - PROCESS | 1965 | 20:29:28 INFO - TEST-INFO | 1965: exit 0 20:29:28 INFO - TEST-OK | kraken | took 58899ms 20:29:28 INFO - SUITE-END | took 1176s 20:29:28 INFO - Completed test suite (00:19:36) 20:29:28 INFO - javascript benchmark 20:29:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3260.3316320154363, 3288.6218084920233, 3297.126261540692, 3342.9498557521656, 3362.9742928406827, 5581.748821303041, 5665.263515742953, 5677.659340439576, 5756.913646295304, 5763.481286684203, 5638.984982703112, 5660.490555849972, 5691.715414229293, 5692.800751973692, 5730.79942202023, 5076.289319495274, 5089.083964886319, 5158.896822063575, 5172.146595811687, 5257.789688412459, 5264.42091369949, 5282.762275697583, 5297.337832770903, 5305.283786688786, 5328.254044433782, 4618.099470603233, 4809.278608208761, 5107.438181799999, 5174.50842169997, 5223.634345595829, 5151.536361727425, 5169.663971841818, 5177.016366890316, 5177.119889618773, 5186.118359878829, 5119.38567371914, 5141.0232055909255, 5146.099432599281, 5158.69047857126, 5169.276301317818, 5584.972075139639, 5608.270924779753, 5687.544996400278, 5713.3429655589725, 5726.7995620153515, 6241.9375806241815, 6254.15568898202, 6279.434850863398, 6280.968595157041, 6286.371362863722, 5708.71456427177, 6071.44993122146, 6198.6280823150755, 6243.781467648656, 6282.4974002079725, 5780.335261444903, 5783.0, 5783.768649254067, 5786.739596718074, 5843.328017278068, 6174.228221472316, 6322.873542529123, 6326.620402775849, 6333.334999825044, 6337.7781777637565, 6081.239845019372, 6105.0, 6117.388261173847, 6167.475764559973, 6176.752929882754, 6287.434130928238, 6295.559310848288, 6296.275928268308, 6301.338359472189, 6311.621302721851, 5964.31410387811, 6223.066540019034, 6233.34549872266, 6237.002079667232, 6238.812835614848, 6066.848328791727, 6131.7176395431725, 6151.661658608819, 6165.815025549241, 6199.783007594712, 4913.828016019422, 4917.237828136645, 4917.754112294371, 4927.926081108786, 4951.7276549789385, 4411.867643970686, 4424.911501769947, 4438.156750217513, 4448.666350023761, 4460.107978404268, 5539.861503462365, 5584.888302233932, 5594.384617692051, 5609.80365687199, 5650.85872853182, 6151.415615516519, 6224.564280500322, 6229.719662615194, 6230.6573138476915, 6236.781712640127, 5590.5248053915875, 5615.943840561542, 5637.6617402955135, 5676.517496012885, 5681.289838770153, 5321.840344789663, 5327.227552005015, 5328.307320048447, 5338.599605029638, 5341.973290133525, 6311.96844015777, 6318.936810631927, 6323.652199129092, 6327.272363678052, 6343.0, 6128.319797384489, 6206.534509911684, 6215.65813880241, 6248.031555108965, 6317.147185129948, 6281.59169653971, 6292.402221788922, 6299.212598425197, 6302.1492098566105, 6314.589551679126, 6169.753209871735, 6197.566170368212, 6197.752089916533, 6210.192674952227, 6215.036669316263, 6122.733612273255, 6132.724027418866, 6199.225096862892, 6213.285472170758, 6224.688765561795, 6137.499563105042, 6144.723487443165, 6148.692565371624, 6151.876962460637, 6153.692315384303, 6106.491003209229, 6127.181496511655, 6135.110179431366, 6142.897836592492, 6143.143438301211, 5653.886922261449, 5716.771329146787, 5901.996660567628, 5948.315943666361, 6018.0, 6130.551011451207, 6134.191681014119, 6147.477464415396, 6167.660778657217, 6179.567430280017, 5997.3402925677265, 5997.520198384032, 6022.247219097613, 6028.638281702936, 6071.5446341523675], "value": 5682.919042661878, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.16908494676795, 514.8790034341929, 523.1551045062223, 523.8376103407943, 553.1951011278591, 30672.233194170156, 40037.0, 41354.0, 41381.96545086375, 43663.563364366346, 42513.36229956553, 42865.0, 43008.569914300846, 43039.92400189997, 43051.569484305146, 9681.370710903786, 9858.112769850712, 9859.753506162331, 9868.654597089104, 9877.851832222523, 8684.348673849456, 8984.966728826203, 11844.881551184508, 12013.978811801007, 12055.45750441228, 12086.214396064228, 12175.93912030443, 12189.024878009783, 12207.328596927166, 12207.938960305186, 12051.15641905067, 12102.697432564168, 12127.4234349534, 12149.028077753846, 12150.817737733985, 11995.040396768238, 12067.0, 12072.818907716392, 12091.879081209252, 12092.395380230953, 39995.80002099971, 40215.0, 40227.39658905119, 40242.79878600618, 40444.595554044376, 3268.297316077055, 3305.2179275497906, 3315.933681326384, 3320.7177389921885, 3383.5770528683915, 30672.846635766902, 30872.228194295098, 30921.536176957365, 31311.84344078288, 31749.84125079383, 3205.6954589313978, 3218.4887268692923, 3222.726068284199, 3251.739860811153, 3318.6515415881468, 293187.53406233044, 342557.0, 345104.0, 348476.0, 350316.719911608, 59488.40511594915, 59874.70062649659, 60028.197744181234, 60093.39906600878, 60096.69951650301, 34336.141596460286, 62994.0, 63204.0, 63205.68397157985, 63277.050844237376, 16102.919485402497, 16889.408870689465, 16966.066866322224, 16966.8971516851, 16991.06549139809, 11730.0, 11940.164188506722, 12846.229226246454, 12992.805107923388, 13007.349632518337, 14417.062890912057, 16737.665246694996, 17008.829911700726, 19012.049397530067, 19499.220031198878, 6764.763233286812, 7839.686412543549, 7903.841923161504, 7932.722354717557, 7967.760967170994, 723.392350425645, 732.8900664900308, 741.5773009384707, 849.3714651158099, 990.0297708245815, 28238.0, 30118.795248190265, 30210.24474388158, 30749.077527674206, 30907.536386954213, 1035.8860525342207, 1045.6706137566794, 1071.2929466552039, 1118.5805323003874, 1156.5200441816721, 15298.0, 15765.842341576667, 15812.60468488297, 15851.682966340839, 16247.67504649924, 185808.21287680697, 186737.0, 187332.0, 187517.0, 187620.0, 58235.12647310294, 58781.415765502206, 58868.0, 58875.0, 59026.40973590209, 1217.598192596441, 1331.1547167548676, 1346.3335648853881, 1363.5977386670756, 1364.6861221919212, 30546.389072218877, 31738.523922140725, 32048.5192722114, 32165.0, 32481.675183247866, 50079.74960125176, 50682.23976640427, 50717.49282507128, 50730.0, 50746.0, 496003.0, 497100.0, 497418.51290743315, 500631.0, 744728.0, 39847.0, 39946.80026599965, 40109.79945100256, 40175.0, 40361.74392969094, 44840.0, 45000.77499612481, 45328.0, 45497.317540237585, 45519.77240113778, 37142.628573714996, 37971.43042854304, 39174.82475525739, 39240.803795980835, 40909.590904090575, 38824.41763373495, 39266.41100383439, 39370.2125957485, 40435.39346909858, 41072.383914240716, 38321.85034448971, 38583.03542411462, 38946.02634934149, 39089.0, 40726.59273407228, 27846.21275791566, 29108.0, 33137.171570710925, 34694.95915122551, 35062.29875402427, 22306.77693223047, 24270.0, 25125.87437062803, 25216.74783252144, 25811.870940645178], "value": 19596.04224670405, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.97540184486155, 346.91500582357327, 363.53467561521256, 364.31145135693527, 378.9280036793009, 15442.305096270667, 18012.459626211203, 20555.794442055576, 20650.89674551629, 20660.380188594347, 18185.54536136594, 20293.797062029375, 20496.487587810312, 20606.381808545768, 20835.479113022182, 4743.62051035918, 5226.451222621627, 5462.863428414282, 5477.233187353773, 5490.396056433789, 3878.0, 4101.712880098395, 5113.437521872591, 6009.75960961561, 6069.30203026651, 6379.266384365811, 6642.070110184553, 6667.266600673946, 6696.129503164606, 6711.563748356366, 6034.1853849730605, 6067.484263837579, 6075.726592303357, 6122.357152498968, 6672.733090676367, 5798.694130517643, 5832.329282132524, 5846.883062338729, 5848.122781582754, 5850.0, 12751.808722869164, 12982.15615984958, 13076.0, 13090.541831035867, 13104.934475327562, 2234.340869443502, 2258.559580881729, 2269.126386341254, 2305.4697419593585, 2816.8028587850285, 8618.913810861937, 9839.55721992512, 9884.703458896172, 9975.800483990351, 10291.845622315672, 1820.4902627264255, 1850.2506484873647, 1851.389041616277, 1910.149983257451, 1946.8539859510452, 79305.0, 124106.75893241042, 126519.0, 128568.0, 130970.3451482746, 10224.670792797038, 10991.450427478594, 11297.49161287744, 11453.59912403062, 11479.71300717472, 13749.518766843112, 14598.781018284733, 14610.050346727428, 14618.0, 14643.194624295553, 7175.425965922714, 8251.174882511701, 8497.532635704973, 8545.31637469001, 8679.0, 8444.366672499464, 8450.323974082059, 8453.365997550209, 8635.870461943075, 8641.04948455682, 4056.7829651104553, 4285.400043993844, 4414.757188354607, 4486.730796152241, 4604.608608268335, 1573.2762929052535, 1588.5075626555804, 1744.6249056452923, 1841.1254654039224, 1916.377177417345, 683.1392445518683, 688.6625553478759, 841.6885752271576, 874.3398733955822, 943.7074506902882, 20026.0, 21655.133794648038, 21660.133594656036, 21742.35596958826, 21788.782112178993, 856.4518708026868, 873.2795443758924, 881.1012766977647, 911.252772726358, 928.4893308680199, 10538.683839484827, 11338.886611133948, 11348.943255283672, 11380.089592832719, 11420.972112509913, 454586.0, 457453.0, 460463.0, 523888.0, 531653.0, 71193.2880671197, 112358.43820780843, 134848.32575837057, 142298.0, 145416.0, 424.52241228617805, 450.38297532380847, 922.7693076730817, 960.500539719356, 964.5563041001218, 8971.820563588823, 9305.674301399418, 9796.755081123029, 10376.377417354983, 11428.371439570903, 51078.744606276734, 55840.720796395755, 56393.30820075404, 56811.33047038762, 56826.29521114373, 74561.62719186369, 77097.61451192708, 77879.61060194662, 78257.0, 78428.21571784437, 8077.555734434427, 8097.39269554774, 8394.328453723809, 8563.871541926752, 8564.700235491977, 12006.63980080599, 12908.870911290767, 12998.480060797463, 13423.194608323533, 13468.79796803029, 11372.31766094037, 11450.770984580427, 11589.420528973686, 11722.355270460206, 11848.822267666164, 10560.788784224425, 11286.20996530235, 11602.18784685064, 11630.244034137753, 11681.299122052704, 11554.94222528882, 11602.245854019462, 11941.0, 11961.64115076549, 11985.64043078709, 7209.6034718091005, 8264.83470330578, 8282.50304981703, 8283.58582070882, 8496.28187852468, 8411.495310281403, 10948.781024379627, 11437.486336625501, 11556.94221528887, 11710.824337635113], "value": 9009.460029130292, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1478.0318891126317, 1524.6950609878024, 1716.9484915452533, 1834.2112891456668, 1906.7139929010655, 8436.367272454567, 8940.59767310471, 8944.373893827435, 8957.059508751578, 8959.865602015967, 7847.332976696974, 8060.0, 8565.0, 8768.123187681229, 8803.0, 2376.6316220985773, 2409.373562873648, 2410.4455975125734, 2410.4696966667307, 2418.069043418288, 2376.275236053003, 2395.73646898841, 2421.079989603948, 2422.818288628351, 2423.5879900416885, 2399.472116134448, 2414.6498757680124, 2416.32342943976, 2426.7694569016003, 2429.5019520998153, 2335.050233055401, 2350.059976009593, 2353.846999944998, 2360.78752912237, 2365.207655435414, 2359.1035406545575, 2373.169390713254, 2380.47629521505, 2386.9522609547885, 2391.641253811925, 8703.825923481558, 8928.553572321358, 8933.508657023862, 8938.776530586721, 8982.236509896667, 5246.449122842085, 5407.675539467645, 5418.91871621926, 5421.1326187810155, 5448.318960129984, 3644.9271014579826, 3664.0, 3708.2027364116575, 3730.850765969358, 3740.682042026431, 1093.2128867215592, 1097.9890201097967, 1099.3623698255074, 1107.4186052322514, 1119.9944000279947, 11471.942640286828, 11602.651920442317, 11608.825867611993, 11613.941930290379, 11615.767684646344, 4459.464864216315, 4565.840195593139, 4748.620110391161, 4750.07373562152, 4751.408278226832, 8327.708530201415, 9612.951935240279, 9615.663451779154, 9618.124465729115, 9633.662821801203, 5356.598255130881, 5364.9731751341, 5365.248865158881, 5366.6243362964215, 5370.248165256867, 4293.3130699088, 4427.468703755506, 4571.405717256735, 4936.777844996984, 4973.701577905337, 3866.6326698964117, 3884.8446062157195, 3987.342088555356, 4002.15954649521, 4036.65688416482, 1252.1047451072527, 1253.2793643654752, 1254.4793910527028, 1260.8991280697521, 1268.8858002779793, 785.681798871458, 890.9599068041954, 904.0959040959041, 917.0096296000437, 953.5613617735919, 5670.914936275959, 5784.566157538202, 5890.3815099414805, 6033.5173186145, 6101.481374083164, 387.4594940058602, 422.75057715947736, 428.7363271587951, 431.39819951168573, 456.6894511732054, 3796.544414670257, 3920.176762879827, 4100.753954762724, 4199.874003779892, 4284.935725964113, 10724.0, 10849.620263290746, 10937.671869843918, 10942.4528773561, 10951.34291942486, 10733.087687546646, 10800.891991080145, 10836.620718274822, 11014.118870490342, 11030.28303160292, 7208.675609597581, 8151.144129866398, 8285.461445006054, 8328.750137496007, 8393.328533717287, 5923.466887980103, 6025.969870150797, 6116.3272040076445, 6140.631562106288, 6186.938130618636, 9953.850692239477, 10338.793224135625, 10345.586176552853, 10355.223358248011, 10387.792244155225, 8711.257748450209, 10079.596816127272, 10441.477926103817, 10494.527746251284, 10518.894811052098, 6932.757353492603, 7152.177499587614, 7189.532680375759, 7322.890156647761, 7438.62806859644, 8283.917160828556, 8386.203310685476, 8499.150084991452, 8503.319734421355, 8503.447275927045, 7332.413406927326, 7505.4746167767735, 7520.849583008418, 7577.242275772379, 7599.506032107895, 7280.460470154652, 7360.815979600553, 7396.852062958818, 7451.850962980819, 7551.924480755122, 6898.448124150156, 7037.401820845285, 7041.823954401181, 7053.047838541731, 7063.71745130189, 4285.957140428681, 4890.975545122252, 5053.191489361684, 5090.898182036264, 5112.718800465861, 4883.194272945067, 5006.599472042155, 5030.320906677552, 5031.119554077948, 5268.525832675077], "value": 4520.302391896864, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [251.98992040318387, 296.8130078050828, 303.60644641097366, 304.51277955271553, 308.04352485532405, 4532.501424843264, 5262.605409566463, 5350.35795704515, 5369.174480676569, 5392.487713667205, 4486.618637415815, 5328.9874923764555, 5371.221172929932, 5378.515933565978, 5401.621886467949, 3400.982995085027, 3431.3137372525593, 3921.450996860442, 4085.2646523625735, 4189.622933935945, 3320.667933206684, 3598.118460977051, 3953.8418463261437, 4131.8967025824295, 4165.958340416587, 3276.0826968448873, 3996.4604778354988, 4063.4311196432523, 4068.8841802262955, 4108.52751933527, 3822.2355528894054, 3872.7676339419586, 4028.939565906514, 4065.4308396824467, 4074.7351422157762, 3192.74458043356, 3293.419158803764, 3507.3511400390753, 3516.7515531986287, 3535.9116022099397, 5154.278401023859, 5155.736844473128, 5187.896242075138, 5224.660397074178, 5251.422343542207, 4454.77726113693, 4652.162610730067, 4658.836940707059, 4691.0, 4856.68431551948, 4676.8363107291425, 5588.3573389060375, 5593.720313984284, 5619.381867994517, 5647.322321321427, 3929.5088113985753, 3933.0, 3933.704972127102, 3934.4885164928664, 3939.7833119178435, 5581.939431508001, 5772.191893134923, 5796.652200867877, 5932.466078052953, 5984.102384642339, 3816.0, 3943.211357728466, 3949.9802500987885, 3985.4221137934837, 4015.8996025099023, 3989.2420440116284, 4000.4599379084034, 4001.599840015975, 4003.159336539301, 4037.7779222143063, 4183.288840897055, 4646.395968524071, 4687.5781179693995, 4690.835820746326, 4702.4592171899985, 5276.868078298074, 5654.971725141348, 5657.264555607745, 5672.404397538282, 5714.514266287412, 1327.4889167670324, 1338.9866101339057, 1340.8860246879124, 1346.1922846292146, 1346.7979803029427, 828.3207769628847, 832.5878690048344, 870.9956450217709, 874.2471344953328, 894.9418287811271, 5174.8965020700125, 5203.583713303003, 5307.911878064988, 5358.91961620576, 5363.436839131913, 5730.484256416859, 5820.941790582125, 5820.970895145582, 5821.039528477754, 5909.674967876808, 4204.096119334296, 4833.589144922651, 5101.387833460009, 5322.695939494849, 5347.973260133752, 2766.7509924106926, 2990.908318463745, 3011.3676128012926, 3042.209025653346, 3072.5391191321482, 5109.821156259513, 6017.819465416044, 6066.846269574326, 6082.0, 6105.053716673922, 5229.790808367623, 5716.628419152742, 5875.676837773964, 6002.639841609517, 6004.0, 5776.480116789425, 5983.880322393527, 6002.909956350658, 6067.575269731076, 6091.42131497507, 5115.488451154854, 5382.654336415896, 5454.318210223722, 5837.270341207349, 5845.327787304344, 4450.821967121279, 5142.460041695568, 5700.0, 5720.799772007959, 5746.195532625519, 5626.690532020778, 6008.819735407945, 6067.873845784732, 6137.514306705792, 6170.722317495633, 2855.1434569628614, 3201.967980320167, 3309.435187729529, 3310.0925370491927, 3396.1509622594353, 4705.341252224623, 4778.92116929137, 4796.409938633238, 4798.424189097331, 4812.614990800797, 4320.703788863266, 4406.250937340596, 4757.0, 4773.641976851681, 4798.49615790337, 4340.631046360968, 4386.951069007009, 4475.530069342803, 4597.011642496879, 4853.98559597763, 4589.4722106958125, 4600.88497787558, 4614.884627884329, 4685.406961633061, 4690.788914498788, 5299.364076310867, 5512.751926163252, 5592.350256674209, 5636.661800291996, 5637.718114094361, 5513.972430137824, 5523.723813809213, 5553.278073850373, 5586.720663966867, 5632.070708333079], "value": 4034.1108776223914, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.6655172069127, 724.0695706017765, 736.5507040705172, 761.657254235594, 808.8907997420354, 15458.458953936615, 16339.26473308701, 16343.673126537462, 16350.182490875453, 16361.83638163618, 14667.339969701363, 14821.110733356007, 14822.777658335106, 14824.925875370634, 14826.925865370658, 654.5974225851091, 672.8284287506679, 776.4409625069969, 829.0714399872137, 856.6744637037948, 839.5927974932154, 849.7493239494364, 862.4307956748547, 864.9654013839439, 865.7143142762905, 921.4885738415177, 926.6895589977332, 926.735880274122, 926.9814603707955, 926.9814603707955, 607.6506009044817, 719.1118968074423, 911.4804561400005, 916.8533034714447, 917.2661870503604, 533.5758072332466, 534.3881255961902, 534.4708738349045, 641.4066988036049, 664.2626684380334, 11561.710957226052, 14498.202598857059, 14591.708165836624, 14592.489262875748, 14634.560963171121, 13703.794443083361, 13939.442422303096, 14084.436622535086, 14155.858441415558, 14315.245478036113, 12326.445309961073, 12684.30236337001, 12849.67875803103, 13072.0, 13202.207867527979, 4548.5906268436, 4591.494935557086, 4594.1271158479785, 4778.880527986794, 4799.0, 113160.0, 113492.86507134905, 115047.0, 115401.4229928845, 116595.0, 11038.061764749926, 11042.779144417067, 11118.61084862026, 11143.16426268017, 11359.432028398547, 16494.75257871133, 20962.266320678704, 22117.33647990563, 22256.777432225794, 22276.331710048726, 6985.825354366181, 8068.193180682002, 8235.670573177005, 8237.423380363318, 8251.854517825408, 4414.668899832525, 4467.12890986261, 4519.683622146484, 4619.584237418649, 4680.321353403738, 3413.573303337083, 3559.928801423957, 3560.5549306336707, 3561.5191949086543, 3565.6612621800887, 649.7022198159143, 657.4116166031375, 662.3674390956625, 662.3806740697463, 663.8539521305305, 523.4686792905204, 538.0771976060996, 541.1071731642821, 560.1849309255024, 578.2973686970267, 7817.335526480199, 7845.411594130349, 8470.89878315815, 9255.814883702289, 9289.674861379819, 594.4501336263938, 606.6238931862274, 613.3682307223567, 616.722474886303, 618.9907151392732, 4200.138971510833, 4456.3538286948215, 4470.530594287618, 4489.5959363656775, 4528.5245049270015, 143700.0, 146074.69626518633, 151245.24377378263, 153455.0, 155398.0, 100181.0, 133974.0, 134917.0, 135120.9731854023, 136952.0, 111026.3346049826, 114413.4279328598, 116587.41706291583, 117604.0, 119527.0, 7568.621568921642, 8566.657333706582, 8685.174908383693, 8778.0, 8807.647694092164, 64567.677161613894, 65305.0, 65486.67256663686, 66930.0, 67123.0, 89945.0, 96695.0, 96716.91038620172, 97827.0, 98025.01974980159, 10593.258471906893, 10636.255462117577, 10682.145428365868, 10686.25196236256, 11232.3260604364, 10260.897391026292, 12450.00799872016, 13931.094478858842, 14428.495002674856, 14542.317588906284, 11471.254368466023, 13376.0, 13756.724865502834, 13815.861841381458, 14135.0, 13156.868431315565, 13299.933500332436, 13373.197608143542, 13708.17750934947, 13781.931090344484, 12943.417546210254, 13005.544805931748, 13033.413496392495, 13188.175429125446, 13331.933340333237, 10543.394949283644, 11108.44457777124, 11192.38441885704, 11206.887931120584, 11443.198976071595, 10333.328333658288, 11278.379689117177, 11358.25840037874, 11378.943105284421, 11579.942100289782], "value": 7705.351023029186, "unit": "score"}], "name": "dromaeo_css", "value": 7214.374300202334, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 114.0, 115.0, 113.0, 113.0, 114.0, 124.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 129.0, 164.0, 109.0, 124.0, 159.0, 136.0, 182.0, 109.0], "value": 137.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 186.0, 185.0, 183.0, 187.0, 181.0, 182.0, 183.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 155.0, 58.0, 58.0, 59.0, 65.0, 140.0, 58.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 133.0, 70.0, 71.0, 76.0, 119.0, 68.0, 72.0, 116.0], "value": 88.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 89.0, 87.0, 89.0, 86.0, 90.0, 86.0, 89.0, 86.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 97.0, 99.0, 98.0, 97.0, 98.0, 101.0, 96.0, 98.0], "value": 97.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 134.0, 89.0, 85.0, 85.0, 85.0, 86.0, 85.0, 84.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 54.0, 53.0, 54.0, 56.0, 54.0, 54.0, 53.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 77.0, 74.0, 72.0, 70.0, 69.0, 69.0, 69.0, 70.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 88.0, 87.0, 87.0, 84.0, 85.0, 89.0, 86.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 137.0, 152.0, 129.0, 145.0, 146.0, 155.0, 194.0, 139.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 179.0, 204.0, 169.0, 169.0, 167.0, 168.0, 173.0, 170.0, 169.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 69.0, 69.0, 67.0, 67.0, 68.0, 67.0, 68.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1488.4999999999998, "alertThreshold": 2.0}]} 20:29:28 INFO - Return code: 0 20:29:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:29:29 INFO - # TBPL SUCCESS # 20:29:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:29:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:29:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:29:29 INFO - 2016-09-08 20:29:29.068 screenresolution[1969:14434] starting screenresolution argv=screenresolution get 20:29:29 INFO - 2016-09-08 20:29:29.070 screenresolution[1969:14434] Display 0: 1600x1200x32@60 20:29:29 INFO - 2016-09-08 20:29:29.076 screenresolution[1970:14437] starting screenresolution argv=screenresolution list 20:29:29 INFO - Available Modes on Display 0 20:29:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:29:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:29:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:29:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:29:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:29:29 INFO - Intel Iris: 20:29:29 INFO - Chipset Model: Intel Iris 20:29:29 INFO - Type: GPU 20:29:29 INFO - Bus: Built-In 20:29:29 INFO - VRAM (Dynamic, Max): 1536 MB 20:29:29 INFO - Vendor: Intel (0x8086) 20:29:29 INFO - Device ID: 0x0a2e 20:29:29 INFO - Revision ID: 0x0009 20:29:29 INFO - Displays: 20:29:29 INFO - Display: 20:29:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:29:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:29:29 INFO - Display Serial Number: 1600x1200 60 20:29:29 INFO - Main Display: Yes 20:29:29 INFO - Mirror: Off 20:29:29 INFO - Online: Yes 20:29:29 INFO - Rotation: Supported 20:29:29 INFO - Return code: 0 20:29:29 INFO - Running post-action listener: _resource_record_post_action 20:29:29 INFO - [mozharness: 2016-09-09 03:29:29.311185Z] Finished run-tests step (success) 20:29:29 INFO - Running post-run listener: _resource_record_post_run 20:29:29 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 790528; Write bytes: 703489536; Read time: 12; Write time: 1896 20:29:29 INFO - TinderboxPrint: CPU usage
53.6% 20:29:29 INFO - TinderboxPrint: I/O read bytes / time
790,528 / 12 20:29:29 INFO - TinderboxPrint: I/O write bytes / time
703,489,536 / 1,896 20:29:29 INFO - TinderboxPrint: CPU idle
2,249.7 (46.4%) 20:29:29 INFO - TinderboxPrint: CPU system
971.1 (20.0%) 20:29:29 INFO - TinderboxPrint: CPU user
1,628.8 (33.6%) 20:29:29 INFO - TinderboxPrint: Swap in / out
406,458,368 / 0 20:29:29 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 385690112; Write bytes: 379454976; Read time: 29109; Write time: 751 20:29:29 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 778240; Write bytes: 294558208; Read time: 10; Write time: 1077 20:29:29 INFO - Running post-run listener: _upload_blobber_files 20:29:29 INFO - Blob upload gear active. 20:29:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:29:29 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:29:29 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:29:29 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:29:31 INFO - (blobuploader) - INFO - Open directory for files ... 20:29:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:29:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:29:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:37 INFO - (blobuploader) - INFO - Done attempting. 20:29:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:29:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:29:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:38 INFO - (blobuploader) - INFO - Done attempting. 20:29:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:29:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:29:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:42 INFO - (blobuploader) - INFO - Done attempting. 20:29:42 INFO - (blobuploader) - INFO - Iteration through files over. 20:29:42 INFO - Return code: 0 20:29:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:29:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:29:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a3466ae4d6d999295f9b4e3c051df20fd726f82aeba9af4d836d5926e4df36b3cc2970ac1351497547950f16b41a50076dcea05b1532074074118e8bc1e3c801", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8739b84cb167ab729d2bf10713dbc64ceaeea9c88fbb0c9c689fa435b39b641e92acb12359e8d6beea8574df42bd892e00761ed09dea2e5b8c258db6dacd5f19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2f76d4ec494b9f0687a8617aa3c0bf4887188e020b905b41d15a25273b8b14f1b14713b31597209159189b82d1b31516c2267f47e996b4f89c011f6c42750e28"} 20:29:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:29:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:29:42 INFO - Contents: 20:29:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a3466ae4d6d999295f9b4e3c051df20fd726f82aeba9af4d836d5926e4df36b3cc2970ac1351497547950f16b41a50076dcea05b1532074074118e8bc1e3c801", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8739b84cb167ab729d2bf10713dbc64ceaeea9c88fbb0c9c689fa435b39b641e92acb12359e8d6beea8574df42bd892e00761ed09dea2e5b8c258db6dacd5f19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2f76d4ec494b9f0687a8617aa3c0bf4887188e020b905b41d15a25273b8b14f1b14713b31597209159189b82d1b31516c2267f47e996b4f89c011f6c42750e28"} 20:29:42 INFO - Running post-run listener: copy_logs_to_upload_dir 20:29:42 INFO - Copying logs to upload dir... 20:29:42 INFO - mkdir: /builds/slave/test/build/upload/logs 20:29:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.836508 ========= master_lag: 1.38 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-09-08 20:29:43.963708) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 20:29:43.964760) ========= 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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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/a3466ae4d6d999295f9b4e3c051df20fd726f82aeba9af4d836d5926e4df36b3cc2970ac1351497547950f16b41a50076dcea05b1532074074118e8bc1e3c801", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8739b84cb167ab729d2bf10713dbc64ceaeea9c88fbb0c9c689fa435b39b641e92acb12359e8d6beea8574df42bd892e00761ed09dea2e5b8c258db6dacd5f19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2f76d4ec494b9f0687a8617aa3c0bf4887188e020b905b41d15a25273b8b14f1b14713b31597209159189b82d1b31516c2267f47e996b4f89c011f6c42750e28"} build_url:https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010973 build_url: 'https://queue.taskcluster.net/v1/task/RtyGHap6T02RACA7DQ7Ftw/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/a3466ae4d6d999295f9b4e3c051df20fd726f82aeba9af4d836d5926e4df36b3cc2970ac1351497547950f16b41a50076dcea05b1532074074118e8bc1e3c801", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8739b84cb167ab729d2bf10713dbc64ceaeea9c88fbb0c9c689fa435b39b641e92acb12359e8d6beea8574df42bd892e00761ed09dea2e5b8c258db6dacd5f19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2f76d4ec494b9f0687a8617aa3c0bf4887188e020b905b41d15a25273b8b14f1b14713b31597209159189b82d1b31516c2267f47e996b4f89c011f6c42750e28"}' ========= master_lag: 0.76 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 20:29:44.738298) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:29:44.738663) ========= 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.Q9BdoxPKH4/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.WA26eQW5cf/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.005241 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:29:44.769078) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 20:29:44.769353) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 20:29:44.772654) ========= ========= Total master_lag: 2.25 =========