builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0248 starttime: 1471410722.65 results: success (0) buildid: 20160816194715 builduid: 5e3a1b1e4c74453bb8c5864110661cfb revision: f0067001c059ff57d6927c6da5a1605f1d29a449 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 22:12:02.650321) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 22:12:02.650761) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 22:12:02.651058) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 22:12:02.675640) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:12:02.675986) ========= 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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners TMPDIR=/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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-08-16 22:12:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.4M=0.001s 2016-08-16 22:12:02 (19.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094996 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:12:02.793720) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:12:02.794060) ========= 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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners TMPDIR=/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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.026431 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:12:02.842835) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-16 22:12:02.843208) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev f0067001c059ff57d6927c6da5a1605f1d29a449 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev f0067001c059ff57d6927c6da5a1605f1d29a449 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6LnB9CbwVj/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.n6LAv6XSNa/Listeners TMPDIR=/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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-08-16 22:12:02,917 truncating revision to first 12 chars 2016-08-16 22:12:02,917 Setting DEBUG logging. 2016-08-16 22:12:02,917 attempt 1/10 2016-08-16 22:12:02,917 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/f0067001c059?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 22:12:04,374 unpacking tar archive at: autoland-f0067001c059/testing/mozharness/ program finished with exit code 0 elapsedTime=1.740457 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-16 22:12:04.606012) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 22:12:04.606339) ========= script_repo_revision: f0067001c059ff57d6927c6da5a1605f1d29a449 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 22:12:04.606731) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 22:12:04.607019) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 22:12:04.626591) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-16 22:12:04.626938) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6LnB9CbwVj/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.n6LAv6XSNa/Listeners TMPDIR=/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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 22:12:04 INFO - MultiFileLogger online at 20160816 22:12:04 in /builds/slave/test 22:12:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 22:12:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:12:04 INFO - {'append_to_log': False, 22:12:04 INFO - 'base_work_dir': '/builds/slave/test', 22:12:04 INFO - 'blob_upload_branch': 'Autoland', 22:12:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:12:04 INFO - 'branch': 'Autoland', 22:12:04 INFO - 'buildbot_json_path': 'buildprops.json', 22:12:04 INFO - 'config_files': ('talos/mac_config.py',), 22:12:04 INFO - 'default_actions': ('clobber', 22:12:04 INFO - 'read-buildbot-config', 22:12:04 INFO - 'download-and-extract', 22:12:04 INFO - 'populate-webroot', 22:12:04 INFO - 'create-virtualenv', 22:12:04 INFO - 'install', 22:12:04 INFO - 'run-tests'), 22:12:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:12:04 INFO - 'download_minidump_stackwalk': True, 22:12:04 INFO - 'download_symbols': 'ondemand', 22:12:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:12:04 INFO - 'tooltool.py': '/tools/tooltool.py', 22:12:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:12:04 INFO - '/tools/misc-python/virtualenv.py')}, 22:12:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:12:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:12:04 INFO - 'installer_path': 'installer.exe', 22:12:04 INFO - 'log_level': 'info', 22:12:04 INFO - 'log_name': 'talos', 22:12:04 INFO - 'log_to_console': True, 22:12:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:12:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:12:04 INFO - 'opt_config_files': (), 22:12:04 INFO - 'pip_index': False, 22:12:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:12:04 INFO - 'cmd': ('bash', 22:12:04 INFO - '-c', 22:12:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:12:04 INFO - 'enabled': True, 22:12:04 INFO - 'halt_on_failure': False, 22:12:04 INFO - 'name': 'check_screen_resolution'},), 22:12:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:12:04 INFO - 'cmd': ('bash', 22:12:04 INFO - '-c', 22:12:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:12:04 INFO - 'enabled': True, 22:12:04 INFO - 'halt_on_failure': False, 22:12:04 INFO - 'name': 'check_screen_resolution'},), 22:12:04 INFO - 'run_cmd_checks_enabled': True, 22:12:04 INFO - 'sps_profile': False, 22:12:04 INFO - 'sps_profile_interval': 0, 22:12:04 INFO - 'suite': 'dromaeojs-e10s', 22:12:04 INFO - 'system_bits': '32', 22:12:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:12:04 INFO - 'title': 't-yosemite-r7-0248', 22:12:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:12:04 INFO - 'use_talos_json': True, 22:12:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:12:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:12:04 INFO - 'work_dir': 'build'} 22:12:04 INFO - [mozharness: 2016-08-17 05:12:04.816440Z] Running clobber step. 22:12:04 INFO - Running pre-action listener: _resource_record_pre_action 22:12:04 INFO - Running main action method: clobber 22:12:04 INFO - rmtree: /builds/slave/test/build 22:12:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:12:06 INFO - Running post-action listener: _resource_record_post_action 22:12:06 INFO - [mozharness: 2016-08-17 05:12:06.868142Z] Finished clobber step (success) 22:12:06 INFO - [mozharness: 2016-08-17 05:12:06.868270Z] Running read-buildbot-config step. 22:12:06 INFO - Running pre-action listener: _resource_record_pre_action 22:12:06 INFO - Running main action method: read_buildbot_config 22:12:06 INFO - Using buildbot properties: 22:12:06 INFO - { 22:12:06 INFO - "project": "", 22:12:06 INFO - "product": "firefox", 22:12:06 INFO - "script_repo_revision": "production", 22:12:06 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 22:12:06 INFO - "repository": "", 22:12:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 22:12:06 INFO - "buildid": "20160816194715", 22:12:06 INFO - "pgo_build": "False", 22:12:06 INFO - "basedir": "/builds/slave/test", 22:12:06 INFO - "buildnumber": 102, 22:12:06 INFO - "slavename": "t-yosemite-r7-0248", 22:12:06 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 22:12:06 INFO - "platform": "yosemite_r7", 22:12:06 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 22:12:06 INFO - "branch": "autoland", 22:12:06 INFO - "repo_path": "integration/autoland", 22:12:06 INFO - "revision": "f0067001c059ff57d6927c6da5a1605f1d29a449", 22:12:06 INFO - "stage_platform": "macosx64", 22:12:06 INFO - "builduid": "5e3a1b1e4c74453bb8c5864110661cfb", 22:12:06 INFO - "slavebuilddir": "test" 22:12:06 INFO - } 22:12:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 22:12:06 INFO - Running post-action listener: _resource_record_post_action 22:12:06 INFO - [mozharness: 2016-08-17 05:12:06.871811Z] Finished read-buildbot-config step (success) 22:12:06 INFO - [mozharness: 2016-08-17 05:12:06.871932Z] Running download-and-extract step. 22:12:06 INFO - Running pre-action listener: _resource_record_pre_action 22:12:06 INFO - Running main action method: download_and_extract 22:12:06 INFO - mkdir: /builds/slave/test/build/tests 22:12: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')]} 22:12:06 INFO - trying https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 22:12:06 INFO - Downloading https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/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 22:12:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/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 22:12:07 INFO - Downloaded 1199 bytes. 22:12:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 22:12:07 INFO - Using the following test package requirements: 22:12:07 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:12:07 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:12:07 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 22:12:07 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:12:07 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 22:12:07 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:12:07 INFO - u'jsshell-mac.zip'], 22:12:07 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:12:07 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 22:12:07 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:12:07 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:12:07 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 22:12:07 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:12:07 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 22:12:07 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:12:07 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 22:12:07 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:12:07 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 22:12:07 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:12:07 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')]} 22:12:07 INFO - trying https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:12:07 INFO - Downloading https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:12:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:12:08 INFO - Downloaded 16333857 bytes. 22:12:08 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 22:12:15 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 22:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:15 INFO - trying https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:12:15 INFO - Downloading https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:12:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:12:16 INFO - Downloaded 16333857 bytes. 22:12:16 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 22:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:23 INFO - trying https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 22:12:23 INFO - Downloading https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 22:12:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:12:24 INFO - Downloaded 14413719 bytes. 22:12:24 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 22:12:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:24 INFO - trying https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:12:24 INFO - Downloading https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 22:12:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/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 22:12:27 INFO - Downloaded 111146416 bytes. 22:12:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:12:27 INFO - mkdir: /builds/slave/test/properties 22:12:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:12:27 INFO - Writing to file /builds/slave/test/properties/build_url 22:12:27 INFO - Contents: 22:12:27 INFO - build_url:https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:12:28 INFO - Running post-action listener: _resource_record_post_action 22:12:28 INFO - Running post-action listener: set_extra_try_arguments 22:12:28 INFO - [mozharness: 2016-08-17 05:12:28.314895Z] Finished download-and-extract step (success) 22:12:28 INFO - [mozharness: 2016-08-17 05:12:28.315011Z] Running populate-webroot step. 22:12:28 INFO - Running pre-action listener: _resource_record_pre_action 22:12:28 INFO - Running main action method: populate_webroot 22:12:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:12:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:12:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:12:28 INFO - '--tppagecycles', 22:12:28 INFO - '7'], 22:12:28 INFO - 'tests': ['tsvgm']}}, 22:12:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:12:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:12:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:12:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:12:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:12:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:12:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:28 INFO - 'talos_options': ['--disable-e10s'], 22:12:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:12:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:12:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:28 INFO - 'talos_options': ['--disable-e10s'], 22:12:28 INFO - 'tests': ['damp', 'tps']}, 22:12:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:28 INFO - 'tests': ['damp', 'tps']}, 22:12:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:12:28 INFO - 'tests': ['dromaeo_dom']}, 22:12:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:12:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:12:28 INFO - 'tests': ['basic_compositor_video']}, 22:12:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:12:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:12:28 INFO - 'tests': ['a11yr', 22:12:28 INFO - 'ts_paint', 22:12:28 INFO - 'tpaint', 22:12:28 INFO - 'sessionrestore', 22:12:28 INFO - 'sessionrestore_no_auto_restore', 22:12:28 INFO - 'tabpaint']}, 22:12:28 INFO - 'other-e10s': {'tests': ['a11yr', 22:12:28 INFO - 'ts_paint', 22:12:28 INFO - 'tpaint', 22:12:28 INFO - 'sessionrestore', 22:12:28 INFO - 'sessionrestore_no_auto_restore', 22:12:28 INFO - 'tabpaint']}, 22:12:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:12:28 INFO - 'ts_paint', 22:12:28 INFO - 'tpaint', 22:12:28 INFO - 'sessionrestore', 22:12:28 INFO - 'sessionrestore_no_auto_restore', 22:12:28 INFO - 'tabpaint']}, 22:12:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:12:28 INFO - 'ts_paint', 22:12:28 INFO - 'tpaint', 22:12:28 INFO - 'sessionrestore', 22:12:28 INFO - 'sessionrestore_no_auto_restore', 22:12:28 INFO - 'tabpaint']}, 22:12:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:12:28 INFO - 'tests': ['a11yr', 22:12:28 INFO - 'ts_paint', 22:12:28 INFO - 'tpaint', 22:12:28 INFO - 'sessionrestore', 22:12:28 INFO - 'sessionrestore_no_auto_restore', 22:12:28 INFO - 'tabpaint']}, 22:12:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:12:28 INFO - 'tests': ['a11yr', 22:12:28 INFO - 'ts_paint', 22:12:28 INFO - 'tpaint', 22:12:28 INFO - 'sessionrestore', 22:12:28 INFO - 'sessionrestore_no_auto_restore', 22:12:28 INFO - 'tabpaint']}, 22:12:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:12:28 INFO - 'tests': ['tsvgx', 22:12:28 INFO - 'tsvgr_opacity', 22:12:28 INFO - 'tart', 22:12:28 INFO - 'tscrollx', 22:12:28 INFO - 'cart']}, 22:12:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:12:28 INFO - 'tsvgr_opacity', 22:12:28 INFO - 'tart', 22:12:28 INFO - 'tscrollx', 22:12:28 INFO - 'cart']}, 22:12:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:28 INFO - 'talos_options': ['--disable-e10s'], 22:12:28 INFO - 'tests': ['tp5o']}, 22:12:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:28 INFO - 'tests': ['tp5o']}, 22:12:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:28 INFO - 'talos_options': ['--disable-e10s', 22:12:28 INFO - '--xperf_path', 22:12:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:12:28 INFO - 'tests': ['tp5n']}, 22:12:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:12:28 INFO - 'talos_options': ['--xperf_path', 22:12:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:12:28 INFO - 'tests': ['tp5n']}}, 22:12:28 INFO - 'talos.zip': {'path': '', 22:12:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:12:28 INFO - Running post-action listener: _resource_record_post_action 22:12:28 INFO - [mozharness: 2016-08-17 05:12:28.333587Z] Finished populate-webroot step (success) 22:12:28 INFO - [mozharness: 2016-08-17 05:12:28.333732Z] Running create-virtualenv step. 22:12:28 INFO - Running pre-action listener: _resource_record_pre_action 22:12:28 INFO - Running main action method: create_virtualenv 22:12:28 INFO - Creating virtualenv /builds/slave/test/build/venv 22:12:28 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 22:12:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:12:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:12:28 INFO - Using real prefix '/tools/python27' 22:12:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:12:29 INFO - Installing distribute.............................................................................................................................................................................................done. 22:12:32 INFO - Installing pip.................done. 22:12:32 INFO - Return code: 0 22:12:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:12:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:32 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 0x10e39ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e431618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bc0d52770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e300d30>, '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 0x10e065db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f8b20>, '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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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 22:12:32 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 22:12:32 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 22:12:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6LnB9CbwVj/Render', 22:12:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:32 INFO - 'HOME': '/Users/cltbld', 22:12:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:32 INFO - 'LOGNAME': 'cltbld', 22:12:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:32 INFO - 'MOZ_NO_REMOTE': '1', 22:12:32 INFO - 'NO_EM_RESTART': '1', 22:12:32 INFO - 'PAGER': '/bin/cat', 22:12:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:32 INFO - 'PWD': '/builds/slave/test', 22:12:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:32 INFO - 'SHELL': '/bin/bash', 22:12:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n6LAv6XSNa/Listeners', 22:12:32 INFO - 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/', 22:12:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:32 INFO - 'USER': 'cltbld', 22:12:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:32 INFO - 'XPC_FLAGS': '0x0', 22:12:32 INFO - 'XPC_SERVICE_NAME': '0', 22:12:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:32 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 22:12:32 INFO - Cleaning up... 22:12:32 INFO - Return code: 0 22:12:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:12:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:32 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 0x10e39ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e431618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bc0d52770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e300d30>, '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 0x10e065db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f8b20>, '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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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 22:12:32 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 22:12:32 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 22:12:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6LnB9CbwVj/Render', 22:12:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:32 INFO - 'HOME': '/Users/cltbld', 22:12:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:32 INFO - 'LOGNAME': 'cltbld', 22:12:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:32 INFO - 'MOZ_NO_REMOTE': '1', 22:12:32 INFO - 'NO_EM_RESTART': '1', 22:12:32 INFO - 'PAGER': '/bin/cat', 22:12:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:32 INFO - 'PWD': '/builds/slave/test', 22:12:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:32 INFO - 'SHELL': '/bin/bash', 22:12:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n6LAv6XSNa/Listeners', 22:12:32 INFO - 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/', 22:12:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:32 INFO - 'USER': 'cltbld', 22:12:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:32 INFO - 'XPC_FLAGS': '0x0', 22:12:32 INFO - 'XPC_SERVICE_NAME': '0', 22:12:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:32 INFO - Downloading/unpacking psutil>=3.1.1 22:12:32 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 22:12:32 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 22:12:32 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 22:12:32 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 22:12:32 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 22:12:32 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 22:12:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:12:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:12:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:12:35 INFO - Installing collected packages: psutil 22:12:35 INFO - Running setup.py install for psutil 22:12:35 INFO - building 'psutil._psutil_osx' extension 22:12:35 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 22:12:36 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 22:12:36 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 22:12:36 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 22:12:36 INFO - building 'psutil._psutil_posix' extension 22:12:36 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 22:12:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:12:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:12:36 INFO - ^ 22:12:36 INFO - 1 warning generated. 22:12:36 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 22:12:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:12:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:12:36 INFO - Successfully installed psutil 22:12:36 INFO - Cleaning up... 22:12:37 INFO - Return code: 0 22:12:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:12:37 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')]} 22:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:37 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')]} 22:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:37 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 0x10e39ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e431618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bc0d52770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e300d30>, '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 0x10e065db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f8b20>, '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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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 22:12:37 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 22:12:37 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 22:12:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6LnB9CbwVj/Render', 22:12:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:37 INFO - 'HOME': '/Users/cltbld', 22:12:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:37 INFO - 'LOGNAME': 'cltbld', 22:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:37 INFO - 'MOZ_NO_REMOTE': '1', 22:12:37 INFO - 'NO_EM_RESTART': '1', 22:12:37 INFO - 'PAGER': '/bin/cat', 22:12:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:37 INFO - 'PWD': '/builds/slave/test', 22:12:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:37 INFO - 'SHELL': '/bin/bash', 22:12:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n6LAv6XSNa/Listeners', 22:12:37 INFO - 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/', 22:12:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:37 INFO - 'USER': 'cltbld', 22:12:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:37 INFO - 'XPC_FLAGS': '0x0', 22:12:37 INFO - 'XPC_SERVICE_NAME': '0', 22:12:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:12:37 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 22:12:37 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 22:12:37 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 22:12:37 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 22:12:37 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 22:12:37 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 22:12:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:12:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:12:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:12:39 INFO - Installing collected packages: mozsystemmonitor 22:12:39 INFO - Running setup.py install for mozsystemmonitor 22:12:40 INFO - Successfully installed mozsystemmonitor 22:12:40 INFO - Cleaning up... 22:12:40 INFO - Return code: 0 22:12:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:40 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 0x10e39ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e431618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bc0d52770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e300d30>, '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 0x10e065db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f8b20>, '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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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 22:12:40 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 22:12:40 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 22:12:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6LnB9CbwVj/Render', 22:12:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:40 INFO - 'HOME': '/Users/cltbld', 22:12:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:40 INFO - 'LOGNAME': 'cltbld', 22:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:40 INFO - 'MOZ_NO_REMOTE': '1', 22:12:40 INFO - 'NO_EM_RESTART': '1', 22:12:40 INFO - 'PAGER': '/bin/cat', 22:12:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:40 INFO - 'PWD': '/builds/slave/test', 22:12:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:40 INFO - 'SHELL': '/bin/bash', 22:12:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n6LAv6XSNa/Listeners', 22:12:40 INFO - 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/', 22:12:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:40 INFO - 'USER': 'cltbld', 22:12:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:40 INFO - 'XPC_FLAGS': '0x0', 22:12:40 INFO - 'XPC_SERVICE_NAME': '0', 22:12:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:40 INFO - Downloading/unpacking jsonschema==2.5.1 22:12:40 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 22:12:40 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 22:12:40 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 22:12:40 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 22:12:40 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 22:12:40 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 22:12:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:12:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:12:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:12:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:12:43 INFO - Downloading functools32-3.2.3-2.tar.gz 22:12:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:12:43 INFO - warning: no files found matching '*.txt' 22:12:43 INFO - no previously-included directories found matching 'build' 22:12:43 INFO - no previously-included directories found matching 'dist' 22:12:43 INFO - no previously-included directories found matching '.git*' 22:12:43 INFO - Installing collected packages: jsonschema, functools32 22:12:43 INFO - Running setup.py install for functools32 22:12:43 INFO - warning: no files found matching '*.txt' 22:12:43 INFO - no previously-included directories found matching 'build' 22:12:43 INFO - no previously-included directories found matching 'dist' 22:12:43 INFO - no previously-included directories found matching '.git*' 22:12:43 INFO - Successfully installed jsonschema functools32 22:12:43 INFO - Cleaning up... 22:12:43 INFO - Return code: 0 22:12:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e39ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e431618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bc0d52770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e300d30>, '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 0x10e065db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f8b20>, '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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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 22:12:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:12:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:12:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6LnB9CbwVj/Render', 22:12:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:43 INFO - 'HOME': '/Users/cltbld', 22:12:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:43 INFO - 'LOGNAME': 'cltbld', 22:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:43 INFO - 'MOZ_NO_REMOTE': '1', 22:12:43 INFO - 'NO_EM_RESTART': '1', 22:12:43 INFO - 'PAGER': '/bin/cat', 22:12:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:43 INFO - 'PWD': '/builds/slave/test', 22:12:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:43 INFO - 'SHELL': '/bin/bash', 22:12:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n6LAv6XSNa/Listeners', 22:12:43 INFO - 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/', 22:12:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:43 INFO - 'USER': 'cltbld', 22:12:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:43 INFO - 'XPC_FLAGS': '0x0', 22:12:43 INFO - 'XPC_SERVICE_NAME': '0', 22:12:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:12:43 INFO - Cleaning up... 22:12:43 INFO - Return code: 0 22:12:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e39ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e431618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bc0d52770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e300d30>, '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 0x10e065db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f8b20>, '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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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 22:12:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:12:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:12:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6LnB9CbwVj/Render', 22:12:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:43 INFO - 'HOME': '/Users/cltbld', 22:12:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:43 INFO - 'LOGNAME': 'cltbld', 22:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:43 INFO - 'MOZ_NO_REMOTE': '1', 22:12:43 INFO - 'NO_EM_RESTART': '1', 22:12:43 INFO - 'PAGER': '/bin/cat', 22:12:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:43 INFO - 'PWD': '/builds/slave/test', 22:12:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:43 INFO - 'SHELL': '/bin/bash', 22:12:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n6LAv6XSNa/Listeners', 22:12:43 INFO - 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/', 22:12:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:43 INFO - 'USER': 'cltbld', 22:12:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:43 INFO - 'XPC_FLAGS': '0x0', 22:12:43 INFO - 'XPC_SERVICE_NAME': '0', 22:12:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:44 INFO - Downloading/unpacking blobuploader==1.2.4 22:12:44 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 22:12:44 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 22:12:44 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 22:12:44 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 22:12:44 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 22:12:44 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 22:12:46 INFO - Downloading blobuploader-1.2.4.tar.gz 22:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:12:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:12:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:12:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:12:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:12:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:12:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:12:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:12:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:12:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:12:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:12:47 INFO - Downloading docopt-0.6.1.tar.gz 22:12:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:12:47 INFO - Installing collected packages: blobuploader, requests, docopt 22:12:47 INFO - Running setup.py install for blobuploader 22:12:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:12:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:12:47 INFO - Running setup.py install for requests 22:12:48 INFO - Running setup.py install for docopt 22:12:48 INFO - Successfully installed blobuploader requests docopt 22:12:48 INFO - Cleaning up... 22:12:48 INFO - Return code: 0 22:12:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:48 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 0x10e39ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e431618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bc0d52770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e300d30>, '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 0x10e065db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f8b20>, '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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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 22:12:48 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 22:12:48 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 22:12:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6LnB9CbwVj/Render', 22:12:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:48 INFO - 'HOME': '/Users/cltbld', 22:12:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:48 INFO - 'LOGNAME': 'cltbld', 22:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:48 INFO - 'MOZ_NO_REMOTE': '1', 22:12:48 INFO - 'NO_EM_RESTART': '1', 22:12:48 INFO - 'PAGER': '/bin/cat', 22:12:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:48 INFO - 'PWD': '/builds/slave/test', 22:12:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:48 INFO - 'SHELL': '/bin/bash', 22:12:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n6LAv6XSNa/Listeners', 22:12:48 INFO - 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/', 22:12:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:48 INFO - 'USER': 'cltbld', 22:12:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:48 INFO - 'XPC_FLAGS': '0x0', 22:12:48 INFO - 'XPC_SERVICE_NAME': '0', 22:12:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:12:48 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-c8eiFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:12:49 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-TL4oWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:12:49 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-U732LL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:12:49 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-b6qkIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:12:49 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-3mQvAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:12:49 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-0LhTSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:12:49 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-R7fOMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:12:49 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-tYzuOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:12:49 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-E5EJPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:12:49 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-e7lGNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:12:49 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-XtV0KI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:12:50 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-4Cnves-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:12:50 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-_9EQQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:12:50 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-KFLvRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:12:50 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-YzCm28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:12:50 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-R4dBae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:12:50 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-jBcRGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:12:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:12:50 INFO - Running setup.py install for manifestparser 22:12:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:12:50 INFO - Running setup.py install for mozcrash 22:12:51 INFO - Running setup.py install for mozdebug 22:12:51 INFO - Running setup.py install for mozdevice 22:12:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:12:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:12:51 INFO - Running setup.py install for mozfile 22:12:51 INFO - Running setup.py install for mozhttpd 22:12:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:12:51 INFO - Running setup.py install for mozinfo 22:12:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:12:51 INFO - Running setup.py install for mozInstall 22:12:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:12:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:12:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:12:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:12:51 INFO - Running setup.py install for mozleak 22:12:52 INFO - Running setup.py install for mozlog 22:12:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:12:52 INFO - Running setup.py install for moznetwork 22:12:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:12:52 INFO - Running setup.py install for mozprocess 22:12:52 INFO - Running setup.py install for mozprofile 22:12:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:12:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:12:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:12:52 INFO - Running setup.py install for mozrunner 22:12:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:12:52 INFO - Running setup.py install for mozscreenshot 22:12:52 INFO - Running setup.py install for moztest 22:12:53 INFO - Running setup.py install for mozversion 22:12:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:12:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:12:53 INFO - Cleaning up... 22:12:53 INFO - Return code: 0 22:12:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:12:53 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')]} 22:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:53 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')]} 22:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:53 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 0x10e39ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e431618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bc0d52770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e300d30>, '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 0x10e065db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f8b20>, '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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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 22:12:53 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 22:12:53 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 22:12:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6LnB9CbwVj/Render', 22:12:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:53 INFO - 'HOME': '/Users/cltbld', 22:12:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:53 INFO - 'LOGNAME': 'cltbld', 22:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:53 INFO - 'MOZ_NO_REMOTE': '1', 22:12:53 INFO - 'NO_EM_RESTART': '1', 22:12:53 INFO - 'PAGER': '/bin/cat', 22:12:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:53 INFO - 'PWD': '/builds/slave/test', 22:12:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:53 INFO - 'SHELL': '/bin/bash', 22:12:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n6LAv6XSNa/Listeners', 22:12:53 INFO - 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/', 22:12:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:53 INFO - 'USER': 'cltbld', 22:12:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:53 INFO - 'XPC_FLAGS': '0x0', 22:12:53 INFO - 'XPC_SERVICE_NAME': '0', 22:12:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:12:53 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-ZCCD9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:12:53 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)) 22:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:12:53 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-Ghhf0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:12:53 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)) 22:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:12:53 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-qMkvfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:12:53 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)) 22:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:12:53 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-38wj7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:12:53 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)) 22:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:12:53 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-TWUaql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:12:54 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)) 22:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:12:54 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-RK5Bpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:12:54 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)) 22:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:12:54 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-uW9tVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:12:54 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)) 22:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:12:54 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-jh9Rq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:12:54 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)) 22:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:12:54 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-N2E832-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:12:54 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)) 22:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:12:54 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-7MGwOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:12:54 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)) 22:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:12:54 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-5goUGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:12:54 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)) 22:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:12:54 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-9uc8tA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:12:54 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)) 22:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:12:54 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-tqNwFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:12:54 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)) 22:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:12:54 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-MHUPp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:12:55 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)) 22:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:12:55 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-bUS3Db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:12:55 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)) 22:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:12:55 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-j2OPZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:12:55 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)) 22:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:12:55 INFO - Running setup.py (path:/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/pip-UBNgHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:12:55 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)) 22:12:55 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)) 22:12:55 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)) 22:12:55 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)) 22:12:55 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)) 22:12:55 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)) 22:12:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:12:55 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 22:12:55 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 22:12:55 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 22:12:55 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 22:12:55 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 22:12:55 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 22:12:58 INFO - Downloading blessings-1.6.tar.gz 22:12:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:12:58 INFO - Installing collected packages: blessings 22:12:58 INFO - Running setup.py install for blessings 22:12:59 INFO - Successfully installed blessings 22:12:59 INFO - Cleaning up... 22:12:59 INFO - Return code: 0 22:12:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:12:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:12:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:12:59 INFO - Reading from file tmpfile_stdout 22:12:59 INFO - Current package versions: 22:12:59 INFO - blessings == 1.6 22:12:59 INFO - blobuploader == 1.2.4 22:12:59 INFO - docopt == 0.6.1 22:12:59 INFO - functools32 == 3.2.3-2 22:12:59 INFO - jsonschema == 2.5.1 22:12:59 INFO - manifestparser == 1.1 22:12:59 INFO - mozInstall == 1.12 22:12:59 INFO - mozcrash == 0.17 22:12:59 INFO - mozdebug == 0.1 22:12:59 INFO - mozdevice == 0.48 22:12:59 INFO - mozfile == 1.2 22:12:59 INFO - mozhttpd == 0.7 22:12:59 INFO - mozinfo == 0.9 22:12:59 INFO - mozleak == 0.1 22:12:59 INFO - mozlog == 3.3 22:12:59 INFO - moznetwork == 0.27 22:12:59 INFO - mozprocess == 0.23 22:12:59 INFO - mozprofile == 0.28 22:12:59 INFO - mozrunner == 6.12 22:12:59 INFO - mozscreenshot == 0.1 22:12:59 INFO - mozsystemmonitor == 0.3 22:12:59 INFO - moztest == 0.7 22:12:59 INFO - mozversion == 1.4 22:12:59 INFO - psutil == 3.1.1 22:12:59 INFO - requests == 1.2.3 22:12:59 INFO - wsgiref == 0.1.2 22:12:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:12:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:59 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 0x10e39ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e431618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bc0d52770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e300d30>, '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 0x10e065db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f8b20>, '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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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 22:12:59 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 22:12:59 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 22:12:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6LnB9CbwVj/Render', 22:12:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:59 INFO - 'HOME': '/Users/cltbld', 22:12:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:59 INFO - 'LOGNAME': 'cltbld', 22:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:59 INFO - 'MOZ_NO_REMOTE': '1', 22:12:59 INFO - 'NO_EM_RESTART': '1', 22:12:59 INFO - 'PAGER': '/bin/cat', 22:12:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:59 INFO - 'PWD': '/builds/slave/test', 22:12:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:59 INFO - 'SHELL': '/bin/bash', 22:12:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n6LAv6XSNa/Listeners', 22:12:59 INFO - 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/', 22:12:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:59 INFO - 'USER': 'cltbld', 22:12:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:59 INFO - 'XPC_FLAGS': '0x0', 22:12:59 INFO - 'XPC_SERVICE_NAME': '0', 22:12:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 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)) 22:13:00 INFO - Cleaning up... 22:13:00 INFO - Return code: 0 22:13:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:00 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 0x10e39ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e431618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bc0d52770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e300d30>, '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 0x10e065db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f8b20>, '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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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 22:13:00 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 22:13:00 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 22:13:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6LnB9CbwVj/Render', 22:13:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:00 INFO - 'HOME': '/Users/cltbld', 22:13:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:00 INFO - 'LOGNAME': 'cltbld', 22:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:00 INFO - 'MOZ_NO_REMOTE': '1', 22:13:00 INFO - 'NO_EM_RESTART': '1', 22:13:00 INFO - 'PAGER': '/bin/cat', 22:13:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:00 INFO - 'PWD': '/builds/slave/test', 22:13:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:00 INFO - 'SHELL': '/bin/bash', 22:13:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n6LAv6XSNa/Listeners', 22:13:00 INFO - 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/', 22:13:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:00 INFO - 'USER': 'cltbld', 22:13:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:00 INFO - 'XPC_FLAGS': '0x0', 22:13:00 INFO - 'XPC_SERVICE_NAME': '0', 22:13:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:13:00 INFO - Cleaning up... 22:13:00 INFO - Return code: 0 22:13:00 INFO - Running post-action listener: _resource_record_post_action 22:13:00 INFO - Running post-action listener: _start_resource_monitoring 22:13:00 INFO - Starting resource monitoring. 22:13:00 INFO - [mozharness: 2016-08-17 05:13:00.355272Z] Finished create-virtualenv step (success) 22:13:00 INFO - [mozharness: 2016-08-17 05:13:00.356235Z] Running install step. 22:13:00 INFO - Running pre-action listener: _resource_record_pre_action 22:13:00 INFO - Running main action method: install 22:13:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:13:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:13:00 INFO - Reading from file tmpfile_stdout 22:13:00 INFO - Detecting whether we're running mozinstall >=1.0... 22:13:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:13:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:13:00 INFO - Reading from file tmpfile_stdout 22:13:00 INFO - Output received: 22:13:00 INFO - Usage: mozinstall [options] installer 22:13:00 INFO - Options: 22:13:00 INFO - -h, --help show this help message and exit 22:13:00 INFO - -d DEST, --destination=DEST 22:13:00 INFO - Directory to install application into. [default: 22:13:00 INFO - "/builds/slave/test"] 22:13:00 INFO - --app=APP Application being installed. [default: firefox] 22:13:00 INFO - mkdir: /builds/slave/test/build/application 22:13:00 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'] 22:13:00 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 22:13:35 INFO - Reading from file tmpfile_stdout 22:13:35 INFO - Output received: 22:13:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:13:35 INFO - Running post-action listener: _resource_record_post_action 22:13:35 INFO - [mozharness: 2016-08-17 05:13:35.074939Z] Finished install step (success) 22:13:35 INFO - [mozharness: 2016-08-17 05:13:35.075089Z] Running run-tests step. 22:13:35 INFO - Running pre-action listener: _resource_record_pre_action 22:13:35 INFO - Running main action method: run_tests 22:13:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:13:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:13:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:13:35 INFO - 2016-08-16 22:13:35.089 screenresolution[1637:7398] starting screenresolution argv=screenresolution get 22:13:35 INFO - 2016-08-16 22:13:35.092 screenresolution[1637:7398] Display 0: 1600x1200x32@60 22:13:35 INFO - 2016-08-16 22:13:35.097 screenresolution[1638:7400] starting screenresolution argv=screenresolution list 22:13:35 INFO - Available Modes on Display 0 22:13:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:13:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:13:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:13:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:13:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:13:35 INFO - Intel Iris: 22:13:35 INFO - Chipset Model: Intel Iris 22:13:35 INFO - Type: GPU 22:13:35 INFO - Bus: Built-In 22:13:35 INFO - VRAM (Dynamic, Max): 1536 MB 22:13:35 INFO - Vendor: Intel (0x8086) 22:13:35 INFO - Device ID: 0x0a2e 22:13:35 INFO - Revision ID: 0x0009 22:13:35 INFO - Displays: 22:13:35 INFO - Display: 22:13:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:13:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:13:35 INFO - Display Serial Number: 1600x1200 60 22:13:35 INFO - Main Display: Yes 22:13:35 INFO - Mirror: Off 22:13:35 INFO - Online: Yes 22:13:35 INFO - Rotation: Supported 22:13:35 INFO - Return code: 0 22:13:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:13:35 INFO - Python 2.7.3 22:13:35 INFO - Return code: 0 22:13:35 INFO - grabbing minidump binary from tooltool 22:13:35 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')]} 22:13:35 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 0x10e300d30>, '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 0x10e065db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:13:35 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 22:13:35 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 22:13:35 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 22:13:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:13:35 INFO - Return code: 0 22:13:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:13:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:13:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:13:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:13:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:13:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0248 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:13:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6LnB9CbwVj/Render', 22:13:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:35 INFO - 'HOME': '/Users/cltbld', 22:13:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:35 INFO - 'LOGNAME': 'cltbld', 22:13:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:13:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:35 INFO - 'MOZ_NO_REMOTE': '1', 22:13:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:13:35 INFO - 'NO_EM_RESTART': '1', 22:13:35 INFO - 'PAGER': '/bin/cat', 22:13:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:35 INFO - 'PWD': '/builds/slave/test', 22:13:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:13:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:35 INFO - 'SHELL': '/bin/bash', 22:13:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n6LAv6XSNa/Listeners', 22:13:35 INFO - 'TMPDIR': '/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/', 22:13:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:35 INFO - 'USER': 'cltbld', 22:13:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:35 INFO - 'XPC_FLAGS': '0x0', 22:13:35 INFO - 'XPC_SERVICE_NAME': '0', 22:13:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:13:35 INFO - mozversion application_buildid: 20160816194715 22:13:35 INFO - mozversion application_changeset: f0067001c059ff57d6927c6da5a1605f1d29a449 22:13:35 INFO - mozversion application_display_name: Nightly 22:13:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:13:35 INFO - mozversion application_name: Firefox 22:13:35 INFO - mozversion application_remotingname: firefox 22:13:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:13:35 INFO - mozversion application_vendor: Mozilla 22:13:35 INFO - mozversion application_version: 51.0a1 22:13:35 INFO - mozversion platform_buildid: 20160816194715 22:13:35 INFO - mozversion platform_changeset: f0067001c059ff57d6927c6da5a1605f1d29a449 22:13:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:13:35 INFO - mozversion platform_version: 51.0a1 22:13:35 INFO - using testdate: 1471410815 22:13:35 INFO - actual date: 1471410815 22:13:35 INFO - starting webserver on 'localhost:49267' 22:13:35 INFO - SUITE-START | Running 2 tests 22:13:35 INFO - TEST-START | dromaeo_css 22:13:35 INFO - operating with platform_type : mac_ 22:13:35 INFO - Initialising browser for dromaeo_css test... 22:13:35 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/tmpDBkBYn/profile http://localhost:49267/getInfo.html) 22:13:38 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 22:13:38 INFO - PROCESS | 1648 | colorDepth:24 22:13:38 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 22:13:38 INFO - PROCESS | 1648 | __metrics 22:13:38 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2983: TypeError: this.tabs is undefined 22:13:39 INFO - TEST-INFO | 1648: exit 0 22:13:39 INFO - Browser initialized. 22:13:39 INFO - Running cycle 1/1 for dromaeo_css test... 22:13:39 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/tmpDBkBYn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:13:51 INFO - PROCESS | 1651 | RSS: Main: 239497216 22:13:51 INFO - PROCESS | 1651 | 22:14:41 INFO - PROCESS | 1651 | 1471410881259 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] 22:16:53 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 22:16:55 INFO - PROCESS | 1651 | RSS: Main: 254296064 22:16:55 INFO - PROCESS | 1651 | 22:19:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 22:19:58 INFO - PROCESS | 1651 | RSS: Main: 264708096 22:19:58 INFO - PROCESS | 1651 | 22:23:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 22:23:01 INFO - PROCESS | 1651 | RSS: Main: 271867904 22:23:01 INFO - PROCESS | 1651 | 22:26:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 22:26:03 INFO - PROCESS | 1651 | RSS: Main: 271224832 22:26:03 INFO - PROCESS | 1651 | 22:29:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 22:29:07 INFO - PROCESS | 1651 | RSS: Main: 276639744 22:29:07 INFO - PROCESS | 1651 | 22:32:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 22:32:10 INFO - PROCESS | 1651 | RSS: Main: 279408640 22:32:10 INFO - PROCESS | 1651 | 22:32:10 INFO - PROCESS | 1651 | __start_tp_report 22:32:10 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 22:32:10 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 22:32:10 INFO - PROCESS | 1651 | |i|pagename|runs| 22:32:10 INFO - PROCESS | 1651 | |0;dojo.html;3378.9662103378964;3433.7253019758423;3463.948040779389;3483.9303213935736;3531.682148606625;4612.630989520839;5769.0192667246565;5804.129380592914;5822.388649191837;5892.057270836667;5824.854378640525;5874.412558744134;5882.14708867214;5928;5935.109733539971;5268.604854635899;5307.310049693534;5399.271098401724;5403.162509810985;5431.511163995239;5195.922061169066;5242.528172464477;5319.574434045287;5320.201969704536;5373.650712703681;5280.234366016906;5320.494553017457;5327.600429967768;5339.199120131972;5339.919901201504;5283.943211357744;5304.973475132639;5309.654872433318;5336.973315133399;5389.865253368658;5176.767045482962;5248.029114613809;5297.920531192035;5302.390225124122;5341.973290133525;5720.485156335908;5860.355360910296;5864.970675146597;5886.087656413263;5904.911426328608;6262.937370626327;6273;6315.179026726542;6335.176427064498;6353.364663533656;5116.386033675945;6197.845053873644;6260.405261500196;6283.214598175228;6310.274318453391;5970.85072873173;6015.608985416022;6041.18444010053;6067.2719273687;6093.573449858512;6331.936680633226;6355.396237357444;6393.1369265148605;6433.228012638419;6448.774292899726;6101.671357587133;6162.969185154136;6168.475679567197;6286.18279623646;6301.52738544611;6282.403171698681;6298.212723409574;6303.936960630427;6313.337099604567;6323.08315294289;6260.624362538262;6291.087792270096;6291.370862913672;6295.338989406138;6296.464800491918;6178.6601736904;6221.720022598994;6246.3441338659695;6319.905201421982;6346.619202847844;5084.313617661643;5103.5406813386235;5105.438401775802;5107.770150343244;5130.30740849988;4560.315952607136;4578.862634120982;4582.541745825458;4600.401947746772;4621.884452888705;4918.262260660929;5796.565257605696;5903.468687818117;5952.851178720567;5981.491573216238;5338.279332290169;6316.463100636405;6344.809655710336;6358.650274234961;6365;4840.709557426566;5968.791092311835;5990.28116625999;6009.549283803644;6029.909551356734;5440.863978400572;5472.616916815785;5520.089185284383;5605.887882242332;5610.691411972381;6409.519286053658;6417.229932408048;6429.7749578764515;6448.000559913221;6452.064450654629;6302.275238347649;6311.936880631135;6315.210598675166;6317.747290108344;6321.336259692666;6265.467435268053;6321.115043893767;6355.523335749745;6390.840228994126;6408.551401401857;6273.3099359071375;6299.181106456132;6328.493720502441;6334.524910631629;6349.650769207553;5733;6302.590331628429;6312.5581209314905;6344.238691357066;6367.840803979938;5009.649324547247;6103.216452666749;6125.510168486406;6155.35368786271;6220.066989951543;6154.476869466145;6206.255249370104;6254;6256.405641464054;6308.621482711052;5291.576673866159;5849.005669036359;6095.847603809941;6125.754969801158;6191.318954914866;5679.261695979496;6111.908321375094;6169.105479705508;6194.81415557534;6269.31037585856;6145.170401995673;6158.507319414526;6179.134921110958;6187.659678717714;6193.225846769154 22:32:10 INFO - PROCESS | 1651 | |1;ext.html;474.5799967029178;495.7298272441519;511.8669146022038;520.0275484843343;536.6128557678402;38122;41016;41166.17667646645;41287.38068928968;41340.79329603352;42359.57640423595;42814.357784633256;42874.785626071825;42946.570534294646;43099.78450107745;9518.476483793405;9724.02759724029;9752.36609620376;9753;9753;9590;9652.75868103296;9668.178204852597;9677.467739274338;9682.177014953737;9688.854667179998;9693.515324233795;9697.709068727949;9714.514274286294;9720.368176068532;8232.794180145484;9595.472249026301;9628.903710962872;9679.467629280352;9687.66093186735;9624.566894489764;9657.903420965771;9684.70945871618;9724.70825875225;9753.414795112316;40170;40456.190876182605;40591.39112913309;40634.984125396804;40775.388369174485;3572.732045096628;3633;3637.7089832813313;3892.3772196448717;4037.8788636340955;32377.83811080953;32664.510032349535;32994.01017969465;33078.50382244268;33413.83293083519;3217;3241.205904553376;3257.218267615779;3308.7353011759;3424.9657503424896;391084;411138.88861111604;413590.999999997;466933.66533167457;479396;60381.698091509264;60450;60535.69732151399;60726.696366518765;60742.69628651829;29260;35170.82414587911;35642.10894727652;36797;36814.44778328327;13386.866131338562;16229.756553651705;16239.51281461558;16417.507474775775;16451.753223701653;10950.452477376099;11916.940415297868;12418.88230059299;12544.247345159138;12546.996240300754;15980.76028859568;19408.514787130436;19589.706154407697;19614.607707846048;19706;7303.6348182590655;7644.579548124907;7938.483998540077;8028.1169071403265;8086.393520485987;877.5963056993727;953.208836665566;956.4261443134064;1071.7213524483534;1133.926294790836;29644.555331670043;30256;30556.694433055385;30905.536416953764;31034.534481982788;996.6412091647002;1009.5053423822421;1011.1051719228535;1072.9892701073045;1140.5551834784442;15379.61550961235;15664.530064098095;15760.211989400485;15962.680746385007;15969.760453593206;614889;618180.9090954517;618652;618828.9058554679;618962;45207.54792452033;55996.440035599124;56134.87730245454;56297;56712.43287567154;1284.9270858832979;1327.7809161488246;1392.401267455004;1419.22652154576;1426.4436869620856;28547.14358569246;28559.571606425503;28819.855900720362;30200.395992080474;30324.69675303307;50119.74940125276;50548.98902022013;50653.49346506488;50673.49326506688;50675.98648026945;499446;500627;500836;501052;501923;26523.60214596744;26842.657867106176;26882.059127930428;26931.461370772082;26941.46117077608;46926;47123.52876471191;47880;48298.517014829406;49050.50949490602;38718;38791.6120838788;38948.2210355797;39686;40091.79954100211;38961.025974350865;39285.41071883867;39437;40564;40615;38694.22611547697;39067.41398879076;39595.20809583735;39660.80169599134;40480.797596011835;31243.84378078095;34671;35189.82405087958;35314.11714707153;35360.82319588386;22527.774722253223;25643.487130257665;25995.700214989552;26065;26232.213033609023 22:32:10 INFO - PROCESS | 1651 | |2;jquery.html;347.44235502019257;373.4957806960602;376.75322663655305;383.77549133756753;404.45196758392376;19860.90069549652;20520.486987825312;21243.04406301716;21284.57430851384;21924.123035078577;20239.595208095867;20483.87338696371;20592.382228533166;21049.684254736192;21301.828399438025;5953.107033944922;6053.545984051192;6063.605865618721;6066.908996365058;6101.389861013908;4166.854160104397;5999;6029.60807547508;6053.909191362133;6492.967535162317;6703.463722902156;6768.289329620393;6773.187217533903;6784.219814721297;6846.863062738742;5663.886722265553;6634.701438435282;6699.028640847074;6736.932630673679;6750.088738020402;4988.426330971912;5059.747012649353;5643.463870932255;5770.16332631766;5815;13266.403011864393;13306.401211945486;19004;24203.515929681485;25749.613755793678;1761.5331937036694;2010.447127040061;2134.3810295014428;2160.751513575943;2207;8980.685676001374;10280.845787313196;10302.639407620773;10332.07011368973;10376.221783366278;1864;1876.493346796371;1884.1521315408122;1888.5265342425114;1962.479942815155;132957;136913.3154334222;137452.31273843566;140585.29707351397;140762.88855667174;8506;9515.381500202604;10670.893291067145;10682.252242343116;11721;14614.85385146135;14617;14638.707225855424;14639.194844283667;14688.853111468963;8202.220789025034;8482.830343393221;8509.787255318666;8535.871961920577;8590.656373745105;8378.874316885252;8390.496570205807;8430.957845210734;8550.845635839207;8568;4410.29435290352;4531.932021019687;4585.977070114628;4616.492185859553;4701.7414042228;1434.189682829198;1558.9710790877966;1890.7825600055894;1947.912343944526;1953.5018570264776;830.3440282177133;837.8270421410075;844.3835999720116;846.9872951905726;898.9640414383482;19108.426747197605;20468.283610073875;21291.254806081713;21497.78502214958;21596;854.4019186569302;915.2769312243387;960.313375936209;960.7742180587557;968.3221744778683;8381.664733410717;8625.180607842245;11370.602028928948;11455.770884582262;11551.364674942959;600158;611235;615781;626194.6424821788;633401;123749;134663.32668336594;146438;147107.5289247094;147586.2413758637;810.8378324335157;921.0743203080995;953.4422362917633;1010.9477760146914;1014.9340292880939;14240.430382784987;16161;17724.202410891285;19304.938228397572;19552.511187220432;47768.761156194;51047.19240441663;53657.46342536525;54855.628609285086;56007.15989260083;81151;81698.77451838108;82266;83087.58456207681;84521.57739211265;7730.11103723079;8061.113277539349;8070.878936815835;8424.368172387218;8468.788280293042;9233.75344328507;12234.75530489403;13050.738985220432;13146.868531314565;13597.52408665692;11287.830682539605;11321.433928303717;11635.53457861676;11675.124365672778;11700.824487632523;9338.439693618133;9833.213342932691;9848.162906153057;10781.02970732638;10974.890251097388;11278.041366483942;11454.484548195518;11715.648530544097;11915.76168476643;11919.404029798648;7849.960750196441;7945.443818932619;8344.37417193725;8374.83250335002;8385.203405676451;10121.190304775748;11347.829782553103;11526.1931664786;11551.249168804;11677.240979336317 22:32:10 INFO - PROCESS | 1651 | |3;mootools.html;1249.781288274552;1527.1524304011268;1710.657868426315;1887.7734671839382;1888.8111188881123;7428.517146385481;8246.67013319468;8941.105889411056;9298.721038368843;9331.346805723602;8998.325125615573;9159.77100572486;9172.266218702505;9180.632774689022;9209.861852072225;2392.3660230039095;2421.104191449166;2421.9636705449434;2424.2363655448567;2432.0393444589376;2346.3195673254736;2390.725066617335;2435.415500279938;2441.377448750566;2442.2306973303444;2396.7363590005084;2412.1436889904007;2418.6855708757835;2432.914847980321;2442.645816356627;2383.988080059606;2394.7485514021037;2404.4950560382254;2405.6271277951946;2411.8914648840846;2381.5117900830287;2388.379021454417;2391.8804059797117;2400.0759707512557;2404.410919324777;9019.278457723367;9168.541572921327;9172.08279172084;9174.587143578556;9189.908100918972;5315.388730295988;5443.727813609304;5476.58925580583;5491.2312276281355;5492.148716948879;3624.855005799765;3705.6294370562996;3745.4381842723537;3751.9062023449633;3776.150366167618;1085.9565617375295;1087.8585783848127;1088.6081010836092;1097.4074000039968;1097.9780404391868;12099.274043557414;12207.145499815017;12233.510659573607;12237.694057648541;12291.446884890202;4490.146872094291;4582.702124361906;4711.552402521755;4756.500567440438;4766.785494652749;9340.766480838034;9414.964353921063;9452.52737363129;9460.24318054554;9468.90531094694;5348.679079255257;5360.517553420211;5360.839174824761;5362.008028514737;5396.406395296514;4505.774711264424;4512.323151527297;4703;4873.147199240189;4992.2761199626575;3268.9836550817568;3763.9247215055548;3800.8669696560487;3857.8842634721004;3877.9224415512094;973.4791886340773;1033.3231733214757;1099.7745462180235;1156.9826452603218;1195.1753290229713;818.4639061414783;900;926.2451102351562;930.4882314726873;932.2122806228726;5157.896842063138;5729.713514324267;5914.615549989237;6048.879022419527;6195;444.68205233258374;454.6817227940389;457.4556278029201;461.1814030096538;463.1339395330686;4010.5187377514885;4025.798710064485;4030.9395359069636;4111.602055301212;4137.606927341898;11064.502097405637;11341.43292835355;11415.885841141482;11418.314901105794;11466.541338346373;9612.9425763166;11273.830892536618;11392.145589080686;11420.314781112993;11443;7988.840223195503;8418.831623367498;8439.70461033861;8502.872456913152;8575;6056.5154787616;6057.939420605738;6072.8785424292155;6168.074788781719;6267.96866015667;10343.327683700536;10535.63125290611;10595.735106622396;10614.097801686634;10615.469226538797;9912.851307230254;10331.535080921527;10351.06840384369;10483.737906552396;10487;6905.343992320722;7470.4023678106705;7610.526578862003;7701.345385642283;7883.014623172104;8618.353623478139;8624.18070283322;8692.217700406994;8702.695405660772;8703.390762646553;7571.772846814604;7749.612519374122;7754.263344982218;7841.9215807843475;7856.175101614248;7480.2145774695055;7596.886046709194;7601.315881570686;7632.007838980897;7661.616919154131;7087.468439867133;7184.856302874018;7237.457190710613;7238.167610724834;7243.420526357984;4727.456342520517;4840.878978025577;4915.115279249626;5088.592912566912;5176.171812510131;5079.187330027178;5080.364954380702;5106.7446627669215;5201.973990130025;5332.653377530448 22:32:10 INFO - PROCESS | 1651 | |4;prototype.html;298.3719270934681;298.8849293022186;310.06515356201044;312.3487528503071;313.3967113306884;4654.604358629516;4955.69740377795;5402;5541.508849424862;5588.133839254911;5534.647774695758;5537.58468114891;5550.944490555104;5594.888102237953;5609.803656872;3354.66453354665;3434.883662809584;4165.125323682015;4249.787510624456;4258.978705106486;3425;3620.294042661682;4200.836569166881;4230.471191101113;4251.236696147498;3761.8683346082894;4071.653909417703;4141.772202528859;4156.7921603919685;4201.957980420202;3093.474109401385;3502.3870822606195;3860.5946375630433;4168.291390463629;4175.331946888483;2812.4515719434717;3022.3955208958077;3450.3271861986923;3484.937094186272;3638.6725194732603;4706.999525201775;4810.85567432974;5152.0161569761285;5364.570834333244;5491.862703432446;3724.7951362675044;4701.459332176809;4702.952970470285;4740.668153229275;4769.618430525549;5325.733713314319;5793.681347525927;5861.267341582302;5883.52931765462;5906.283109769512;3132.37352529495;3773.471713960048;3927.9214415711526;3943.38877473992;3948.545917219499;5617.634853734494;6105.45050945417;6139.846503837395;6163.445289923929;6236.532260080467;3424.7260219182403;3998.300297447935;4016.879493615196;4026.979865100656;4027.8791636250958;3577.83899724513;3939.3106206413763;4040.9595904040584;4053.6554392876938;4081.2653722330265;4010.2981978153707;4155.771432571177;4315.892102697395;4757.262624293171;4766.571008609242;4345.044090300129;5597.5295316903785;5819.447152520504;5852.2735792941185;5907.645541267538;1124.5726623882872;1157.4155051698838;1277.0741212620962;1364;1368.8699573540498;743.3681370834747;904.8099899021148;921.589892497832;923.1507013547553;932.0725877751545;4522.796474158605;5010.899782004412;5126.7183204198955;5139.331886854685;5161.948380516147;5510.669359838423;5934.884185802402;5949.560680121216;5962.970185149134;5990.73041713124;4398.890027749332;5156.587473002151;5394.32570928634;5475.289731582892;5527.53015993637;2609.425926296212;2724.7820174386;3075.7846950713683;3130.4678204705256;3199.776015678927;5041.0509851736415;5073.568746656501;5522.944770552243;6197.876042479215;6250.312465628777;5008.449070602231;5428.91856622143;6005.789797357072;6101.667974687313;6221.751129954841;5510.9448905510435;6285.120083188357;6290.937090629127;6303.338149494237;6340.481033026715;5121.871953201051;5709.800156994485;6020;6079.939200608114;6219.657918814328;4824.807007719652;5711.971440142773;5734.885302294014;5964.777220669839;5967.8209653710455;5277.525022747971;5386.165144402624;5562.3047119110115;6170.660613666112;6305.432511074026;3767.0286278381045;3965.960340396559;3969;3977.3039718049804;3996.1807829395484;4748.905021899612;5623.718814059363;6034.487068599218;6068.514518838394;6210.968945155245;5170.508801663836;5311.3891902431715;5516.3380394352935;5710.571707122065;5823.621464604957;4425.336199570091;5357.19642053695;5604.747786349705;5835.44563266489;5841.853953651193;5419.206555411149;5712.914306285326;5716.571257155647;5722.4277572242445;5740.6268592541355;5512.944870551243;5847.970760146342;5862.912056319074;5899.4985426239255;5983.850403739942;4998.800047998185;5100.260462232881;5724.685142317213;5744.741486633028;5756.971215143897 22:32:10 INFO - PROCESS | 1651 | |5;yui.html;663.5156335874811;693.6115775165907;725.0031207090251;736.701635837486;750.3584435307815;15348.462803801869;15435.845641543581;15766.369345226192;15961.28174232161;16321.510354689379;14410.495632652859;14589.270536473186;14622.48821291255;14635;14669.119852808839;672.2336536348542;844.0673056272822;861.6596444404456;862.2153840005597;863.4603372891943;833.8832563441093;851.0553285852699;864.8529749942494;871.7297637732323;875.0855356152836;889.8131392407602;901.9368644194909;909.6543313540845;911.1753862754218;913.0549880873298;697.8137166816382;755.5919803306208;896.6413434626169;901.0673952459206;906.4787747045442;534.254714673029;666.4701562258017;667.6695035957184;668.4151367553391;673.0980486148584;14878.776818347733;15036.548903532912;15047.849521504755;15065.76086141474;15219.391224351013;14796.852031479655;14868.992685987361;14874.925625371912;15138.167400792952;15172.924135379362;11963.64109076729;12043.879561204363;12320.420537020993;12435.12954093214;12560.497580096877;3828.540575130974;3851.441540976543;4047.271491131595;4080.163566468867;4080.5307389650275;120520;121869.78130218673;122237;123424;124447.75552244452;10601.787964240671;10957.90420957898;11367.204295699386;11392.430378481044;11886.108541859396;20719;23680.526389472114;23742.16902408442;23790.167344143218;23868.880655596957;7552.509086909334;7785.688572457151;7863.685452581947;7987.8402431951035;8013.358931285483;4430.955690443118;4542.737001534499;4557.635389168859;4559.863204103882;4575.199340115533;3302.9174270642948;3438.9312213755584;3442.638522955104;3442.7762195457217;3482.5472688550326;600.4625859855405;669.0633113640941;674.2515807453723;681.5433659448181;683.5112894280613;586.3374386942812;593.7031484257872;594.6045879490177;678.1760161403918;680.1566058088034;9231.769205769775;9322.30082743797;9400.764980875532;9587.760305992406;9802.803943921224;518.6473198225168;547.6084599511369;561.5619816543103;645.7998020613625;675.2133764164693;4389.582989615981;4742.881427964328;4775.355327030876;4797.865852146836;4867.63492738039;113209.30186047216;129994;133544;168608;169219;144886.55113448942;146327.2683636575;146345.86616136474;147140.26429867782;149135;112705;118158.40920795513;118254.40872795582;119932.80067199391;121400;8860.734177974671;9082.001959568885;9103.590338434655;9132;9345.15893569582;64772.67613661902;66236.66881665561;66638;67716.50404154253;67928.98106528308;62431.37568624256;71085;72586.27413725795;78439.97940236717;81351.37297253903;13220;13262.469501220228;13381.39783709755;13971.441142354599;14262.71474570482;12319.162420715944;15082.773758393852;15128.319225634652;15187.696246075238;15589.220538973233;11260.38067906273;11378.715532111764;11772.411379430823;12072.698182545506;14016.579502614937;12882.64732203105;13287.80068298957;14029.579112626638;14278.2146981917;14326.856731432552;11586.22748532199;13743.931280343933;13956.511522096678;14085.08446950945;14448.076288721573;11010.284331518425;11336.6032188873;11366.886331136913;11439.428028598702;11840.230385024946;10705;10722.918476237746;11662.533498660297;11790.174687772116;11802.468888899848 22:32:10 INFO - PROCESS | 1651 | __end_tp_report 22:32:10 INFO - PROCESS | 1651 | __start_cc_report 22:32:10 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,414 22:32:10 INFO - PROCESS | 1651 | __end_cc_report 22:32:10 INFO - PROCESS | 1651 | __startTimestamp1471411930244__endTimestamp 22:32:10 INFO - PROCESS | 1651 | 22:32:10 INFO - PROCESS | 1651 | ------- Summary: start ------- 22:32:10 INFO - PROCESS | 1651 | Number of tests: 6 22:32:10 INFO - PROCESS | 1651 | 22:32:10 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5817.41 Median:6095.85 stddev:647.37 (10.6%) stddev-sans-first:620.45 22:32:10 INFO - PROCESS | 1651 | Values: 3379.0 3433.7 3463.9 3483.9 3531.7 4612.6 5769.0 5804.1 5822.4 5892.1 5824.9 5874.4 5882.1 5928.0 5935.1 5268.6 5307.3 5399.3 5403.2 5431.5 5195.9 5242.5 5319.6 5320.2 5373.7 5280.2 5320.5 5327.6 5339.2 5339.9 5283.9 5305.0 5309.7 5337.0 5389.9 5176.8 5248.0 5297.9 5302.4 5342.0 5720.5 5860.4 5865.0 5886.1 5904.9 6262.9 6273.0 6315.2 6335.2 6353.4 5116.4 6197.8 6260.4 6283.2 6310.3 5970.9 6015.6 6041.2 6067.3 6093.6 6331.9 6355.4 6393.1 6433.2 6448.8 6101.7 6163.0 6168.5 6286.2 6301.5 6282.4 6298.2 6303.9 6313.3 6323.1 6260.6 6291.1 6291.4 6295.3 6296.5 6178.7 6221.7 6246.3 6319.9 6346.6 5084.3 5103.5 5105.4 5107.8 5130.3 4560.3 4578.9 4582.5 4600.4 4621.9 4918.3 5796.6 5903.5 5952.9 5981.5 5338.3 6316.5 6344.8 6358.7 6365.0 4840.7 5968.8 5990.3 6009.5 6029.9 5440.9 5472.6 5520.1 5605.9 5610.7 6409.5 6417.2 6429.8 6448.0 6452.1 6302.3 6311.9 6315.2 6317.7 6321.3 6265.5 6321.1 6355.5 6390.8 6408.6 6273.3 6299.2 6328.5 6334.5 6349.7 5733.0 6302.6 6312.6 6344.2 6367.8 5009.6 6103.2 6125.5 6155.4 6220.1 6154.5 6206.3 6254.0 6256.4 6308.6 5291.6 5849.0 6095.8 6125.8 6191.3 5679.3 6111.9 6169.1 6194.8 6269.3 6145.2 6158.5 6179.1 6187.7 6193.2 22:32:10 INFO - PROCESS | 1651 | 22:32:10 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:65271.60 Median:22247.03 stddev:139515.94 (627.1%) stddev-sans-first:139822.49 22:32:10 INFO - PROCESS | 1651 | Values: 474.6 495.7 511.9 520.0 536.6 38122.0 41016.0 41166.2 41287.4 41340.8 42359.6 42814.4 42874.8 42946.6 43099.8 9518.5 9724.0 9752.4 9753.0 9753.0 9590.0 9652.8 9668.2 9677.5 9682.2 9688.9 9693.5 9697.7 9714.5 9720.4 8232.8 9595.5 9628.9 9679.5 9687.7 9624.6 9657.9 9684.7 9724.7 9753.4 40170.0 40456.2 40591.4 40635.0 40775.4 3572.7 3633.0 3637.7 3892.4 4037.9 32377.8 32664.5 32994.0 33078.5 33413.8 3217.0 3241.2 3257.2 3308.7 3425.0 391084.0 411138.9 413591.0 466933.7 479396.0 60381.7 60450.0 60535.7 60726.7 60742.7 29260.0 35170.8 35642.1 36797.0 36814.4 13386.9 16229.8 16239.5 16417.5 16451.8 10950.5 11916.9 12418.9 12544.2 12547.0 15980.8 19408.5 19589.7 19614.6 19706.0 7303.6 7644.6 7938.5 8028.1 8086.4 877.6 953.2 956.4 1071.7 1133.9 29644.6 30256.0 30556.7 30905.5 31034.5 996.6 1009.5 1011.1 1073.0 1140.6 15379.6 15664.5 15760.2 15962.7 15969.8 614889.0 618180.9 618652.0 618828.9 618962.0 45207.5 55996.4 56134.9 56297.0 56712.4 1284.9 1327.8 1392.4 1419.2 1426.4 28547.1 28559.6 28819.9 30200.4 30324.7 50119.7 50549.0 50653.5 50673.5 50676.0 499446.0 500627.0 500836.0 501052.0 501923.0 26523.6 26842.7 26882.1 26931.5 26941.5 46926.0 47123.5 47880.0 48298.5 49050.5 38718.0 38791.6 38948.2 39686.0 40091.8 38961.0 39285.4 39437.0 40564.0 40615.0 38694.2 39067.4 39595.2 39660.8 40480.8 31243.8 34671.0 35189.8 35314.1 35360.8 22527.8 25643.5 25995.7 26065.0 26232.2 22:32:10 INFO - PROCESS | 1651 | 22:32:10 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:36344.16 Median:380.26 stddev:103787.25 (27293.4%) stddev-sans-first:104043.21 22:32:10 INFO - PROCESS | 1651 | Values: 347.4 373.5 376.8 383.8 404.5 19860.9 20520.5 21243.0 21284.6 21924.1 20239.6 20483.9 20592.4 21049.7 21301.8 5953.1 6053.5 6063.6 6066.9 6101.4 4166.9 5999.0 6029.6 6053.9 6493.0 6703.5 6768.3 6773.2 6784.2 6846.9 5663.9 6634.7 6699.0 6736.9 6750.1 4988.4 5059.7 5643.5 5770.2 5815.0 13266.4 13306.4 19004.0 24203.5 25749.6 1761.5 2010.4 2134.4 2160.8 2207.0 8980.7 10280.8 10302.6 10332.1 10376.2 1864.0 1876.5 1884.2 1888.5 1962.5 132957.0 136913.3 137452.3 140585.3 140762.9 8506.0 9515.4 10670.9 10682.3 11721.0 14614.9 14617.0 14638.7 14639.2 14688.9 8202.2 8482.8 8509.8 8535.9 8590.7 8378.9 8390.5 8431.0 8550.8 8568.0 4410.3 4531.9 4586.0 4616.5 4701.7 1434.2 1559.0 1890.8 1947.9 1953.5 830.3 837.8 844.4 847.0 899.0 19108.4 20468.3 21291.3 21497.8 21596.0 854.4 915.3 960.3 960.8 968.3 8381.7 8625.2 11370.6 11455.8 11551.4 600158.0 611235.0 615781.0 626194.6 633401.0 123749.0 134663.3 146438.0 147107.5 147586.2 810.8 921.1 953.4 1010.9 1014.9 14240.4 16161.0 17724.2 19304.9 19552.5 47768.8 51047.2 53657.5 54855.6 56007.2 81151.0 81698.8 82266.0 83087.6 84521.6 7730.1 8061.1 8070.9 8424.4 8468.8 9233.8 12234.8 13050.7 13146.9 13597.5 11287.8 11321.4 11635.5 11675.1 11700.8 9338.4 9833.2 9848.2 10781.0 10974.9 11278.0 11454.5 11715.6 11915.8 11919.4 7850.0 7945.4 8344.4 8374.8 8385.2 10121.2 11347.8 11526.2 11551.2 11677.2 22:32:10 INFO - PROCESS | 1651 | 22:32:10 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5830.16 Median:4803.83 stddev:3348.61 (69.7%) stddev-sans-first:3340.31 22:32:10 INFO - PROCESS | 1651 | Values: 1249.8 1527.2 1710.7 1887.8 1888.8 7428.5 8246.7 8941.1 9298.7 9331.3 8998.3 9159.8 9172.3 9180.6 9209.9 2392.4 2421.1 2422.0 2424.2 2432.0 2346.3 2390.7 2435.4 2441.4 2442.2 2396.7 2412.1 2418.7 2432.9 2442.6 2384.0 2394.7 2404.5 2405.6 2411.9 2381.5 2388.4 2391.9 2400.1 2404.4 9019.3 9168.5 9172.1 9174.6 9189.9 5315.4 5443.7 5476.6 5491.2 5492.1 3624.9 3705.6 3745.4 3751.9 3776.2 1086.0 1087.9 1088.6 1097.4 1098.0 12099.3 12207.1 12233.5 12237.7 12291.4 4490.1 4582.7 4711.6 4756.5 4766.8 9340.8 9415.0 9452.5 9460.2 9468.9 5348.7 5360.5 5360.8 5362.0 5396.4 4505.8 4512.3 4703.0 4873.1 4992.3 3269.0 3763.9 3800.9 3857.9 3877.9 973.5 1033.3 1099.8 1157.0 1195.2 818.5 900.0 926.2 930.5 932.2 5157.9 5729.7 5914.6 6048.9 6195.0 444.7 454.7 457.5 461.2 463.1 4010.5 4025.8 4030.9 4111.6 4137.6 11064.5 11341.4 11415.9 11418.3 11466.5 9612.9 11273.8 11392.1 11420.3 11443.0 7988.8 8418.8 8439.7 8502.9 8575.0 6056.5 6057.9 6072.9 6168.1 6268.0 10343.3 10535.6 10595.7 10614.1 10615.5 9912.9 10331.5 10351.1 10483.7 10487.0 6905.3 7470.4 7610.5 7701.3 7883.0 8618.4 8624.2 8692.2 8702.7 8703.4 7571.8 7749.6 7754.3 7841.9 7856.2 7480.2 7596.9 7601.3 7632.0 7661.6 7087.5 7184.9 7237.5 7238.2 7243.4 4727.5 4840.9 4915.1 5088.6 5176.2 5079.2 5080.4 5106.7 5202.0 5332.7 22:32:10 INFO - PROCESS | 1651 | 22:32:10 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4621.90 Median:5159.27 stddev:1481.57 (28.7%) stddev-sans-first:1449.75 22:32:10 INFO - PROCESS | 1651 | Values: 298.4 298.9 310.1 312.3 313.4 4654.6 4955.7 5402.0 5541.5 5588.1 5534.6 5537.6 5550.9 5594.9 5609.8 3354.7 3434.9 4165.1 4249.8 4259.0 3425.0 3620.3 4200.8 4230.5 4251.2 3761.9 4071.7 4141.8 4156.8 4202.0 3093.5 3502.4 3860.6 4168.3 4175.3 2812.5 3022.4 3450.3 3484.9 3638.7 4707.0 4810.9 5152.0 5364.6 5491.9 3724.8 4701.5 4703.0 4740.7 4769.6 5325.7 5793.7 5861.3 5883.5 5906.3 3132.4 3773.5 3927.9 3943.4 3948.5 5617.6 6105.5 6139.8 6163.4 6236.5 3424.7 3998.3 4016.9 4027.0 4027.9 3577.8 3939.3 4041.0 4053.7 4081.3 4010.3 4155.8 4315.9 4757.3 4766.6 4345.0 5597.5 5819.4 5852.3 5907.6 1124.6 1157.4 1277.1 1364.0 1368.9 743.4 904.8 921.6 923.2 932.1 4522.8 5010.9 5126.7 5139.3 5161.9 5510.7 5934.9 5949.6 5963.0 5990.7 4398.9 5156.6 5394.3 5475.3 5527.5 2609.4 2724.8 3075.8 3130.5 3199.8 5041.1 5073.6 5522.9 6197.9 6250.3 5008.4 5428.9 6005.8 6101.7 6221.8 5510.9 6285.1 6290.9 6303.3 6340.5 5121.9 5709.8 6020.0 6079.9 6219.7 4824.8 5712.0 5734.9 5964.8 5967.8 5277.5 5386.2 5562.3 6170.7 6305.4 3767.0 3966.0 3969.0 3977.3 3996.2 4748.9 5623.7 6034.5 6068.5 6211.0 5170.5 5311.4 5516.3 5710.6 5823.6 4425.3 5357.2 5604.7 5835.4 5841.9 5419.2 5712.9 5716.6 5722.4 5740.6 5512.9 5848.0 5862.9 5899.5 5983.9 4998.8 5100.3 5724.7 5744.7 5757.0 22:32:10 INFO - PROCESS | 1651 | 22:32:10 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:25480.58 Median:3370.92 stddev:41317.60 (1225.7%) stddev-sans-first:41391.49 22:32:10 INFO - PROCESS | 1651 | Values: 663.5 693.6 725.0 736.7 750.4 15348.5 15435.8 15766.4 15961.3 16321.5 14410.5 14589.3 14622.5 14635.0 14669.1 672.2 844.1 861.7 862.2 863.5 833.9 851.1 864.9 871.7 875.1 889.8 901.9 909.7 911.2 913.1 697.8 755.6 896.6 901.1 906.5 534.3 666.5 667.7 668.4 673.1 14878.8 15036.5 15047.8 15065.8 15219.4 14796.9 14869.0 14874.9 15138.2 15172.9 11963.6 12043.9 12320.4 12435.1 12560.5 3828.5 3851.4 4047.3 4080.2 4080.5 120520.0 121869.8 122237.0 123424.0 124447.8 10601.8 10957.9 11367.2 11392.4 11886.1 20719.0 23680.5 23742.2 23790.2 23868.9 7552.5 7785.7 7863.7 7987.8 8013.4 4431.0 4542.7 4557.6 4559.9 4575.2 3302.9 3438.9 3442.6 3442.8 3482.5 600.5 669.1 674.3 681.5 683.5 586.3 593.7 594.6 678.2 680.2 9231.8 9322.3 9400.8 9587.8 9802.8 518.6 547.6 561.6 645.8 675.2 4389.6 4742.9 4775.4 4797.9 4867.6 113209.3 129994.0 133544.0 168608.0 169219.0 144886.6 146327.3 146345.9 147140.3 149135.0 112705.0 118158.4 118254.4 119932.8 121400.0 8860.7 9082.0 9103.6 9132.0 9345.2 64772.7 66236.7 66638.0 67716.5 67929.0 62431.4 71085.0 72586.3 78440.0 81351.4 13220.0 13262.5 13381.4 13971.4 14262.7 12319.2 15082.8 15128.3 15187.7 15589.2 11260.4 11378.7 11772.4 12072.7 14016.6 12882.6 13287.8 14029.6 14278.2 14326.9 11586.2 13743.9 13956.5 14085.1 14448.1 11010.3 11336.6 11366.9 11439.4 11840.2 10705.0 10722.9 11662.5 11790.2 11802.5 22:32:10 INFO - PROCESS | 1651 | -------- Summary: end -------- 22:32:10 INFO - PROCESS | 1651 | 22:32:10 INFO - TEST-INFO | 1651: exit 0 22:32:10 INFO - TEST-OK | dromaeo_css | took 1115157ms 22:32:10 INFO - TEST-START | kraken 22:32:10 INFO - operating with platform_type : mac_ 22:32:10 INFO - Initialising browser for kraken test... 22:32:10 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/tmpw07dgn/profile http://localhost:49267/getInfo.html) 22:32:13 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 22:32:13 INFO - PROCESS | 1664 | colorDepth:24 22:32:13 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 22:32:13 INFO - PROCESS | 1664 | __metrics 22:32:13 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2983: TypeError: this.tabs is undefined 22:32:14 INFO - TEST-INFO | 1664: exit 0 22:32:14 INFO - Browser initialized. 22:32:14 INFO - Running cycle 1/1 for kraken test... 22:32:14 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/T/tmpw07dgn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:32:26 INFO - PROCESS | 1666 | RSS: Main: 239669248 22:32:26 INFO - PROCESS | 1666 | 22:32:29 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 22:32:30 INFO - PROCESS | 1666 | RSS: Main: 252432384 22:32:30 INFO - PROCESS | 1666 | 22:32:33 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 22:32:33 INFO - PROCESS | 1666 | RSS: Main: 261148672 22:32:33 INFO - PROCESS | 1666 | 22:32:37 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 22:32:37 INFO - PROCESS | 1666 | RSS: Main: 264159232 22:32:37 INFO - PROCESS | 1666 | 22:32:39 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 22:32:40 INFO - PROCESS | 1666 | RSS: Main: 267157504 22:32:40 INFO - PROCESS | 1666 | 22:32:42 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:32:43 INFO - PROCESS | 1666 | RSS: Main: 267259904 22:32:43 INFO - PROCESS | 1666 | 22:32:46 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 22:32:46 INFO - PROCESS | 1666 | RSS: Main: 267280384 22:32:46 INFO - PROCESS | 1666 | 22:32:49 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 22:32:50 INFO - PROCESS | 1666 | RSS: Main: 267018240 22:32:50 INFO - PROCESS | 1666 | 22:32:53 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 22:32:54 INFO - PROCESS | 1666 | RSS: Main: 267907072 22:32:54 INFO - PROCESS | 1666 | 22:32:55 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:32:56 INFO - PROCESS | 1666 | RSS: Main: 267280384 22:32:56 INFO - PROCESS | 1666 | 22:32:58 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:32:58 INFO - PROCESS | 1666 | RSS: Main: 267382784 22:32:58 INFO - PROCESS | 1666 | 22:33:01 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:33:01 INFO - PROCESS | 1666 | RSS: Main: 267739136 22:33:01 INFO - PROCESS | 1666 | 22:33:04 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:33:05 INFO - PROCESS | 1666 | RSS: Main: 267948032 22:33:05 INFO - PROCESS | 1666 | 22:33:08 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:33:09 INFO - PROCESS | 1666 | RSS: Main: 267780096 22:33:09 INFO - PROCESS | 1666 | 22:33:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 22:33:11 INFO - PROCESS | 1666 | RSS: Main: 268730368 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | __start_tp_report 22:33:11 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 22:33:11 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 22:33:11 INFO - PROCESS | 1666 | |i|pagename|runs| 22:33:11 INFO - PROCESS | 1666 | |0;ai-astar;107;116;108;118;115;117;123;113;175;169 22:33:11 INFO - PROCESS | 1666 | |1;audio-beat-detection;144;98;172;199;113;140;162;133;201;146 22:33:11 INFO - PROCESS | 1666 | |2;audio-dft;193;205;218;211;195;196;197;195;201;196 22:33:11 INFO - PROCESS | 1666 | |3;audio-fft;70;92;76;146;62;78;85;83;164;82 22:33:11 INFO - PROCESS | 1666 | |4;audio-oscillator;71;73;117;87;61;77;141;56;82;144 22:33:11 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;93;90;136;126;91;88;134;88;92;87 22:33:11 INFO - PROCESS | 1666 | |6;imaging-darkroom;106;120;96;96;110;153;104;98;96;96 22:33:11 INFO - PROCESS | 1666 | |7;imaging-desaturate;90;98;80;100;98;144;148;117;92;89 22:33:11 INFO - PROCESS | 1666 | |8;json-parse-financial;57;57;60;59;59;63;58;57;58;60 22:33:11 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;67;70;70;78;80;79;86;106;102;103 22:33:11 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;87;88;89;83;87;96;96;89;88;91 22:33:11 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;158;157;136;160;155;160;156;162;169;159 22:33:11 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;173;176;174;174;170;170;172;170;171;173 22:33:11 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;67;70;84;87;91;91;90;69;68;68 22:33:11 INFO - PROCESS | 1666 | __end_tp_report 22:33:11 INFO - PROCESS | 1666 | __start_cc_report 22:33:11 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1064 22:33:11 INFO - PROCESS | 1666 | __end_cc_report 22:33:11 INFO - PROCESS | 1666 | __startTimestamp1471411991136__endTimestamp 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | ------- Summary: start ------- 22:33:11 INFO - PROCESS | 1666 | Number of tests: 14 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:126.10 Median:117.50 stddev:24.67 (21.0%) stddev-sans-first:25.18 22:33:11 INFO - PROCESS | 1666 | Values: 107.0 116.0 108.0 118.0 115.0 117.0 123.0 113.0 175.0 169.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:150.80 Median:167.00 stddev:33.56 (20.1%) stddev-sans-first:35.51 22:33:11 INFO - PROCESS | 1666 | Values: 144.0 98.0 172.0 199.0 113.0 140.0 162.0 133.0 201.0 146.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:200.70 Median:199.00 stddev:8.21 (4.1%) stddev-sans-first:8.22 22:33:11 INFO - PROCESS | 1666 | Values: 193.0 205.0 218.0 211.0 195.0 196.0 197.0 195.0 201.0 196.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:93.80 Median:80.00 stddev:33.56 (41.9%) stddev-sans-first:34.47 22:33:11 INFO - PROCESS | 1666 | Values: 70.0 92.0 76.0 146.0 62.0 78.0 85.0 83.0 164.0 82.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:72.00 stddev:31.84 (44.2%) stddev-sans-first:32.95 22:33:11 INFO - PROCESS | 1666 | Values: 71.0 73.0 117.0 87.0 61.0 77.0 141.0 56.0 82.0 144.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:102.50 Median:89.00 stddev:20.59 (23.1%) stddev-sans-first:21.55 22:33:11 INFO - PROCESS | 1666 | Values: 93.0 90.0 136.0 126.0 91.0 88.0 134.0 88.0 92.0 87.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:96.00 stddev:17.85 (18.6%) stddev-sans-first:18.92 22:33:11 INFO - PROCESS | 1666 | Values: 106.0 120.0 96.0 96.0 110.0 153.0 104.0 98.0 96.0 96.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:105.60 Median:89.50 stddev:23.35 (26.1%) stddev-sans-first:24.08 22:33:11 INFO - PROCESS | 1666 | Values: 90.0 98.0 80.0 100.0 98.0 144.0 148.0 117.0 92.0 89.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:59.00 stddev:1.87 (3.2%) stddev-sans-first:1.87 22:33:11 INFO - PROCESS | 1666 | Values: 57.0 57.0 60.0 59.0 59.0 63.0 58.0 57.0 58.0 60.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:84.10 Median:74.00 stddev:14.65 (19.8%) stddev-sans-first:14.17 22:33:11 INFO - PROCESS | 1666 | Values: 67.0 70.0 70.0 78.0 80.0 79.0 86.0 106.0 102.0 103.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:89.00 stddev:4.03 (4.5%) stddev-sans-first:4.18 22:33:11 INFO - PROCESS | 1666 | Values: 87.0 88.0 89.0 83.0 87.0 96.0 96.0 89.0 88.0 91.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:157.20 Median:159.50 stddev:8.42 (5.3%) stddev-sans-first:8.92 22:33:11 INFO - PROCESS | 1666 | Values: 158.0 157.0 136.0 160.0 155.0 160.0 156.0 162.0 169.0 159.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:173.00 stddev:2.06 (1.2%) stddev-sans-first:2.17 22:33:11 INFO - PROCESS | 1666 | Values: 173.0 176.0 174.0 174.0 170.0 170.0 172.0 170.0 171.0 173.0 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.50 Median:85.50 stddev:10.87 (12.7%) stddev-sans-first:10.70 22:33:11 INFO - PROCESS | 1666 | Values: 67.0 70.0 84.0 87.0 91.0 91.0 90.0 69.0 68.0 68.0 22:33:11 INFO - PROCESS | 1666 | -------- Summary: end -------- 22:33:11 INFO - PROCESS | 1666 | 22:33:11 INFO - TEST-INFO | 1666: exit 0 22:33:11 INFO - TEST-OK | kraken | took 61141ms 22:33:11 INFO - SUITE-END | took 1176s 22:33:12 INFO - Completed test suite (00:19:36) 22:33:12 INFO - javascript benchmark 22:33:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7373.076352053509, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3378.9662103378964, 3433.7253019758423, 3463.948040779389, 3483.9303213935736, 3531.682148606625, 4612.630989520839, 5769.0192667246565, 5804.129380592914, 5822.388649191837, 5892.057270836667, 5824.854378640525, 5874.412558744134, 5882.14708867214, 5928.0, 5935.109733539971, 5268.604854635899, 5307.310049693534, 5399.271098401724, 5403.162509810985, 5431.511163995239, 5195.922061169066, 5242.528172464477, 5319.574434045287, 5320.201969704536, 5373.650712703681, 5280.234366016906, 5320.494553017457, 5327.600429967768, 5339.199120131972, 5339.919901201504, 5283.943211357744, 5304.973475132639, 5309.654872433318, 5336.973315133399, 5389.865253368658, 5176.767045482962, 5248.029114613809, 5297.920531192035, 5302.390225124122, 5341.973290133525, 5720.485156335908, 5860.355360910296, 5864.970675146597, 5886.087656413263, 5904.911426328608, 6262.937370626327, 6273.0, 6315.179026726542, 6335.176427064498, 6353.364663533656, 5116.386033675945, 6197.845053873644, 6260.405261500196, 6283.214598175228, 6310.274318453391, 5970.85072873173, 6015.608985416022, 6041.18444010053, 6067.2719273687, 6093.573449858512, 6331.936680633226, 6355.396237357444, 6393.1369265148605, 6433.228012638419, 6448.774292899726, 6101.671357587133, 6162.969185154136, 6168.475679567197, 6286.18279623646, 6301.52738544611, 6282.403171698681, 6298.212723409574, 6303.936960630427, 6313.337099604567, 6323.08315294289, 6260.624362538262, 6291.087792270096, 6291.370862913672, 6295.338989406138, 6296.464800491918, 6178.6601736904, 6221.720022598994, 6246.3441338659695, 6319.905201421982, 6346.619202847844, 5084.313617661643, 5103.5406813386235, 5105.438401775802, 5107.770150343244, 5130.30740849988, 4560.315952607136, 4578.862634120982, 4582.541745825458, 4600.401947746772, 4621.884452888705, 4918.262260660929, 5796.565257605696, 5903.468687818117, 5952.851178720567, 5981.491573216238, 5338.279332290169, 6316.463100636405, 6344.809655710336, 6358.650274234961, 6365.0, 4840.709557426566, 5968.791092311835, 5990.28116625999, 6009.549283803644, 6029.909551356734, 5440.863978400572, 5472.616916815785, 5520.089185284383, 5605.887882242332, 5610.691411972381, 6409.519286053658, 6417.229932408048, 6429.7749578764515, 6448.000559913221, 6452.064450654629, 6302.275238347649, 6311.936880631135, 6315.210598675166, 6317.747290108344, 6321.336259692666, 6265.467435268053, 6321.115043893767, 6355.523335749745, 6390.840228994126, 6408.551401401857, 6273.3099359071375, 6299.181106456132, 6328.493720502441, 6334.524910631629, 6349.650769207553, 5733.0, 6302.590331628429, 6312.5581209314905, 6344.238691357066, 6367.840803979938, 5009.649324547247, 6103.216452666749, 6125.510168486406, 6155.35368786271, 6220.066989951543, 6154.476869466145, 6206.255249370104, 6254.0, 6256.405641464054, 6308.621482711052, 5291.576673866159, 5849.005669036359, 6095.847603809941, 6125.754969801158, 6191.318954914866, 5679.261695979496, 6111.908321375094, 6169.105479705508, 6194.81415557534, 6269.31037585856, 6145.170401995673, 6158.507319414526, 6179.134921110958, 6187.659678717714, 6193.225846769154], "value": 5779.27411883021, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.5799967029178, 495.7298272441519, 511.8669146022038, 520.0275484843343, 536.6128557678402, 38122.0, 41016.0, 41166.17667646645, 41287.38068928968, 41340.79329603352, 42359.57640423595, 42814.357784633256, 42874.785626071825, 42946.570534294646, 43099.78450107745, 9518.476483793405, 9724.02759724029, 9752.36609620376, 9753.0, 9753.0, 9590.0, 9652.75868103296, 9668.178204852597, 9677.467739274338, 9682.177014953737, 9688.854667179998, 9693.515324233795, 9697.709068727949, 9714.514274286294, 9720.368176068532, 8232.794180145484, 9595.472249026301, 9628.903710962872, 9679.467629280352, 9687.66093186735, 9624.566894489764, 9657.903420965771, 9684.70945871618, 9724.70825875225, 9753.414795112316, 40170.0, 40456.190876182605, 40591.39112913309, 40634.984125396804, 40775.388369174485, 3572.732045096628, 3633.0, 3637.7089832813313, 3892.3772196448717, 4037.8788636340955, 32377.83811080953, 32664.510032349535, 32994.01017969465, 33078.50382244268, 33413.83293083519, 3217.0, 3241.205904553376, 3257.218267615779, 3308.7353011759, 3424.9657503424896, 391084.0, 411138.88861111604, 413590.999999997, 466933.66533167457, 479396.0, 60381.698091509264, 60450.0, 60535.69732151399, 60726.696366518765, 60742.69628651829, 29260.0, 35170.82414587911, 35642.10894727652, 36797.0, 36814.44778328327, 13386.866131338562, 16229.756553651705, 16239.51281461558, 16417.507474775775, 16451.753223701653, 10950.452477376099, 11916.940415297868, 12418.88230059299, 12544.247345159138, 12546.996240300754, 15980.76028859568, 19408.514787130436, 19589.706154407697, 19614.607707846048, 19706.0, 7303.6348182590655, 7644.579548124907, 7938.483998540077, 8028.1169071403265, 8086.393520485987, 877.5963056993727, 953.208836665566, 956.4261443134064, 1071.7213524483534, 1133.926294790836, 29644.555331670043, 30256.0, 30556.694433055385, 30905.536416953764, 31034.534481982788, 996.6412091647002, 1009.5053423822421, 1011.1051719228535, 1072.9892701073045, 1140.5551834784442, 15379.61550961235, 15664.530064098095, 15760.211989400485, 15962.680746385007, 15969.760453593206, 614889.0, 618180.9090954517, 618652.0, 618828.9058554679, 618962.0, 45207.54792452033, 55996.440035599124, 56134.87730245454, 56297.0, 56712.43287567154, 1284.9270858832979, 1327.7809161488246, 1392.401267455004, 1419.22652154576, 1426.4436869620856, 28547.14358569246, 28559.571606425503, 28819.855900720362, 30200.395992080474, 30324.69675303307, 50119.74940125276, 50548.98902022013, 50653.49346506488, 50673.49326506688, 50675.98648026945, 499446.0, 500627.0, 500836.0, 501052.0, 501923.0, 26523.60214596744, 26842.657867106176, 26882.059127930428, 26931.461370772082, 26941.46117077608, 46926.0, 47123.52876471191, 47880.0, 48298.517014829406, 49050.50949490602, 38718.0, 38791.6120838788, 38948.2210355797, 39686.0, 40091.79954100211, 38961.025974350865, 39285.41071883867, 39437.0, 40564.0, 40615.0, 38694.22611547697, 39067.41398879076, 39595.20809583735, 39660.80169599134, 40480.797596011835, 31243.84378078095, 34671.0, 35189.82405087958, 35314.11714707153, 35360.82319588386, 22527.774722253223, 25643.487130257665, 25995.700214989552, 26065.0, 26232.213033609023], "value": 19641.844730511773, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.44235502019257, 373.4957806960602, 376.75322663655305, 383.77549133756753, 404.45196758392376, 19860.90069549652, 20520.486987825312, 21243.04406301716, 21284.57430851384, 21924.123035078577, 20239.595208095867, 20483.87338696371, 20592.382228533166, 21049.684254736192, 21301.828399438025, 5953.107033944922, 6053.545984051192, 6063.605865618721, 6066.908996365058, 6101.389861013908, 4166.854160104397, 5999.0, 6029.60807547508, 6053.909191362133, 6492.967535162317, 6703.463722902156, 6768.289329620393, 6773.187217533903, 6784.219814721297, 6846.863062738742, 5663.886722265553, 6634.701438435282, 6699.028640847074, 6736.932630673679, 6750.088738020402, 4988.426330971912, 5059.747012649353, 5643.463870932255, 5770.16332631766, 5815.0, 13266.403011864393, 13306.401211945486, 19004.0, 24203.515929681485, 25749.613755793678, 1761.5331937036694, 2010.447127040061, 2134.3810295014428, 2160.751513575943, 2207.0, 8980.685676001374, 10280.845787313196, 10302.639407620773, 10332.07011368973, 10376.221783366278, 1864.0, 1876.493346796371, 1884.1521315408122, 1888.5265342425114, 1962.479942815155, 132957.0, 136913.3154334222, 137452.31273843566, 140585.29707351397, 140762.88855667174, 8506.0, 9515.381500202604, 10670.893291067145, 10682.252242343116, 11721.0, 14614.85385146135, 14617.0, 14638.707225855424, 14639.194844283667, 14688.853111468963, 8202.220789025034, 8482.830343393221, 8509.787255318666, 8535.871961920577, 8590.656373745105, 8378.874316885252, 8390.496570205807, 8430.957845210734, 8550.845635839207, 8568.0, 4410.29435290352, 4531.932021019687, 4585.977070114628, 4616.492185859553, 4701.7414042228, 1434.189682829198, 1558.9710790877966, 1890.7825600055894, 1947.912343944526, 1953.5018570264776, 830.3440282177133, 837.8270421410075, 844.3835999720116, 846.9872951905726, 898.9640414383482, 19108.426747197605, 20468.283610073875, 21291.254806081713, 21497.78502214958, 21596.0, 854.4019186569302, 915.2769312243387, 960.313375936209, 960.7742180587557, 968.3221744778683, 8381.664733410717, 8625.180607842245, 11370.602028928948, 11455.770884582262, 11551.364674942959, 600158.0, 611235.0, 615781.0, 626194.6424821788, 633401.0, 123749.0, 134663.32668336594, 146438.0, 147107.5289247094, 147586.2413758637, 810.8378324335157, 921.0743203080995, 953.4422362917633, 1010.9477760146914, 1014.9340292880939, 14240.430382784987, 16161.0, 17724.202410891285, 19304.938228397572, 19552.511187220432, 47768.761156194, 51047.19240441663, 53657.46342536525, 54855.628609285086, 56007.15989260083, 81151.0, 81698.77451838108, 82266.0, 83087.58456207681, 84521.57739211265, 7730.11103723079, 8061.113277539349, 8070.878936815835, 8424.368172387218, 8468.788280293042, 9233.75344328507, 12234.75530489403, 13050.738985220432, 13146.868531314565, 13597.52408665692, 11287.830682539605, 11321.433928303717, 11635.53457861676, 11675.124365672778, 11700.824487632523, 9338.439693618133, 9833.213342932691, 9848.162906153057, 10781.02970732638, 10974.890251097388, 11278.041366483942, 11454.484548195518, 11715.648530544097, 11915.76168476643, 11919.404029798648, 7849.960750196441, 7945.443818932619, 8344.37417193725, 8374.83250335002, 8385.203405676451, 10121.190304775748, 11347.829782553103, 11526.1931664786, 11551.249168804, 11677.240979336317], "value": 9503.304904780129, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1249.781288274552, 1527.1524304011268, 1710.657868426315, 1887.7734671839382, 1888.8111188881123, 7428.517146385481, 8246.67013319468, 8941.105889411056, 9298.721038368843, 9331.346805723602, 8998.325125615573, 9159.77100572486, 9172.266218702505, 9180.632774689022, 9209.861852072225, 2392.3660230039095, 2421.104191449166, 2421.9636705449434, 2424.2363655448567, 2432.0393444589376, 2346.3195673254736, 2390.725066617335, 2435.415500279938, 2441.377448750566, 2442.2306973303444, 2396.7363590005084, 2412.1436889904007, 2418.6855708757835, 2432.914847980321, 2442.645816356627, 2383.988080059606, 2394.7485514021037, 2404.4950560382254, 2405.6271277951946, 2411.8914648840846, 2381.5117900830287, 2388.379021454417, 2391.8804059797117, 2400.0759707512557, 2404.410919324777, 9019.278457723367, 9168.541572921327, 9172.08279172084, 9174.587143578556, 9189.908100918972, 5315.388730295988, 5443.727813609304, 5476.58925580583, 5491.2312276281355, 5492.148716948879, 3624.855005799765, 3705.6294370562996, 3745.4381842723537, 3751.9062023449633, 3776.150366167618, 1085.9565617375295, 1087.8585783848127, 1088.6081010836092, 1097.4074000039968, 1097.9780404391868, 12099.274043557414, 12207.145499815017, 12233.510659573607, 12237.694057648541, 12291.446884890202, 4490.146872094291, 4582.702124361906, 4711.552402521755, 4756.500567440438, 4766.785494652749, 9340.766480838034, 9414.964353921063, 9452.52737363129, 9460.24318054554, 9468.90531094694, 5348.679079255257, 5360.517553420211, 5360.839174824761, 5362.008028514737, 5396.406395296514, 4505.774711264424, 4512.323151527297, 4703.0, 4873.147199240189, 4992.2761199626575, 3268.9836550817568, 3763.9247215055548, 3800.8669696560487, 3857.8842634721004, 3877.9224415512094, 973.4791886340773, 1033.3231733214757, 1099.7745462180235, 1156.9826452603218, 1195.1753290229713, 818.4639061414783, 900.0, 926.2451102351562, 930.4882314726873, 932.2122806228726, 5157.896842063138, 5729.713514324267, 5914.615549989237, 6048.879022419527, 6195.0, 444.68205233258374, 454.6817227940389, 457.4556278029201, 461.1814030096538, 463.1339395330686, 4010.5187377514885, 4025.798710064485, 4030.9395359069636, 4111.602055301212, 4137.606927341898, 11064.502097405637, 11341.43292835355, 11415.885841141482, 11418.314901105794, 11466.541338346373, 9612.9425763166, 11273.830892536618, 11392.145589080686, 11420.314781112993, 11443.0, 7988.840223195503, 8418.831623367498, 8439.70461033861, 8502.872456913152, 8575.0, 6056.5154787616, 6057.939420605738, 6072.8785424292155, 6168.074788781719, 6267.96866015667, 10343.327683700536, 10535.63125290611, 10595.735106622396, 10614.097801686634, 10615.469226538797, 9912.851307230254, 10331.535080921527, 10351.06840384369, 10483.737906552396, 10487.0, 6905.343992320722, 7470.4023678106705, 7610.526578862003, 7701.345385642283, 7883.014623172104, 8618.353623478139, 8624.18070283322, 8692.217700406994, 8702.695405660772, 8703.390762646553, 7571.772846814604, 7749.612519374122, 7754.263344982218, 7841.9215807843475, 7856.175101614248, 7480.2145774695055, 7596.886046709194, 7601.315881570686, 7632.007838980897, 7661.616919154131, 7087.468439867133, 7184.856302874018, 7237.457190710613, 7238.167610724834, 7243.420526357984, 4727.456342520517, 4840.878978025577, 4915.115279249626, 5088.592912566912, 5176.171812510131, 5079.187330027178, 5080.364954380702, 5106.7446627669215, 5201.973990130025, 5332.653377530448], "value": 4568.917315915282, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.3719270934681, 298.8849293022186, 310.06515356201044, 312.3487528503071, 313.3967113306884, 4654.604358629516, 4955.69740377795, 5402.0, 5541.508849424862, 5588.133839254911, 5534.647774695758, 5537.58468114891, 5550.944490555104, 5594.888102237953, 5609.803656872, 3354.66453354665, 3434.883662809584, 4165.125323682015, 4249.787510624456, 4258.978705106486, 3425.0, 3620.294042661682, 4200.836569166881, 4230.471191101113, 4251.236696147498, 3761.8683346082894, 4071.653909417703, 4141.772202528859, 4156.7921603919685, 4201.957980420202, 3093.474109401385, 3502.3870822606195, 3860.5946375630433, 4168.291390463629, 4175.331946888483, 2812.4515719434717, 3022.3955208958077, 3450.3271861986923, 3484.937094186272, 3638.6725194732603, 4706.999525201775, 4810.85567432974, 5152.0161569761285, 5364.570834333244, 5491.862703432446, 3724.7951362675044, 4701.459332176809, 4702.952970470285, 4740.668153229275, 4769.618430525549, 5325.733713314319, 5793.681347525927, 5861.267341582302, 5883.52931765462, 5906.283109769512, 3132.37352529495, 3773.471713960048, 3927.9214415711526, 3943.38877473992, 3948.545917219499, 5617.634853734494, 6105.45050945417, 6139.846503837395, 6163.445289923929, 6236.532260080467, 3424.7260219182403, 3998.300297447935, 4016.879493615196, 4026.979865100656, 4027.8791636250958, 3577.83899724513, 3939.3106206413763, 4040.9595904040584, 4053.6554392876938, 4081.2653722330265, 4010.2981978153707, 4155.771432571177, 4315.892102697395, 4757.262624293171, 4766.571008609242, 4345.044090300129, 5597.5295316903785, 5819.447152520504, 5852.2735792941185, 5907.645541267538, 1124.5726623882872, 1157.4155051698838, 1277.0741212620962, 1364.0, 1368.8699573540498, 743.3681370834747, 904.8099899021148, 921.589892497832, 923.1507013547553, 932.0725877751545, 4522.796474158605, 5010.899782004412, 5126.7183204198955, 5139.331886854685, 5161.948380516147, 5510.669359838423, 5934.884185802402, 5949.560680121216, 5962.970185149134, 5990.73041713124, 4398.890027749332, 5156.587473002151, 5394.32570928634, 5475.289731582892, 5527.53015993637, 2609.425926296212, 2724.7820174386, 3075.7846950713683, 3130.4678204705256, 3199.776015678927, 5041.0509851736415, 5073.568746656501, 5522.944770552243, 6197.876042479215, 6250.312465628777, 5008.449070602231, 5428.91856622143, 6005.789797357072, 6101.667974687313, 6221.751129954841, 5510.9448905510435, 6285.120083188357, 6290.937090629127, 6303.338149494237, 6340.481033026715, 5121.871953201051, 5709.800156994485, 6020.0, 6079.939200608114, 6219.657918814328, 4824.807007719652, 5711.971440142773, 5734.885302294014, 5964.777220669839, 5967.8209653710455, 5277.525022747971, 5386.165144402624, 5562.3047119110115, 6170.660613666112, 6305.432511074026, 3767.0286278381045, 3965.960340396559, 3969.0, 3977.3039718049804, 3996.1807829395484, 4748.905021899612, 5623.718814059363, 6034.487068599218, 6068.514518838394, 6210.968945155245, 5170.508801663836, 5311.3891902431715, 5516.3380394352935, 5710.571707122065, 5823.621464604957, 4425.336199570091, 5357.19642053695, 5604.747786349705, 5835.44563266489, 5841.853953651193, 5419.206555411149, 5712.914306285326, 5716.571257155647, 5722.4277572242445, 5740.6268592541355, 5512.944870551243, 5847.970760146342, 5862.912056319074, 5899.4985426239255, 5983.850403739942, 4998.800047998185, 5100.260462232881, 5724.685142317213, 5744.741486633028, 5756.971215143897], "value": 4148.680816459213, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.5156335874811, 693.6115775165907, 725.0031207090251, 736.701635837486, 750.3584435307815, 15348.462803801869, 15435.845641543581, 15766.369345226192, 15961.28174232161, 16321.510354689379, 14410.495632652859, 14589.270536473186, 14622.48821291255, 14635.0, 14669.119852808839, 672.2336536348542, 844.0673056272822, 861.6596444404456, 862.2153840005597, 863.4603372891943, 833.8832563441093, 851.0553285852699, 864.8529749942494, 871.7297637732323, 875.0855356152836, 889.8131392407602, 901.9368644194909, 909.6543313540845, 911.1753862754218, 913.0549880873298, 697.8137166816382, 755.5919803306208, 896.6413434626169, 901.0673952459206, 906.4787747045442, 534.254714673029, 666.4701562258017, 667.6695035957184, 668.4151367553391, 673.0980486148584, 14878.776818347733, 15036.548903532912, 15047.849521504755, 15065.76086141474, 15219.391224351013, 14796.852031479655, 14868.992685987361, 14874.925625371912, 15138.167400792952, 15172.924135379362, 11963.64109076729, 12043.879561204363, 12320.420537020993, 12435.12954093214, 12560.497580096877, 3828.540575130974, 3851.441540976543, 4047.271491131595, 4080.163566468867, 4080.5307389650275, 120520.0, 121869.78130218673, 122237.0, 123424.0, 124447.75552244452, 10601.787964240671, 10957.90420957898, 11367.204295699386, 11392.430378481044, 11886.108541859396, 20719.0, 23680.526389472114, 23742.16902408442, 23790.167344143218, 23868.880655596957, 7552.509086909334, 7785.688572457151, 7863.685452581947, 7987.8402431951035, 8013.358931285483, 4430.955690443118, 4542.737001534499, 4557.635389168859, 4559.863204103882, 4575.199340115533, 3302.9174270642948, 3438.9312213755584, 3442.638522955104, 3442.7762195457217, 3482.5472688550326, 600.4625859855405, 669.0633113640941, 674.2515807453723, 681.5433659448181, 683.5112894280613, 586.3374386942812, 593.7031484257872, 594.6045879490177, 678.1760161403918, 680.1566058088034, 9231.769205769775, 9322.30082743797, 9400.764980875532, 9587.760305992406, 9802.803943921224, 518.6473198225168, 547.6084599511369, 561.5619816543103, 645.7998020613625, 675.2133764164693, 4389.582989615981, 4742.881427964328, 4775.355327030876, 4797.865852146836, 4867.63492738039, 113209.30186047216, 129994.0, 133544.0, 168608.0, 169219.0, 144886.55113448942, 146327.2683636575, 146345.86616136474, 147140.26429867782, 149135.0, 112705.0, 118158.40920795513, 118254.40872795582, 119932.80067199391, 121400.0, 8860.734177974671, 9082.001959568885, 9103.590338434655, 9132.0, 9345.15893569582, 64772.67613661902, 66236.66881665561, 66638.0, 67716.50404154253, 67928.98106528308, 62431.37568624256, 71085.0, 72586.27413725795, 78439.97940236717, 81351.37297253903, 13220.0, 13262.469501220228, 13381.39783709755, 13971.441142354599, 14262.71474570482, 12319.162420715944, 15082.773758393852, 15128.319225634652, 15187.696246075238, 15589.220538973233, 11260.38067906273, 11378.715532111764, 11772.411379430823, 12072.698182545506, 14016.579502614937, 12882.64732203105, 13287.80068298957, 14029.579112626638, 14278.2146981917, 14326.856731432552, 11586.22748532199, 13743.931280343933, 13956.511522096678, 14085.08446950945, 14448.076288721573, 11010.284331518425, 11336.6032188873, 11366.886331136913, 11439.428028598702, 11840.230385024946, 10705.0, 10722.918476237746, 11662.533498660297, 11790.174687772116, 11802.468888899848], "value": 7855.869977324059, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1618.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 108.0, 118.0, 115.0, 117.0, 123.0, 113.0, 175.0, 169.0], "value": 126.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 98.0, 172.0, 199.0, 113.0, 140.0, 162.0, 133.0, 201.0, 146.0], "value": 150.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 205.0, 218.0, 211.0, 195.0, 196.0, 197.0, 195.0, 201.0, 196.0], "value": 200.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 92.0, 76.0, 146.0, 62.0, 78.0, 85.0, 83.0, 164.0, 82.0], "value": 93.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 117.0, 87.0, 61.0, 77.0, 141.0, 56.0, 82.0, 144.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 136.0, 126.0, 91.0, 88.0, 134.0, 88.0, 92.0, 87.0], "value": 102.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 120.0, 96.0, 96.0, 110.0, 153.0, 104.0, 98.0, 96.0, 96.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 98.0, 80.0, 100.0, 98.0, 144.0, 148.0, 117.0, 92.0, 89.0], "value": 105.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 60.0, 59.0, 59.0, 63.0, 58.0, 57.0, 58.0, 60.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 70.0, 78.0, 80.0, 79.0, 86.0, 106.0, 102.0, 103.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 89.0, 83.0, 87.0, 96.0, 96.0, 89.0, 88.0, 91.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 157.0, 136.0, 160.0, 155.0, 160.0, 156.0, 162.0, 169.0, 159.0], "value": 157.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 176.0, 174.0, 174.0, 170.0, 170.0, 172.0, 170.0, 171.0, 173.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 84.0, 87.0, 91.0, 91.0, 90.0, 69.0, 68.0, 68.0], "value": 78.5, "unit": "score"}]}]} 22:33:12 INFO - Return code: 0 22:33:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:33:12 ERROR - # TBPL SUCCESS # 22:33:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:33:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:33:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:33:12 INFO - 2016-08-16 22:33:12.475 screenresolution[1671:10530] starting screenresolution argv=screenresolution get 22:33:12 INFO - 2016-08-16 22:33:12.478 screenresolution[1671:10530] Display 0: 1600x1200x32@60 22:33:12 INFO - 2016-08-16 22:33:12.484 screenresolution[1672:10533] starting screenresolution argv=screenresolution list 22:33:12 INFO - Available Modes on Display 0 22:33:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:33:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:33:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:33:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:33:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:33:12 INFO - Intel Iris: 22:33:12 INFO - Chipset Model: Intel Iris 22:33:12 INFO - Type: GPU 22:33:12 INFO - Bus: Built-In 22:33:12 INFO - VRAM (Dynamic, Max): 1536 MB 22:33:12 INFO - Vendor: Intel (0x8086) 22:33:12 INFO - Device ID: 0x0a2e 22:33:12 INFO - Revision ID: 0x0009 22:33:12 INFO - Displays: 22:33:12 INFO - Display: 22:33:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:33:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:33:12 INFO - Display Serial Number: 1600x1200 60 22:33:12 INFO - Main Display: Yes 22:33:12 INFO - Mirror: Off 22:33:12 INFO - Online: Yes 22:33:12 INFO - Rotation: Supported 22:33:12 INFO - Return code: 0 22:33:12 INFO - Running post-action listener: _resource_record_post_action 22:33:12 INFO - [mozharness: 2016-08-17 05:33:12.720257Z] Finished run-tests step (success) 22:33:12 INFO - Running post-run listener: _resource_record_post_run 22:33:12 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 48039936; Write bytes: 713053184; Read time: 379; Write time: 2060 22:33:12 INFO - TinderboxPrint: CPU usage
54.3% 22:33:12 INFO - TinderboxPrint: I/O read bytes / time
48,039,936 / 379 22:33:12 INFO - TinderboxPrint: I/O write bytes / time
713,053,184 / 2,060 22:33:12 INFO - TinderboxPrint: CPU idle
2,214.8 (45.7%) 22:33:12 INFO - TinderboxPrint: CPU system
977.8 (20.2%) 22:33:12 INFO - TinderboxPrint: CPU user
1,653.1 (34.1%) 22:33:12 INFO - TinderboxPrint: Swap in / out
446,550,016 / 0 22:33:12 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392354304; Write bytes: 385525248; Read time: 29260; Write time: 783 22:33:12 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 47069184; Write bytes: 306253312; Read time: 364; Write time: 1221 22:33:13 INFO - Running post-run listener: _upload_blobber_files 22:33:13 INFO - Blob upload gear active. 22:33:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:33:13 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 22:33:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:33:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:33:13 INFO - (blobuploader) - INFO - Open directory for files ... 22:33:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:33:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:33:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:33:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:33:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:33:14 INFO - (blobuploader) - INFO - Done attempting. 22:33:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:33:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:33:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:33:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:33:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:33:15 INFO - (blobuploader) - INFO - Done attempting. 22:33:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:33:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:33:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:33:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:33:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:33:17 INFO - (blobuploader) - INFO - Done attempting. 22:33:17 INFO - (blobuploader) - INFO - Iteration through files over. 22:33:17 INFO - Return code: 0 22:33:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:33:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:33:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04d7a15106a578fe5c4b863fdba18a5d7b1d06fffe1d259c02f862d75e82167e1f6bacf2bcb8d302ba0d0bd95157ec90eccaa7b68e363785127e7d1c684b2c20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ae17f3b48064d5cd42e5c9a80b2aa1c73f2a88865f4bc4af7acb9e6f72540ffb9e03cab189bbb9b11b0518a0fc49c1cea07bf0c91f039a9797e89031b7a52363", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d77d20d5e7eaa3b3fa8a016d0337dcb785bfa374dab65bc14ff4bc5733f5a80acd4e1f0e65bfbf728c48c95edee1d991583eb68bfe2643f973423786d0be578c"} 22:33:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:33:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:33:17 INFO - Contents: 22:33:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04d7a15106a578fe5c4b863fdba18a5d7b1d06fffe1d259c02f862d75e82167e1f6bacf2bcb8d302ba0d0bd95157ec90eccaa7b68e363785127e7d1c684b2c20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ae17f3b48064d5cd42e5c9a80b2aa1c73f2a88865f4bc4af7acb9e6f72540ffb9e03cab189bbb9b11b0518a0fc49c1cea07bf0c91f039a9797e89031b7a52363", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d77d20d5e7eaa3b3fa8a016d0337dcb785bfa374dab65bc14ff4bc5733f5a80acd4e1f0e65bfbf728c48c95edee1d991583eb68bfe2643f973423786d0be578c"} 22:33:17 INFO - Running post-run listener: copy_logs_to_upload_dir 22:33:17 INFO - Copying logs to upload dir... 22:33:17 INFO - mkdir: /builds/slave/test/build/upload/logs 22:33:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.394982 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-16 22:33:18.059723) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 22:33:18.063299) ========= 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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners TMPDIR=/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04d7a15106a578fe5c4b863fdba18a5d7b1d06fffe1d259c02f862d75e82167e1f6bacf2bcb8d302ba0d0bd95157ec90eccaa7b68e363785127e7d1c684b2c20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ae17f3b48064d5cd42e5c9a80b2aa1c73f2a88865f4bc4af7acb9e6f72540ffb9e03cab189bbb9b11b0518a0fc49c1cea07bf0c91f039a9797e89031b7a52363", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d77d20d5e7eaa3b3fa8a016d0337dcb785bfa374dab65bc14ff4bc5733f5a80acd4e1f0e65bfbf728c48c95edee1d991583eb68bfe2643f973423786d0be578c"} build_url:https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010458 build_url: 'https://queue.taskcluster.net/v1/task/c1CFiewwRfC2UYihYz2E2g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04d7a15106a578fe5c4b863fdba18a5d7b1d06fffe1d259c02f862d75e82167e1f6bacf2bcb8d302ba0d0bd95157ec90eccaa7b68e363785127e7d1c684b2c20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ae17f3b48064d5cd42e5c9a80b2aa1c73f2a88865f4bc4af7acb9e6f72540ffb9e03cab189bbb9b11b0518a0fc49c1cea07bf0c91f039a9797e89031b7a52363", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d77d20d5e7eaa3b3fa8a016d0337dcb785bfa374dab65bc14ff4bc5733f5a80acd4e1f0e65bfbf728c48c95edee1d991583eb68bfe2643f973423786d0be578c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 22:33:18.095700) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:33:18.096017) ========= 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.6LnB9CbwVj/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.n6LAv6XSNa/Listeners TMPDIR=/var/folders/qj/8bxn99110qxb41zzcmfgldl000000w/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.005162 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 22:33:18.144626) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 22:33:18.144936) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 22:33:18.145236) ========= ========= Total master_lag: 0.17 =========