builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0037 starttime: 1473302550.99 results: success (0) buildid: 20160907175716 builduid: 0aad801a4896498f96ec88398c84536c revision: 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 19:42:30.986886) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 19:42:30.987324) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 19:42:30.987600) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 19:42:31.012750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 19:42:31.013076) ========= 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.sja40nuIFO/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.28YETixZAV/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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-07 19:42:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.1M=0.001s 2016-09-07 19:42:31 (15.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142415 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 19:42:31.178455) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 19:42:31.178764) ========= 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.sja40nuIFO/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.28YETixZAV/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.028723 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 19:42:31.228329) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-07 19:42:31.228674) ========= 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.sja40nuIFO/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.28YETixZAV/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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-07 19:42:31,297 truncating revision to first 12 chars 2016-09-07 19:42:31,297 Setting DEBUG logging. 2016-09-07 19:42:31,297 attempt 1/10 2016-09-07 19:42:31,297 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-07 19:42:33,499 unpacking tar archive at: mozilla-central-77940cbf0c2a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.451549 ========= master_lag: 0.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-07 19:42:33.907509) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 19:42:33.907837) ========= script_repo_revision: 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 19:42:33.908287) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 19:42:33.908556) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 19:42:33.927483) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 43 secs) (at 2016-09-07 19:42:33.928101) ========= /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.sja40nuIFO/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.28YETixZAV/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 19:42:34 INFO - MultiFileLogger online at 20160907 19:42:34 in /builds/slave/test 19:42:34 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 19:42:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:42:34 INFO - {'append_to_log': False, 19:42:34 INFO - 'base_work_dir': '/builds/slave/test', 19:42:34 INFO - 'blob_upload_branch': 'Firefox', 19:42:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:42:34 INFO - 'branch': 'Firefox', 19:42:34 INFO - 'buildbot_json_path': 'buildprops.json', 19:42:34 INFO - 'config_files': ('talos/mac_config.py',), 19:42:34 INFO - 'default_actions': ('clobber', 19:42:34 INFO - 'read-buildbot-config', 19:42:34 INFO - 'download-and-extract', 19:42:34 INFO - 'populate-webroot', 19:42:34 INFO - 'create-virtualenv', 19:42:34 INFO - 'install', 19:42:34 INFO - 'run-tests'), 19:42:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:42:34 INFO - 'download_minidump_stackwalk': True, 19:42:34 INFO - 'download_symbols': 'ondemand', 19:42:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:42:34 INFO - 'tooltool.py': '/tools/tooltool.py', 19:42:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:42:34 INFO - '/tools/misc-python/virtualenv.py')}, 19:42:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:42:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:42:34 INFO - 'installer_path': 'installer.exe', 19:42:34 INFO - 'log_level': 'info', 19:42:34 INFO - 'log_name': 'talos', 19:42:34 INFO - 'log_to_console': True, 19:42:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:42:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:42:34 INFO - 'opt_config_files': (), 19:42:34 INFO - 'pip_index': False, 19:42:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:42:34 INFO - 'cmd': ('bash', 19:42:34 INFO - '-c', 19:42:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:42:34 INFO - 'enabled': True, 19:42:34 INFO - 'halt_on_failure': False, 19:42:34 INFO - 'name': 'check_screen_resolution'},), 19:42:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:42:34 INFO - 'cmd': ('bash', 19:42:34 INFO - '-c', 19:42:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:42:34 INFO - 'enabled': True, 19:42:34 INFO - 'halt_on_failure': False, 19:42:34 INFO - 'name': 'check_screen_resolution'},), 19:42:34 INFO - 'run_cmd_checks_enabled': True, 19:42:34 INFO - 'sps_profile': False, 19:42:34 INFO - 'sps_profile_interval': 0, 19:42:34 INFO - 'suite': 'dromaeojs', 19:42:34 INFO - 'system_bits': '32', 19:42:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:42:34 INFO - 'title': 't-yosemite-r7-0037', 19:42:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:42:34 INFO - 'use_talos_json': True, 19:42:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:42:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:42:34 INFO - 'work_dir': 'build'} 19:42:34 INFO - [mozharness: 2016-09-08 02:42:34.117722Z] Running clobber step. 19:42:34 INFO - Running pre-action listener: _resource_record_pre_action 19:42:34 INFO - Running main action method: clobber 19:42:34 INFO - rmtree: /builds/slave/test/build 19:42:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:42:36 INFO - Running post-action listener: _resource_record_post_action 19:42:36 INFO - [mozharness: 2016-09-08 02:42:36.473404Z] Finished clobber step (success) 19:42:36 INFO - [mozharness: 2016-09-08 02:42:36.473595Z] Running read-buildbot-config step. 19:42:36 INFO - Running pre-action listener: _resource_record_pre_action 19:42:36 INFO - Running main action method: read_buildbot_config 19:42:36 INFO - Using buildbot properties: 19:42:36 INFO - { 19:42:36 INFO - "project": "", 19:42:36 INFO - "product": "firefox", 19:42:36 INFO - "script_repo_revision": "production", 19:42:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:42:36 INFO - "repository": "", 19:42:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:42:36 INFO - "buildid": "20160907175716", 19:42:36 INFO - "pgo_build": "False", 19:42:36 INFO - "basedir": "/builds/slave/test", 19:42:36 INFO - "buildnumber": 38, 19:42:36 INFO - "slavename": "t-yosemite-r7-0037", 19:42:36 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:42:36 INFO - "platform": "yosemite_r7", 19:42:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:42:36 INFO - "branch": "mozilla-central", 19:42:36 INFO - "repo_path": "mozilla-central", 19:42:36 INFO - "revision": "77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501", 19:42:36 INFO - "stage_platform": "macosx64", 19:42:36 INFO - "builduid": "0aad801a4896498f96ec88398c84536c", 19:42:36 INFO - "slavebuilddir": "test" 19:42:36 INFO - } 19:42:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:42:36 INFO - Running post-action listener: _resource_record_post_action 19:42:36 INFO - [mozharness: 2016-09-08 02:42:36.476835Z] Finished read-buildbot-config step (success) 19:42:36 INFO - [mozharness: 2016-09-08 02:42:36.476940Z] Running download-and-extract step. 19:42:36 INFO - Running pre-action listener: _resource_record_pre_action 19:42:36 INFO - Running main action method: download_and_extract 19:42:36 INFO - mkdir: /builds/slave/test/build/tests 19:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:42:36 INFO - trying https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:42:36 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 19:42:36 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 19:42:37 INFO - Downloaded 1199 bytes. 19:42:37 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:42:37 INFO - Using the following test package requirements: 19:42:37 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:42:37 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:42:37 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:42:37 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:42:37 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:42:37 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:42:37 INFO - u'jsshell-mac.zip'], 19:42:37 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:42:37 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:42:37 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:42:37 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:42:37 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:42:37 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:42:37 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:42:37 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:42:37 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:42:37 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:42:37 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:42:37 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:42:38 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 19:42:38 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 19:42:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:42:46 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 19:42:46 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 19:42:53 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 19:42:53 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 19:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:42:54 INFO - trying https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:42:54 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 19:42:54 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 19:42:57 INFO - Downloaded 111456518 bytes. 19:42:57 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 19:42:57 INFO - mkdir: /builds/slave/test/properties 19:42:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:42:57 INFO - Writing to file /builds/slave/test/properties/build_url 19:42:57 INFO - Contents: 19:42:57 INFO - build_url:https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:42:58 INFO - Running post-action listener: _resource_record_post_action 19:42:58 INFO - Running post-action listener: set_extra_try_arguments 19:42:58 INFO - [mozharness: 2016-09-08 02:42:58.075453Z] Finished download-and-extract step (success) 19:42:58 INFO - [mozharness: 2016-09-08 02:42:58.075593Z] Running populate-webroot step. 19:42:58 INFO - Running pre-action listener: _resource_record_pre_action 19:42:58 INFO - Running main action method: populate_webroot 19:42:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:42:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:42:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:42:58 INFO - '--tppagecycles', 19:42:58 INFO - '7'], 19:42:58 INFO - 'tests': ['tsvgm']}}, 19:42:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:42:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:42:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:42:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:42:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:42:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:42:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:42:58 INFO - 'talos_options': ['--disable-e10s'], 19:42:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:42:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:42:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:42:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:42:58 INFO - 'talos_options': ['--disable-e10s'], 19:42:58 INFO - 'tests': ['damp', 'tps']}, 19:42:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:42:58 INFO - 'tests': ['damp', 'tps']}, 19:42:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:42:58 INFO - 'tests': ['dromaeo_dom']}, 19:42:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:42:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:42:58 INFO - 'tests': ['basic_compositor_video']}, 19:42:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:42:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:42:58 INFO - 'tests': ['a11yr', 19:42:58 INFO - 'ts_paint', 19:42:58 INFO - 'tpaint', 19:42:58 INFO - 'sessionrestore', 19:42:58 INFO - 'sessionrestore_no_auto_restore', 19:42:58 INFO - 'tabpaint']}, 19:42:58 INFO - 'other-e10s': {'tests': ['a11yr', 19:42:58 INFO - 'ts_paint', 19:42:58 INFO - 'tpaint', 19:42:58 INFO - 'sessionrestore', 19:42:58 INFO - 'sessionrestore_no_auto_restore', 19:42:58 INFO - 'tabpaint']}, 19:42:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:42:58 INFO - 'ts_paint', 19:42:58 INFO - 'tpaint', 19:42:58 INFO - 'sessionrestore', 19:42:58 INFO - 'sessionrestore_no_auto_restore', 19:42:58 INFO - 'tabpaint']}, 19:42:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:42:58 INFO - 'ts_paint', 19:42:58 INFO - 'tpaint', 19:42:58 INFO - 'sessionrestore', 19:42:58 INFO - 'sessionrestore_no_auto_restore', 19:42:58 INFO - 'tabpaint']}, 19:42:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:42:58 INFO - 'tests': ['a11yr', 19:42:58 INFO - 'ts_paint', 19:42:58 INFO - 'tpaint', 19:42:58 INFO - 'sessionrestore', 19:42:58 INFO - 'sessionrestore_no_auto_restore', 19:42:58 INFO - 'tabpaint']}, 19:42:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:42:58 INFO - 'tests': ['a11yr', 19:42:58 INFO - 'ts_paint', 19:42:58 INFO - 'tpaint', 19:42:58 INFO - 'sessionrestore', 19:42:58 INFO - 'sessionrestore_no_auto_restore', 19:42:58 INFO - 'tabpaint']}, 19:42:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:42:58 INFO - 'tests': ['tsvgx', 19:42:58 INFO - 'tsvgr_opacity', 19:42:58 INFO - 'tart', 19:42:58 INFO - 'tscrollx', 19:42:58 INFO - 'cart']}, 19:42:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:42:58 INFO - 'tsvgr_opacity', 19:42:58 INFO - 'tart', 19:42:58 INFO - 'tscrollx', 19:42:58 INFO - 'cart']}, 19:42:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:42:58 INFO - 'talos_options': ['--disable-e10s'], 19:42:58 INFO - 'tests': ['tp5o']}, 19:42:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:42:58 INFO - 'tests': ['tp5o']}, 19:42:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:42:58 INFO - 'talos_options': ['--disable-e10s', 19:42:58 INFO - '--xperf_path', 19:42:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:42:58 INFO - 'tests': ['tp5n']}, 19:42:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:42:58 INFO - 'talos_options': ['--xperf_path', 19:42:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:42:58 INFO - 'tests': ['tp5n']}}, 19:42:58 INFO - 'talos.zip': {'path': '', 19:42:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:42:58 INFO - Running post-action listener: _resource_record_post_action 19:42:58 INFO - [mozharness: 2016-09-08 02:42:58.094223Z] Finished populate-webroot step (success) 19:42:58 INFO - [mozharness: 2016-09-08 02:42:58.094340Z] Running create-virtualenv step. 19:42:58 INFO - Running pre-action listener: _resource_record_pre_action 19:42:58 INFO - Running main action method: create_virtualenv 19:42:58 INFO - Creating virtualenv /builds/slave/test/build/venv 19:42:58 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 19:42:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:42:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:42:58 INFO - Using real prefix '/tools/python27' 19:42:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:42:58 INFO - Installing distribute.............................................................................................................................................................................................done. 19:43:01 INFO - Installing pip.................done. 19:43:01 INFO - Return code: 0 19:43:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10251ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b1d6a910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10247fd30>, '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 0x1021e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102477b20>, '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.sja40nuIFO/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.28YETixZAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 19:43:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:43:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:43:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sja40nuIFO/Render', 19:43:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:43:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:43:01 INFO - 'HOME': '/Users/cltbld', 19:43:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:43:01 INFO - 'LOGNAME': 'cltbld', 19:43:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:01 INFO - 'MOZ_NO_REMOTE': '1', 19:43:01 INFO - 'NO_EM_RESTART': '1', 19:43:01 INFO - 'PAGER': '/bin/cat', 19:43:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:43:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:01 INFO - 'PWD': '/builds/slave/test', 19:43:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:43:01 INFO - 'SHELL': '/bin/bash', 19:43:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.28YETixZAV/Listeners', 19:43:01 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 19:43:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:43:01 INFO - 'USER': 'cltbld', 19:43:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:43:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:43:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:01 INFO - 'XPC_FLAGS': '0x0', 19:43:01 INFO - 'XPC_SERVICE_NAME': '0', 19:43:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:43:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:02 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 19:43:02 INFO - Cleaning up... 19:43:02 INFO - Return code: 0 19:43:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:43:02 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')]} 19:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:02 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')]} 19:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:02 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 0x10251ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b1d6a910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10247fd30>, '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 0x1021e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102477b20>, '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.sja40nuIFO/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.28YETixZAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 19:43:02 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 19:43:02 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 19:43:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sja40nuIFO/Render', 19:43:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:43:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:43:02 INFO - 'HOME': '/Users/cltbld', 19:43:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:43:02 INFO - 'LOGNAME': 'cltbld', 19:43:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:02 INFO - 'MOZ_NO_REMOTE': '1', 19:43:02 INFO - 'NO_EM_RESTART': '1', 19:43:02 INFO - 'PAGER': '/bin/cat', 19:43:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:43:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:02 INFO - 'PWD': '/builds/slave/test', 19:43:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:43:02 INFO - 'SHELL': '/bin/bash', 19:43:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.28YETixZAV/Listeners', 19:43:02 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 19:43:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:43:02 INFO - 'USER': 'cltbld', 19:43:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:43:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:43:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:02 INFO - 'XPC_FLAGS': '0x0', 19:43:02 INFO - 'XPC_SERVICE_NAME': '0', 19:43:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:43:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:02 INFO - Downloading/unpacking psutil>=3.1.1 19:43: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 19:43: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 19:43: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 19:43: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 19:43: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 19:43: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 19:43:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:43:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:43:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:43:05 INFO - Installing collected packages: psutil 19:43:05 INFO - Running setup.py install for psutil 19:43:05 INFO - building 'psutil._psutil_osx' extension 19:43:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:43:06 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 19:43:06 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 19:43:06 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 19:43:06 INFO - building 'psutil._psutil_posix' extension 19:43:06 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 19:43:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:43:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:43:06 INFO - ^ 19:43:06 INFO - 1 warning generated. 19:43:06 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 19:43:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:43:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:43:06 INFO - Successfully installed psutil 19:43:06 INFO - Cleaning up... 19:43:06 INFO - Return code: 0 19:43:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:43:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10251ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b1d6a910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10247fd30>, '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 0x1021e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102477b20>, '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.sja40nuIFO/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.28YETixZAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 19:43:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:43:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sja40nuIFO/Render', 19:43:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:43:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:43:06 INFO - 'HOME': '/Users/cltbld', 19:43:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:43:06 INFO - 'LOGNAME': 'cltbld', 19:43:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:06 INFO - 'MOZ_NO_REMOTE': '1', 19:43:06 INFO - 'NO_EM_RESTART': '1', 19:43:06 INFO - 'PAGER': '/bin/cat', 19:43:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:43:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:06 INFO - 'PWD': '/builds/slave/test', 19:43:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:43:06 INFO - 'SHELL': '/bin/bash', 19:43:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.28YETixZAV/Listeners', 19:43:06 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 19:43:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:43:06 INFO - 'USER': 'cltbld', 19:43:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:43:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:43:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:06 INFO - 'XPC_FLAGS': '0x0', 19:43:06 INFO - 'XPC_SERVICE_NAME': '0', 19:43:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:43:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:43:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:43:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:43:09 INFO - Installing collected packages: mozsystemmonitor 19:43:09 INFO - Running setup.py install for mozsystemmonitor 19:43:09 INFO - Successfully installed mozsystemmonitor 19:43:09 INFO - Cleaning up... 19:43:09 INFO - Return code: 0 19:43:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10251ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b1d6a910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10247fd30>, '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 0x1021e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102477b20>, '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.sja40nuIFO/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.28YETixZAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 19:43:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:43:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:43:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sja40nuIFO/Render', 19:43:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:43:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:43:09 INFO - 'HOME': '/Users/cltbld', 19:43:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:43:09 INFO - 'LOGNAME': 'cltbld', 19:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:09 INFO - 'MOZ_NO_REMOTE': '1', 19:43:09 INFO - 'NO_EM_RESTART': '1', 19:43:09 INFO - 'PAGER': '/bin/cat', 19:43:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:09 INFO - 'PWD': '/builds/slave/test', 19:43:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:43:09 INFO - 'SHELL': '/bin/bash', 19:43:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.28YETixZAV/Listeners', 19:43:09 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 19:43:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:43:09 INFO - 'USER': 'cltbld', 19:43:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:43:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:09 INFO - 'XPC_FLAGS': '0x0', 19:43:09 INFO - 'XPC_SERVICE_NAME': '0', 19:43:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:09 INFO - Downloading/unpacking jsonschema==2.5.1 19:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:43:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:12 INFO - Downloading functools32-3.2.3-2.tar.gz 19:43:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:43:12 INFO - warning: no files found matching '*.txt' 19:43:12 INFO - no previously-included directories found matching 'build' 19:43:12 INFO - no previously-included directories found matching 'dist' 19:43:12 INFO - no previously-included directories found matching '.git*' 19:43:12 INFO - Installing collected packages: jsonschema, functools32 19:43:13 INFO - Running setup.py install for functools32 19:43:13 INFO - warning: no files found matching '*.txt' 19:43:13 INFO - no previously-included directories found matching 'build' 19:43:13 INFO - no previously-included directories found matching 'dist' 19:43:13 INFO - no previously-included directories found matching '.git*' 19:43:13 INFO - Successfully installed jsonschema functools32 19:43:13 INFO - Cleaning up... 19:43:13 INFO - Return code: 0 19:43:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:13 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 0x10251ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b1d6a910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10247fd30>, '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 0x1021e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102477b20>, '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.sja40nuIFO/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.28YETixZAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 19:43:13 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 19:43:13 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 19:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sja40nuIFO/Render', 19:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:43:13 INFO - 'HOME': '/Users/cltbld', 19:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:43:13 INFO - 'LOGNAME': 'cltbld', 19:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:13 INFO - 'MOZ_NO_REMOTE': '1', 19:43:13 INFO - 'NO_EM_RESTART': '1', 19:43:13 INFO - 'PAGER': '/bin/cat', 19:43:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:13 INFO - 'PWD': '/builds/slave/test', 19:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:43:13 INFO - 'SHELL': '/bin/bash', 19:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.28YETixZAV/Listeners', 19:43:13 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 19:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:43:13 INFO - 'USER': 'cltbld', 19:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:13 INFO - 'XPC_FLAGS': '0x0', 19:43:13 INFO - 'XPC_SERVICE_NAME': '0', 19:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:43:13 INFO - Cleaning up... 19:43:13 INFO - Return code: 0 19:43:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:13 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 0x10251ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b1d6a910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10247fd30>, '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 0x1021e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102477b20>, '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.sja40nuIFO/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.28YETixZAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 19:43:13 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 19:43:13 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 19:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sja40nuIFO/Render', 19:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:43:13 INFO - 'HOME': '/Users/cltbld', 19:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:43:13 INFO - 'LOGNAME': 'cltbld', 19:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:13 INFO - 'MOZ_NO_REMOTE': '1', 19:43:13 INFO - 'NO_EM_RESTART': '1', 19:43:13 INFO - 'PAGER': '/bin/cat', 19:43:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:13 INFO - 'PWD': '/builds/slave/test', 19:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:43:13 INFO - 'SHELL': '/bin/bash', 19:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.28YETixZAV/Listeners', 19:43:13 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 19:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:43:13 INFO - 'USER': 'cltbld', 19:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:13 INFO - 'XPC_FLAGS': '0x0', 19:43:13 INFO - 'XPC_SERVICE_NAME': '0', 19:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:13 INFO - Downloading/unpacking blobuploader==1.2.4 19:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:16 INFO - Downloading blobuploader-1.2.4.tar.gz 19:43:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:43:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:43:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:17 INFO - Downloading docopt-0.6.1.tar.gz 19:43:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:43:17 INFO - Installing collected packages: blobuploader, requests, docopt 19:43:17 INFO - Running setup.py install for blobuploader 19:43:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:43:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:43:17 INFO - Running setup.py install for requests 19:43:18 INFO - Running setup.py install for docopt 19:43:18 INFO - Successfully installed blobuploader requests docopt 19:43:18 INFO - Cleaning up... 19:43:18 INFO - Return code: 0 19:43:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:43:18 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')]} 19:43:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:18 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')]} 19:43:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:18 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 0x10251ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b1d6a910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10247fd30>, '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 0x1021e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102477b20>, '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.sja40nuIFO/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.28YETixZAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 19:43:18 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 19:43:18 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 19:43:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sja40nuIFO/Render', 19:43:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:43:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:43:18 INFO - 'HOME': '/Users/cltbld', 19:43:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:43:18 INFO - 'LOGNAME': 'cltbld', 19:43:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:18 INFO - 'MOZ_NO_REMOTE': '1', 19:43:18 INFO - 'NO_EM_RESTART': '1', 19:43:18 INFO - 'PAGER': '/bin/cat', 19:43:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:43:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:18 INFO - 'PWD': '/builds/slave/test', 19:43:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:43:18 INFO - 'SHELL': '/bin/bash', 19:43:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.28YETixZAV/Listeners', 19:43:18 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 19:43:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:43:18 INFO - 'USER': 'cltbld', 19:43:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:43:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:43:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:18 INFO - 'XPC_FLAGS': '0x0', 19:43:18 INFO - 'XPC_SERVICE_NAME': '0', 19:43:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:43:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:43:18 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-6bxp2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:43:18 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-xCDUpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:43:18 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-agrLeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:43:18 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-jwwf_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:43:19 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-HxwEXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:43:19 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-ZkDWb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:43:19 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-gRuPZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:43:19 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-TyN_ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:43:19 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-xUPDbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:43:19 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-5JhwY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:43:19 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-h9Vzfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:43:19 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-cdJA8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:43:19 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-HJZOGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:43:19 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-KUFiyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:43:20 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-kODZws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:43:20 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-RC1nl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:43:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:43:20 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Oubnw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:43:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:43:20 INFO - Running setup.py install for manifestparser 19:43:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:43:20 INFO - Running setup.py install for mozcrash 19:43:20 INFO - Running setup.py install for mozdebug 19:43:20 INFO - Running setup.py install for mozdevice 19:43:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:43:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:43:20 INFO - Running setup.py install for mozfile 19:43:21 INFO - Running setup.py install for mozhttpd 19:43:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:43:21 INFO - Running setup.py install for mozinfo 19:43:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:43:21 INFO - Running setup.py install for mozInstall 19:43:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:43:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:43:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:43:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:43:21 INFO - Running setup.py install for mozleak 19:43:21 INFO - Running setup.py install for mozlog 19:43:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:43:21 INFO - Running setup.py install for moznetwork 19:43:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:43:21 INFO - Running setup.py install for mozprocess 19:43:22 INFO - Running setup.py install for mozprofile 19:43:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:43:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:43:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:43:22 INFO - Running setup.py install for mozrunner 19:43:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:43:22 INFO - Running setup.py install for mozscreenshot 19:43:22 INFO - Running setup.py install for moztest 19:43:22 INFO - Running setup.py install for mozversion 19:43:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:43:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:43:22 INFO - Cleaning up... 19:43:22 INFO - Return code: 0 19:43:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:43:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10251ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b1d6a910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10247fd30>, '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 0x1021e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102477b20>, '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.sja40nuIFO/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.28YETixZAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 19:43:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:43:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:43:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sja40nuIFO/Render', 19:43:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:43:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:43:22 INFO - 'HOME': '/Users/cltbld', 19:43:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:43:22 INFO - 'LOGNAME': 'cltbld', 19:43:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:22 INFO - 'MOZ_NO_REMOTE': '1', 19:43:22 INFO - 'NO_EM_RESTART': '1', 19:43:22 INFO - 'PAGER': '/bin/cat', 19:43:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:43:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:22 INFO - 'PWD': '/builds/slave/test', 19:43:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:43:22 INFO - 'SHELL': '/bin/bash', 19:43:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.28YETixZAV/Listeners', 19:43:22 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 19:43:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:43:22 INFO - 'USER': 'cltbld', 19:43:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:43:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:43:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:22 INFO - 'XPC_FLAGS': '0x0', 19:43:22 INFO - 'XPC_SERVICE_NAME': '0', 19:43:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:43:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:43:23 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-vl7PiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:43:23 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)) 19:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:43:23 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-hZSdBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:43:23 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)) 19:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:43:23 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-gASmyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:43:23 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)) 19:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:43:23 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-FRAHA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:43:23 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)) 19:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:43:23 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-CXzn6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:43:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:43:23 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-RJhAHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:43:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:43:23 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-eTWRTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:43:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:43:23 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-nz4poZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:43:24 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)) 19:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:43:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-H1wbhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:43:24 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)) 19:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:43:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-6rW7Js-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:43:24 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)) 19:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:43:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Fh5poG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:43:24 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)) 19:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:43:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-fRiFGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:43:24 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)) 19:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:43:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-nqS5nC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:43:24 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)) 19:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:43:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-YUEZG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:43:24 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)) 19:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:43:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-WpNbGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:43:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:43:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-FcfZ3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:43:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:43:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-m7wLse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:43:25 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)) 19:43:25 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)) 19:43:25 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)) 19:43:25 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)) 19:43:25 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)) 19:43:25 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)) 19:43:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:43:25 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 19:43:25 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 19:43:25 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 19:43:25 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 19:43:25 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 19:43:25 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 19:43:27 INFO - Downloading blessings-1.6.tar.gz 19:43:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:43:27 INFO - Installing collected packages: blessings 19:43:27 INFO - Running setup.py install for blessings 19:43:27 INFO - Successfully installed blessings 19:43:27 INFO - Cleaning up... 19:43:27 INFO - Return code: 0 19:43:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:43:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:43:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:43:28 INFO - Reading from file tmpfile_stdout 19:43:28 INFO - Current package versions: 19:43:28 INFO - blessings == 1.6 19:43:28 INFO - blobuploader == 1.2.4 19:43:28 INFO - docopt == 0.6.1 19:43:28 INFO - functools32 == 3.2.3-2 19:43:28 INFO - jsonschema == 2.5.1 19:43:28 INFO - manifestparser == 1.1 19:43:28 INFO - mozInstall == 1.12 19:43:28 INFO - mozcrash == 0.17 19:43:28 INFO - mozdebug == 0.1 19:43:28 INFO - mozdevice == 0.48 19:43:28 INFO - mozfile == 1.2 19:43:28 INFO - mozhttpd == 0.7 19:43:28 INFO - mozinfo == 0.9 19:43:28 INFO - mozleak == 0.1 19:43:28 INFO - mozlog == 3.3 19:43:28 INFO - moznetwork == 0.27 19:43:28 INFO - mozprocess == 0.23 19:43:28 INFO - mozprofile == 0.28 19:43:28 INFO - mozrunner == 6.12 19:43:28 INFO - mozscreenshot == 0.1 19:43:28 INFO - mozsystemmonitor == 0.3 19:43:28 INFO - moztest == 0.7 19:43:28 INFO - mozversion == 1.4 19:43:28 INFO - psutil == 3.1.1 19:43:28 INFO - requests == 1.2.3 19:43:28 INFO - wsgiref == 0.1.2 19:43:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:43:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:28 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 0x10251ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b1d6a910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10247fd30>, '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 0x1021e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102477b20>, '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.sja40nuIFO/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.28YETixZAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 19:43:28 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 19:43:28 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 19:43:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sja40nuIFO/Render', 19:43:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:43:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:43:28 INFO - 'HOME': '/Users/cltbld', 19:43:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:43:28 INFO - 'LOGNAME': 'cltbld', 19:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:28 INFO - 'MOZ_NO_REMOTE': '1', 19:43:28 INFO - 'NO_EM_RESTART': '1', 19:43:28 INFO - 'PAGER': '/bin/cat', 19:43:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:28 INFO - 'PWD': '/builds/slave/test', 19:43:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:43:28 INFO - 'SHELL': '/bin/bash', 19:43:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.28YETixZAV/Listeners', 19:43:28 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 19:43:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:43:28 INFO - 'USER': 'cltbld', 19:43:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:43:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:28 INFO - 'XPC_FLAGS': '0x0', 19:43:28 INFO - 'XPC_SERVICE_NAME': '0', 19:43:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:43:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:28 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)) 19:43:28 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)) 19:43:28 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)) 19:43:28 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)) 19:43:28 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)) 19:43:28 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)) 19:43:28 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)) 19:43:28 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)) 19:43:28 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)) 19:43:28 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)) 19:43:28 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)) 19:43:28 INFO - Cleaning up... 19:43:28 INFO - Return code: 0 19:43:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:43:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10251ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b1d6a910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10247fd30>, '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 0x1021e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102477b20>, '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.sja40nuIFO/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.28YETixZAV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 19:43:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:43:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:43:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sja40nuIFO/Render', 19:43:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:43:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:43:28 INFO - 'HOME': '/Users/cltbld', 19:43:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:43:28 INFO - 'LOGNAME': 'cltbld', 19:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:28 INFO - 'MOZ_NO_REMOTE': '1', 19:43:28 INFO - 'NO_EM_RESTART': '1', 19:43:28 INFO - 'PAGER': '/bin/cat', 19:43:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:28 INFO - 'PWD': '/builds/slave/test', 19:43:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:43:28 INFO - 'SHELL': '/bin/bash', 19:43:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.28YETixZAV/Listeners', 19:43:28 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 19:43:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:43:28 INFO - 'USER': 'cltbld', 19:43:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:43:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:28 INFO - 'XPC_FLAGS': '0x0', 19:43:28 INFO - 'XPC_SERVICE_NAME': '0', 19:43:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:43:29 INFO - Cleaning up... 19:43:29 INFO - Return code: 0 19:43:29 INFO - Running post-action listener: _resource_record_post_action 19:43:29 INFO - Running post-action listener: _start_resource_monitoring 19:43:29 INFO - Starting resource monitoring. 19:43:29 INFO - [mozharness: 2016-09-08 02:43:29.092814Z] Finished create-virtualenv step (success) 19:43:29 INFO - [mozharness: 2016-09-08 02:43:29.093848Z] Running install step. 19:43:29 INFO - Running pre-action listener: _resource_record_pre_action 19:43:29 INFO - Running main action method: install 19:43:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:43:29 INFO - Reading from file tmpfile_stdout 19:43:29 INFO - Detecting whether we're running mozinstall >=1.0... 19:43:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:43:29 INFO - Reading from file tmpfile_stdout 19:43:29 INFO - Output received: 19:43:29 INFO - Usage: mozinstall [options] installer 19:43:29 INFO - Options: 19:43:29 INFO - -h, --help show this help message and exit 19:43:29 INFO - -d DEST, --destination=DEST 19:43:29 INFO - Directory to install application into. [default: 19:43:29 INFO - "/builds/slave/test"] 19:43:29 INFO - --app=APP Application being installed. [default: firefox] 19:43:29 INFO - mkdir: /builds/slave/test/build/application 19:43:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:44:03 INFO - Reading from file tmpfile_stdout 19:44:03 INFO - Output received: 19:44:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:44:03 INFO - Running post-action listener: _resource_record_post_action 19:44:03 INFO - [mozharness: 2016-09-08 02:44:03.963658Z] Finished install step (success) 19:44:03 INFO - [mozharness: 2016-09-08 02:44:03.963797Z] Running run-tests step. 19:44:03 INFO - Running pre-action listener: _resource_record_pre_action 19:44:03 INFO - Running main action method: run_tests 19:44:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:44:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:44:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:44:03 INFO - 2016-09-07 19:44:03.978 screenresolution[1638:7528] starting screenresolution argv=screenresolution get 19:44:03 INFO - 2016-09-07 19:44:03.980 screenresolution[1638:7528] Display 0: 1600x1200x32@60 19:44:03 INFO - 2016-09-07 19:44:03.986 screenresolution[1639:7530] starting screenresolution argv=screenresolution list 19:44:03 INFO - Available Modes on Display 0 19:44:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:44:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:44:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:44:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:44:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:44:04 INFO - Intel Iris: 19:44:04 INFO - Chipset Model: Intel Iris 19:44:04 INFO - Type: GPU 19:44:04 INFO - Bus: Built-In 19:44:04 INFO - VRAM (Dynamic, Max): 1536 MB 19:44:04 INFO - Vendor: Intel (0x8086) 19:44:04 INFO - Device ID: 0x0a2e 19:44:04 INFO - Revision ID: 0x0009 19:44:04 INFO - Displays: 19:44:04 INFO - Display: 19:44:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:44:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:44:04 INFO - Display Serial Number: 1600x1200 60 19:44:04 INFO - Main Display: Yes 19:44:04 INFO - Mirror: Off 19:44:04 INFO - Online: Yes 19:44:04 INFO - Rotation: Supported 19:44:04 INFO - Return code: 0 19:44:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:44:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:44:04 INFO - Python 2.7.3 19:44:04 INFO - Return code: 0 19:44:04 INFO - grabbing minidump binary from tooltool 19:44:04 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')]} 19:44:04 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 0x10247fd30>, '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 0x1021e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102477b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:44:04 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 19:44:04 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 19:44:04 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 19:44:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:44:04 INFO - Return code: 0 19:44:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:44:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:44:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:44:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:44:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:44:04 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-0037', '--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 19:44:04 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-0037 --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 19:44:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sja40nuIFO/Render', 19:44:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:44:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:44:04 INFO - 'HOME': '/Users/cltbld', 19:44:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:44:04 INFO - 'LOGNAME': 'cltbld', 19:44:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:44:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:44:04 INFO - 'MOZ_NO_REMOTE': '1', 19:44:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:44:04 INFO - 'NO_EM_RESTART': '1', 19:44:04 INFO - 'PAGER': '/bin/cat', 19:44:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:44:04 INFO - 'PWD': '/builds/slave/test', 19:44:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:44:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:44:04 INFO - 'SHELL': '/bin/bash', 19:44:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.28YETixZAV/Listeners', 19:44:04 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 19:44:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:44:04 INFO - 'USER': 'cltbld', 19:44:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:44:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:44:04 INFO - 'XPC_FLAGS': '0x0', 19:44:04 INFO - 'XPC_SERVICE_NAME': '0', 19:44:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:44:04 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-0037', '--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 19:44:04 INFO - mozversion application_buildid: 20160907175716 19:44:04 INFO - mozversion application_changeset: 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 19:44:04 INFO - mozversion application_display_name: Nightly 19:44:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:44:04 INFO - mozversion application_name: Firefox 19:44:04 INFO - mozversion application_remotingname: firefox 19:44:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:44:04 INFO - mozversion application_vendor: Mozilla 19:44:04 INFO - mozversion application_version: 51.0a1 19:44:04 INFO - mozversion platform_buildid: 20160907175716 19:44:04 INFO - mozversion platform_changeset: 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 19:44:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:44:04 INFO - mozversion platform_version: 51.0a1 19:44:04 INFO - using testdate: 1473302644 19:44:04 INFO - actual date: 1473302644 19:44:04 INFO - starting webserver on 'localhost:49271' 19:44:04 INFO - SUITE-START | Running 2 tests 19:44:04 INFO - TEST-START | dromaeo_css 19:44:04 INFO - operating with platform_type : mac_ 19:44:04 INFO - Initialising browser for dromaeo_css test... 19:44:04 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpdx8PQs/profile http://localhost:49271/getInfo.html) 19:44:07 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 19:44:07 INFO - PROCESS | 1649 | colorDepth:24 19:44:07 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 19:44:07 INFO - PROCESS | 1649 | __metrics 19:44:07 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:44:07 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:44:08 INFO - TEST-INFO | 1649: exit 0 19:44:08 INFO - Browser initialized. 19:44:08 INFO - Running cycle 1/1 for dromaeo_css test... 19:44:08 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpdx8PQs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:44:20 INFO - PROCESS | 1652 | RSS: Main: 232251392 19:44:20 INFO - PROCESS | 1652 | 19:45:11 INFO - PROCESS | 1652 | 1473302711146 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] 19:47:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 19:47:24 INFO - PROCESS | 1652 | RSS: Main: 376623104 19:47:24 INFO - PROCESS | 1652 | 19:50:27 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 19:50:27 INFO - PROCESS | 1652 | RSS: Main: 366030848 19:50:27 INFO - PROCESS | 1652 | 19:53:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 19:53:30 INFO - PROCESS | 1652 | RSS: Main: 360488960 19:53:30 INFO - PROCESS | 1652 | 19:56:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 19:56:33 INFO - PROCESS | 1652 | RSS: Main: 348823552 19:56:33 INFO - PROCESS | 1652 | 19:59:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 19:59:37 INFO - PROCESS | 1652 | RSS: Main: 329367552 19:59:37 INFO - PROCESS | 1652 | 20:02:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 20:02:41 INFO - PROCESS | 1652 | RSS: Main: 515170304 20:02:41 INFO - PROCESS | 1652 | 20:02:41 INFO - PROCESS | 1652 | __start_tp_report 20:02:41 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 20:02:41 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 20:02:41 INFO - PROCESS | 1652 | |i|pagename|runs| 20:02:41 INFO - PROCESS | 1652 | |0;dojo.html;2959.8372089535073;3031.8180909145467;3336.56624638797;3353.0946644405994;3417.9233541434446;5613.943860561399;5618.831435056944;5681.062624666925;5757.654540727554;5765.913511297329;5682.687452190138;5687.061634830258;5722.256106706132;5747.568932330071;5751.338596061454;4315.568443155675;5067.442581316071;5103.311053007834;5220.3996540397775;5321.760520776575;5238.47615238475;5283.313169288006;5294.126469132589;5306.628536002481;5308.867278318035;5180.352455942988;5186.455422180655;5234.528892399702;5248.160294352904;5293.602979776532;5175.792968281265;5183.611229157828;5219;5262.894742105136;5269.499397557226;5182.015417070782;5186.818461353834;5203.167493201107;5250.894982100374;5304.151335786256;5584.301962254718;5762.567807414461;5765.365809760923;5770.394108618577;5772.740226689809;5782.479576838105;6112.837854638568;6185.381461853779;6198.4731297839735;6266.49868010558;6260.968695156541;6270.153529273535;6280.748770049238;6300.842478938017;6313.87372252557;4888.899997500534;5743.702518992436;5848.239728835224;5861.296644402699;5869.031609784338;6234.438900498886;6258.280297765815;6259.499240060784;6284.402981716729;6368.267649220306;6228.532860035515;6247.781327653511;6248.4064013919515;6250.218722659668;6256.43692067707;5913.49735272498;6090.847728806815;6226.968865155645;6267.655278959701;6304.148939893147;5380.784768609212;6096.375503402309;6145.924463218918;6185.257769067741;6190.969045154746;6282.371762823773;6288.962321216948;6301.0863424804065;6310.747570097236;6313.715882785285;5026.723530205873;5034.219695947134;5042.117629414838;5042.420121686053;5075.416327122354;4406.317020861771;4434.866953991321;4512.210363186429;4522.186006518858;4564.360989461478;5637.830865074055;5651.943480565142;5669.886602267931;5725.5133313667975;5785.363610002896;6248.562600618004;6294.496440284858;6295.779647712308;6301.684915754286;6306.054091886253;4818.469968303484;4825.4933232010835;5747.597668163272;5750.597458177971;5770.932377510174;5249.632525723239;5280.3663560373;5376.247325374451;5436.157395603688;5450.073487507133;6201.224846894138;6203.844903877349;6209.689515524296;6309.463695585927;6315.747370105144;6285;6290.811275661737;6301.558890877594;6312.715927783169;6327.050942358683;6182.629042257479;6258.405451482102;6335.683215839282;6340.175777149126;6344.238691357066;6119.99004232014;6123.343628574382;6152.753889844356;6221.875562488815;6244.5316601256;5214.843554693365;6290.308066112632;6295.464885484602;6297.905531416941;6325.019621958604;4984.651074424755;6122.428649698624;6143.419326628778;6185.567010309235;6198.0702894566175;6014.548908831767;6033.366496517802;6038.184845046038;6045.516358691206;6108.645631960441;5942.7920022799;5955.434233747787;5965.70171491415;5987.790427335197;6043.09353596964;6126.846828829315;6147.877042459037;6159.84600384994;6162.322144564184;6164.537659675452;5852.678102704222;5871.354151043296;5936.762529498772;6031.396860313934;6043.45608895184 20:02:41 INFO - PROCESS | 1652 | |1;ext.html;467.7544289248145;468.9507601701822;506.02337488646896;509.3226009407488;564.5681053993693;30975.535366969514;32890.50664240035;32901;41266.58733412665;42641.573584264224;43781.78109109458;44225;44246.557534424646;44282.77858610702;44355.33466997997;9793.510324483765;9968.700938971806;9969.90030099697;9972.601095956154;9996.500174991257;8838.204561589455;9021.052789457111;9051.59267832949;9171.908280917172;9843.950780246088;12058.457369418396;12314;12322.383880805968;12574.74850502994;12579.119461637689;10324.845127323097;12263.448144833505;12313.876861231363;12340.13619046667;12354.876451235463;11942.522299108025;12008.219465734786;12012.939935300268;12024.39878006105;12033.71359441712;41419.1716165678;41635.791821041006;41673;41694.58305416937;41813.790931045456;3322.800631962066;3370.3886569814117;3607.621199774012;4012.578679238667;4072.6741860651077;31278.687213127803;32070;32084.679153208403;32245;32686.182845428815;3140.5132204508336;3253.154179913216;3254.023792862132;3255;3346.8828590999196;428033.85983070196;503640;505170;507300;507563;33973.150671233416;34514;34610.65389346125;62898;63912;35420.291594168484;35994.28011439757;36029;36046.63953360485;36123.84575773767;14328.49850255236;14531.273436328353;14537.055091419024;14548;14571.052881562664;12139.393030348623;12142.75714485723;12200.023998080133;12225.755484890253;12259.75480490403;6581.848176569081;7371.631418429057;7513.060867391576;7531.887021694679;7881.369490440751;2843.644544431946;3084.706952839477;3139.717425431722;3203.4554125798672;3304.966950330514;871.8212766383003;873.3929918706438;1010.2877471382658;1054.5973854773133;1099.373357186412;29154.416911661647;29393.412131757246;30184;30342.54486182709;31142;1041.0838462153408;1095.7863216672831;1100.290312748289;1118.105515587543;1140.7148212946763;15534.689306214039;15725.606859828367;15798.289076991563;16052.835898150619;16457.506274811774;629474;633488;634974;635305;635876.8206158939;58893.70553147207;59457;59488.405115948284;59509;59544.40455595475;1128.8081026225561;1226.920250183753;1344.0927374022692;1363.2502123831853;1390.8191935048378;28943.855280724303;31066.844665776527;31299.43502824804;31565.684343157194;31860;50186.498135018184;50950;51025.979480410926;51089.74455127701;51113.48886511087;520550;524089;524337;524501;526031;26986.1904142876;27342.863285683445;27358.45283094287;27523.31191720143;27732.168034958984;47504.76247618878;47626.90425122356;47677;51018.48981510138;51678.741606291725;33519;39322.41016384814;39739.00652483595;40185;41631.58368416277;32033;39239;40043;40987.38518922159;41805.372919406844;38344.65793697207;38748.225035499694;39136.217275653755;39379.818605441884;39693.80153099332;27649;35479.82260088683;35509.42798288669;35793.284134316644;35839.104022398606;22161.778382216617;25362;25525.744742552335;26000.479990400465;26521.204363869114 20:02:41 INFO - PROCESS | 1652 | |2;jquery.html;356.8429890848027;356.89828398906286;377.3923982388356;384.6518900395142;407.0495393256745;15336.693266134684;16207.513774586767;20529.692054619154;21181.152753889826;21240.36278911633;19788.802111978875;20390.796092039076;20591.86744729039;21242.681359779577;21759.347219583397;5749.540036797057;5989.0417533194695;6063.757449702007;6090.177825993478;6180.721867515973;6081.878362432749;6103.633781973096;6147.477464415508;6153.077038444225;6165.907511387333;4190.350495673176;4662.463816661077;6777.220619628756;6779.796606101825;6847.178338599375;6545.116409284781;6769.796906092825;6784;6826.24911259761;6830.282820303871;5043.672161309503;5860.062390017577;5908.172855800149;5914.37899020601;5914.822555323347;25614.487710245878;26582;26794.464110717872;27015.919363225446;27377.178684639293;2316.119874447716;2448.9142879999295;2517.3958250020046;2581.2514370832437;2852.044565070704;16095.517134485866;16138.677226455522;16246.268917898722;16670.749938750927;16899.23953422099;1869.3176990398542;1877.0474282561272;1884.7738271407381;1927.5181204698824;1945.1830231302886;129358;130706;131335;133443;135343.32328338394;10593.099586535076;10787.190960677981;11297.973264338083;11309.660710178709;11532.827007594893;14198;14213.50252741149;14216;14224.21766802815;14229.715405691828;8294.83410331802;8567.61445734931;8570.271526920156;8725.476471411735;8727.519986400808;8503.489790612583;8505.02192247887;8522.849415328852;8692.174243446863;8695.521746303888;6797.286284940109;6908.240093589701;6919.238883722786;6946.097007389007;6946.756863509753;1543.5060780550116;1722.776039114907;1883.2372888979985;1884.3781552088053;1888.990555047216;815.604431850552;885.6125074213415;894.7584152278979;947.545178314413;960.7406000379998;22767.658485122738;23183.53632927332;23438.88280558586;23627.7637223629;23672.64491032636;917.3486824354783;943.4056544377136;946.8437707778277;959.6065613098596;968.7239136845965;11735;12089.939550302368;12250.571230006906;12260.241445674652;12346.629601111801;538423.2315353749;565884.1705791445;574718;580273;580607;116405.52350288515;130019;132341;133162;135101.87432846465;977.4184360305607;979.9216062715109;998.5756053677303;1021.1269364693229;1022.5194158745378;16701.49895503137;18888.433346999613;20504.487387815425;20509.794902050788;20605.484862878548;49760;54471.45528544664;55003.724981374835;55604;55814.883702324914;81671.59164204141;81949.18050819654;83114.58442707748;84601;85760.57119714361;6880.139982502187;7436.888446673196;7495.99297343715;7508;7647.732329368446;11781.528738850693;12449.688757781129;13175.670608234488;13216;13746.450141994208;11198.328100314007;11349.37578433161;11372.772544549229;11554.94222528882;11869.940650296694;11127.554897803997;11379.487923043318;11538.59614913474;11623.88376116228;11931.701707457383;11283.435828208721;11297.322160670386;11632.88367116318;11762.470688819194;11855.288682679067;7425.8143546411775;7513.39892808563;7630.923690763243;7642.121156067124;7760.844783104193;10817.296875703054;11399.487023083815;11509.424528773696;11510.94244528772;11546.653600392001 20:02:41 INFO - PROCESS | 1652 | |3;mootools.html;1357.9524716634917;1536.7771673107395;1730.5241058708862;1822.899740514273;1925.9711104333428;6916.6887490062945;8988.235999939996;8993.73018809436;9001.549922503882;9001.684941027066;7918.564478953655;8114.188581141882;8475.83048339033;8689.435186712859;8799.780005499866;2367.4081479630095;2410;2411.986965474505;2412.7587241275905;2416.5529377065213;2378.4053986503372;2399.592069348215;2420.9394765130924;2422.358075110097;2423.3820375804144;2051.548659294953;2387.7254115776736;2409.915652952147;2411.397150712322;2416.66166736657;2358.952820943589;2373.1693907132453;2379.679278000709;2381;2398.7241467231142;2354.281944007077;2367.2188177901253;2372.525494901027;2375.489269807;2376.120835290936;8275.213854683796;8343.45767525109;8344.582770861492;8350.039745429292;8352.958235208785;5226.843194704127;5400.648957817729;5407.621466497347;5411.21537377082;5434.239206511092;3650.5619325681087;3661.7070634349457;3686.4101743721144;3724.776513409204;3728.5898551159353;924.96300147994;1026.184183574053;1031.7987992341496;1042.4110377636687;1048.9108425783818;11376.260543064674;11622.07023438123;11783.234089784137;11803.822942655866;11813.64559063238;4556.384888040072;4622.884427889262;4819.855404337875;4821.951780482221;4824.517548245218;9996.100350968447;10007.399556026661;10015.449150296803;10020.64927727526;10054.547545360476;4450.3991961085485;5344.278522399504;5345.65253258537;5347.491988261109;5367.35591728995;4401.537838526972;4561.521040290788;4698.88252793676;4804.807807687724;4901.730404827697;3869.1294458746556;3916.784576848244;4015;4031.5766844481495;4033.8184781684895;1104.679642903549;1288.5747703257903;1299.5581502289267;1300.8894243989366;1312.737452509502;804.4730701390598;887.7748706784596;891.9687810926587;911.1799380557419;943.1087622197095;5615.326160860723;5759.769609215668;5858.297004359504;5992.280926288873;6030.427109424598;748.0200936772845;855.4183155454226;875.1336177184542;932.9253659707207;996.4818294486971;3822.8470861165797;3854.6338097880657;3915.530136383595;3981.5620281768984;4046.59534046593;10380.844287335696;10846.67459976202;10860.945695271474;10873.456327183609;10877.728056798485;10813.459327033617;10821.783564328669;10822;10828.837567436494;10831;6908.894576867677;7746.573938433327;8115.1073381927945;8285;8302.501849889026;5709;5953.613015154001;6035.39646035393;6064.818055458343;6084.7566097357385;9662.806743865223;9755;9998.650047248311;10057.547410366697;10215.387076775418;9880.50597470138;10266;10299.845502317621;10449.111825494614;10455.581776728846;7587.506812057199;7894.447388682966;8085.393595480245;8203.015638123463;8203.548804815793;8214.917850821654;8300.501969881827;8304.086550479564;8431.704890328809;8436.11420800807;7357.668904899186;7370.226126256666;7458.813529661585;7486.064241969754;7550.433717471102;7244.529105608119;7290.380317673057;7304.598247096461;7323.926760732537;7520.623968801648;6874.690638921361;7042.577445353295;7044.471664625071;7051.894221586783;7057.7176912922905;4768.499307572793;4883.316335713074;4905.7792399342825;4909.337239472626;5014.974925125351;4201.243776120205;4766.594839438687;4863.686804562819;5085.5931525477135;5111.872203194801 20:02:41 INFO - PROCESS | 1652 | |4;prototype.html;264.33256028527967;293.2083374887802;305.3511288511912;314.8347117763174;317.33518279205055;5127.05149547334;5308.203769434586;5536.501714845663;5545.279113715222;5578.748956296966;4870.9269360959615;5099.337086178801;5395.541378982782;5402.702851343184;5421.891562168754;3228.8053420234546;3426.725861931052;4109.486314210723;4148.365787724794;4152.626263636272;3728.422094575332;4123.525794533637;4148.232576973254;4156.916861662765;4181.749095054306;3415.019288812483;3866.709996750241;3945.230680017398;4126.216018956388;4140.503139623234;4111.992561822372;4120.917581648365;4131.256373852705;4137.379393091031;4152.979235103835;2964.7479964203067;3257.03917344384;3550.1479644885044;3563.9109022274392;3577.3918433866306;5047.066292735819;5266.552343050846;5273.367195936474;5298.046351656701;5344.14493681013;3969.980150099231;4339.849939765933;4716.245400735866;4740.360051393087;4748.6438517111355;4710.37301278014;5566.777328906879;5721.542276617861;5740.56945729072;5752.9424705753245;3771.9434208486896;3829.8085095745378;3945.3490174121234;3956.4460975463458;3984.920301593981;5608.242887210256;5653.802116925929;6007.339192688801;6011.939880601181;6021.217241758587;3069.677683843209;3392.067181525051;3611.476335931328;4004.799760011988;4031.879043628696;4040.919181616351;4056.782965110514;4080.897977550585;4089.4070359798257;4123.958760412417;4606.976965115153;4734.928976065362;4746.342048452878;4748.7863046163;4785.832495862628;5085.262636917626;5622.493975542221;5695.572832037614;5724.971375143181;5729.398413166641;1203.6218529783348;1347.9258640774656;1372.72336726845;1380.9930950345183;1388.4654408052972;715.4991505945754;740.4779630360583;834.7120243515977;853.9957300213583;856.2336708645852;3734.458503516975;3931.941020884689;4326.394304797331;4389.604935555815;4518.909621807546;4218.894527636834;4841.9031619367415;4889.975550122226;4897.4328214970865;4922.4339200992345;3848.422736589534;3863.072862512977;4003.957981429434;4061.3095773718537;4522.638188944877;2242.3742786479997;2330.3570982457327;2468.638762734749;2599.766021058114;2609.9347516312246;4450.8442204523335;4459.881556260751;5006.749662516932;5121.308623335876;5129.871103492009;4357.302831546937;5070.898582028412;5133.948660513422;5136.409312928986;5154.587632989352;4246.915061698749;4719.622430205575;5090.643654944119;5706.3152421709665;6184.288806787097;5640.266765320482;5966.433188847052;5970.432808883149;5985.970070149621;5986.138940383341;5636.323641163087;5908.645481271137;5978.953683105527;5993.332966829719;5994.910076348771;5097.80201652604;5770.7782503735525;5800.854978625568;6075.417947372437;6103.816885493442;3053.374058318084;3435.3301106285035;3471.5005196258653;3682.8895133146048;3998.900027499336;5201.973990130176;5712.115381732198;5848.56135789826;6016.42843929826;6022.1870047543025;5303.018941495836;5387.922415516834;5448.700321482356;5571.972140139435;5862.589618726819;5604.439556044526;5662.8018019370775;5745.299709136699;5758.712064396847;5790.7973220937065;4768.213244814567;5403.540699040625;5626.859328516656;5698.686572238566;5719.656820590778;4946.35697359341;5460.31746031746;5705.914411283917;5769.624974376652;5790;4585.541445855388;4784.06245470585;5601.691906945157;5669.943300566942;5684.8010319638615 20:02:41 INFO - PROCESS | 1652 | |5;yui.html;709.0994437064925;714.8748968930432;762.302493218705;782.9843403131938;825.1748251748252;13482.595522134327;14994;16266.75599866003;16289.674206515863;16322.836771632281;14713.337899794507;14881.479148229813;14889;14897;14920.179390133566;674.7672053141658;692.7540723043322;838.1618381618382;842.6587232170965;857.9270761985208;832.3258160889699;844.8352571248608;846.8221673448584;847.8601030829936;866.8613021916494;887.6050157679836;889.9911000890005;890.2388457868526;890.8351954888334;890.8396488632044;705.400409651797;720.6540860386991;721.8339781850204;884.4648987362649;887.6094518411878;542.7123624479033;661.3155384177353;678.6131904814258;678.7386856060402;679.4224908827457;14697.265136743226;14714.969952103356;14720.779188312184;14732.70534589313;14751.852481475154;10773.5690572377;13564.796528052088;13907.721845563032;13955.58133256004;14062.929685351508;12422.937885310606;12740.299283539402;12783.16909400886;12836.550720724823;13375;3912.197999410114;4533.116042371739;4637.489876113625;4674.836380726692;4686.461056978457;94532;116307;116569.33722651083;117298;117306;10939.453027348602;11000.944995275133;11019.283746556448;11096.167787415976;11322.716932076764;21860.344189674335;21866.781332186794;21868.562628747655;21899.67150492744;21907.67138492924;7766.611669416619;7855.842883142305;7950.6422211000645;7963.362930965509;8137.145599712064;3972.145988612462;4229.492460904711;4315;4342.2835232186335;4365.219379109188;3406.318736252759;3553.1650062235353;3558.0393293811044;3560.572731272264;3560.750747447654;651.6155468273741;655.5804285257439;659.2287024181719;661.24287690594;665.4875745675803;590.9497692696083;597.8864015836978;622.0886401421939;644.3009334871716;673.4780545077604;8437;8951.015388307282;8998.14517620825;9075.682351117679;9106.681266155652;512.50799232737;572.9512991395694;601.6991504247876;614.9262088549314;631.7883509024529;4315.352697095461;4393.319035549495;4407.537208593051;4421.204183247047;4491.932621010687;167629;167722;168193.31806681777;169616.4557531638;172703;106220;142968.28515857353;145825;146728;150404;76576;101316;123763;124316.37841810733;126658.36670816586;8407.663693452194;8444.831103377775;8553.230209281195;8616.569171541523;8814.2948564116;60141.69929150326;64461.03308450283;67155;68485;69244;99982.50026249758;100827.49586252315;104424;107451.46274268579;109113;10664.520096595517;10882.782344353227;10938.945305273422;11236.606718764804;11364.602238921598;12136.393180341125;12162.878371216175;12220.938895305466;12235.632931012084;12283;9682.709518714168;11677.357745324089;11722.882771172179;11789.882101179222;11946.641600751991;11490.310581365145;12781.488740450277;13333.866581346938;13359.933200333937;13961.720765584834;13324;13371.502539082103;13631.931840340734;13885.652858678613;14182.858171418155;11095.112391008539;11347.37589432589;11382;11541.480633371351;11764.941175294069;9539.8041656046;9880.25898057663;11309.829838423793;11438.428078596204;11598.18812683138 20:02:41 INFO - PROCESS | 1652 | __end_tp_report 20:02:41 INFO - PROCESS | 1652 | __start_cc_report 20:02:41 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1367 20:02:41 INFO - PROCESS | 1652 | __end_cc_report 20:02:41 INFO - PROCESS | 1652 | __startTimestamp1473303761082__endTimestamp 20:02:41 INFO - PROCESS | 1652 | 20:02:41 INFO - PROCESS | 1652 | ------- Summary: start ------- 20:02:41 INFO - PROCESS | 1652 | Number of tests: 6 20:02:41 INFO - PROCESS | 1652 | 20:02:41 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5719.98 Median:5936.76 stddev:671.06 (11.3%) stddev-sans-first:637.23 20:02:41 INFO - PROCESS | 1652 | Values: 2959.8 3031.8 3336.6 3353.1 3417.9 5613.9 5618.8 5681.1 5757.7 5765.9 5682.7 5687.1 5722.3 5747.6 5751.3 4315.6 5067.4 5103.3 5220.4 5321.8 5238.5 5283.3 5294.1 5306.6 5308.9 5180.4 5186.5 5234.5 5248.2 5293.6 5175.8 5183.6 5219.0 5262.9 5269.5 5182.0 5186.8 5203.2 5250.9 5304.2 5584.3 5762.6 5765.4 5770.4 5772.7 5782.5 6112.8 6185.4 6198.5 6266.5 6261.0 6270.2 6280.7 6300.8 6313.9 4888.9 5743.7 5848.2 5861.3 5869.0 6234.4 6258.3 6259.5 6284.4 6368.3 6228.5 6247.8 6248.4 6250.2 6256.4 5913.5 6090.8 6227.0 6267.7 6304.1 5380.8 6096.4 6145.9 6185.3 6191.0 6282.4 6289.0 6301.1 6310.7 6313.7 5026.7 5034.2 5042.1 5042.4 5075.4 4406.3 4434.9 4512.2 4522.2 4564.4 5637.8 5651.9 5669.9 5725.5 5785.4 6248.6 6294.5 6295.8 6301.7 6306.1 4818.5 4825.5 5747.6 5750.6 5770.9 5249.6 5280.4 5376.2 5436.2 5450.1 6201.2 6203.8 6209.7 6309.5 6315.7 6285.0 6290.8 6301.6 6312.7 6327.1 6182.6 6258.4 6335.7 6340.2 6344.2 6120.0 6123.3 6152.8 6221.9 6244.5 5214.8 6290.3 6295.5 6297.9 6325.0 4984.7 6122.4 6143.4 6185.6 6198.1 6014.5 6033.4 6038.2 6045.5 6108.6 5942.8 5955.4 5965.7 5987.8 6043.1 6126.8 6147.9 6159.8 6162.3 6164.5 5852.7 5871.4 5936.8 6031.4 6043.5 20:02:41 INFO - PROCESS | 1652 | 20:02:41 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:67416.37 Median:35494.63 stddev:147842.04 (416.5%) stddev-sans-first:148171.33 20:02:41 INFO - PROCESS | 1652 | Values: 467.8 469.0 506.0 509.3 564.6 30975.5 32890.5 32901.0 41266.6 42641.6 43781.8 44225.0 44246.6 44282.8 44355.3 9793.5 9968.7 9969.9 9972.6 9996.5 8838.2 9021.1 9051.6 9171.9 9844.0 12058.5 12314.0 12322.4 12574.7 12579.1 10324.8 12263.4 12313.9 12340.1 12354.9 11942.5 12008.2 12012.9 12024.4 12033.7 41419.2 41635.8 41673.0 41694.6 41813.8 3322.8 3370.4 3607.6 4012.6 4072.7 31278.7 32070.0 32084.7 32245.0 32686.2 3140.5 3253.2 3254.0 3255.0 3346.9 428033.9 503640.0 505170.0 507300.0 507563.0 33973.2 34514.0 34610.7 62898.0 63912.0 35420.3 35994.3 36029.0 36046.6 36123.8 14328.5 14531.3 14537.1 14548.0 14571.1 12139.4 12142.8 12200.0 12225.8 12259.8 6581.8 7371.6 7513.1 7531.9 7881.4 2843.6 3084.7 3139.7 3203.5 3305.0 871.8 873.4 1010.3 1054.6 1099.4 29154.4 29393.4 30184.0 30342.5 31142.0 1041.1 1095.8 1100.3 1118.1 1140.7 15534.7 15725.6 15798.3 16052.8 16457.5 629474.0 633488.0 634974.0 635305.0 635876.8 58893.7 59457.0 59488.4 59509.0 59544.4 1128.8 1226.9 1344.1 1363.3 1390.8 28943.9 31066.8 31299.4 31565.7 31860.0 50186.5 50950.0 51026.0 51089.7 51113.5 520550.0 524089.0 524337.0 524501.0 526031.0 26986.2 27342.9 27358.5 27523.3 27732.2 47504.8 47626.9 47677.0 51018.5 51678.7 33519.0 39322.4 39739.0 40185.0 41631.6 32033.0 39239.0 40043.0 40987.4 41805.4 38344.7 38748.2 39136.2 39379.8 39693.8 27649.0 35479.8 35509.4 35793.3 35839.1 22161.8 25362.0 25525.7 26000.5 26521.2 20:02:41 INFO - PROCESS | 1652 | 20:02:41 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:35214.44 Median:14097.87 stddev:95530.27 (677.6%) stddev-sans-first:95762.41 20:02:41 INFO - PROCESS | 1652 | Values: 356.8 356.9 377.4 384.7 407.0 15336.7 16207.5 20529.7 21181.2 21240.4 19788.8 20390.8 20591.9 21242.7 21759.3 5749.5 5989.0 6063.8 6090.2 6180.7 6081.9 6103.6 6147.5 6153.1 6165.9 4190.4 4662.5 6777.2 6779.8 6847.2 6545.1 6769.8 6784.0 6826.2 6830.3 5043.7 5860.1 5908.2 5914.4 5914.8 25614.5 26582.0 26794.5 27015.9 27377.2 2316.1 2448.9 2517.4 2581.3 2852.0 16095.5 16138.7 16246.3 16670.7 16899.2 1869.3 1877.0 1884.8 1927.5 1945.2 129358.0 130706.0 131335.0 133443.0 135343.3 10593.1 10787.2 11298.0 11309.7 11532.8 14198.0 14213.5 14216.0 14224.2 14229.7 8294.8 8567.6 8570.3 8725.5 8727.5 8503.5 8505.0 8522.8 8692.2 8695.5 6797.3 6908.2 6919.2 6946.1 6946.8 1543.5 1722.8 1883.2 1884.4 1889.0 815.6 885.6 894.8 947.5 960.7 22767.7 23183.5 23438.9 23627.8 23672.6 917.3 943.4 946.8 959.6 968.7 11735.0 12089.9 12250.6 12260.2 12346.6 538423.2 565884.2 574718.0 580273.0 580607.0 116405.5 130019.0 132341.0 133162.0 135101.9 977.4 979.9 998.6 1021.1 1022.5 16701.5 18888.4 20504.5 20509.8 20605.5 49760.0 54471.5 55003.7 55604.0 55814.9 81671.6 81949.2 83114.6 84601.0 85760.6 6880.1 7436.9 7496.0 7508.0 7647.7 11781.5 12449.7 13175.7 13216.0 13746.5 11198.3 11349.4 11372.8 11554.9 11869.9 11127.6 11379.5 11538.6 11623.9 11931.7 11283.4 11297.3 11632.9 11762.5 11855.3 7425.8 7513.4 7630.9 7642.1 7760.8 10817.3 11399.5 11509.4 11510.9 11546.7 20:02:41 INFO - PROCESS | 1652 | 20:02:41 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5707.98 Median:4823.23 stddev:3203.10 (66.4%) stddev-sans-first:3195.40 20:02:41 INFO - PROCESS | 1652 | Values: 1358.0 1536.8 1730.5 1822.9 1926.0 6916.7 8988.2 8993.7 9001.5 9001.7 7918.6 8114.2 8475.8 8689.4 8799.8 2367.4 2410.0 2412.0 2412.8 2416.6 2378.4 2399.6 2420.9 2422.4 2423.4 2051.5 2387.7 2409.9 2411.4 2416.7 2359.0 2373.2 2379.7 2381.0 2398.7 2354.3 2367.2 2372.5 2375.5 2376.1 8275.2 8343.5 8344.6 8350.0 8353.0 5226.8 5400.6 5407.6 5411.2 5434.2 3650.6 3661.7 3686.4 3724.8 3728.6 925.0 1026.2 1031.8 1042.4 1048.9 11376.3 11622.1 11783.2 11803.8 11813.6 4556.4 4622.9 4819.9 4822.0 4824.5 9996.1 10007.4 10015.4 10020.6 10054.5 4450.4 5344.3 5345.7 5347.5 5367.4 4401.5 4561.5 4698.9 4804.8 4901.7 3869.1 3916.8 4015.0 4031.6 4033.8 1104.7 1288.6 1299.6 1300.9 1312.7 804.5 887.8 892.0 911.2 943.1 5615.3 5759.8 5858.3 5992.3 6030.4 748.0 855.4 875.1 932.9 996.5 3822.8 3854.6 3915.5 3981.6 4046.6 10380.8 10846.7 10860.9 10873.5 10877.7 10813.5 10821.8 10822.0 10828.8 10831.0 6908.9 7746.6 8115.1 8285.0 8302.5 5709.0 5953.6 6035.4 6064.8 6084.8 9662.8 9755.0 9998.7 10057.5 10215.4 9880.5 10266.0 10299.8 10449.1 10455.6 7587.5 7894.4 8085.4 8203.0 8203.5 8214.9 8300.5 8304.1 8431.7 8436.1 7357.7 7370.2 7458.8 7486.1 7550.4 7244.5 7290.4 7304.6 7323.9 7520.6 6874.7 7042.6 7044.5 7051.9 7057.7 4768.5 4883.3 4905.8 4909.3 5015.0 4201.2 4766.6 4863.7 5085.6 5111.9 20:02:41 INFO - PROCESS | 1652 | 20:02:41 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4449.16 Median:4856.42 stddev:1419.50 (29.2%) stddev-sans-first:1388.30 20:02:41 INFO - PROCESS | 1652 | Values: 264.3 293.2 305.4 314.8 317.3 5127.1 5308.2 5536.5 5545.3 5578.7 4870.9 5099.3 5395.5 5402.7 5421.9 3228.8 3426.7 4109.5 4148.4 4152.6 3728.4 4123.5 4148.2 4156.9 4181.7 3415.0 3866.7 3945.2 4126.2 4140.5 4112.0 4120.9 4131.3 4137.4 4153.0 2964.7 3257.0 3550.1 3563.9 3577.4 5047.1 5266.6 5273.4 5298.0 5344.1 3970.0 4339.8 4716.2 4740.4 4748.6 4710.4 5566.8 5721.5 5740.6 5752.9 3771.9 3829.8 3945.3 3956.4 3984.9 5608.2 5653.8 6007.3 6011.9 6021.2 3069.7 3392.1 3611.5 4004.8 4031.9 4040.9 4056.8 4080.9 4089.4 4124.0 4607.0 4734.9 4746.3 4748.8 4785.8 5085.3 5622.5 5695.6 5725.0 5729.4 1203.6 1347.9 1372.7 1381.0 1388.5 715.5 740.5 834.7 854.0 856.2 3734.5 3931.9 4326.4 4389.6 4518.9 4218.9 4841.9 4890.0 4897.4 4922.4 3848.4 3863.1 4004.0 4061.3 4522.6 2242.4 2330.4 2468.6 2599.8 2609.9 4450.8 4459.9 5006.7 5121.3 5129.9 4357.3 5070.9 5133.9 5136.4 5154.6 4246.9 4719.6 5090.6 5706.3 6184.3 5640.3 5966.4 5970.4 5986.0 5986.1 5636.3 5908.6 5979.0 5993.3 5994.9 5097.8 5770.8 5800.9 6075.4 6103.8 3053.4 3435.3 3471.5 3682.9 3998.9 5202.0 5712.1 5848.6 6016.4 6022.2 5303.0 5387.9 5448.7 5572.0 5862.6 5604.4 5662.8 5745.3 5758.7 5790.8 4768.2 5403.5 5626.9 5698.7 5719.7 4946.4 5460.3 5705.9 5769.6 5790.0 4585.5 4784.1 5601.7 5669.9 5684.8 20:02:41 INFO - PROCESS | 1652 | 20:02:41 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:26027.23 Median:3555.60 stddev:43306.14 (1218.0%) stddev-sans-first:43385.90 20:02:41 INFO - PROCESS | 1652 | Values: 709.1 714.9 762.3 783.0 825.2 13482.6 14994.0 16266.8 16289.7 16322.8 14713.3 14881.5 14889.0 14897.0 14920.2 674.8 692.8 838.2 842.7 857.9 832.3 844.8 846.8 847.9 866.9 887.6 890.0 890.2 890.8 890.8 705.4 720.7 721.8 884.5 887.6 542.7 661.3 678.6 678.7 679.4 14697.3 14715.0 14720.8 14732.7 14751.9 10773.6 13564.8 13907.7 13955.6 14062.9 12422.9 12740.3 12783.2 12836.6 13375.0 3912.2 4533.1 4637.5 4674.8 4686.5 94532.0 116307.0 116569.3 117298.0 117306.0 10939.5 11000.9 11019.3 11096.2 11322.7 21860.3 21866.8 21868.6 21899.7 21907.7 7766.6 7855.8 7950.6 7963.4 8137.1 3972.1 4229.5 4315.0 4342.3 4365.2 3406.3 3553.2 3558.0 3560.6 3560.8 651.6 655.6 659.2 661.2 665.5 590.9 597.9 622.1 644.3 673.5 8437.0 8951.0 8998.1 9075.7 9106.7 512.5 573.0 601.7 614.9 631.8 4315.4 4393.3 4407.5 4421.2 4491.9 167629.0 167722.0 168193.3 169616.5 172703.0 106220.0 142968.3 145825.0 146728.0 150404.0 76576.0 101316.0 123763.0 124316.4 126658.4 8407.7 8444.8 8553.2 8616.6 8814.3 60141.7 64461.0 67155.0 68485.0 69244.0 99982.5 100827.5 104424.0 107451.5 109113.0 10664.5 10882.8 10938.9 11236.6 11364.6 12136.4 12162.9 12220.9 12235.6 12283.0 9682.7 11677.4 11722.9 11789.9 11946.6 11490.3 12781.5 13333.9 13359.9 13961.7 13324.0 13371.5 13631.9 13885.7 14182.9 11095.1 11347.4 11382.0 11541.5 11764.9 9539.8 9880.3 11309.8 11438.4 11598.2 20:02:41 INFO - PROCESS | 1652 | -------- Summary: end -------- 20:02:41 INFO - PROCESS | 1652 | 20:02:41 INFO - TEST-INFO | 1652: exit 0 20:02:41 INFO - TEST-OK | dromaeo_css | took 1117161ms 20:02:41 INFO - TEST-START | kraken 20:02:41 INFO - operating with platform_type : mac_ 20:02:41 INFO - Initialising browser for kraken test... 20:02:41 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpvUGCcM/profile http://localhost:49271/getInfo.html) 20:02:44 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 20:02:44 INFO - PROCESS | 1661 | colorDepth:24 20:02:44 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 20:02:44 INFO - PROCESS | 1661 | __metrics 20:02:44 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:02:44 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:02:44 INFO - TEST-INFO | 1661: exit 0 20:02:44 INFO - Browser initialized. 20:02:44 INFO - Running cycle 1/1 for kraken test... 20:02:44 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpvUGCcM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:02:56 INFO - PROCESS | 1662 | RSS: Main: 243613696 20:02:56 INFO - PROCESS | 1662 | 20:02:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 20:03:00 INFO - PROCESS | 1662 | RSS: Main: 293789696 20:03:00 INFO - PROCESS | 1662 | 20:03:02 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 20:03:03 INFO - PROCESS | 1662 | RSS: Main: 289071104 20:03:03 INFO - PROCESS | 1662 | 20:03:07 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 20:03:07 INFO - PROCESS | 1662 | RSS: Main: 380391424 20:03:07 INFO - PROCESS | 1662 | 20:03:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 20:03:10 INFO - PROCESS | 1662 | RSS: Main: 281505792 20:03:10 INFO - PROCESS | 1662 | 20:03:12 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:03:13 INFO - PROCESS | 1662 | RSS: Main: 284295168 20:03:13 INFO - PROCESS | 1662 | 20:03:15 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 20:03:16 INFO - PROCESS | 1662 | RSS: Main: 322875392 20:03:16 INFO - PROCESS | 1662 | 20:03:19 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 20:03:20 INFO - PROCESS | 1662 | RSS: Main: 323338240 20:03:20 INFO - PROCESS | 1662 | 20:03:22 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 20:03:23 INFO - PROCESS | 1662 | RSS: Main: 321708032 20:03:23 INFO - PROCESS | 1662 | 20:03:24 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:03:25 INFO - PROCESS | 1662 | RSS: Main: 298377216 20:03:25 INFO - PROCESS | 1662 | 20:03:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:03:28 INFO - PROCESS | 1662 | RSS: Main: 303448064 20:03:28 INFO - PROCESS | 1662 | 20:03:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:03:30 INFO - PROCESS | 1662 | RSS: Main: 306737152 20:03:30 INFO - PROCESS | 1662 | 20:03:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:03:34 INFO - PROCESS | 1662 | RSS: Main: 320217088 20:03:34 INFO - PROCESS | 1662 | 20:03:37 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:03:37 INFO - PROCESS | 1662 | RSS: Main: 316264448 20:03:37 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 20:03:39 INFO - PROCESS | 1662 | RSS: Main: 360144896 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | __start_tp_report 20:03:39 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 20:03:39 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 20:03:39 INFO - PROCESS | 1662 | |i|pagename|runs| 20:03:39 INFO - PROCESS | 1662 | |0;ai-astar;113;113;110;114;113;112;124;111;114;111 20:03:39 INFO - PROCESS | 1662 | |1;audio-beat-detection;147;108;129;160;105;133;129;107;158;106 20:03:39 INFO - PROCESS | 1662 | |2;audio-dft;185;183;185;183;183;183;181;184;183;184 20:03:39 INFO - PROCESS | 1662 | |3;audio-fft;77;73;76;153;79;81;79;66;142;57 20:03:39 INFO - PROCESS | 1662 | |4;audio-oscillator;82;82;138;70;71;75;119;68;68;116 20:03:39 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;87;83;87;95;135;113;88;83;87;83 20:03:39 INFO - PROCESS | 1662 | |6;imaging-darkroom;100;137;93;103;142;93;93;93;96;95 20:03:39 INFO - PROCESS | 1662 | |7;imaging-desaturate;86;89;84;85;85;88;89;85;85;84 20:03:39 INFO - PROCESS | 1662 | |8;json-parse-financial;54;55;56;54;53;54;54;54;53;53 20:03:39 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;66;73;80;75;72;68;71;70;68;70 20:03:39 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;89;84;83;88;85;86;88;84;86;85 20:03:39 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;136;140;136;138;139;149;147;153;176;149 20:03:39 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;165;167;168;167;168;164;170;167;168;170 20:03:39 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;68;67;69;67;66;69;66;67;65;66 20:03:39 INFO - PROCESS | 1662 | __end_tp_report 20:03:39 INFO - PROCESS | 1662 | __start_cc_report 20:03:39 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2099 20:03:39 INFO - PROCESS | 1662 | __end_cc_report 20:03:39 INFO - PROCESS | 1662 | __startTimestamp1473303819946__endTimestamp 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | ------- Summary: start ------- 20:03:39 INFO - PROCESS | 1662 | Number of tests: 14 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:113.50 Median:113.00 stddev:3.92 (3.5%) stddev-sans-first:4.16 20:03:39 INFO - PROCESS | 1662 | Values: 113.0 113.0 110.0 114.0 113.0 112.0 124.0 111.0 114.0 111.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:131.00 stddev:21.51 (16.4%) stddev-sans-first:21.72 20:03:39 INFO - PROCESS | 1662 | Values: 147.0 108.0 129.0 160.0 105.0 133.0 129.0 107.0 158.0 106.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:183.40 Median:183.50 stddev:1.17 (0.6%) stddev-sans-first:1.09 20:03:39 INFO - PROCESS | 1662 | Values: 185.0 183.0 185.0 183.0 183.0 183.0 181.0 184.0 183.0 184.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:88.30 Median:76.50 stddev:32.12 (42.0%) stddev-sans-first:33.81 20:03:39 INFO - PROCESS | 1662 | Values: 77.0 73.0 76.0 153.0 79.0 81.0 79.0 66.0 142.0 57.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:70.50 stddev:25.58 (36.3%) stddev-sans-first:27.01 20:03:39 INFO - PROCESS | 1662 | Values: 82.0 82.0 138.0 70.0 71.0 75.0 119.0 68.0 68.0 116.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:94.10 Median:87.00 stddev:16.96 (19.5%) stddev-sans-first:17.79 20:03:39 INFO - PROCESS | 1662 | Values: 87.0 83.0 87.0 95.0 135.0 113.0 88.0 83.0 87.0 83.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:104.50 Median:93.00 stddev:18.79 (20.2%) stddev-sans-first:19.86 20:03:39 INFO - PROCESS | 1662 | Values: 100.0 137.0 93.0 103.0 142.0 93.0 93.0 93.0 96.0 95.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:85.50 stddev:1.94 (2.3%) stddev-sans-first:2.06 20:03:39 INFO - PROCESS | 1662 | Values: 86.0 89.0 84.0 85.0 85.0 88.0 89.0 85.0 85.0 84.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:0.94 (1.7%) stddev-sans-first:1.00 20:03:39 INFO - PROCESS | 1662 | Values: 54.0 55.0 56.0 54.0 53.0 54.0 54.0 54.0 53.0 53.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.50 stddev:4.03 (5.6%) stddev-sans-first:3.79 20:03:39 INFO - PROCESS | 1662 | Values: 66.0 73.0 80.0 75.0 72.0 68.0 71.0 70.0 68.0 70.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:86.00 stddev:1.99 (2.3%) stddev-sans-first:1.74 20:03:39 INFO - PROCESS | 1662 | Values: 89.0 84.0 83.0 88.0 85.0 86.0 88.0 84.0 86.0 85.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:146.30 Median:148.00 stddev:12.09 (8.2%) stddev-sans-first:12.24 20:03:39 INFO - PROCESS | 1662 | Values: 136.0 140.0 136.0 138.0 139.0 149.0 147.0 153.0 176.0 149.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:168.00 stddev:1.90 (1.1%) stddev-sans-first:1.80 20:03:39 INFO - PROCESS | 1662 | Values: 165.0 167.0 168.0 167.0 168.0 164.0 170.0 167.0 168.0 170.0 20:03:39 INFO - PROCESS | 1662 | 20:03:39 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.33 (2.0%) stddev-sans-first:1.36 20:03:39 INFO - PROCESS | 1662 | Values: 68.0 67.0 69.0 67.0 66.0 69.0 66.0 67.0 65.0 66.0 20:03:39 INFO - PROCESS | 1662 | -------- Summary: end -------- 20:03:39 INFO - PROCESS | 1662 | 20:03:40 INFO - TEST-INFO | 1662: exit 0 20:03:40 INFO - TEST-OK | kraken | took 59071ms 20:03:40 INFO - SUITE-END | took 1176s 20:03:41 INFO - Completed test suite (00:19:36) 20:03:41 INFO - javascript benchmark 20:03:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2959.8372089535073, 3031.8180909145467, 3336.56624638797, 3353.0946644405994, 3417.9233541434446, 5613.943860561399, 5618.831435056944, 5681.062624666925, 5757.654540727554, 5765.913511297329, 5682.687452190138, 5687.061634830258, 5722.256106706132, 5747.568932330071, 5751.338596061454, 4315.568443155675, 5067.442581316071, 5103.311053007834, 5220.3996540397775, 5321.760520776575, 5238.47615238475, 5283.313169288006, 5294.126469132589, 5306.628536002481, 5308.867278318035, 5180.352455942988, 5186.455422180655, 5234.528892399702, 5248.160294352904, 5293.602979776532, 5175.792968281265, 5183.611229157828, 5219.0, 5262.894742105136, 5269.499397557226, 5182.015417070782, 5186.818461353834, 5203.167493201107, 5250.894982100374, 5304.151335786256, 5584.301962254718, 5762.567807414461, 5765.365809760923, 5770.394108618577, 5772.740226689809, 5782.479576838105, 6112.837854638568, 6185.381461853779, 6198.4731297839735, 6266.49868010558, 6260.968695156541, 6270.153529273535, 6280.748770049238, 6300.842478938017, 6313.87372252557, 4888.899997500534, 5743.702518992436, 5848.239728835224, 5861.296644402699, 5869.031609784338, 6234.438900498886, 6258.280297765815, 6259.499240060784, 6284.402981716729, 6368.267649220306, 6228.532860035515, 6247.781327653511, 6248.4064013919515, 6250.218722659668, 6256.43692067707, 5913.49735272498, 6090.847728806815, 6226.968865155645, 6267.655278959701, 6304.148939893147, 5380.784768609212, 6096.375503402309, 6145.924463218918, 6185.257769067741, 6190.969045154746, 6282.371762823773, 6288.962321216948, 6301.0863424804065, 6310.747570097236, 6313.715882785285, 5026.723530205873, 5034.219695947134, 5042.117629414838, 5042.420121686053, 5075.416327122354, 4406.317020861771, 4434.866953991321, 4512.210363186429, 4522.186006518858, 4564.360989461478, 5637.830865074055, 5651.943480565142, 5669.886602267931, 5725.5133313667975, 5785.363610002896, 6248.562600618004, 6294.496440284858, 6295.779647712308, 6301.684915754286, 6306.054091886253, 4818.469968303484, 4825.4933232010835, 5747.597668163272, 5750.597458177971, 5770.932377510174, 5249.632525723239, 5280.3663560373, 5376.247325374451, 5436.157395603688, 5450.073487507133, 6201.224846894138, 6203.844903877349, 6209.689515524296, 6309.463695585927, 6315.747370105144, 6285.0, 6290.811275661737, 6301.558890877594, 6312.715927783169, 6327.050942358683, 6182.629042257479, 6258.405451482102, 6335.683215839282, 6340.175777149126, 6344.238691357066, 6119.99004232014, 6123.343628574382, 6152.753889844356, 6221.875562488815, 6244.5316601256, 5214.843554693365, 6290.308066112632, 6295.464885484602, 6297.905531416941, 6325.019621958604, 4984.651074424755, 6122.428649698624, 6143.419326628778, 6185.567010309235, 6198.0702894566175, 6014.548908831767, 6033.366496517802, 6038.184845046038, 6045.516358691206, 6108.645631960441, 5942.7920022799, 5955.434233747787, 5965.70171491415, 5987.790427335197, 6043.09353596964, 6126.846828829315, 6147.877042459037, 6159.84600384994, 6162.322144564184, 6164.537659675452, 5852.678102704222, 5871.354151043296, 5936.762529498772, 6031.396860313934, 6043.45608895184], "value": 5676.180243587027, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.7544289248145, 468.9507601701822, 506.02337488646896, 509.3226009407488, 564.5681053993693, 30975.535366969514, 32890.50664240035, 32901.0, 41266.58733412665, 42641.573584264224, 43781.78109109458, 44225.0, 44246.557534424646, 44282.77858610702, 44355.33466997997, 9793.510324483765, 9968.700938971806, 9969.90030099697, 9972.601095956154, 9996.500174991257, 8838.204561589455, 9021.052789457111, 9051.59267832949, 9171.908280917172, 9843.950780246088, 12058.457369418396, 12314.0, 12322.383880805968, 12574.74850502994, 12579.119461637689, 10324.845127323097, 12263.448144833505, 12313.876861231363, 12340.13619046667, 12354.876451235463, 11942.522299108025, 12008.219465734786, 12012.939935300268, 12024.39878006105, 12033.71359441712, 41419.1716165678, 41635.791821041006, 41673.0, 41694.58305416937, 41813.790931045456, 3322.800631962066, 3370.3886569814117, 3607.621199774012, 4012.578679238667, 4072.6741860651077, 31278.687213127803, 32070.0, 32084.679153208403, 32245.0, 32686.182845428815, 3140.5132204508336, 3253.154179913216, 3254.023792862132, 3255.0, 3346.8828590999196, 428033.85983070196, 503640.0, 505170.0, 507300.0, 507563.0, 33973.150671233416, 34514.0, 34610.65389346125, 62898.0, 63912.0, 35420.291594168484, 35994.28011439757, 36029.0, 36046.63953360485, 36123.84575773767, 14328.49850255236, 14531.273436328353, 14537.055091419024, 14548.0, 14571.052881562664, 12139.393030348623, 12142.75714485723, 12200.023998080133, 12225.755484890253, 12259.75480490403, 6581.848176569081, 7371.631418429057, 7513.060867391576, 7531.887021694679, 7881.369490440751, 2843.644544431946, 3084.706952839477, 3139.717425431722, 3203.4554125798672, 3304.966950330514, 871.8212766383003, 873.3929918706438, 1010.2877471382658, 1054.5973854773133, 1099.373357186412, 29154.416911661647, 29393.412131757246, 30184.0, 30342.54486182709, 31142.0, 1041.0838462153408, 1095.7863216672831, 1100.290312748289, 1118.105515587543, 1140.7148212946763, 15534.689306214039, 15725.606859828367, 15798.289076991563, 16052.835898150619, 16457.506274811774, 629474.0, 633488.0, 634974.0, 635305.0, 635876.8206158939, 58893.70553147207, 59457.0, 59488.405115948284, 59509.0, 59544.40455595475, 1128.8081026225561, 1226.920250183753, 1344.0927374022692, 1363.2502123831853, 1390.8191935048378, 28943.855280724303, 31066.844665776527, 31299.43502824804, 31565.684343157194, 31860.0, 50186.498135018184, 50950.0, 51025.979480410926, 51089.74455127701, 51113.48886511087, 520550.0, 524089.0, 524337.0, 524501.0, 526031.0, 26986.1904142876, 27342.863285683445, 27358.45283094287, 27523.31191720143, 27732.168034958984, 47504.76247618878, 47626.90425122356, 47677.0, 51018.48981510138, 51678.741606291725, 33519.0, 39322.41016384814, 39739.00652483595, 40185.0, 41631.58368416277, 32033.0, 39239.0, 40043.0, 40987.38518922159, 41805.372919406844, 38344.65793697207, 38748.225035499694, 39136.217275653755, 39379.818605441884, 39693.80153099332, 27649.0, 35479.82260088683, 35509.42798288669, 35793.284134316644, 35839.104022398606, 22161.778382216617, 25362.0, 25525.744742552335, 26000.479990400465, 26521.204363869114], "value": 18936.53278791242, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.8429890848027, 356.89828398906286, 377.3923982388356, 384.6518900395142, 407.0495393256745, 15336.693266134684, 16207.513774586767, 20529.692054619154, 21181.152753889826, 21240.36278911633, 19788.802111978875, 20390.796092039076, 20591.86744729039, 21242.681359779577, 21759.347219583397, 5749.540036797057, 5989.0417533194695, 6063.757449702007, 6090.177825993478, 6180.721867515973, 6081.878362432749, 6103.633781973096, 6147.477464415508, 6153.077038444225, 6165.907511387333, 4190.350495673176, 4662.463816661077, 6777.220619628756, 6779.796606101825, 6847.178338599375, 6545.116409284781, 6769.796906092825, 6784.0, 6826.24911259761, 6830.282820303871, 5043.672161309503, 5860.062390017577, 5908.172855800149, 5914.37899020601, 5914.822555323347, 25614.487710245878, 26582.0, 26794.464110717872, 27015.919363225446, 27377.178684639293, 2316.119874447716, 2448.9142879999295, 2517.3958250020046, 2581.2514370832437, 2852.044565070704, 16095.517134485866, 16138.677226455522, 16246.268917898722, 16670.749938750927, 16899.23953422099, 1869.3176990398542, 1877.0474282561272, 1884.7738271407381, 1927.5181204698824, 1945.1830231302886, 129358.0, 130706.0, 131335.0, 133443.0, 135343.32328338394, 10593.099586535076, 10787.190960677981, 11297.973264338083, 11309.660710178709, 11532.827007594893, 14198.0, 14213.50252741149, 14216.0, 14224.21766802815, 14229.715405691828, 8294.83410331802, 8567.61445734931, 8570.271526920156, 8725.476471411735, 8727.519986400808, 8503.489790612583, 8505.02192247887, 8522.849415328852, 8692.174243446863, 8695.521746303888, 6797.286284940109, 6908.240093589701, 6919.238883722786, 6946.097007389007, 6946.756863509753, 1543.5060780550116, 1722.776039114907, 1883.2372888979985, 1884.3781552088053, 1888.990555047216, 815.604431850552, 885.6125074213415, 894.7584152278979, 947.545178314413, 960.7406000379998, 22767.658485122738, 23183.53632927332, 23438.88280558586, 23627.7637223629, 23672.64491032636, 917.3486824354783, 943.4056544377136, 946.8437707778277, 959.6065613098596, 968.7239136845965, 11735.0, 12089.939550302368, 12250.571230006906, 12260.241445674652, 12346.629601111801, 538423.2315353749, 565884.1705791445, 574718.0, 580273.0, 580607.0, 116405.52350288515, 130019.0, 132341.0, 133162.0, 135101.87432846465, 977.4184360305607, 979.9216062715109, 998.5756053677303, 1021.1269364693229, 1022.5194158745378, 16701.49895503137, 18888.433346999613, 20504.487387815425, 20509.794902050788, 20605.484862878548, 49760.0, 54471.45528544664, 55003.724981374835, 55604.0, 55814.883702324914, 81671.59164204141, 81949.18050819654, 83114.58442707748, 84601.0, 85760.57119714361, 6880.139982502187, 7436.888446673196, 7495.99297343715, 7508.0, 7647.732329368446, 11781.528738850693, 12449.688757781129, 13175.670608234488, 13216.0, 13746.450141994208, 11198.328100314007, 11349.37578433161, 11372.772544549229, 11554.94222528882, 11869.940650296694, 11127.554897803997, 11379.487923043318, 11538.59614913474, 11623.88376116228, 11931.701707457383, 11283.435828208721, 11297.322160670386, 11632.88367116318, 11762.470688819194, 11855.288682679067, 7425.8143546411775, 7513.39892808563, 7630.923690763243, 7642.121156067124, 7760.844783104193, 10817.296875703054, 11399.487023083815, 11509.424528773696, 11510.94244528772, 11546.653600392001], "value": 9991.713540828183, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.9524716634917, 1536.7771673107395, 1730.5241058708862, 1822.899740514273, 1925.9711104333428, 6916.6887490062945, 8988.235999939996, 8993.73018809436, 9001.549922503882, 9001.684941027066, 7918.564478953655, 8114.188581141882, 8475.83048339033, 8689.435186712859, 8799.780005499866, 2367.4081479630095, 2410.0, 2411.986965474505, 2412.7587241275905, 2416.5529377065213, 2378.4053986503372, 2399.592069348215, 2420.9394765130924, 2422.358075110097, 2423.3820375804144, 2051.548659294953, 2387.7254115776736, 2409.915652952147, 2411.397150712322, 2416.66166736657, 2358.952820943589, 2373.1693907132453, 2379.679278000709, 2381.0, 2398.7241467231142, 2354.281944007077, 2367.2188177901253, 2372.525494901027, 2375.489269807, 2376.120835290936, 8275.213854683796, 8343.45767525109, 8344.582770861492, 8350.039745429292, 8352.958235208785, 5226.843194704127, 5400.648957817729, 5407.621466497347, 5411.21537377082, 5434.239206511092, 3650.5619325681087, 3661.7070634349457, 3686.4101743721144, 3724.776513409204, 3728.5898551159353, 924.96300147994, 1026.184183574053, 1031.7987992341496, 1042.4110377636687, 1048.9108425783818, 11376.260543064674, 11622.07023438123, 11783.234089784137, 11803.822942655866, 11813.64559063238, 4556.384888040072, 4622.884427889262, 4819.855404337875, 4821.951780482221, 4824.517548245218, 9996.100350968447, 10007.399556026661, 10015.449150296803, 10020.64927727526, 10054.547545360476, 4450.3991961085485, 5344.278522399504, 5345.65253258537, 5347.491988261109, 5367.35591728995, 4401.537838526972, 4561.521040290788, 4698.88252793676, 4804.807807687724, 4901.730404827697, 3869.1294458746556, 3916.784576848244, 4015.0, 4031.5766844481495, 4033.8184781684895, 1104.679642903549, 1288.5747703257903, 1299.5581502289267, 1300.8894243989366, 1312.737452509502, 804.4730701390598, 887.7748706784596, 891.9687810926587, 911.1799380557419, 943.1087622197095, 5615.326160860723, 5759.769609215668, 5858.297004359504, 5992.280926288873, 6030.427109424598, 748.0200936772845, 855.4183155454226, 875.1336177184542, 932.9253659707207, 996.4818294486971, 3822.8470861165797, 3854.6338097880657, 3915.530136383595, 3981.5620281768984, 4046.59534046593, 10380.844287335696, 10846.67459976202, 10860.945695271474, 10873.456327183609, 10877.728056798485, 10813.459327033617, 10821.783564328669, 10822.0, 10828.837567436494, 10831.0, 6908.894576867677, 7746.573938433327, 8115.1073381927945, 8285.0, 8302.501849889026, 5709.0, 5953.613015154001, 6035.39646035393, 6064.818055458343, 6084.7566097357385, 9662.806743865223, 9755.0, 9998.650047248311, 10057.547410366697, 10215.387076775418, 9880.50597470138, 10266.0, 10299.845502317621, 10449.111825494614, 10455.581776728846, 7587.506812057199, 7894.447388682966, 8085.393595480245, 8203.015638123463, 8203.548804815793, 8214.917850821654, 8300.501969881827, 8304.086550479564, 8431.704890328809, 8436.11420800807, 7357.668904899186, 7370.226126256666, 7458.813529661585, 7486.064241969754, 7550.433717471102, 7244.529105608119, 7290.380317673057, 7304.598247096461, 7323.926760732537, 7520.623968801648, 6874.690638921361, 7042.577445353295, 7044.471664625071, 7051.894221586783, 7057.7176912922905, 4768.499307572793, 4883.316335713074, 4905.7792399342825, 4909.337239472626, 5014.974925125351, 4201.243776120205, 4766.594839438687, 4863.686804562819, 5085.5931525477135, 5111.872203194801], "value": 4581.611838692113, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.33256028527967, 293.2083374887802, 305.3511288511912, 314.8347117763174, 317.33518279205055, 5127.05149547334, 5308.203769434586, 5536.501714845663, 5545.279113715222, 5578.748956296966, 4870.9269360959615, 5099.337086178801, 5395.541378982782, 5402.702851343184, 5421.891562168754, 3228.8053420234546, 3426.725861931052, 4109.486314210723, 4148.365787724794, 4152.626263636272, 3728.422094575332, 4123.525794533637, 4148.232576973254, 4156.916861662765, 4181.749095054306, 3415.019288812483, 3866.709996750241, 3945.230680017398, 4126.216018956388, 4140.503139623234, 4111.992561822372, 4120.917581648365, 4131.256373852705, 4137.379393091031, 4152.979235103835, 2964.7479964203067, 3257.03917344384, 3550.1479644885044, 3563.9109022274392, 3577.3918433866306, 5047.066292735819, 5266.552343050846, 5273.367195936474, 5298.046351656701, 5344.14493681013, 3969.980150099231, 4339.849939765933, 4716.245400735866, 4740.360051393087, 4748.6438517111355, 4710.37301278014, 5566.777328906879, 5721.542276617861, 5740.56945729072, 5752.9424705753245, 3771.9434208486896, 3829.8085095745378, 3945.3490174121234, 3956.4460975463458, 3984.920301593981, 5608.242887210256, 5653.802116925929, 6007.339192688801, 6011.939880601181, 6021.217241758587, 3069.677683843209, 3392.067181525051, 3611.476335931328, 4004.799760011988, 4031.879043628696, 4040.919181616351, 4056.782965110514, 4080.897977550585, 4089.4070359798257, 4123.958760412417, 4606.976965115153, 4734.928976065362, 4746.342048452878, 4748.7863046163, 4785.832495862628, 5085.262636917626, 5622.493975542221, 5695.572832037614, 5724.971375143181, 5729.398413166641, 1203.6218529783348, 1347.9258640774656, 1372.72336726845, 1380.9930950345183, 1388.4654408052972, 715.4991505945754, 740.4779630360583, 834.7120243515977, 853.9957300213583, 856.2336708645852, 3734.458503516975, 3931.941020884689, 4326.394304797331, 4389.604935555815, 4518.909621807546, 4218.894527636834, 4841.9031619367415, 4889.975550122226, 4897.4328214970865, 4922.4339200992345, 3848.422736589534, 3863.072862512977, 4003.957981429434, 4061.3095773718537, 4522.638188944877, 2242.3742786479997, 2330.3570982457327, 2468.638762734749, 2599.766021058114, 2609.9347516312246, 4450.8442204523335, 4459.881556260751, 5006.749662516932, 5121.308623335876, 5129.871103492009, 4357.302831546937, 5070.898582028412, 5133.948660513422, 5136.409312928986, 5154.587632989352, 4246.915061698749, 4719.622430205575, 5090.643654944119, 5706.3152421709665, 6184.288806787097, 5640.266765320482, 5966.433188847052, 5970.432808883149, 5985.970070149621, 5986.138940383341, 5636.323641163087, 5908.645481271137, 5978.953683105527, 5993.332966829719, 5994.910076348771, 5097.80201652604, 5770.7782503735525, 5800.854978625568, 6075.417947372437, 6103.816885493442, 3053.374058318084, 3435.3301106285035, 3471.5005196258653, 3682.8895133146048, 3998.900027499336, 5201.973990130176, 5712.115381732198, 5848.56135789826, 6016.42843929826, 6022.1870047543025, 5303.018941495836, 5387.922415516834, 5448.700321482356, 5571.972140139435, 5862.589618726819, 5604.439556044526, 5662.8018019370775, 5745.299709136699, 5758.712064396847, 5790.7973220937065, 4768.213244814567, 5403.540699040625, 5626.859328516656, 5698.686572238566, 5719.656820590778, 4946.35697359341, 5460.31746031746, 5705.914411283917, 5769.624974376652, 5790.0, 4585.541445855388, 4784.06245470585, 5601.691906945157, 5669.943300566942, 5684.8010319638615], "value": 3994.1976409281565, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.0994437064925, 714.8748968930432, 762.302493218705, 782.9843403131938, 825.1748251748252, 13482.595522134327, 14994.0, 16266.75599866003, 16289.674206515863, 16322.836771632281, 14713.337899794507, 14881.479148229813, 14889.0, 14897.0, 14920.179390133566, 674.7672053141658, 692.7540723043322, 838.1618381618382, 842.6587232170965, 857.9270761985208, 832.3258160889699, 844.8352571248608, 846.8221673448584, 847.8601030829936, 866.8613021916494, 887.6050157679836, 889.9911000890005, 890.2388457868526, 890.8351954888334, 890.8396488632044, 705.400409651797, 720.6540860386991, 721.8339781850204, 884.4648987362649, 887.6094518411878, 542.7123624479033, 661.3155384177353, 678.6131904814258, 678.7386856060402, 679.4224908827457, 14697.265136743226, 14714.969952103356, 14720.779188312184, 14732.70534589313, 14751.852481475154, 10773.5690572377, 13564.796528052088, 13907.721845563032, 13955.58133256004, 14062.929685351508, 12422.937885310606, 12740.299283539402, 12783.16909400886, 12836.550720724823, 13375.0, 3912.197999410114, 4533.116042371739, 4637.489876113625, 4674.836380726692, 4686.461056978457, 94532.0, 116307.0, 116569.33722651083, 117298.0, 117306.0, 10939.453027348602, 11000.944995275133, 11019.283746556448, 11096.167787415976, 11322.716932076764, 21860.344189674335, 21866.781332186794, 21868.562628747655, 21899.67150492744, 21907.67138492924, 7766.611669416619, 7855.842883142305, 7950.6422211000645, 7963.362930965509, 8137.145599712064, 3972.145988612462, 4229.492460904711, 4315.0, 4342.2835232186335, 4365.219379109188, 3406.318736252759, 3553.1650062235353, 3558.0393293811044, 3560.572731272264, 3560.750747447654, 651.6155468273741, 655.5804285257439, 659.2287024181719, 661.24287690594, 665.4875745675803, 590.9497692696083, 597.8864015836978, 622.0886401421939, 644.3009334871716, 673.4780545077604, 8437.0, 8951.015388307282, 8998.14517620825, 9075.682351117679, 9106.681266155652, 512.50799232737, 572.9512991395694, 601.6991504247876, 614.9262088549314, 631.7883509024529, 4315.352697095461, 4393.319035549495, 4407.537208593051, 4421.204183247047, 4491.932621010687, 167629.0, 167722.0, 168193.31806681777, 169616.4557531638, 172703.0, 106220.0, 142968.28515857353, 145825.0, 146728.0, 150404.0, 76576.0, 101316.0, 123763.0, 124316.37841810733, 126658.36670816586, 8407.663693452194, 8444.831103377775, 8553.230209281195, 8616.569171541523, 8814.2948564116, 60141.69929150326, 64461.03308450283, 67155.0, 68485.0, 69244.0, 99982.50026249758, 100827.49586252315, 104424.0, 107451.46274268579, 109113.0, 10664.520096595517, 10882.782344353227, 10938.945305273422, 11236.606718764804, 11364.602238921598, 12136.393180341125, 12162.878371216175, 12220.938895305466, 12235.632931012084, 12283.0, 9682.709518714168, 11677.357745324089, 11722.882771172179, 11789.882101179222, 11946.641600751991, 11490.310581365145, 12781.488740450277, 13333.866581346938, 13359.933200333937, 13961.720765584834, 13324.0, 13371.502539082103, 13631.931840340734, 13885.652858678613, 14182.858171418155, 11095.112391008539, 11347.37589432589, 11382.0, 11541.480633371351, 11764.941175294069, 9539.8041656046, 9880.25898057663, 11309.829838423793, 11438.428078596204, 11598.18812683138], "value": 7726.5642649770625, "unit": "score"}], "name": "dromaeo_css", "value": 7304.314445486546, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 110.0, 114.0, 113.0, 112.0, 124.0, 111.0, 114.0, 111.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 108.0, 129.0, 160.0, 105.0, 133.0, 129.0, 107.0, 158.0, 106.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 185.0, 183.0, 183.0, 183.0, 181.0, 184.0, 183.0, 184.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 76.0, 153.0, 79.0, 81.0, 79.0, 66.0, 142.0, 57.0], "value": 88.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 138.0, 70.0, 71.0, 75.0, 119.0, 68.0, 68.0, 116.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 95.0, 135.0, 113.0, 88.0, 83.0, 87.0, 83.0], "value": 94.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 137.0, 93.0, 103.0, 142.0, 93.0, 93.0, 93.0, 96.0, 95.0], "value": 104.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 84.0, 85.0, 85.0, 88.0, 89.0, 85.0, 85.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 54.0, 53.0, 54.0, 54.0, 54.0, 53.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 80.0, 75.0, 72.0, 68.0, 71.0, 70.0, 68.0, 70.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 83.0, 88.0, 85.0, 86.0, 88.0, 84.0, 86.0, 85.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 140.0, 136.0, 138.0, 139.0, 149.0, 147.0, 153.0, 176.0, 149.0], "value": 146.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 168.0, 167.0, 168.0, 164.0, 170.0, 167.0, 168.0, 170.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 67.0, 66.0, 69.0, 66.0, 67.0, 65.0, 66.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1478.7, "alertThreshold": 2.0}]} 20:03:41 INFO - Return code: 0 20:03:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:03:41 INFO - # TBPL SUCCESS # 20:03:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:03:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:03:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:03:41 INFO - 2016-09-07 20:03:41.281 screenresolution[1666:9815] starting screenresolution argv=screenresolution get 20:03:41 INFO - 2016-09-07 20:03:41.283 screenresolution[1666:9815] Display 0: 1600x1200x32@60 20:03:41 INFO - 2016-09-07 20:03:41.288 screenresolution[1667:9818] starting screenresolution argv=screenresolution list 20:03:41 INFO - Available Modes on Display 0 20:03:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:03:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:03:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:03:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:03:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:03:41 INFO - Intel Iris: 20:03:41 INFO - Chipset Model: Intel Iris 20:03:41 INFO - Type: GPU 20:03:41 INFO - Bus: Built-In 20:03:41 INFO - VRAM (Dynamic, Max): 1536 MB 20:03:41 INFO - Vendor: Intel (0x8086) 20:03:41 INFO - Device ID: 0x0a2e 20:03:41 INFO - Revision ID: 0x0009 20:03:41 INFO - Displays: 20:03:41 INFO - Display: 20:03:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:03:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:03:41 INFO - Display Serial Number: 1600x1200 60 20:03:41 INFO - Main Display: Yes 20:03:41 INFO - Mirror: Off 20:03:41 INFO - Online: Yes 20:03:41 INFO - Rotation: Supported 20:03:41 INFO - Return code: 0 20:03:41 INFO - Running post-action listener: _resource_record_post_action 20:03:41 INFO - [mozharness: 2016-09-08 03:03:41.514353Z] Finished run-tests step (success) 20:03:41 INFO - Running post-run listener: _resource_record_post_run 20:03:41 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 44987904; Write bytes: 703127040; Read time: 430; Write time: 2494 20:03:41 INFO - TinderboxPrint: CPU usage
53.5% 20:03:41 INFO - TinderboxPrint: I/O read bytes / time
44,987,904 / 430 20:03:41 INFO - TinderboxPrint: I/O write bytes / time
703,127,040 / 2,494 20:03:41 INFO - TinderboxPrint: CPU idle
2,252.0 (46.5%) 20:03:41 INFO - TinderboxPrint: CPU system
967.3 (20.0%) 20:03:41 INFO - TinderboxPrint: CPU user
1,626.4 (33.6%) 20:03:41 INFO - TinderboxPrint: Swap in / out
458,096,640 / 0 20:03:41 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387914240; Write bytes: 382867456; Read time: 29321; Write time: 818 20:03:41 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 44013056; Write bytes: 294061568; Read time: 414; Write time: 1625 20:03:42 INFO - Running post-run listener: _upload_blobber_files 20:03:42 INFO - Blob upload gear active. 20:03:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:03:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:03:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:03:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:03:42 INFO - (blobuploader) - INFO - Open directory for files ... 20:03:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:03:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:03:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:03:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:03:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:03:43 INFO - (blobuploader) - INFO - Done attempting. 20:03:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:03:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:03:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:03:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:03:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:03:44 INFO - (blobuploader) - INFO - Done attempting. 20:03:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:03:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:03:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:03:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:03:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:03:46 INFO - (blobuploader) - INFO - Done attempting. 20:03:46 INFO - (blobuploader) - INFO - Iteration through files over. 20:03:46 INFO - Return code: 0 20:03:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:03:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:03:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/093279a1c71aacfe5329d8c105870412986fd21f07bbb901b765f72355db238046eea5496369a26172a0617c1f6fe64698f04bbf4bba128e634cc910aea5d499", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22b0170629c3a8266f8e03f638e942f10b69f82bb50b51a63a2ba6615ae7822834d9b3e472bfea5001aa067e0cd944b5c449e9379464aa8251e5d30b91353264", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10a35ceca8340f29eb9c2bd8892d94a6b0a0e88ee9df4af470efadd1c46205ef45187b12ab43f90080c1e0bfb436e8e4c4969c3b6e222fc25c255dd940232b6b"} 20:03:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:03:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:03:46 INFO - Contents: 20:03:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/093279a1c71aacfe5329d8c105870412986fd21f07bbb901b765f72355db238046eea5496369a26172a0617c1f6fe64698f04bbf4bba128e634cc910aea5d499", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22b0170629c3a8266f8e03f638e942f10b69f82bb50b51a63a2ba6615ae7822834d9b3e472bfea5001aa067e0cd944b5c449e9379464aa8251e5d30b91353264", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10a35ceca8340f29eb9c2bd8892d94a6b0a0e88ee9df4af470efadd1c46205ef45187b12ab43f90080c1e0bfb436e8e4c4969c3b6e222fc25c255dd940232b6b"} 20:03:46 INFO - Running post-run listener: copy_logs_to_upload_dir 20:03:46 INFO - Copying logs to upload dir... 20:03:46 INFO - mkdir: /builds/slave/test/build/upload/logs 20:03:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.801961 ========= master_lag: 90.90 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 43 secs) (at 2016-09-07 20:05:17.634633) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 20:05:17.637672) ========= 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.sja40nuIFO/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.28YETixZAV/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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/093279a1c71aacfe5329d8c105870412986fd21f07bbb901b765f72355db238046eea5496369a26172a0617c1f6fe64698f04bbf4bba128e634cc910aea5d499", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22b0170629c3a8266f8e03f638e942f10b69f82bb50b51a63a2ba6615ae7822834d9b3e472bfea5001aa067e0cd944b5c449e9379464aa8251e5d30b91353264", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10a35ceca8340f29eb9c2bd8892d94a6b0a0e88ee9df4af470efadd1c46205ef45187b12ab43f90080c1e0bfb436e8e4c4969c3b6e222fc25c255dd940232b6b"} 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.011759 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/093279a1c71aacfe5329d8c105870412986fd21f07bbb901b765f72355db238046eea5496369a26172a0617c1f6fe64698f04bbf4bba128e634cc910aea5d499", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22b0170629c3a8266f8e03f638e942f10b69f82bb50b51a63a2ba6615ae7822834d9b3e472bfea5001aa067e0cd944b5c449e9379464aa8251e5d30b91353264", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10a35ceca8340f29eb9c2bd8892d94a6b0a0e88ee9df4af470efadd1c46205ef45187b12ab43f90080c1e0bfb436e8e4c4969c3b6e222fc25c255dd940232b6b"}' ========= master_lag: 0.72 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 20:05:18.366472) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 20:05:18.366776) ========= 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.sja40nuIFO/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.28YETixZAV/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.005006 ========= master_lag: 1.23 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 20:05:19.600242) ========= ========= Started reboot skipped (results: 3, elapsed: 7 secs) (at 2016-09-07 20:05:19.600534) ========= ========= Finished reboot skipped (results: 3, elapsed: 7 secs) (at 2016-09-07 20:05:27.068807) ========= ========= Total master_lag: 93.12 =========