builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0351 starttime: 1473327850.57 results: success (0) buildid: 20160907175716 builduid: 0aad801a4896498f96ec88398c84536c revision: 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.568317) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.568675) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.568944) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.633849) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.634100) ========= 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.EoKGqXcuXF/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.cG3VavnMNG/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:44:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 218M=0s 2016-09-08 02:44:10 (218 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.171301 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.836542) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.837420) ========= 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.EoKGqXcuXF/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.cG3VavnMNG/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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.026035 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.894632) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 02:44:10.894958) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EoKGqXcuXF/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.cG3VavnMNG/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:44:10,988 truncating revision to first 12 chars 2016-09-08 02:44:10,988 Setting DEBUG logging. 2016-09-08 02:44:10,989 attempt 1/10 2016-09-08 02:44:10,989 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/77940cbf0c2a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 02:44:12,059 unpacking tar archive at: mozilla-central-77940cbf0c2a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.337912 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 02:44:12.260225) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:12.260556) ========= script_repo_revision: 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:12.260984) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:12.261450) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:12.280847) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-08 02:44:12.281172) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EoKGqXcuXF/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.cG3VavnMNG/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:44:12 INFO - MultiFileLogger online at 20160908 02:44:12 in /builds/slave/test 02:44:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:44:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:44:12 INFO - {'append_to_log': False, 02:44:12 INFO - 'base_work_dir': '/builds/slave/test', 02:44:12 INFO - 'blob_upload_branch': 'Firefox', 02:44:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:44:12 INFO - 'branch': 'Firefox', 02:44:12 INFO - 'buildbot_json_path': 'buildprops.json', 02:44:12 INFO - 'config_files': ('talos/mac_config.py',), 02:44:12 INFO - 'default_actions': ('clobber', 02:44:12 INFO - 'read-buildbot-config', 02:44:12 INFO - 'download-and-extract', 02:44:12 INFO - 'populate-webroot', 02:44:12 INFO - 'create-virtualenv', 02:44:12 INFO - 'install', 02:44:12 INFO - 'run-tests'), 02:44:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:44:12 INFO - 'download_minidump_stackwalk': True, 02:44:12 INFO - 'download_symbols': 'ondemand', 02:44:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:44:12 INFO - 'tooltool.py': '/tools/tooltool.py', 02:44:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:44:12 INFO - '/tools/misc-python/virtualenv.py')}, 02:44:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:44:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:44:12 INFO - 'installer_path': 'installer.exe', 02:44:12 INFO - 'log_level': 'info', 02:44:12 INFO - 'log_name': 'talos', 02:44:12 INFO - 'log_to_console': True, 02:44:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:44:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:44:12 INFO - 'opt_config_files': (), 02:44:12 INFO - 'pip_index': False, 02:44:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:44:12 INFO - 'cmd': ('bash', 02:44:12 INFO - '-c', 02:44:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:44:12 INFO - 'enabled': True, 02:44:12 INFO - 'halt_on_failure': False, 02:44:12 INFO - 'name': 'check_screen_resolution'},), 02:44:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:44:12 INFO - 'cmd': ('bash', 02:44:12 INFO - '-c', 02:44:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:44:12 INFO - 'enabled': True, 02:44:12 INFO - 'halt_on_failure': False, 02:44:12 INFO - 'name': 'check_screen_resolution'},), 02:44:12 INFO - 'run_cmd_checks_enabled': True, 02:44:12 INFO - 'sps_profile': False, 02:44:12 INFO - 'sps_profile_interval': 0, 02:44:12 INFO - 'suite': 'dromaeojs', 02:44:12 INFO - 'system_bits': '32', 02:44:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:44:12 INFO - 'title': 't-yosemite-r7-0351', 02:44:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:44:12 INFO - 'use_talos_json': True, 02:44:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:44:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:44:12 INFO - 'work_dir': 'build'} 02:44:12 INFO - [mozharness: 2016-09-08 09:44:12.483347Z] Running clobber step. 02:44:12 INFO - Running pre-action listener: _resource_record_pre_action 02:44:12 INFO - Running main action method: clobber 02:44:12 INFO - rmtree: /builds/slave/test/build 02:44:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:44:14 INFO - Running post-action listener: _resource_record_post_action 02:44:14 INFO - [mozharness: 2016-09-08 09:44:14.984342Z] Finished clobber step (success) 02:44:14 INFO - [mozharness: 2016-09-08 09:44:14.984483Z] Running read-buildbot-config step. 02:44:14 INFO - Running pre-action listener: _resource_record_pre_action 02:44:14 INFO - Running main action method: read_buildbot_config 02:44:14 INFO - Using buildbot properties: 02:44:14 INFO - { 02:44:14 INFO - "project": "", 02:44:14 INFO - "product": "firefox", 02:44:14 INFO - "script_repo_revision": "production", 02:44:14 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 02:44:14 INFO - "repository": "", 02:44:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 02:44:14 INFO - "buildid": "20160907175716", 02:44:14 INFO - "pgo_build": "False", 02:44:14 INFO - "basedir": "/builds/slave/test", 02:44:14 INFO - "buildnumber": 24, 02:44:14 INFO - "slavename": "t-yosemite-r7-0351", 02:44:14 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:44:14 INFO - "platform": "yosemite_r7", 02:44:14 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:44:14 INFO - "branch": "mozilla-central", 02:44:14 INFO - "repo_path": "mozilla-central", 02:44:14 INFO - "revision": "77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501", 02:44:14 INFO - "stage_platform": "macosx64", 02:44:14 INFO - "builduid": "0aad801a4896498f96ec88398c84536c", 02:44:14 INFO - "slavebuilddir": "test" 02:44:14 INFO - } 02:44:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 02:44:14 INFO - Running post-action listener: _resource_record_post_action 02:44:14 INFO - [mozharness: 2016-09-08 09:44:14.987740Z] Finished read-buildbot-config step (success) 02:44:14 INFO - [mozharness: 2016-09-08 09:44:14.987884Z] Running download-and-extract step. 02:44:14 INFO - Running pre-action listener: _resource_record_pre_action 02:44:14 INFO - Running main action method: download_and_extract 02:44:14 INFO - mkdir: /builds/slave/test/build/tests 02:44:14 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')]} 02:44:14 INFO - trying https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 02:44:14 INFO - Downloading https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/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 02:44:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/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 02:44:15 INFO - Downloaded 1199 bytes. 02:44:15 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 02:44:15 INFO - Using the following test package requirements: 02:44:15 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:44:15 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 02:44:15 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 02:44:15 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'jsshell-mac.zip'], 02:44:15 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 02:44:15 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:44:15 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 02:44:15 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 02:44:15 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 02:44:15 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 02:44:15 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:44:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:44:16 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 02:44:23 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 02:44:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:44:23 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 02:44:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 02:44:30 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 02:44:31 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')]} 02:44:31 INFO - trying https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:44:31 INFO - Downloading https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 02:44:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/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 02:44:34 INFO - Downloaded 111456518 bytes. 02:44:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:44:34 INFO - mkdir: /builds/slave/test/properties 02:44:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:44:34 INFO - Writing to file /builds/slave/test/properties/build_url 02:44:34 INFO - Contents: 02:44:34 INFO - build_url:https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:44:35 INFO - Running post-action listener: _resource_record_post_action 02:44:35 INFO - Running post-action listener: set_extra_try_arguments 02:44:35 INFO - [mozharness: 2016-09-08 09:44:35.135796Z] Finished download-and-extract step (success) 02:44:35 INFO - [mozharness: 2016-09-08 09:44:35.135952Z] Running populate-webroot step. 02:44:35 INFO - Running pre-action listener: _resource_record_pre_action 02:44:35 INFO - Running main action method: populate_webroot 02:44:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:44:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:44:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:44:35 INFO - '--tppagecycles', 02:44:35 INFO - '7'], 02:44:35 INFO - 'tests': ['tsvgm']}}, 02:44:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:44:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:44:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:44:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:44:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:44:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:44:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:35 INFO - 'talos_options': ['--disable-e10s'], 02:44:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:35 INFO - 'talos_options': ['--disable-e10s'], 02:44:35 INFO - 'tests': ['damp', 'tps']}, 02:44:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:35 INFO - 'tests': ['damp', 'tps']}, 02:44:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:44:35 INFO - 'tests': ['dromaeo_dom']}, 02:44:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:44:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:44:35 INFO - 'tests': ['basic_compositor_video']}, 02:44:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:44:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:44:35 INFO - 'tests': ['a11yr', 02:44:35 INFO - 'ts_paint', 02:44:35 INFO - 'tpaint', 02:44:35 INFO - 'sessionrestore', 02:44:35 INFO - 'sessionrestore_no_auto_restore', 02:44:35 INFO - 'tabpaint']}, 02:44:35 INFO - 'other-e10s': {'tests': ['a11yr', 02:44:35 INFO - 'ts_paint', 02:44:35 INFO - 'tpaint', 02:44:35 INFO - 'sessionrestore', 02:44:35 INFO - 'sessionrestore_no_auto_restore', 02:44:35 INFO - 'tabpaint']}, 02:44:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:44:35 INFO - 'ts_paint', 02:44:35 INFO - 'tpaint', 02:44:35 INFO - 'sessionrestore', 02:44:35 INFO - 'sessionrestore_no_auto_restore', 02:44:35 INFO - 'tabpaint']}, 02:44:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:44:35 INFO - 'ts_paint', 02:44:35 INFO - 'tpaint', 02:44:35 INFO - 'sessionrestore', 02:44:35 INFO - 'sessionrestore_no_auto_restore', 02:44:35 INFO - 'tabpaint']}, 02:44:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:44:35 INFO - 'tests': ['a11yr', 02:44:35 INFO - 'ts_paint', 02:44:35 INFO - 'tpaint', 02:44:35 INFO - 'sessionrestore', 02:44:35 INFO - 'sessionrestore_no_auto_restore', 02:44:35 INFO - 'tabpaint']}, 02:44:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:44:35 INFO - 'tests': ['a11yr', 02:44:35 INFO - 'ts_paint', 02:44:35 INFO - 'tpaint', 02:44:35 INFO - 'sessionrestore', 02:44:35 INFO - 'sessionrestore_no_auto_restore', 02:44:35 INFO - 'tabpaint']}, 02:44:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:44:35 INFO - 'tests': ['tsvgx', 02:44:35 INFO - 'tsvgr_opacity', 02:44:35 INFO - 'tart', 02:44:35 INFO - 'tscrollx', 02:44:35 INFO - 'cart']}, 02:44:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:44:35 INFO - 'tsvgr_opacity', 02:44:35 INFO - 'tart', 02:44:35 INFO - 'tscrollx', 02:44:35 INFO - 'cart']}, 02:44:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:35 INFO - 'talos_options': ['--disable-e10s'], 02:44:35 INFO - 'tests': ['tp5o']}, 02:44:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:35 INFO - 'tests': ['tp5o']}, 02:44:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:35 INFO - 'talos_options': ['--disable-e10s', 02:44:35 INFO - '--xperf_path', 02:44:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:35 INFO - 'tests': ['tp5n']}, 02:44:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:35 INFO - 'talos_options': ['--xperf_path', 02:44:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:35 INFO - 'tests': ['tp5n']}}, 02:44:35 INFO - 'talos.zip': {'path': '', 02:44:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:44:35 INFO - Running post-action listener: _resource_record_post_action 02:44:35 INFO - [mozharness: 2016-09-08 09:44:35.155191Z] Finished populate-webroot step (success) 02:44:35 INFO - [mozharness: 2016-09-08 09:44:35.155309Z] Running create-virtualenv step. 02:44:35 INFO - Running pre-action listener: _resource_record_pre_action 02:44:35 INFO - Running main action method: create_virtualenv 02:44:35 INFO - Creating virtualenv /builds/slave/test/build/venv 02:44:35 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 02:44:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:44:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:44:35 INFO - Using real prefix '/tools/python27' 02:44:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:44:36 INFO - Installing distribute.............................................................................................................................................................................................done. 02:44:39 INFO - Installing pip.................done. 02:44:39 INFO - Return code: 0 02:44:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:44:39 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')]} 02:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:39 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')]} 02:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:39 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 0x11015aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b857ccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100bed30>, '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 0x10fe23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100b6b20>, '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.EoKGqXcuXF/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.cG3VavnMNG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:44:39 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 02:44:39 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 02:44:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EoKGqXcuXF/Render', 02:44:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:39 INFO - 'HOME': '/Users/cltbld', 02:44:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:39 INFO - 'LOGNAME': 'cltbld', 02:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:39 INFO - 'MOZ_NO_REMOTE': '1', 02:44:39 INFO - 'NO_EM_RESTART': '1', 02:44:39 INFO - 'PAGER': '/bin/cat', 02:44:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:39 INFO - 'PWD': '/builds/slave/test', 02:44:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:39 INFO - 'SHELL': '/bin/bash', 02:44:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cG3VavnMNG/Listeners', 02:44:39 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 02:44:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:39 INFO - 'USER': 'cltbld', 02:44:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:39 INFO - 'XPC_FLAGS': '0x0', 02:44:39 INFO - 'XPC_SERVICE_NAME': '0', 02:44:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:39 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 02:44:39 INFO - Cleaning up... 02:44:39 INFO - Return code: 0 02:44:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:44:39 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')]} 02:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:39 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')]} 02:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:39 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 0x11015aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b857ccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100bed30>, '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 0x10fe23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100b6b20>, '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.EoKGqXcuXF/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.cG3VavnMNG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:44:39 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 02:44:39 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 02:44:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EoKGqXcuXF/Render', 02:44:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:39 INFO - 'HOME': '/Users/cltbld', 02:44:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:39 INFO - 'LOGNAME': 'cltbld', 02:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:39 INFO - 'MOZ_NO_REMOTE': '1', 02:44:39 INFO - 'NO_EM_RESTART': '1', 02:44:39 INFO - 'PAGER': '/bin/cat', 02:44:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:39 INFO - 'PWD': '/builds/slave/test', 02:44:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:39 INFO - 'SHELL': '/bin/bash', 02:44:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cG3VavnMNG/Listeners', 02:44:39 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 02:44:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:39 INFO - 'USER': 'cltbld', 02:44:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:39 INFO - 'XPC_FLAGS': '0x0', 02:44:39 INFO - 'XPC_SERVICE_NAME': '0', 02:44:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:39 INFO - Downloading/unpacking psutil>=3.1.1 02:44:39 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 02:44:39 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 02:44:39 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 02:44:39 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 02:44:39 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 02:44:39 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 02:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:44:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:42 INFO - Installing collected packages: psutil 02:44:42 INFO - Running setup.py install for psutil 02:44:42 INFO - building 'psutil._psutil_osx' extension 02:44:42 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 02:44:43 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 02:44:43 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 02:44:43 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 02:44:43 INFO - building 'psutil._psutil_posix' extension 02:44:43 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 02:44:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:44:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:44:43 INFO - ^ 02:44:43 INFO - 1 warning generated. 02:44:43 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 02:44:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:43 INFO - Successfully installed psutil 02:44:43 INFO - Cleaning up... 02:44:43 INFO - Return code: 0 02:44:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:43 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 0x11015aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b857ccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100bed30>, '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 0x10fe23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100b6b20>, '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.EoKGqXcuXF/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.cG3VavnMNG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:44:43 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 02:44:43 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 02:44:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EoKGqXcuXF/Render', 02:44:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:43 INFO - 'HOME': '/Users/cltbld', 02:44:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:43 INFO - 'LOGNAME': 'cltbld', 02:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:43 INFO - 'MOZ_NO_REMOTE': '1', 02:44:43 INFO - 'NO_EM_RESTART': '1', 02:44:43 INFO - 'PAGER': '/bin/cat', 02:44:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:43 INFO - 'PWD': '/builds/slave/test', 02:44:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:43 INFO - 'SHELL': '/bin/bash', 02:44:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cG3VavnMNG/Listeners', 02:44:43 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 02:44:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:43 INFO - 'USER': 'cltbld', 02:44:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:43 INFO - 'XPC_FLAGS': '0x0', 02:44:43 INFO - 'XPC_SERVICE_NAME': '0', 02:44:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:44:43 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 02:44:43 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 02:44:43 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 02:44:43 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 02:44:43 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 02:44:43 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 02:44:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:44:46 INFO - Installing collected packages: mozsystemmonitor 02:44:46 INFO - Running setup.py install for mozsystemmonitor 02:44:46 INFO - Successfully installed mozsystemmonitor 02:44:46 INFO - Cleaning up... 02:44:46 INFO - Return code: 0 02:44:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11015aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b857ccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100bed30>, '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 0x10fe23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100b6b20>, '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.EoKGqXcuXF/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.cG3VavnMNG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:44:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:44:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EoKGqXcuXF/Render', 02:44:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:46 INFO - 'HOME': '/Users/cltbld', 02:44:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:46 INFO - 'LOGNAME': 'cltbld', 02:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:46 INFO - 'MOZ_NO_REMOTE': '1', 02:44:46 INFO - 'NO_EM_RESTART': '1', 02:44:46 INFO - 'PAGER': '/bin/cat', 02:44:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:46 INFO - 'PWD': '/builds/slave/test', 02:44:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:46 INFO - 'SHELL': '/bin/bash', 02:44:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cG3VavnMNG/Listeners', 02:44:46 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 02:44:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:46 INFO - 'USER': 'cltbld', 02:44:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:46 INFO - 'XPC_FLAGS': '0x0', 02:44:46 INFO - 'XPC_SERVICE_NAME': '0', 02:44:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:47 INFO - Downloading/unpacking jsonschema==2.5.1 02:44:47 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 02:44:47 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 02:44:47 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 02:44:47 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 02:44:47 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 02:44:47 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 02:44:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:44:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:44:49 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 02:44:49 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 02:44:49 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 02:44:49 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 02:44:49 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 02:44:49 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 02:44:49 INFO - Downloading functools32-3.2.3-2.tar.gz 02:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:44:50 INFO - warning: no files found matching '*.txt' 02:44:50 INFO - no previously-included directories found matching 'build' 02:44:50 INFO - no previously-included directories found matching 'dist' 02:44:50 INFO - no previously-included directories found matching '.git*' 02:44:50 INFO - Installing collected packages: jsonschema, functools32 02:44:50 INFO - Running setup.py install for functools32 02:44:50 INFO - warning: no files found matching '*.txt' 02:44:50 INFO - no previously-included directories found matching 'build' 02:44:50 INFO - no previously-included directories found matching 'dist' 02:44:50 INFO - no previously-included directories found matching '.git*' 02:44:50 INFO - Successfully installed jsonschema functools32 02:44:50 INFO - Cleaning up... 02:44:50 INFO - Return code: 0 02:44:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:44: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')]} 02:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44: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')]} 02:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44: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', '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 0x11015aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b857ccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100bed30>, '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 0x10fe23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100b6b20>, '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.EoKGqXcuXF/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.cG3VavnMNG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:44: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:44: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 functools32==3.2.3-2 02:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EoKGqXcuXF/Render', 02:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:50 INFO - 'HOME': '/Users/cltbld', 02:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:50 INFO - 'LOGNAME': 'cltbld', 02:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:50 INFO - 'MOZ_NO_REMOTE': '1', 02:44:50 INFO - 'NO_EM_RESTART': '1', 02:44:50 INFO - 'PAGER': '/bin/cat', 02:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:50 INFO - 'PWD': '/builds/slave/test', 02:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:50 INFO - 'SHELL': '/bin/bash', 02:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cG3VavnMNG/Listeners', 02:44:50 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 02:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:50 INFO - 'USER': 'cltbld', 02:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:50 INFO - 'XPC_FLAGS': '0x0', 02:44:50 INFO - 'XPC_SERVICE_NAME': '0', 02:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:44:50 INFO - Cleaning up... 02:44:50 INFO - Return code: 0 02:44:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:44: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')]} 02:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44: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')]} 02:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44: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', '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 0x11015aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b857ccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100bed30>, '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 0x10fe23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100b6b20>, '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.EoKGqXcuXF/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.cG3VavnMNG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:44: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:44: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 blobuploader==1.2.4 02:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EoKGqXcuXF/Render', 02:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:50 INFO - 'HOME': '/Users/cltbld', 02:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:50 INFO - 'LOGNAME': 'cltbld', 02:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:50 INFO - 'MOZ_NO_REMOTE': '1', 02:44:50 INFO - 'NO_EM_RESTART': '1', 02:44:50 INFO - 'PAGER': '/bin/cat', 02:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:50 INFO - 'PWD': '/builds/slave/test', 02:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:50 INFO - 'SHELL': '/bin/bash', 02:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cG3VavnMNG/Listeners', 02:44:50 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 02:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:50 INFO - 'USER': 'cltbld', 02:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:50 INFO - 'XPC_FLAGS': '0x0', 02:44:50 INFO - 'XPC_SERVICE_NAME': '0', 02:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:50 INFO - Downloading/unpacking blobuploader==1.2.4 02:44: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 02:44: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 02:44: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 02:44: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 02:44: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 02:44: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 02:44:53 INFO - Downloading blobuploader-1.2.4.tar.gz 02:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:44:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:44:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:44: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 02:44: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 02:44: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 02:44: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 02:44: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 02:44: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 02:44:54 INFO - Downloading docopt-0.6.1.tar.gz 02:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:44:54 INFO - Installing collected packages: blobuploader, requests, docopt 02:44:54 INFO - Running setup.py install for blobuploader 02:44:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:44:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:44:54 INFO - Running setup.py install for requests 02:44:55 INFO - Running setup.py install for docopt 02:44:55 INFO - Successfully installed blobuploader requests docopt 02:44:55 INFO - Cleaning up... 02:44:55 INFO - Return code: 0 02:44:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:55 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 0x11015aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b857ccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100bed30>, '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 0x10fe23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100b6b20>, '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.EoKGqXcuXF/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.cG3VavnMNG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:44:55 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 02:44:55 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 02:44:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EoKGqXcuXF/Render', 02:44:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:55 INFO - 'HOME': '/Users/cltbld', 02:44:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:55 INFO - 'LOGNAME': 'cltbld', 02:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:55 INFO - 'MOZ_NO_REMOTE': '1', 02:44:55 INFO - 'NO_EM_RESTART': '1', 02:44:55 INFO - 'PAGER': '/bin/cat', 02:44:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:55 INFO - 'PWD': '/builds/slave/test', 02:44:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:55 INFO - 'SHELL': '/bin/bash', 02:44:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cG3VavnMNG/Listeners', 02:44:55 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 02:44:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:55 INFO - 'USER': 'cltbld', 02:44:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:55 INFO - 'XPC_FLAGS': '0x0', 02:44:55 INFO - 'XPC_SERVICE_NAME': '0', 02:44:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:55 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-2wOtGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:55 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-fAek1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:55 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-zO1h3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:56 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-OS9IYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:56 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-xdcr2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:56 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-6A9S7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:56 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Quu9Ee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:56 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-F8CmSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:56 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-i_peuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:56 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-duYZ5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:56 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Yjg717-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:56 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-BsTjJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:57 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Bhpqwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:57 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-tovsPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:57 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-VoxpXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:57 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-TmhFY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:57 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-_jv9QH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:44:57 INFO - Running setup.py install for manifestparser 02:44:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:44:57 INFO - Running setup.py install for mozcrash 02:44:57 INFO - Running setup.py install for mozdebug 02:44:57 INFO - Running setup.py install for mozdevice 02:44:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:44:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:44:58 INFO - Running setup.py install for mozfile 02:44:58 INFO - Running setup.py install for mozhttpd 02:44:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:44:58 INFO - Running setup.py install for mozinfo 02:44:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:44:58 INFO - Running setup.py install for mozInstall 02:44:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:44:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:44:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:44:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:44:58 INFO - Running setup.py install for mozleak 02:44:58 INFO - Running setup.py install for mozlog 02:44:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Running setup.py install for moznetwork 02:44:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Running setup.py install for mozprocess 02:44:59 INFO - Running setup.py install for mozprofile 02:44:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Running setup.py install for mozrunner 02:44:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Running setup.py install for mozscreenshot 02:44:59 INFO - Running setup.py install for moztest 02:44:59 INFO - Running setup.py install for mozversion 02:45:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:45:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:45:00 INFO - Cleaning up... 02:45:00 INFO - Return code: 0 02:45:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45:00 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')]} 02:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:00 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')]} 02:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:00 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 0x11015aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b857ccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100bed30>, '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 0x10fe23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100b6b20>, '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.EoKGqXcuXF/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.cG3VavnMNG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:45:00 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 02:45:00 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 02:45:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EoKGqXcuXF/Render', 02:45:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:00 INFO - 'HOME': '/Users/cltbld', 02:45:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:00 INFO - 'LOGNAME': 'cltbld', 02:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:00 INFO - 'MOZ_NO_REMOTE': '1', 02:45:00 INFO - 'NO_EM_RESTART': '1', 02:45:00 INFO - 'PAGER': '/bin/cat', 02:45:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:00 INFO - 'PWD': '/builds/slave/test', 02:45:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:00 INFO - 'SHELL': '/bin/bash', 02:45:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cG3VavnMNG/Listeners', 02:45:00 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 02:45:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:00 INFO - 'USER': 'cltbld', 02:45:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:00 INFO - 'XPC_FLAGS': '0x0', 02:45:00 INFO - 'XPC_SERVICE_NAME': '0', 02:45:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:45:00 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Zl39Bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:45:00 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)) 02:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:45:00 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-LE3LRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:45:00 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)) 02:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:45:00 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-J1kBSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:45:00 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)) 02:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:45:00 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-TrrHhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:45:00 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)) 02:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:45:00 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-S7iGzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:45:00 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)) 02:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:45:00 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-vXvX2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:45:01 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)) 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:45:01 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-kaMCZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:45:01 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)) 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:45:01 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-1nOvLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:45:01 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)) 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:45:01 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-kHBRSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:45:01 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)) 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:45:01 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-ke7HqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:45:01 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)) 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:45:01 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-I9Dg0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:45:01 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)) 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:45:01 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-fnzP5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:45:01 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)) 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:45:01 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-E4aCdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:45:01 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)) 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:45:01 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-ooaLba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:45:01 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)) 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:01 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-ZGWDUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:01 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)) 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:45:01 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-t_d9qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:45:02 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)) 02:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:45:02 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-O0O7JM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:45:02 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)) 02:45:02 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)) 02:45:02 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)) 02:45:02 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)) 02:45:02 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)) 02:45:02 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)) 02:45:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:45:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:04 INFO - Downloading blessings-1.6.tar.gz 02:45:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:45:04 INFO - Installing collected packages: blessings 02:45:04 INFO - Running setup.py install for blessings 02:45:04 INFO - Successfully installed blessings 02:45:04 INFO - Cleaning up... 02:45:04 INFO - Return code: 0 02:45:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:45:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:45:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:45:05 INFO - Reading from file tmpfile_stdout 02:45:05 INFO - Current package versions: 02:45:05 INFO - blessings == 1.6 02:45:05 INFO - blobuploader == 1.2.4 02:45:05 INFO - docopt == 0.6.1 02:45:05 INFO - functools32 == 3.2.3-2 02:45:05 INFO - jsonschema == 2.5.1 02:45:05 INFO - manifestparser == 1.1 02:45:05 INFO - mozInstall == 1.12 02:45:05 INFO - mozcrash == 0.17 02:45:05 INFO - mozdebug == 0.1 02:45:05 INFO - mozdevice == 0.48 02:45:05 INFO - mozfile == 1.2 02:45:05 INFO - mozhttpd == 0.7 02:45:05 INFO - mozinfo == 0.9 02:45:05 INFO - mozleak == 0.1 02:45:05 INFO - mozlog == 3.3 02:45:05 INFO - moznetwork == 0.27 02:45:05 INFO - mozprocess == 0.23 02:45:05 INFO - mozprofile == 0.28 02:45:05 INFO - mozrunner == 6.12 02:45:05 INFO - mozscreenshot == 0.1 02:45:05 INFO - mozsystemmonitor == 0.3 02:45:05 INFO - moztest == 0.7 02:45:05 INFO - mozversion == 1.4 02:45:05 INFO - psutil == 3.1.1 02:45:05 INFO - requests == 1.2.3 02:45:05 INFO - wsgiref == 0.1.2 02:45:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:05 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 0x11015aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b857ccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100bed30>, '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 0x10fe23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100b6b20>, '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.EoKGqXcuXF/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.cG3VavnMNG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:45:05 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 02:45:05 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 02:45:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EoKGqXcuXF/Render', 02:45:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:05 INFO - 'HOME': '/Users/cltbld', 02:45:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:05 INFO - 'LOGNAME': 'cltbld', 02:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:05 INFO - 'MOZ_NO_REMOTE': '1', 02:45:05 INFO - 'NO_EM_RESTART': '1', 02:45:05 INFO - 'PAGER': '/bin/cat', 02:45:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:05 INFO - 'PWD': '/builds/slave/test', 02:45:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:05 INFO - 'SHELL': '/bin/bash', 02:45:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cG3VavnMNG/Listeners', 02:45:05 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 02:45:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:05 INFO - 'USER': 'cltbld', 02:45:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:05 INFO - 'XPC_FLAGS': '0x0', 02:45:05 INFO - 'XPC_SERVICE_NAME': '0', 02:45:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:05 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)) 02:45:05 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)) 02:45:05 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)) 02:45:05 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)) 02:45:05 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)) 02:45:05 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)) 02:45:05 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)) 02:45:05 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)) 02:45:05 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)) 02:45:05 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)) 02:45:05 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)) 02:45:05 INFO - Cleaning up... 02:45:05 INFO - Return code: 0 02:45:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:05 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 0x11015aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b857ccf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100bed30>, '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 0x10fe23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100b6b20>, '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.EoKGqXcuXF/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.cG3VavnMNG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 02:45:05 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 02:45:05 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 02:45:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EoKGqXcuXF/Render', 02:45:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:05 INFO - 'HOME': '/Users/cltbld', 02:45:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:05 INFO - 'LOGNAME': 'cltbld', 02:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:05 INFO - 'MOZ_NO_REMOTE': '1', 02:45:05 INFO - 'NO_EM_RESTART': '1', 02:45:05 INFO - 'PAGER': '/bin/cat', 02:45:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:05 INFO - 'PWD': '/builds/slave/test', 02:45:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:05 INFO - 'SHELL': '/bin/bash', 02:45:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cG3VavnMNG/Listeners', 02:45:05 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 02:45:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:05 INFO - 'USER': 'cltbld', 02:45:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:05 INFO - 'XPC_FLAGS': '0x0', 02:45:05 INFO - 'XPC_SERVICE_NAME': '0', 02:45:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:45:05 INFO - Cleaning up... 02:45:05 INFO - Return code: 0 02:45:05 INFO - Running post-action listener: _resource_record_post_action 02:45:05 INFO - Running post-action listener: _start_resource_monitoring 02:45:05 INFO - Starting resource monitoring. 02:45:05 INFO - [mozharness: 2016-09-08 09:45:05.778642Z] Finished create-virtualenv step (success) 02:45:05 INFO - [mozharness: 2016-09-08 09:45:05.779682Z] Running install step. 02:45:05 INFO - Running pre-action listener: _resource_record_pre_action 02:45:05 INFO - Running main action method: install 02:45:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:45:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:45:06 INFO - Reading from file tmpfile_stdout 02:45:06 INFO - Detecting whether we're running mozinstall >=1.0... 02:45:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:45:06 INFO - Reading from file tmpfile_stdout 02:45:06 INFO - Output received: 02:45:06 INFO - Usage: mozinstall [options] installer 02:45:06 INFO - Options: 02:45:06 INFO - -h, --help show this help message and exit 02:45:06 INFO - -d DEST, --destination=DEST 02:45:06 INFO - Directory to install application into. [default: 02:45:06 INFO - "/builds/slave/test"] 02:45:06 INFO - --app=APP Application being installed. [default: firefox] 02:45:06 INFO - mkdir: /builds/slave/test/build/application 02:45:06 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'] 02:45:06 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 02:45:40 INFO - Reading from file tmpfile_stdout 02:45:40 INFO - Output received: 02:45:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:45:40 INFO - Running post-action listener: _resource_record_post_action 02:45:40 INFO - [mozharness: 2016-09-08 09:45:40.558210Z] Finished install step (success) 02:45:40 INFO - [mozharness: 2016-09-08 09:45:40.558380Z] Running run-tests step. 02:45:40 INFO - Running pre-action listener: _resource_record_pre_action 02:45:40 INFO - Running main action method: run_tests 02:45:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:45:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:45:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:45:40 INFO - 2016-09-08 02:45:40.572 screenresolution[1641:7958] starting screenresolution argv=screenresolution get 02:45:40 INFO - 2016-09-08 02:45:40.575 screenresolution[1641:7958] Display 0: 1600x1200x32@60 02:45:40 INFO - 2016-09-08 02:45:40.581 screenresolution[1642:7960] starting screenresolution argv=screenresolution list 02:45:40 INFO - Available Modes on Display 0 02:45:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:45:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:45:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:45:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:45:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:45:40 INFO - Intel Iris: 02:45:40 INFO - Chipset Model: Intel Iris 02:45:40 INFO - Type: GPU 02:45:40 INFO - Bus: Built-In 02:45:40 INFO - VRAM (Dynamic, Max): 1536 MB 02:45:40 INFO - Vendor: Intel (0x8086) 02:45:40 INFO - Device ID: 0x0a2e 02:45:40 INFO - Revision ID: 0x0009 02:45:40 INFO - Displays: 02:45:40 INFO - Display: 02:45:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:45:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:45:40 INFO - Display Serial Number: 1600x1200 60 02:45:40 INFO - Main Display: Yes 02:45:40 INFO - Mirror: Off 02:45:40 INFO - Online: Yes 02:45:40 INFO - Rotation: Supported 02:45:40 INFO - Return code: 0 02:45:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:45:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:45:40 INFO - Python 2.7.3 02:45:40 INFO - Return code: 0 02:45:40 INFO - grabbing minidump binary from tooltool 02:45:40 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')]} 02:45:40 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 0x1100bed30>, '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 0x10fe23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1100b6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:45:40 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 02:45:40 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 02:45:40 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 02:45:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:45:40 INFO - Return code: 0 02:45:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:45:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:45:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:45:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:45:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:45:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0351', '--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 02:45:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0351 --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 02:45:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EoKGqXcuXF/Render', 02:45:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:40 INFO - 'HOME': '/Users/cltbld', 02:45:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:40 INFO - 'LOGNAME': 'cltbld', 02:45:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:45:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:40 INFO - 'MOZ_NO_REMOTE': '1', 02:45:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:40 INFO - 'NO_EM_RESTART': '1', 02:45:40 INFO - 'PAGER': '/bin/cat', 02:45:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:40 INFO - 'PWD': '/builds/slave/test', 02:45:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:40 INFO - 'SHELL': '/bin/bash', 02:45:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cG3VavnMNG/Listeners', 02:45:40 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 02:45:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:40 INFO - 'USER': 'cltbld', 02:45:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:40 INFO - 'XPC_FLAGS': '0x0', 02:45:40 INFO - 'XPC_SERVICE_NAME': '0', 02:45:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0351', '--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 02:45:41 INFO - mozversion application_buildid: 20160907175716 02:45:41 INFO - mozversion application_changeset: 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 02:45:41 INFO - mozversion application_display_name: Nightly 02:45:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:45:41 INFO - mozversion application_name: Firefox 02:45:41 INFO - mozversion application_remotingname: firefox 02:45:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:45:41 INFO - mozversion application_vendor: Mozilla 02:45:41 INFO - mozversion application_version: 51.0a1 02:45:41 INFO - mozversion platform_buildid: 20160907175716 02:45:41 INFO - mozversion platform_changeset: 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 02:45:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:45:41 INFO - mozversion platform_version: 51.0a1 02:45:41 INFO - using testdate: 1473327941 02:45:41 INFO - actual date: 1473327941 02:45:41 INFO - starting webserver on 'localhost:49272' 02:45:41 INFO - SUITE-START | Running 2 tests 02:45:41 INFO - TEST-START | dromaeo_css 02:45:41 INFO - operating with platform_type : mac_ 02:45:41 INFO - Initialising browser for dromaeo_css test... 02:45:41 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpkejDvi/profile http://localhost:49272/getInfo.html) 02:45:43 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 02:45:43 INFO - PROCESS | 1652 | colorDepth:24 02:45:43 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 02:45:43 INFO - PROCESS | 1652 | __metrics 02:45:43 INFO - PROCESS | 1652 | 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" 02:45:43 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:45:44 INFO - TEST-INFO | 1652: exit 0 02:45:44 INFO - Browser initialized. 02:45:44 INFO - Running cycle 1/1 for dromaeo_css test... 02:45:44 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpkejDvi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:56 INFO - PROCESS | 1655 | RSS: Main: 231919616 02:45:56 INFO - PROCESS | 1655 | 02:46:47 INFO - PROCESS | 1655 | 1473328007447 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] 02:48:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 02:49:00 INFO - PROCESS | 1655 | RSS: Main: 384217088 02:49:00 INFO - PROCESS | 1655 | 02:52:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 02:52:04 INFO - PROCESS | 1655 | RSS: Main: 369250304 02:52:04 INFO - PROCESS | 1655 | 02:55:06 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 02:55:07 INFO - PROCESS | 1655 | RSS: Main: 334290944 02:55:07 INFO - PROCESS | 1655 | 02:58:09 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 02:58:10 INFO - PROCESS | 1655 | RSS: Main: 329732096 02:58:10 INFO - PROCESS | 1655 | 03:01:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 03:01:14 INFO - PROCESS | 1655 | RSS: Main: 331554816 03:01:14 INFO - PROCESS | 1655 | 03:04:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 03:04:17 INFO - PROCESS | 1655 | RSS: Main: 381599744 03:04:17 INFO - PROCESS | 1655 | 03:04:17 INFO - PROCESS | 1655 | __start_tp_report 03:04:17 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 03:04:17 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 03:04:17 INFO - PROCESS | 1655 | |i|pagename|runs| 03:04:17 INFO - PROCESS | 1655 | |0;dojo.html;3335.5830521184853;3336.432806422907;3346.5649465569472;3389.57630296213;3421.640727723589;5599.020171469997;5646.830595082144;5689.658620482774;5743.396943320949;5746;5670;5720.570957178207;5721.170430287616;5730.484256416921;5733.48398644122;5157.948420515803;5188.6367954243215;5203.895922081556;5206.895862082756;5222.138347172712;5204.219367094928;5231.058409486291;5253.553447956909;5338.359396872381;5347.919781203265;5188.610854185932;5206.140986737203;5220.086484865134;5262.789488420459;5280.630355875091;5061.417936937262;5246.5802735781235;5299.966506531228;5341.946580534183;5368.91946620801;5135.64050516464;5211.452797456251;5221.373435187802;5234.031704134747;5249.527542521191;5579.8605034874045;5595.272614560122;5605.859853503654;5625.240592519998;5646.983542962265;6081.209442772455;6126.914660831522;6139.079138129272;6152.507799376039;6156.4766994805495;6264.123022776815;6318.936810631927;6336.588121772116;6341.778037768656;6351.555391122577;5618.185363122325;5626.437356264381;5741.942580574224;5772.960867043972;5789.305283366023;6397.10440538325;6397.4562162216735;6411.4229719325485;6413.262474815456;6421.775237866652;6118.1434599156155;6127.84680382994;6142.447179753844;6147.200863887666;6151.477124444383;6080.3583032580955;6143.634700640286;6217.129601855744;6292.465140463112;6293.96853015732;6178.135061091451;6211.3167551569295;6217.191765070603;6219.689015549205;6235.407636274546;6170.660613666291;6180.042093475518;6241.375862413723;6251.499880009588;6260.467860231931;4889;4912.992836468587;4914.189158788759;4981.153203955337;5001.89996200074;4465.37484752135;4472.888177795516;4473.150101480708;4476.753778542212;4486.641068714495;5670.347909990404;5674.2623458950075;5678.432156784371;5684.232628595087;5697.829065128053;6166.81499555014;6204.5967012144065;6247.96876015617;6260.655663938527;6350.364963503613;5681.0910254359105;5726.455986681259;5737.741801618937;5751.626144300691;5764.221830052889;5314.176302673091;5394.8921021579345;5411.107167317348;5420.83737487876;5426.240326354314;6311.211098612674;6340.714667839866;6344.302126766052;6350.364963503613;6354.809355719428;6314.589551679126;6319.494440444845;6336.303006669171;6344.841378965378;6345.650989195638;6285;6339.461145802658;6349.873002539831;6355.523335749745;6357.237131544244;6140.749659041634;6143.174139895698;6155.169052178077;6188.907166392418;6196.442320191204;6151.938480615316;6179.28938172098;6247.437730604267;6252.093446450148;6284.74861005573;5926.970365148147;6066.393360663898;6091.695415229309;6092.512598991982;6092.6039807414145;5872.941270587239;6019.006863867413;6022.728977196124;6035.758569657341;6088.573799833969;5873.324567674772;5937.614055086406;5938.020226662552;5943.910841337297;5957.4042595740075;6112.969435152973;6131.724072416841;6134.447899689049;6144.170536977451;6154.661493617894;5769.451902069297;5863.824085277449;5868.295804503487;5875.911861321998;5990.400959903975 03:04:17 INFO - PROCESS | 1655 | |1;ext.html;486.38472332499384;499.4880247746058;526.1029943945573;530.7717681396998;532.6138549551575;25153.62269565958;40396.19207615846;41556.16887662245;41618;41886.581134188724;43018.78490607542;43377.566224337745;43401;43466;43551;9678.661246856343;9865.457399843006;9865.506724663774;9872.60509579616;9881.604735810559;8741.082186370435;11748.706282342968;11898.22661526998;11907.880921190765;12233.63299101024;12001.93999030008;12082.214656047372;12108;12118.575849845258;12130.150889437742;11964.70088247792;12039.819402708967;12043.096767742454;12063.577774777841;12071.336076515789;11791.410429478577;11866;11877.109216808687;11880.049596032297;11898.22661526998;40698;41016.179676406304;41039;41081.1783764326;41102;3296.175956010997;3379.797212167278;3489.616142224353;3845.8461661533506;3905.453236546886;23316.65025024626;30628.54057189144;31131.68868311333;31568.368632627447;32097.51853722196;2778.8054836161477;3272.6236482804543;3298.191942973987;3370.2248482848836;3412.4710669846213;494148.9999999964;499091;499308;501002;501167;57534.13698794522;57785;57899.710501447225;57918;57926.628070912164;34113.658863411045;34923.825380872935;34955.951321460394;36225.818870905474;36343;16132.193390330436;16281.267342969359;16294.266757995918;16303.266353014144;16321.265543050591;11254.943725281484;11501.654950351336;12340.753184936251;12392.380380980914;12431.564895228805;17147.314107435814;17242.137893105293;18453.907730461262;19512.414627561197;19512.70730939037;7956.045274567089;8036.6693658838285;8039.397045221632;8134.83730325402;8177.568925438025;942.1944237676825;948.4356807699407;992.9304948653669;995.5520015992832;1036.0005189983253;23688.40778980505;30434.086977390714;30557;30612.519766345387;30637.540436893465;1042.7810159866513;1087.141158484804;1089.2320913755784;1114.1922106472905;1176.8293597428326;14789.778153327708;15083.321250543751;15638.98346607467;15721.371145154295;16041.117738524494;684662;690787;691085;693247;694572;57121;57742.7112864433;57774.844503110544;57825;57849;1229.8339724137127;1347.3465369295884;1371.6433727230792;1384.5292600515872;1397.105852254558;30370.54444183295;30382.392352153274;31205.531917021715;31411.528827067155;32463.86376476812;48679;53921.19118213309;54524.72737636286;54640.726796365765;58839;619332;626226;626946;629432;635413;26486.602700959116;27000.054998074975;27140.728592713822;27355.72644273532;42372.57627423686;47041.05917881692;47116.764416179074;47216.527834721215;47452.288215676104;47730;39082;39127;39195;40389;40827.59172408357;32820.507692384155;35481.46777798387;38310;38928;40713;37205.069873253386;37584;38481.807590961864;38532.80733596314;38691.80654096712;30524.38951220919;33504;33800;34285.828570857986;35850;25079.996800127792;25193.682659178237;25268.494630107663;25452.74547254578;25808.09671661483 03:04:17 INFO - PROCESS | 1655 | |2;jquery.html;345.4783277251353;366.18341099348453;371.5133175540043;380.8819266027531;400.67945643485206;15395.846041539595;16206.865519413646;19557.511062223453;20086.49783755403;20247.493812654673;20391.184352625914;20467.283645072424;21045.26341578045;21106.89446552765;21140;5341.412444631087;5955.136505206753;6221.720022598971;6223;6241.188645476082;4250.511191213019;4493.9775301123445;6046.365131661156;6226.719797609118;6535.477161827066;6808.693608787592;6875.6562171891455;6880.380765731084;6882.9311706883045;6925.965370173142;6520.510961677869;6743.662816859162;6794.864102717967;6815.977603359486;6822.4200942919915;5762.07806750922;5785.942140578582;5853.5902486825935;5870.970645146747;5887.61730487517;20182.39452816418;24761.752382476305;25532.87233563839;25963;26226;2243.371855880356;2252.7521972583004;2382.344855164826;2408.674828898111;3016.8039077459894;13239.153581979825;15532.844671553254;15690.843091569051;16287.348506059743;16306;1850.9167087481096;1857.544901499128;1883.6703576873992;1905.8951757653324;1956.0120354719309;118478.4076079614;120630;122973.38513307465;123979;125527;10330.896691033144;10680.839787403194;10743.677689669323;10885.891141088647;11055.72360690989;13693.520726774515;13726.245056521788;13727.245001525012;13744.518941837188;13835.584932452042;7128.964355178295;8337.83324333522;8605.397622166389;8643.654253829902;8765.912340876637;8622.136946876184;8625.611847466878;8672.43629164115;8815.162559556831;8858.379913406128;5461.808836690697;6559.934400656028;6672.398624330126;6674.532782705164;6674.933250667528;1669.983300167007;1733.3326669232436;1783.3223375117632;1799.7480352750363;1826.2512369928265;874.1608056266056;881.2377293641083;898.4878619187132;908.5502676175336;985.3989066669326;15349.9232503839;16755.664886702198;21470.78529214719;22055.558888822135;22271.44321391978;949.4588084791602;970.713639476342;980.6812785844629;1002.7693630464888;1012.9240306977006;11076.956801163895;11790.587329443428;11908.940455297668;11998.040156787436;12143.696407589705;574545;578239;583910;599568;600383;113029;115790;116284;116915.41542292404;118879;979.0943377375899;987.7678745494803;1008.6973907827622;1012.0233974214918;1016.5832008876325;11707.433980916907;18047.819521804613;20440;20551.79448205499;20587.07358168856;39759.60240397674;47810.28284575664;50745.98621568821;51503;52038.95922081613;67429.97710068706;68889;69329.96005059827;70153.64923175555;70946;7629.885551716618;7890.881636775568;8054;8061.717839875576;8413.200745929127;10421.63524276677;12788.46538415396;13034.217946922836;13098.279594622387;13534.86465135336;9847.45838978863;10696.83954740664;10711.839322410015;11495.942520287344;11520.423978801195;10191.337563058378;10816.351018938889;10923.617673381394;11278.266912650652;11335.206535542433;11041.613543525938;11225.326480411202;11273.887261127284;11413.714657133638;11850.703732406759;6841.350071743176;6950.33971772701;7843.333316668147;8128.268455839003;8401.663933442594;10618.469076545987;10678.49055471979;10887.510062047386;11265.774684506428;11269.436528173723 03:04:17 INFO - PROCESS | 1655 | |3;mootools.html;1358.93205339733;1492.5373134328356;1656.4947690954261;1844.714069319256;1855.9072046397685;8428;8991.820163596723;9015.594298256578;9019.639214431423;9050.140236677513;7903.288704016637;8118;8562.229399354057;8732;8828.911710882889;2379.7144342678857;2412.7828495435497;2413.2036427978815;2414.4688168602966;2417.6615273861676;2367.893444794988;2385.4632707640817;2391.9043238270447;2413.227767114524;2419.322589674894;2402.843815152018;2421.794147497465;2423.9394015149587;2428.138011006094;2430.453148041694;2394.0543485323374;2401.687780588521;2406.3743426709007;2408.89159987801;2410.2648692148887;2386.6658667786523;2389.569877422063;2392.9760702392928;2397.436602398435;2401.435662619283;8821.16198961104;8842.867356989651;8930.19628233462;8960.8655870162;8965.686200983;5244.737763111867;5410.621256512046;5421.457854214586;5424.511793938565;5429.782808687687;3526.418141006731;3582.5700915889997;3706.0179052551093;3709.4992176056426;3710.109573702319;1051.2588625019418;1088.0860077534908;1092.5738961804902;1107.6510899066768;1107.8061339265596;11696.649100526913;11810.940945295304;11815.645530634094;11848.0521558276;11881.584144554985;4538.410006699108;4652.371929789435;4794.472608013116;4822.7829747661435;4836.322914791932;9964.003599640124;9987.550560224807;9991.500424978722;9992.050755178247;9995.650152244636;5370.543503802221;5375.16684913839;5379.569634429236;5380.354357477127;5383.730813459311;4455.997400584907;4633.0733853229485;4876.878078048077;4944.505549445027;4951.678140920829;3877.3408520551575;3905.6484916357094;3984.820683069269;4014.277430062617;4035.93946090803;1083.285031878956;1297.7534268488957;1298.337847697681;1307.45740517686;1309.5482058689736;827.8096037911193;866.9783255418539;902.3683421604771;909.5628906327986;934.3226161033332;5571.331440227118;5754.4533269339345;5928.644281343133;6003.549733769984;6053.969730151409;857.4340934982881;884.8451520983803;898.5507246376811;915.190056799737;925.6667599664115;3846.9038274042814;3907.7264591478893;3931.5085614298214;3980.920381592352;4073.327900896319;9102.499362534993;9665.855012174823;10915.181361397768;10917;10917.727056823484;10866.293690910066;10871.45642717861;10886.510107045202;10898.073663738513;10904.291221070605;7766.495177813424;8180.9590952044855;8183.386246031571;8196.83606327882;8343.874841877378;5308;5886.793962211302;5906.793262235801;6094.268687757321;6173.290071641639;9777.85333219988;9844.507774611384;10130.290879638354;10205.897941020792;10213.285070045025;10168.74578135523;10361.4301213434;10434.739131521772;10489.737756556147;10494.317869338469;7058.858822823618;7254.455915806441;7354.558726476428;7465.29079737424;7569.697212111454;8396.664133434595;8489.235968762841;8548.401611887357;8550.74347769568;8556.229939305493;6244.2819075806865;7533.434992375484;7582.052243469567;7606.619669016416;7673.462857599914;7356.227596102546;7404.592747398729;7476.876790404101;7509.098908131059;7590;6901.24086350491;7068.399186069241;7076.433885289268;7085.610291434021;7089.964550177217;4928.137575924127;4929.137400954747;5002.349694539686;5027.748612569284;5114;4901.681390709592;4980.613183035571;5022.4977502249485;5179.663321884065;5182.144946083854 03:04:17 INFO - PROCESS | 1655 | |4;prototype.html;301.394197662698;305.1668943783471;309.2824646819379;314.39165215308367;318.1854452601339;5290.68255904646;5342.786288548458;5372.435894231108;5373.596980226479;5402.729863506824;5229.790808367661;5276.4698237511075;5325.973370133144;5352.9197062044;5366.719144363649;3845.2693988142164;4035.051762835731;4035.7982100894983;4042.7776472294013;4056.4320995060716;3329.9843547717937;3361.445361515347;3999.860004899829;4015.939760903574;4028.45615841862;3553.9466907996402;3773.6792372648356;3802.6007269236748;4002.359622460407;4013.876114687892;4026.3960405939197;4038;4049.635532802062;4050.1494686115952;4051.9189616207805;2635.774364920321;2943.882244710209;3427.2288735034667;3485.6514348565192;3496.7552271341015;4621.676482646216;4629.305604159369;5124.487551244845;5180.792768289264;5233.790648374061;3873.5158105236846;4651.5813576778255;4674.368960190365;4711.387519622427;4722.504137065627;4484.641228701728;5288.603354748371;5508.14623733322;5576.124593407583;5648.943510564883;3741.605372561563;3751.268502641986;3849.073944576516;3915.2169566086613;3917.764934103963;4364.363363738598;5596.412376700469;5710.602191695934;5761.539076873882;5944.464998150187;3372.5278461015478;3395.609504906918;3906.628870257321;3937.783421911765;3972.324704800191;3984.86052988144;4018.2767101921936;4018.557958624549;4026.456428382131;4054.8378064877666;4497.8183339642255;4600.493945665974;4607.387414404999;4631.374764406829;4714.339992401066;4604.493505714368;4633.212353899845;5586.497215250647;5637.915431268534;5650.434956504317;1189.5487505243589;1351.6823546466574;1352.7362164378048;1353.587155917435;1360.836699596035;742.5173637135905;783.5964478293683;818.6643476174739;829.8921140251729;850.7192626433263;3507.649235076472;4279.850205242801;4288.442502474721;4298.32365377503;4318.52496225415;4137.97931010349;4212.684048696298;4372.40972468719;4783.06730187617;4798.664093513421;3386.153461634591;3477.6004556855687;3831.976424754053;4041.3125271693993;4092.9181416371507;1865.2072869030608;2059.331941127691;2076.719642848226;2192.9561408772056;2494.975050249511;4789.760511974388;4885.55876016576;4970.304157417964;4981.530448517696;5021.271915572222;4090.1410703752545;4539.843574302246;4575.725456472623;4816.253480710496;4871.634627402959;3851.1129546522;4266.850660226877;4370.606645401929;4754.168020596382;4966.950330496721;4029.9096417137216;4115.71190016696;4236.279832428556;4288.670512141246;4445.377227435828;4671.509491503343;4682.836100736458;4703.835365762182;4706.599939005086;4720.811167553259;4872.416464649074;4998.3252260945765;5537.307836520435;5788.53691704671;6039.63762174271;3606.9098272542346;3970.3845903884944;3976.602339766116;3992.9201415972097;4011.4183443401125;5828.737706803289;5831.650100993954;5831.879944229817;5844.392791982239;5851.385604511465;4453.643708503377;4456.420665313489;5314.28257185275;5738.799142030009;5782.855428614318;4911.71605203582;4932.506749325039;5698.6580805151825;5706.372299047184;5740.770369185186;4824.235039128208;5245;5479.452054794648;5514.365847927379;5670.744816483186;4823.3325842134645;5666.603337766317;5695.582650409721;5714.885702285847;5724.341700704305;5142.51146141116;5576.832695019156;5627.268455100811;5641.50500117461;5697.006047883207 03:04:17 INFO - PROCESS | 1655 | |5;yui.html;711.5979471598547;756.4477931110289;776.562646842061;813.1705660226571;818.2431251092738;14986.925065374671;16277;16278.430254941079;16289.918550407247;16291.918540407296;14885.627859303495;15030.549083527512;15053.62365940852;15060.774088388656;15065.322060507277;676.2189670930084;694.5311914457747;840.357126798;841.4698739793937;863.4430792139067;784.7175016993878;832.2426591801432;841.7011960753936;844.7212419901418;845.3152946113637;900.9819803603924;901.7339884733987;902.0618556701023;902.6975963052386;906.7869050773063;898.5327629632587;898.5911410308294;898.6944438890808;898.7124120281512;899.2805755395657;528.1101344234976;543.8667526456044;544.1484077418827;546.2243614067993;665.7037618259876;14663.56009319722;15280.617984550365;15286.312115954808;15299.61750956235;15330.386784528606;14338.283085845771;14622.780658290134;14894.627634309121;15032.398704051824;15057.623559410993;11974.760504789943;11984.760304793943;12033.93983030088;12151.513939442411;12269.57056503018;3816.637419445148;3852.383618621035;3869.496965394481;3887.3197190491833;4011.7793521356316;120630.79369206283;122295.3885230577;123448;126187;126549.1017634727;11343.829842552368;11416.828747568794;11464.885351146382;11474.598389056342;11916.344601047025;23855.045798168227;23897.044118235423;23999.16002939889;24004.7599524006;24301.756982430303;7987.520748755093;7989.241022103007;8001.199880011952;8063.27430531255;8074.071481779553;4259.765712885759;4351.477822661301;4395.626371758372;4410.82368114286;4435.600795928317;3624.619414961391;3750.643688849555;3751.5873253942045;3752.5496940366784;3838.596947320491;714.589111261027;715.2239819795476;716.6309350684402;722.2344315025985;731.3235257386896;565.1099518258759;605.9666718330446;627.9183706118175;653.3335997282745;660.5739298152664;8965.775855603662;9361.578728957213;9564.091411315914;9634.421934683942;9680.854787178198;626.1984659635674;642.5502148496072;659.5976454362835;681.9454443644496;684.5345165287553;4175.68682348825;4503.617192538671;4698.88252793676;4741.383620129361;4744.312074749211;98124;99756;153959;162796.18601906914;167079.44427502374;115420.42289788666;126438.36780816037;132594;139616.30191849178;141374.2931285337;100253;101977;112043;115306;116823.41588292005;9485.288603354915;9657.420554766737;9754.53713931544;9983.900160998297;10084;40693;58466;61491.692541538796;61992.69003654953;62422.06366904591;83808.16191838004;88354;89110.97001904919;90178;90555.92498337722;13145.21128732279;13151.934240328737;13229.3385330735;13297.069205155547;13416.993725470433;14823.925880370529;15101.39594416211;15191.088534687955;15212.619684507976;15256.923715381352;13984.860151398763;14069.437222510986;14079;14283.28583570838;14723.926380368459;13975.301234938415;14033.649158771112;14125.858741412454;14252.28738563047;14326.49857254991;13383.464661413434;13609.11540749848;13698.315084245947;13749.862501375259;13867.653308667364;11279.774404512027;11418.828717569077;11945.223560468541;12088.516459341528;12102.394880256128;9278.907210928075;11194.344327719817;11416.94291528537;11576.073914087021;11625.767484650429 03:04:17 INFO - PROCESS | 1655 | __end_tp_report 03:04:17 INFO - PROCESS | 1655 | __start_cc_report 03:04:17 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1342 03:04:17 INFO - PROCESS | 1655 | __end_cc_report 03:04:17 INFO - PROCESS | 1655 | __startTimestamp1473329057846__endTimestamp 03:04:17 INFO - PROCESS | 1655 | 03:04:17 INFO - PROCESS | 1655 | ------- Summary: start ------- 03:04:17 INFO - PROCESS | 1655 | Number of tests: 6 03:04:17 INFO - PROCESS | 1655 | 03:04:17 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5750.54 Median:5937.61 stddev:635.59 (10.7%) stddev-sans-first:608.65 03:04:17 INFO - PROCESS | 1655 | Values: 3335.6 3336.4 3346.6 3389.6 3421.6 5599.0 5646.8 5689.7 5743.4 5746.0 5670.0 5720.6 5721.2 5730.5 5733.5 5157.9 5188.6 5203.9 5206.9 5222.1 5204.2 5231.1 5253.6 5338.4 5347.9 5188.6 5206.1 5220.1 5262.8 5280.6 5061.4 5246.6 5300.0 5341.9 5368.9 5135.6 5211.5 5221.4 5234.0 5249.5 5579.9 5595.3 5605.9 5625.2 5647.0 6081.2 6126.9 6139.1 6152.5 6156.5 6264.1 6318.9 6336.6 6341.8 6351.6 5618.2 5626.4 5741.9 5773.0 5789.3 6397.1 6397.5 6411.4 6413.3 6421.8 6118.1 6127.8 6142.4 6147.2 6151.5 6080.4 6143.6 6217.1 6292.5 6294.0 6178.1 6211.3 6217.2 6219.7 6235.4 6170.7 6180.0 6241.4 6251.5 6260.5 4889.0 4913.0 4914.2 4981.2 5001.9 4465.4 4472.9 4473.2 4476.8 4486.6 5670.3 5674.3 5678.4 5684.2 5697.8 6166.8 6204.6 6248.0 6260.7 6350.4 5681.1 5726.5 5737.7 5751.6 5764.2 5314.2 5394.9 5411.1 5420.8 5426.2 6311.2 6340.7 6344.3 6350.4 6354.8 6314.6 6319.5 6336.3 6344.8 6345.7 6285.0 6339.5 6349.9 6355.5 6357.2 6140.7 6143.2 6155.2 6188.9 6196.4 6151.9 6179.3 6247.4 6252.1 6284.7 5927.0 6066.4 6091.7 6092.5 6092.6 5872.9 6019.0 6022.7 6035.8 6088.6 5873.3 5937.6 5938.0 5943.9 5957.4 6113.0 6131.7 6134.4 6144.2 6154.7 5769.5 5863.8 5868.3 5875.9 5990.4 03:04:17 INFO - PROCESS | 1655 | 03:04:17 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:72894.61 Median:35218.71 stddev:163624.71 (464.6%) stddev-sans-first:163993.41 03:04:17 INFO - PROCESS | 1655 | Values: 486.4 499.5 526.1 530.8 532.6 25153.6 40396.2 41556.2 41618.0 41886.6 43018.8 43377.6 43401.0 43466.0 43551.0 9678.7 9865.5 9865.5 9872.6 9881.6 8741.1 11748.7 11898.2 11907.9 12233.6 12001.9 12082.2 12108.0 12118.6 12130.2 11964.7 12039.8 12043.1 12063.6 12071.3 11791.4 11866.0 11877.1 11880.0 11898.2 40698.0 41016.2 41039.0 41081.2 41102.0 3296.2 3379.8 3489.6 3845.8 3905.5 23316.7 30628.5 31131.7 31568.4 32097.5 2778.8 3272.6 3298.2 3370.2 3412.5 494149.0 499091.0 499308.0 501002.0 501167.0 57534.1 57785.0 57899.7 57918.0 57926.6 34113.7 34923.8 34956.0 36225.8 36343.0 16132.2 16281.3 16294.3 16303.3 16321.3 11254.9 11501.7 12340.8 12392.4 12431.6 17147.3 17242.1 18453.9 19512.4 19512.7 7956.0 8036.7 8039.4 8134.8 8177.6 942.2 948.4 992.9 995.6 1036.0 23688.4 30434.1 30557.0 30612.5 30637.5 1042.8 1087.1 1089.2 1114.2 1176.8 14789.8 15083.3 15639.0 15721.4 16041.1 684662.0 690787.0 691085.0 693247.0 694572.0 57121.0 57742.7 57774.8 57825.0 57849.0 1229.8 1347.3 1371.6 1384.5 1397.1 30370.5 30382.4 31205.5 31411.5 32463.9 48679.0 53921.2 54524.7 54640.7 58839.0 619332.0 626226.0 626946.0 629432.0 635413.0 26486.6 27000.1 27140.7 27355.7 42372.6 47041.1 47116.8 47216.5 47452.3 47730.0 39082.0 39127.0 39195.0 40389.0 40827.6 32820.5 35481.5 38310.0 38928.0 40713.0 37205.1 37584.0 38481.8 38532.8 38691.8 30524.4 33504.0 33800.0 34285.8 35850.0 25080.0 25193.7 25268.5 25452.7 25808.1 03:04:17 INFO - PROCESS | 1655 | 03:04:17 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:34243.93 Median:2248.06 stddev:97805.58 (4350.7%) stddev-sans-first:98046.83 03:04:17 INFO - PROCESS | 1655 | Values: 345.5 366.2 371.5 380.9 400.7 15395.8 16206.9 19557.5 20086.5 20247.5 20391.2 20467.3 21045.3 21106.9 21140.0 5341.4 5955.1 6221.7 6223.0 6241.2 4250.5 4494.0 6046.4 6226.7 6535.5 6808.7 6875.7 6880.4 6882.9 6926.0 6520.5 6743.7 6794.9 6816.0 6822.4 5762.1 5785.9 5853.6 5871.0 5887.6 20182.4 24761.8 25532.9 25963.0 26226.0 2243.4 2252.8 2382.3 2408.7 3016.8 13239.2 15532.8 15690.8 16287.3 16306.0 1850.9 1857.5 1883.7 1905.9 1956.0 118478.4 120630.0 122973.4 123979.0 125527.0 10330.9 10680.8 10743.7 10885.9 11055.7 13693.5 13726.2 13727.2 13744.5 13835.6 7129.0 8337.8 8605.4 8643.7 8765.9 8622.1 8625.6 8672.4 8815.2 8858.4 5461.8 6559.9 6672.4 6674.5 6674.9 1670.0 1733.3 1783.3 1799.7 1826.3 874.2 881.2 898.5 908.6 985.4 15349.9 16755.7 21470.8 22055.6 22271.4 949.5 970.7 980.7 1002.8 1012.9 11077.0 11790.6 11908.9 11998.0 12143.7 574545.0 578239.0 583910.0 599568.0 600383.0 113029.0 115790.0 116284.0 116915.4 118879.0 979.1 987.8 1008.7 1012.0 1016.6 11707.4 18047.8 20440.0 20551.8 20587.1 39759.6 47810.3 50746.0 51503.0 52039.0 67430.0 68889.0 69330.0 70153.6 70946.0 7629.9 7890.9 8054.0 8061.7 8413.2 10421.6 12788.5 13034.2 13098.3 13534.9 9847.5 10696.8 10711.8 11495.9 11520.4 10191.3 10816.4 10923.6 11278.3 11335.2 11041.6 11225.3 11273.9 11413.7 11850.7 6841.4 6950.3 7843.3 8128.3 8401.7 10618.5 10678.5 10887.5 11265.8 11269.4 03:04:17 INFO - PROCESS | 1655 | 03:04:17 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5746.61 Median:5012.42 stddev:3211.64 (64.1%) stddev-sans-first:3203.72 03:04:17 INFO - PROCESS | 1655 | Values: 1358.9 1492.5 1656.5 1844.7 1855.9 8428.0 8991.8 9015.6 9019.6 9050.1 7903.3 8118.0 8562.2 8732.0 8828.9 2379.7 2412.8 2413.2 2414.5 2417.7 2367.9 2385.5 2391.9 2413.2 2419.3 2402.8 2421.8 2423.9 2428.1 2430.5 2394.1 2401.7 2406.4 2408.9 2410.3 2386.7 2389.6 2393.0 2397.4 2401.4 8821.2 8842.9 8930.2 8960.9 8965.7 5244.7 5410.6 5421.5 5424.5 5429.8 3526.4 3582.6 3706.0 3709.5 3710.1 1051.3 1088.1 1092.6 1107.7 1107.8 11696.6 11810.9 11815.6 11848.1 11881.6 4538.4 4652.4 4794.5 4822.8 4836.3 9964.0 9987.6 9991.5 9992.1 9995.7 5370.5 5375.2 5379.6 5380.4 5383.7 4456.0 4633.1 4876.9 4944.5 4951.7 3877.3 3905.6 3984.8 4014.3 4035.9 1083.3 1297.8 1298.3 1307.5 1309.5 827.8 867.0 902.4 909.6 934.3 5571.3 5754.5 5928.6 6003.5 6054.0 857.4 884.8 898.6 915.2 925.7 3846.9 3907.7 3931.5 3980.9 4073.3 9102.5 9665.9 10915.2 10917.0 10917.7 10866.3 10871.5 10886.5 10898.1 10904.3 7766.5 8181.0 8183.4 8196.8 8343.9 5308.0 5886.8 5906.8 6094.3 6173.3 9777.9 9844.5 10130.3 10205.9 10213.3 10168.7 10361.4 10434.7 10489.7 10494.3 7058.9 7254.5 7354.6 7465.3 7569.7 8396.7 8489.2 8548.4 8550.7 8556.2 6244.3 7533.4 7582.1 7606.6 7673.5 7356.2 7404.6 7476.9 7509.1 7590.0 6901.2 7068.4 7076.4 7085.6 7090.0 4928.1 4929.1 5002.3 5027.7 5114.0 4901.7 4980.6 5022.5 5179.7 5182.1 03:04:17 INFO - PROCESS | 1655 | 03:04:17 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4253.25 Median:4602.49 stddev:1334.84 (29.0%) stddev-sans-first:1305.21 03:04:17 INFO - PROCESS | 1655 | Values: 301.4 305.2 309.3 314.4 318.2 5290.7 5342.8 5372.4 5373.6 5402.7 5229.8 5276.5 5326.0 5352.9 5366.7 3845.3 4035.1 4035.8 4042.8 4056.4 3330.0 3361.4 3999.9 4015.9 4028.5 3553.9 3773.7 3802.6 4002.4 4013.9 4026.4 4038.0 4049.6 4050.1 4051.9 2635.8 2943.9 3427.2 3485.7 3496.8 4621.7 4629.3 5124.5 5180.8 5233.8 3873.5 4651.6 4674.4 4711.4 4722.5 4484.6 5288.6 5508.1 5576.1 5648.9 3741.6 3751.3 3849.1 3915.2 3917.8 4364.4 5596.4 5710.6 5761.5 5944.5 3372.5 3395.6 3906.6 3937.8 3972.3 3984.9 4018.3 4018.6 4026.5 4054.8 4497.8 4600.5 4607.4 4631.4 4714.3 4604.5 4633.2 5586.5 5637.9 5650.4 1189.5 1351.7 1352.7 1353.6 1360.8 742.5 783.6 818.7 829.9 850.7 3507.6 4279.9 4288.4 4298.3 4318.5 4138.0 4212.7 4372.4 4783.1 4798.7 3386.2 3477.6 3832.0 4041.3 4092.9 1865.2 2059.3 2076.7 2193.0 2495.0 4789.8 4885.6 4970.3 4981.5 5021.3 4090.1 4539.8 4575.7 4816.3 4871.6 3851.1 4266.9 4370.6 4754.2 4967.0 4029.9 4115.7 4236.3 4288.7 4445.4 4671.5 4682.8 4703.8 4706.6 4720.8 4872.4 4998.3 5537.3 5788.5 6039.6 3606.9 3970.4 3976.6 3992.9 4011.4 5828.7 5831.7 5831.9 5844.4 5851.4 4453.6 4456.4 5314.3 5738.8 5782.9 4911.7 4932.5 5698.7 5706.4 5740.8 4824.2 5245.0 5479.5 5514.4 5670.7 4823.3 5666.6 5695.6 5714.9 5724.3 5142.5 5576.8 5627.3 5641.5 5697.0 03:04:17 INFO - PROCESS | 1655 | 03:04:17 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:24953.99 Median:3752.07 stddev:39630.97 (1056.2%) stddev-sans-first:39700.34 03:04:17 INFO - PROCESS | 1655 | Values: 711.6 756.4 776.6 813.2 818.2 14986.9 16277.0 16278.4 16289.9 16291.9 14885.6 15030.5 15053.6 15060.8 15065.3 676.2 694.5 840.4 841.5 863.4 784.7 832.2 841.7 844.7 845.3 901.0 901.7 902.1 902.7 906.8 898.5 898.6 898.7 898.7 899.3 528.1 543.9 544.1 546.2 665.7 14663.6 15280.6 15286.3 15299.6 15330.4 14338.3 14622.8 14894.6 15032.4 15057.6 11974.8 11984.8 12033.9 12151.5 12269.6 3816.6 3852.4 3869.5 3887.3 4011.8 120630.8 122295.4 123448.0 126187.0 126549.1 11343.8 11416.8 11464.9 11474.6 11916.3 23855.0 23897.0 23999.2 24004.8 24301.8 7987.5 7989.2 8001.2 8063.3 8074.1 4259.8 4351.5 4395.6 4410.8 4435.6 3624.6 3750.6 3751.6 3752.5 3838.6 714.6 715.2 716.6 722.2 731.3 565.1 606.0 627.9 653.3 660.6 8965.8 9361.6 9564.1 9634.4 9680.9 626.2 642.6 659.6 681.9 684.5 4175.7 4503.6 4698.9 4741.4 4744.3 98124.0 99756.0 153959.0 162796.2 167079.4 115420.4 126438.4 132594.0 139616.3 141374.3 100253.0 101977.0 112043.0 115306.0 116823.4 9485.3 9657.4 9754.5 9983.9 10084.0 40693.0 58466.0 61491.7 61992.7 62422.1 83808.2 88354.0 89111.0 90178.0 90555.9 13145.2 13151.9 13229.3 13297.1 13417.0 14823.9 15101.4 15191.1 15212.6 15256.9 13984.9 14069.4 14079.0 14283.3 14723.9 13975.3 14033.6 14125.9 14252.3 14326.5 13383.5 13609.1 13698.3 13749.9 13867.7 11279.8 11418.8 11945.2 12088.5 12102.4 9278.9 11194.3 11416.9 11576.1 11625.8 03:04:17 INFO - PROCESS | 1655 | -------- Summary: end -------- 03:04:17 INFO - PROCESS | 1655 | 03:04:18 INFO - TEST-INFO | 1655: exit 0 03:04:18 INFO - TEST-OK | dromaeo_css | took 1117299ms 03:04:18 INFO - TEST-START | kraken 03:04:18 INFO - operating with platform_type : mac_ 03:04:18 INFO - Initialising browser for kraken test... 03:04:18 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpyrZ7Qa/profile http://localhost:49272/getInfo.html) 03:04:20 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 03:04:20 INFO - PROCESS | 1662 | colorDepth:24 03:04:20 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 03:04:20 INFO - PROCESS | 1662 | __metrics 03:04:20 INFO - PROCESS | 1662 | 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" 03:04:20 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:04:21 INFO - TEST-INFO | 1662: exit 0 03:04:21 INFO - Browser initialized. 03:04:21 INFO - Running cycle 1/1 for kraken test... 03:04:21 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpyrZ7Qa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:04:33 INFO - PROCESS | 1663 | RSS: Main: 232079360 03:04:33 INFO - PROCESS | 1663 | 03:04:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 03:04:37 INFO - PROCESS | 1663 | RSS: Main: 291737600 03:04:37 INFO - PROCESS | 1663 | 03:04:39 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 03:04:40 INFO - PROCESS | 1663 | RSS: Main: 287854592 03:04:40 INFO - PROCESS | 1663 | 03:04:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 03:04:44 INFO - PROCESS | 1663 | RSS: Main: 379654144 03:04:44 INFO - PROCESS | 1663 | 03:04:46 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 03:04:47 INFO - PROCESS | 1663 | RSS: Main: 279986176 03:04:47 INFO - PROCESS | 1663 | 03:04:49 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:04:49 INFO - PROCESS | 1663 | RSS: Main: 284045312 03:04:49 INFO - PROCESS | 1663 | 03:04:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 03:04:53 INFO - PROCESS | 1663 | RSS: Main: 321298432 03:04:53 INFO - PROCESS | 1663 | 03:04:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 03:04:56 INFO - PROCESS | 1663 | RSS: Main: 321839104 03:04:56 INFO - PROCESS | 1663 | 03:04:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 03:05:00 INFO - PROCESS | 1663 | RSS: Main: 321196032 03:05:00 INFO - PROCESS | 1663 | 03:05:01 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:05:02 INFO - PROCESS | 1663 | RSS: Main: 296652800 03:05:02 INFO - PROCESS | 1663 | 03:05:04 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:05:04 INFO - PROCESS | 1663 | RSS: Main: 302407680 03:05:04 INFO - PROCESS | 1663 | 03:05:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:05:07 INFO - PROCESS | 1663 | RSS: Main: 300929024 03:05:07 INFO - PROCESS | 1663 | 03:05:10 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:05:11 INFO - PROCESS | 1663 | RSS: Main: 318537728 03:05:11 INFO - PROCESS | 1663 | 03:05:14 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:05:14 INFO - PROCESS | 1663 | RSS: Main: 314822656 03:05:14 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 03:05:16 INFO - PROCESS | 1663 | RSS: Main: 358440960 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | __start_tp_report 03:05:16 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 03:05:16 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 03:05:16 INFO - PROCESS | 1663 | |i|pagename|runs| 03:05:16 INFO - PROCESS | 1663 | |0;ai-astar;113;113;117;113;114;113;113;113;112;124 03:05:16 INFO - PROCESS | 1663 | |1;audio-beat-detection;144;112;128;160;106;129;139;107;159;108 03:05:16 INFO - PROCESS | 1663 | |2;audio-dft;183;186;184;183;184;183;179;184;263;201 03:05:16 INFO - PROCESS | 1663 | |3;audio-fft;78;73;75;153;58;60;60;83;165;76 03:05:16 INFO - PROCESS | 1663 | |4;audio-oscillator;82;82;131;72;70;92;118;68;70;119 03:05:16 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;90;87;89;87;90;86;90;87;90;86 03:05:16 INFO - PROCESS | 1663 | |6;imaging-darkroom;97;98;99;100;98;98;104;99;98;99 03:05:16 INFO - PROCESS | 1663 | |7;imaging-desaturate;86;83;84;85;85;84;84;85;85;84 03:05:16 INFO - PROCESS | 1663 | |8;json-parse-financial;54;55;55;54;54;54;52;55;76;59 03:05:16 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;68;65;74;76;72;69;69;69;69;71 03:05:16 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;86;86;85;85;85;87;86;85;85;85 03:05:16 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;139;140;137;137;124;156;150;154;178;132 03:05:16 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;162;174;169;166;179;232;171;169;168;166 03:05:16 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;68;66;67;68;67;67;67;68;66;67 03:05:16 INFO - PROCESS | 1663 | __end_tp_report 03:05:16 INFO - PROCESS | 1663 | __start_cc_report 03:05:16 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1963 03:05:16 INFO - PROCESS | 1663 | __end_cc_report 03:05:16 INFO - PROCESS | 1663 | __startTimestamp1473329116601__endTimestamp 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | ------- Summary: start ------- 03:05:16 INFO - PROCESS | 1663 | Number of tests: 14 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:114.50 Median:113.00 stddev:3.60 (3.2%) stddev-sans-first:3.77 03:05:16 INFO - PROCESS | 1663 | Values: 113.0 113.0 117.0 113.0 114.0 113.0 113.0 113.0 112.0 124.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:134.00 stddev:20.90 (15.6%) stddev-sans-first:21.47 03:05:16 INFO - PROCESS | 1663 | Values: 144.0 112.0 128.0 160.0 106.0 129.0 139.0 107.0 159.0 108.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:193.00 Median:184.00 stddev:25.28 (13.7%) stddev-sans-first:26.55 03:05:16 INFO - PROCESS | 1663 | Values: 183.0 186.0 184.0 183.0 184.0 183.0 179.0 184.0 263.0 201.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:88.10 Median:74.00 stddev:38.42 (51.9%) stddev-sans-first:40.58 03:05:16 INFO - PROCESS | 1663 | Values: 78.0 73.0 75.0 153.0 58.0 60.0 60.0 83.0 165.0 76.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:71.00 stddev:23.67 (33.3%) stddev-sans-first:24.91 03:05:16 INFO - PROCESS | 1663 | Values: 82.0 82.0 131.0 72.0 70.0 92.0 118.0 68.0 70.0 119.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:88.20 Median:89.50 stddev:1.75 (2.0%) stddev-sans-first:1.73 03:05:16 INFO - PROCESS | 1663 | Values: 90.0 87.0 89.0 87.0 90.0 86.0 90.0 87.0 90.0 86.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:99.00 Median:98.00 stddev:1.94 (2.0%) stddev-sans-first:1.92 03:05:16 INFO - PROCESS | 1663 | Values: 97.0 98.0 99.0 100.0 98.0 98.0 104.0 99.0 98.0 99.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:0.85 (1.0%) stddev-sans-first:0.71 03:05:16 INFO - PROCESS | 1663 | Values: 86.0 83.0 84.0 85.0 85.0 84.0 84.0 85.0 85.0 84.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:55.00 stddev:6.97 (12.7%) stddev-sans-first:7.32 03:05:16 INFO - PROCESS | 1663 | Values: 54.0 55.0 55.0 54.0 54.0 54.0 52.0 55.0 76.0 59.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.00 stddev:3.16 (4.5%) stddev-sans-first:3.24 03:05:16 INFO - PROCESS | 1663 | Values: 68.0 65.0 74.0 76.0 72.0 69.0 69.0 69.0 69.0 71.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:85.50 stddev:0.71 (0.8%) stddev-sans-first:0.73 03:05:16 INFO - PROCESS | 1663 | Values: 86.0 86.0 85.0 85.0 85.0 87.0 86.0 85.0 85.0 85.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:145.00 stddev:15.33 (10.6%) stddev-sans-first:16.12 03:05:16 INFO - PROCESS | 1663 | Values: 139.0 140.0 137.0 137.0 124.0 156.0 150.0 154.0 178.0 132.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:170.00 stddev:20.36 (12.0%) stddev-sans-first:20.99 03:05:16 INFO - PROCESS | 1663 | Values: 162.0 174.0 169.0 166.0 179.0 232.0 171.0 169.0 168.0 166.0 03:05:16 INFO - PROCESS | 1663 | 03:05:16 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 03:05:16 INFO - PROCESS | 1663 | Values: 68.0 66.0 67.0 68.0 67.0 67.0 67.0 68.0 66.0 67.0 03:05:16 INFO - PROCESS | 1663 | -------- Summary: end -------- 03:05:16 INFO - PROCESS | 1663 | 03:05:17 INFO - TEST-INFO | 1663: exit 0 03:05:17 INFO - TEST-OK | kraken | took 58936ms 03:05:17 INFO - SUITE-END | took 1176s 03:05:17 INFO - Completed test suite (00:19:36) 03:05:17 INFO - javascript benchmark 03:05:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3335.5830521184853, 3336.432806422907, 3346.5649465569472, 3389.57630296213, 3421.640727723589, 5599.020171469997, 5646.830595082144, 5689.658620482774, 5743.396943320949, 5746.0, 5670.0, 5720.570957178207, 5721.170430287616, 5730.484256416921, 5733.48398644122, 5157.948420515803, 5188.6367954243215, 5203.895922081556, 5206.895862082756, 5222.138347172712, 5204.219367094928, 5231.058409486291, 5253.553447956909, 5338.359396872381, 5347.919781203265, 5188.610854185932, 5206.140986737203, 5220.086484865134, 5262.789488420459, 5280.630355875091, 5061.417936937262, 5246.5802735781235, 5299.966506531228, 5341.946580534183, 5368.91946620801, 5135.64050516464, 5211.452797456251, 5221.373435187802, 5234.031704134747, 5249.527542521191, 5579.8605034874045, 5595.272614560122, 5605.859853503654, 5625.240592519998, 5646.983542962265, 6081.209442772455, 6126.914660831522, 6139.079138129272, 6152.507799376039, 6156.4766994805495, 6264.123022776815, 6318.936810631927, 6336.588121772116, 6341.778037768656, 6351.555391122577, 5618.185363122325, 5626.437356264381, 5741.942580574224, 5772.960867043972, 5789.305283366023, 6397.10440538325, 6397.4562162216735, 6411.4229719325485, 6413.262474815456, 6421.775237866652, 6118.1434599156155, 6127.84680382994, 6142.447179753844, 6147.200863887666, 6151.477124444383, 6080.3583032580955, 6143.634700640286, 6217.129601855744, 6292.465140463112, 6293.96853015732, 6178.135061091451, 6211.3167551569295, 6217.191765070603, 6219.689015549205, 6235.407636274546, 6170.660613666291, 6180.042093475518, 6241.375862413723, 6251.499880009588, 6260.467860231931, 4889.0, 4912.992836468587, 4914.189158788759, 4981.153203955337, 5001.89996200074, 4465.37484752135, 4472.888177795516, 4473.150101480708, 4476.753778542212, 4486.641068714495, 5670.347909990404, 5674.2623458950075, 5678.432156784371, 5684.232628595087, 5697.829065128053, 6166.81499555014, 6204.5967012144065, 6247.96876015617, 6260.655663938527, 6350.364963503613, 5681.0910254359105, 5726.455986681259, 5737.741801618937, 5751.626144300691, 5764.221830052889, 5314.176302673091, 5394.8921021579345, 5411.107167317348, 5420.83737487876, 5426.240326354314, 6311.211098612674, 6340.714667839866, 6344.302126766052, 6350.364963503613, 6354.809355719428, 6314.589551679126, 6319.494440444845, 6336.303006669171, 6344.841378965378, 6345.650989195638, 6285.0, 6339.461145802658, 6349.873002539831, 6355.523335749745, 6357.237131544244, 6140.749659041634, 6143.174139895698, 6155.169052178077, 6188.907166392418, 6196.442320191204, 6151.938480615316, 6179.28938172098, 6247.437730604267, 6252.093446450148, 6284.74861005573, 5926.970365148147, 6066.393360663898, 6091.695415229309, 6092.512598991982, 6092.6039807414145, 5872.941270587239, 6019.006863867413, 6022.728977196124, 6035.758569657341, 6088.573799833969, 5873.324567674772, 5937.614055086406, 5938.020226662552, 5943.910841337297, 5957.4042595740075, 6112.969435152973, 6131.724072416841, 6134.447899689049, 6144.170536977451, 6154.661493617894, 5769.451902069297, 5863.824085277449, 5868.295804503487, 5875.911861321998, 5990.400959903975], "value": 5709.233676135546, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.38472332499384, 499.4880247746058, 526.1029943945573, 530.7717681396998, 532.6138549551575, 25153.62269565958, 40396.19207615846, 41556.16887662245, 41618.0, 41886.581134188724, 43018.78490607542, 43377.566224337745, 43401.0, 43466.0, 43551.0, 9678.661246856343, 9865.457399843006, 9865.506724663774, 9872.60509579616, 9881.604735810559, 8741.082186370435, 11748.706282342968, 11898.22661526998, 11907.880921190765, 12233.63299101024, 12001.93999030008, 12082.214656047372, 12108.0, 12118.575849845258, 12130.150889437742, 11964.70088247792, 12039.819402708967, 12043.096767742454, 12063.577774777841, 12071.336076515789, 11791.410429478577, 11866.0, 11877.109216808687, 11880.049596032297, 11898.22661526998, 40698.0, 41016.179676406304, 41039.0, 41081.1783764326, 41102.0, 3296.175956010997, 3379.797212167278, 3489.616142224353, 3845.8461661533506, 3905.453236546886, 23316.65025024626, 30628.54057189144, 31131.68868311333, 31568.368632627447, 32097.51853722196, 2778.8054836161477, 3272.6236482804543, 3298.191942973987, 3370.2248482848836, 3412.4710669846213, 494148.9999999964, 499091.0, 499308.0, 501002.0, 501167.0, 57534.13698794522, 57785.0, 57899.710501447225, 57918.0, 57926.628070912164, 34113.658863411045, 34923.825380872935, 34955.951321460394, 36225.818870905474, 36343.0, 16132.193390330436, 16281.267342969359, 16294.266757995918, 16303.266353014144, 16321.265543050591, 11254.943725281484, 11501.654950351336, 12340.753184936251, 12392.380380980914, 12431.564895228805, 17147.314107435814, 17242.137893105293, 18453.907730461262, 19512.414627561197, 19512.70730939037, 7956.045274567089, 8036.6693658838285, 8039.397045221632, 8134.83730325402, 8177.568925438025, 942.1944237676825, 948.4356807699407, 992.9304948653669, 995.5520015992832, 1036.0005189983253, 23688.40778980505, 30434.086977390714, 30557.0, 30612.519766345387, 30637.540436893465, 1042.7810159866513, 1087.141158484804, 1089.2320913755784, 1114.1922106472905, 1176.8293597428326, 14789.778153327708, 15083.321250543751, 15638.98346607467, 15721.371145154295, 16041.117738524494, 684662.0, 690787.0, 691085.0, 693247.0, 694572.0, 57121.0, 57742.7112864433, 57774.844503110544, 57825.0, 57849.0, 1229.8339724137127, 1347.3465369295884, 1371.6433727230792, 1384.5292600515872, 1397.105852254558, 30370.54444183295, 30382.392352153274, 31205.531917021715, 31411.528827067155, 32463.86376476812, 48679.0, 53921.19118213309, 54524.72737636286, 54640.726796365765, 58839.0, 619332.0, 626226.0, 626946.0, 629432.0, 635413.0, 26486.602700959116, 27000.054998074975, 27140.728592713822, 27355.72644273532, 42372.57627423686, 47041.05917881692, 47116.764416179074, 47216.527834721215, 47452.288215676104, 47730.0, 39082.0, 39127.0, 39195.0, 40389.0, 40827.59172408357, 32820.507692384155, 35481.46777798387, 38310.0, 38928.0, 40713.0, 37205.069873253386, 37584.0, 38481.807590961864, 38532.80733596314, 38691.80654096712, 30524.38951220919, 33504.0, 33800.0, 34285.828570857986, 35850.0, 25079.996800127792, 25193.682659178237, 25268.494630107663, 25452.74547254578, 25808.09671661483], "value": 20353.38721296355, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.4783277251353, 366.18341099348453, 371.5133175540043, 380.8819266027531, 400.67945643485206, 15395.846041539595, 16206.865519413646, 19557.511062223453, 20086.49783755403, 20247.493812654673, 20391.184352625914, 20467.283645072424, 21045.26341578045, 21106.89446552765, 21140.0, 5341.412444631087, 5955.136505206753, 6221.720022598971, 6223.0, 6241.188645476082, 4250.511191213019, 4493.9775301123445, 6046.365131661156, 6226.719797609118, 6535.477161827066, 6808.693608787592, 6875.6562171891455, 6880.380765731084, 6882.9311706883045, 6925.965370173142, 6520.510961677869, 6743.662816859162, 6794.864102717967, 6815.977603359486, 6822.4200942919915, 5762.07806750922, 5785.942140578582, 5853.5902486825935, 5870.970645146747, 5887.61730487517, 20182.39452816418, 24761.752382476305, 25532.87233563839, 25963.0, 26226.0, 2243.371855880356, 2252.7521972583004, 2382.344855164826, 2408.674828898111, 3016.8039077459894, 13239.153581979825, 15532.844671553254, 15690.843091569051, 16287.348506059743, 16306.0, 1850.9167087481096, 1857.544901499128, 1883.6703576873992, 1905.8951757653324, 1956.0120354719309, 118478.4076079614, 120630.0, 122973.38513307465, 123979.0, 125527.0, 10330.896691033144, 10680.839787403194, 10743.677689669323, 10885.891141088647, 11055.72360690989, 13693.520726774515, 13726.245056521788, 13727.245001525012, 13744.518941837188, 13835.584932452042, 7128.964355178295, 8337.83324333522, 8605.397622166389, 8643.654253829902, 8765.912340876637, 8622.136946876184, 8625.611847466878, 8672.43629164115, 8815.162559556831, 8858.379913406128, 5461.808836690697, 6559.934400656028, 6672.398624330126, 6674.532782705164, 6674.933250667528, 1669.983300167007, 1733.3326669232436, 1783.3223375117632, 1799.7480352750363, 1826.2512369928265, 874.1608056266056, 881.2377293641083, 898.4878619187132, 908.5502676175336, 985.3989066669326, 15349.9232503839, 16755.664886702198, 21470.78529214719, 22055.558888822135, 22271.44321391978, 949.4588084791602, 970.713639476342, 980.6812785844629, 1002.7693630464888, 1012.9240306977006, 11076.956801163895, 11790.587329443428, 11908.940455297668, 11998.040156787436, 12143.696407589705, 574545.0, 578239.0, 583910.0, 599568.0, 600383.0, 113029.0, 115790.0, 116284.0, 116915.41542292404, 118879.0, 979.0943377375899, 987.7678745494803, 1008.6973907827622, 1012.0233974214918, 1016.5832008876325, 11707.433980916907, 18047.819521804613, 20440.0, 20551.79448205499, 20587.07358168856, 39759.60240397674, 47810.28284575664, 50745.98621568821, 51503.0, 52038.95922081613, 67429.97710068706, 68889.0, 69329.96005059827, 70153.64923175555, 70946.0, 7629.885551716618, 7890.881636775568, 8054.0, 8061.717839875576, 8413.200745929127, 10421.63524276677, 12788.46538415396, 13034.217946922836, 13098.279594622387, 13534.86465135336, 9847.45838978863, 10696.83954740664, 10711.839322410015, 11495.942520287344, 11520.423978801195, 10191.337563058378, 10816.351018938889, 10923.617673381394, 11278.266912650652, 11335.206535542433, 11041.613543525938, 11225.326480411202, 11273.887261127284, 11413.714657133638, 11850.703732406759, 6841.350071743176, 6950.33971772701, 7843.333316668147, 8128.268455839003, 8401.663933442594, 10618.469076545987, 10678.49055471979, 10887.510062047386, 11265.774684506428, 11269.436528173723], "value": 9691.786547565986, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.93205339733, 1492.5373134328356, 1656.4947690954261, 1844.714069319256, 1855.9072046397685, 8428.0, 8991.820163596723, 9015.594298256578, 9019.639214431423, 9050.140236677513, 7903.288704016637, 8118.0, 8562.229399354057, 8732.0, 8828.911710882889, 2379.7144342678857, 2412.7828495435497, 2413.2036427978815, 2414.4688168602966, 2417.6615273861676, 2367.893444794988, 2385.4632707640817, 2391.9043238270447, 2413.227767114524, 2419.322589674894, 2402.843815152018, 2421.794147497465, 2423.9394015149587, 2428.138011006094, 2430.453148041694, 2394.0543485323374, 2401.687780588521, 2406.3743426709007, 2408.89159987801, 2410.2648692148887, 2386.6658667786523, 2389.569877422063, 2392.9760702392928, 2397.436602398435, 2401.435662619283, 8821.16198961104, 8842.867356989651, 8930.19628233462, 8960.8655870162, 8965.686200983, 5244.737763111867, 5410.621256512046, 5421.457854214586, 5424.511793938565, 5429.782808687687, 3526.418141006731, 3582.5700915889997, 3706.0179052551093, 3709.4992176056426, 3710.109573702319, 1051.2588625019418, 1088.0860077534908, 1092.5738961804902, 1107.6510899066768, 1107.8061339265596, 11696.649100526913, 11810.940945295304, 11815.645530634094, 11848.0521558276, 11881.584144554985, 4538.410006699108, 4652.371929789435, 4794.472608013116, 4822.7829747661435, 4836.322914791932, 9964.003599640124, 9987.550560224807, 9991.500424978722, 9992.050755178247, 9995.650152244636, 5370.543503802221, 5375.16684913839, 5379.569634429236, 5380.354357477127, 5383.730813459311, 4455.997400584907, 4633.0733853229485, 4876.878078048077, 4944.505549445027, 4951.678140920829, 3877.3408520551575, 3905.6484916357094, 3984.820683069269, 4014.277430062617, 4035.93946090803, 1083.285031878956, 1297.7534268488957, 1298.337847697681, 1307.45740517686, 1309.5482058689736, 827.8096037911193, 866.9783255418539, 902.3683421604771, 909.5628906327986, 934.3226161033332, 5571.331440227118, 5754.4533269339345, 5928.644281343133, 6003.549733769984, 6053.969730151409, 857.4340934982881, 884.8451520983803, 898.5507246376811, 915.190056799737, 925.6667599664115, 3846.9038274042814, 3907.7264591478893, 3931.5085614298214, 3980.920381592352, 4073.327900896319, 9102.499362534993, 9665.855012174823, 10915.181361397768, 10917.0, 10917.727056823484, 10866.293690910066, 10871.45642717861, 10886.510107045202, 10898.073663738513, 10904.291221070605, 7766.495177813424, 8180.9590952044855, 8183.386246031571, 8196.83606327882, 8343.874841877378, 5308.0, 5886.793962211302, 5906.793262235801, 6094.268687757321, 6173.290071641639, 9777.85333219988, 9844.507774611384, 10130.290879638354, 10205.897941020792, 10213.285070045025, 10168.74578135523, 10361.4301213434, 10434.739131521772, 10489.737756556147, 10494.317869338469, 7058.858822823618, 7254.455915806441, 7354.558726476428, 7465.29079737424, 7569.697212111454, 8396.664133434595, 8489.235968762841, 8548.401611887357, 8550.74347769568, 8556.229939305493, 6244.2819075806865, 7533.434992375484, 7582.052243469567, 7606.619669016416, 7673.462857599914, 7356.227596102546, 7404.592747398729, 7476.876790404101, 7509.098908131059, 7590.0, 6901.24086350491, 7068.399186069241, 7076.433885289268, 7085.610291434021, 7089.964550177217, 4928.137575924127, 4929.137400954747, 5002.349694539686, 5027.748612569284, 5114.0, 4901.681390709592, 4980.613183035571, 5022.4977502249485, 5179.663321884065, 5182.144946083854], "value": 4620.145599249149, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.394197662698, 305.1668943783471, 309.2824646819379, 314.39165215308367, 318.1854452601339, 5290.68255904646, 5342.786288548458, 5372.435894231108, 5373.596980226479, 5402.729863506824, 5229.790808367661, 5276.4698237511075, 5325.973370133144, 5352.9197062044, 5366.719144363649, 3845.2693988142164, 4035.051762835731, 4035.7982100894983, 4042.7776472294013, 4056.4320995060716, 3329.9843547717937, 3361.445361515347, 3999.860004899829, 4015.939760903574, 4028.45615841862, 3553.9466907996402, 3773.6792372648356, 3802.6007269236748, 4002.359622460407, 4013.876114687892, 4026.3960405939197, 4038.0, 4049.635532802062, 4050.1494686115952, 4051.9189616207805, 2635.774364920321, 2943.882244710209, 3427.2288735034667, 3485.6514348565192, 3496.7552271341015, 4621.676482646216, 4629.305604159369, 5124.487551244845, 5180.792768289264, 5233.790648374061, 3873.5158105236846, 4651.5813576778255, 4674.368960190365, 4711.387519622427, 4722.504137065627, 4484.641228701728, 5288.603354748371, 5508.14623733322, 5576.124593407583, 5648.943510564883, 3741.605372561563, 3751.268502641986, 3849.073944576516, 3915.2169566086613, 3917.764934103963, 4364.363363738598, 5596.412376700469, 5710.602191695934, 5761.539076873882, 5944.464998150187, 3372.5278461015478, 3395.609504906918, 3906.628870257321, 3937.783421911765, 3972.324704800191, 3984.86052988144, 4018.2767101921936, 4018.557958624549, 4026.456428382131, 4054.8378064877666, 4497.8183339642255, 4600.493945665974, 4607.387414404999, 4631.374764406829, 4714.339992401066, 4604.493505714368, 4633.212353899845, 5586.497215250647, 5637.915431268534, 5650.434956504317, 1189.5487505243589, 1351.6823546466574, 1352.7362164378048, 1353.587155917435, 1360.836699596035, 742.5173637135905, 783.5964478293683, 818.6643476174739, 829.8921140251729, 850.7192626433263, 3507.649235076472, 4279.850205242801, 4288.442502474721, 4298.32365377503, 4318.52496225415, 4137.97931010349, 4212.684048696298, 4372.40972468719, 4783.06730187617, 4798.664093513421, 3386.153461634591, 3477.6004556855687, 3831.976424754053, 4041.3125271693993, 4092.9181416371507, 1865.2072869030608, 2059.331941127691, 2076.719642848226, 2192.9561408772056, 2494.975050249511, 4789.760511974388, 4885.55876016576, 4970.304157417964, 4981.530448517696, 5021.271915572222, 4090.1410703752545, 4539.843574302246, 4575.725456472623, 4816.253480710496, 4871.634627402959, 3851.1129546522, 4266.850660226877, 4370.606645401929, 4754.168020596382, 4966.950330496721, 4029.9096417137216, 4115.71190016696, 4236.279832428556, 4288.670512141246, 4445.377227435828, 4671.509491503343, 4682.836100736458, 4703.835365762182, 4706.599939005086, 4720.811167553259, 4872.416464649074, 4998.3252260945765, 5537.307836520435, 5788.53691704671, 6039.63762174271, 3606.9098272542346, 3970.3845903884944, 3976.602339766116, 3992.9201415972097, 4011.4183443401125, 5828.737706803289, 5831.650100993954, 5831.879944229817, 5844.392791982239, 5851.385604511465, 4453.643708503377, 4456.420665313489, 5314.28257185275, 5738.799142030009, 5782.855428614318, 4911.71605203582, 4932.506749325039, 5698.6580805151825, 5706.372299047184, 5740.770369185186, 4824.235039128208, 5245.0, 5479.452054794648, 5514.365847927379, 5670.744816483186, 4823.3325842134645, 5666.603337766317, 5695.582650409721, 5714.885702285847, 5724.341700704305, 5142.51146141116, 5576.832695019156, 5627.268455100811, 5641.50500117461, 5697.006047883207], "value": 3836.351981703194, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.5979471598547, 756.4477931110289, 776.562646842061, 813.1705660226571, 818.2431251092738, 14986.925065374671, 16277.0, 16278.430254941079, 16289.918550407247, 16291.918540407296, 14885.627859303495, 15030.549083527512, 15053.62365940852, 15060.774088388656, 15065.322060507277, 676.2189670930084, 694.5311914457747, 840.357126798, 841.4698739793937, 863.4430792139067, 784.7175016993878, 832.2426591801432, 841.7011960753936, 844.7212419901418, 845.3152946113637, 900.9819803603924, 901.7339884733987, 902.0618556701023, 902.6975963052386, 906.7869050773063, 898.5327629632587, 898.5911410308294, 898.6944438890808, 898.7124120281512, 899.2805755395657, 528.1101344234976, 543.8667526456044, 544.1484077418827, 546.2243614067993, 665.7037618259876, 14663.56009319722, 15280.617984550365, 15286.312115954808, 15299.61750956235, 15330.386784528606, 14338.283085845771, 14622.780658290134, 14894.627634309121, 15032.398704051824, 15057.623559410993, 11974.760504789943, 11984.760304793943, 12033.93983030088, 12151.513939442411, 12269.57056503018, 3816.637419445148, 3852.383618621035, 3869.496965394481, 3887.3197190491833, 4011.7793521356316, 120630.79369206283, 122295.3885230577, 123448.0, 126187.0, 126549.1017634727, 11343.829842552368, 11416.828747568794, 11464.885351146382, 11474.598389056342, 11916.344601047025, 23855.045798168227, 23897.044118235423, 23999.16002939889, 24004.7599524006, 24301.756982430303, 7987.520748755093, 7989.241022103007, 8001.199880011952, 8063.27430531255, 8074.071481779553, 4259.765712885759, 4351.477822661301, 4395.626371758372, 4410.82368114286, 4435.600795928317, 3624.619414961391, 3750.643688849555, 3751.5873253942045, 3752.5496940366784, 3838.596947320491, 714.589111261027, 715.2239819795476, 716.6309350684402, 722.2344315025985, 731.3235257386896, 565.1099518258759, 605.9666718330446, 627.9183706118175, 653.3335997282745, 660.5739298152664, 8965.775855603662, 9361.578728957213, 9564.091411315914, 9634.421934683942, 9680.854787178198, 626.1984659635674, 642.5502148496072, 659.5976454362835, 681.9454443644496, 684.5345165287553, 4175.68682348825, 4503.617192538671, 4698.88252793676, 4741.383620129361, 4744.312074749211, 98124.0, 99756.0, 153959.0, 162796.18601906914, 167079.44427502374, 115420.42289788666, 126438.36780816037, 132594.0, 139616.30191849178, 141374.2931285337, 100253.0, 101977.0, 112043.0, 115306.0, 116823.41588292005, 9485.288603354915, 9657.420554766737, 9754.53713931544, 9983.900160998297, 10084.0, 40693.0, 58466.0, 61491.692541538796, 61992.69003654953, 62422.06366904591, 83808.16191838004, 88354.0, 89110.97001904919, 90178.0, 90555.92498337722, 13145.21128732279, 13151.934240328737, 13229.3385330735, 13297.069205155547, 13416.993725470433, 14823.925880370529, 15101.39594416211, 15191.088534687955, 15212.619684507976, 15256.923715381352, 13984.860151398763, 14069.437222510986, 14079.0, 14283.28583570838, 14723.926380368459, 13975.301234938415, 14033.649158771112, 14125.858741412454, 14252.28738563047, 14326.49857254991, 13383.464661413434, 13609.11540749848, 13698.315084245947, 13749.862501375259, 13867.653308667364, 11279.774404512027, 11418.828717569077, 11945.223560468541, 12088.516459341528, 12102.394880256128, 9278.907210928075, 11194.344327719817, 11416.94291528537, 11576.073914087021, 11625.767484650429], "value": 7927.511647645444, "unit": "score"}], "name": "dromaeo_css", "value": 7354.670273216714, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 117.0, 113.0, 114.0, 113.0, 113.0, 113.0, 112.0, 124.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 112.0, 128.0, 160.0, 106.0, 129.0, 139.0, 107.0, 159.0, 108.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 184.0, 183.0, 184.0, 183.0, 179.0, 184.0, 263.0, 201.0], "value": 193.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 75.0, 153.0, 58.0, 60.0, 60.0, 83.0, 165.0, 76.0], "value": 88.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 131.0, 72.0, 70.0, 92.0, 118.0, 68.0, 70.0, 119.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 87.0, 90.0, 86.0, 90.0, 87.0, 90.0, 86.0], "value": 88.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 99.0, 100.0, 98.0, 98.0, 104.0, 99.0, 98.0, 99.0], "value": 99.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 84.0, 85.0, 85.0, 84.0, 84.0, 85.0, 85.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 54.0, 54.0, 54.0, 52.0, 55.0, 76.0, 59.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 74.0, 76.0, 72.0, 69.0, 69.0, 69.0, 69.0, 71.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 85.0, 85.0, 87.0, 86.0, 85.0, 85.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 137.0, 137.0, 124.0, 156.0, 150.0, 154.0, 178.0, 132.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 174.0, 169.0, 166.0, 179.0, 232.0, 171.0, 169.0, 168.0, 166.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 68.0, 67.0, 67.0, 67.0, 68.0, 66.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1486.8, "alertThreshold": 2.0}]} 03:05:17 INFO - Return code: 0 03:05:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:05:17 INFO - # TBPL SUCCESS # 03:05:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:05:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:05:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:05:17 INFO - 2016-09-08 03:05:17.928 screenresolution[1667:10418] starting screenresolution argv=screenresolution get 03:05:17 INFO - 2016-09-08 03:05:17.931 screenresolution[1667:10418] Display 0: 1600x1200x32@60 03:05:17 INFO - 2016-09-08 03:05:17.937 screenresolution[1668:10421] starting screenresolution argv=screenresolution list 03:05:17 INFO - Available Modes on Display 0 03:05:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:05:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:05:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:05:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:05:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:05:18 INFO - Intel Iris: 03:05:18 INFO - Chipset Model: Intel Iris 03:05:18 INFO - Type: GPU 03:05:18 INFO - Bus: Built-In 03:05:18 INFO - VRAM (Dynamic, Max): 1536 MB 03:05:18 INFO - Vendor: Intel (0x8086) 03:05:18 INFO - Device ID: 0x0a2e 03:05:18 INFO - Revision ID: 0x0009 03:05:18 INFO - Displays: 03:05:18 INFO - Display: 03:05:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:05:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:05:18 INFO - Display Serial Number: 1600x1200 60 03:05:18 INFO - Main Display: Yes 03:05:18 INFO - Mirror: Off 03:05:18 INFO - Online: Yes 03:05:18 INFO - Rotation: Supported 03:05:18 INFO - Return code: 0 03:05:18 INFO - Running post-action listener: _resource_record_post_action 03:05:18 INFO - [mozharness: 2016-09-08 10:05:18.174321Z] Finished run-tests step (success) 03:05:18 INFO - Running post-run listener: _resource_record_post_run 03:05:18 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31931392; Write bytes: 704901120; Read time: 195; Write time: 2016 03:05:18 INFO - TinderboxPrint: CPU usage
53.5% 03:05:18 INFO - TinderboxPrint: I/O read bytes / time
31,931,392 / 195 03:05:18 INFO - TinderboxPrint: I/O write bytes / time
704,901,120 / 2,016 03:05:18 INFO - TinderboxPrint: CPU idle
2,254.1 (46.5%) 03:05:18 INFO - TinderboxPrint: CPU system
965.2 (19.9%) 03:05:18 INFO - TinderboxPrint: CPU user
1,626.3 (33.6%) 03:05:18 INFO - TinderboxPrint: Swap in / out
433,651,712 / 0 03:05:18 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394172928; Write bytes: 388027904; Read time: 29327; Write time: 839 03:05:18 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 30989312; Write bytes: 296966656; Read time: 183; Write time: 1125 03:05:18 INFO - Running post-run listener: _upload_blobber_files 03:05:18 INFO - Blob upload gear active. 03:05:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:05:18 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 03:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:05:19 INFO - (blobuploader) - INFO - Open directory for files ... 03:05:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:05:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:05:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:20 INFO - (blobuploader) - INFO - Done attempting. 03:05:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:05:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:05:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:22 INFO - (blobuploader) - INFO - Done attempting. 03:05:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:05:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:05:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:24 INFO - (blobuploader) - INFO - Done attempting. 03:05:24 INFO - (blobuploader) - INFO - Iteration through files over. 03:05:25 INFO - Return code: 0 03:05:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:05:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:05:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42dad4fd66dee6c7e5a5459f515cba548f6eae52e89dbdaa4ea4a482236fdbc76ea1702403e9bae3713eb4026c80a590e4c81a774e4a42daa8bacc7ca7efd7d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af628225293e5d9d308d27f4fd5d08dcd72433d7107efdb151f732398cf1ae61ace8a7ff72a639e8cf659bfbcfd5996271fb1a7580dc9eb7ed5e7cb1a508c206", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50de5be7e967e5945790f7adae91dc8a23f0e4d397030a60fd433767d5d84613e9cc28790342d38c82acc9ea180f6891dc168ddc36792fe3eb0924bafe55371a"} 03:05:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:05:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:05:25 INFO - Contents: 03:05:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42dad4fd66dee6c7e5a5459f515cba548f6eae52e89dbdaa4ea4a482236fdbc76ea1702403e9bae3713eb4026c80a590e4c81a774e4a42daa8bacc7ca7efd7d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af628225293e5d9d308d27f4fd5d08dcd72433d7107efdb151f732398cf1ae61ace8a7ff72a639e8cf659bfbcfd5996271fb1a7580dc9eb7ed5e7cb1a508c206", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50de5be7e967e5945790f7adae91dc8a23f0e4d397030a60fd433767d5d84613e9cc28790342d38c82acc9ea180f6891dc168ddc36792fe3eb0924bafe55371a"} 03:05:25 INFO - Running post-run listener: copy_logs_to_upload_dir 03:05:25 INFO - Copying logs to upload dir... 03:05:25 INFO - mkdir: /builds/slave/test/build/upload/logs 03:05:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.744580 ========= master_lag: 1.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-08 03:05:26.370617) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 03:05:26.375898) ========= 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.EoKGqXcuXF/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.cG3VavnMNG/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42dad4fd66dee6c7e5a5459f515cba548f6eae52e89dbdaa4ea4a482236fdbc76ea1702403e9bae3713eb4026c80a590e4c81a774e4a42daa8bacc7ca7efd7d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af628225293e5d9d308d27f4fd5d08dcd72433d7107efdb151f732398cf1ae61ace8a7ff72a639e8cf659bfbcfd5996271fb1a7580dc9eb7ed5e7cb1a508c206", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50de5be7e967e5945790f7adae91dc8a23f0e4d397030a60fd433767d5d84613e9cc28790342d38c82acc9ea180f6891dc168ddc36792fe3eb0924bafe55371a"} build_url:https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011306 build_url: 'https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42dad4fd66dee6c7e5a5459f515cba548f6eae52e89dbdaa4ea4a482236fdbc76ea1702403e9bae3713eb4026c80a590e4c81a774e4a42daa8bacc7ca7efd7d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af628225293e5d9d308d27f4fd5d08dcd72433d7107efdb151f732398cf1ae61ace8a7ff72a639e8cf659bfbcfd5996271fb1a7580dc9eb7ed5e7cb1a508c206", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50de5be7e967e5945790f7adae91dc8a23f0e4d397030a60fd433767d5d84613e9cc28790342d38c82acc9ea180f6891dc168ddc36792fe3eb0924bafe55371a"}' ========= master_lag: 0.12 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 03:05:26.509131) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 14 secs) (at 2016-09-08 03:05:26.509430) ========= 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.EoKGqXcuXF/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.cG3VavnMNG/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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.005332 ========= master_lag: 14.66 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 14 secs) (at 2016-09-08 03:05:41.171010) ========= ========= Started reboot skipped (results: 3, elapsed: 2 secs) (at 2016-09-08 03:05:41.171306) ========= ========= Finished reboot skipped (results: 3, elapsed: 2 secs) (at 2016-09-08 03:05:44.005853) ========= ========= Total master_lag: 16.21 =========