builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0199 starttime: 1463475784.59 results: success (0) buildid: 20160517004009 builduid: ef91a013044b49f4abd91345e579e6b6 revision: 17724486e1591858eea09dc327475a25883854a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 02:03:04.594328) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 02:03:04.594754) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 02:03:04.595025) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 02:03:04.614493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 02:03:04.614795) ========= 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.nEhZiZes6Q/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.Vucxapimpv/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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-05-17 02:03:04-- 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% 6.70M=0.002s 2016-05-17 02:03:04 (6.70 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135904 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 02:03:04.768171) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 02:03:04.768479) ========= 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.nEhZiZes6Q/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.Vucxapimpv/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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.026825 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 02:03:04.816114) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-17 02:03:04.816454) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 17724486e1591858eea09dc327475a25883854a4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 17724486e1591858eea09dc327475a25883854a4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nEhZiZes6Q/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.Vucxapimpv/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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-05-17 02:03:04,895 truncating revision to first 12 chars 2016-05-17 02:03:04,895 Setting DEBUG logging. 2016-05-17 02:03:04,896 attempt 1/10 2016-05-17 02:03:04,896 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/17724486e159?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 02:03:07,091 unpacking tar archive at: mozilla-aurora-17724486e159/testing/mozharness/ program finished with exit code 0 elapsedTime=2.447751 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-17 02:03:07.281747) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 02:03:07.282059) ========= script_repo_revision: 17724486e1591858eea09dc327475a25883854a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 02:03:07.282426) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 02:03:07.282702) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 02:03:07.297978) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-17 02:03:07.298356) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nEhZiZes6Q/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.Vucxapimpv/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:03:07 INFO - MultiFileLogger online at 20160517 02:03:07 in /builds/slave/test 02:03:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:03:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:03:07 INFO - {'append_to_log': False, 02:03:07 INFO - 'base_work_dir': '/builds/slave/test', 02:03:07 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:03:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:03:07 INFO - 'branch': 'Mozilla-Aurora', 02:03:07 INFO - 'buildbot_json_path': 'buildprops.json', 02:03:07 INFO - 'config_files': ('talos/mac_config.py',), 02:03:07 INFO - 'default_actions': ('clobber', 02:03:07 INFO - 'read-buildbot-config', 02:03:07 INFO - 'download-and-extract', 02:03:07 INFO - 'populate-webroot', 02:03:07 INFO - 'create-virtualenv', 02:03:07 INFO - 'install', 02:03:07 INFO - 'run-tests'), 02:03:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:03:07 INFO - 'download_minidump_stackwalk': True, 02:03:07 INFO - 'download_symbols': 'ondemand', 02:03:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:03:07 INFO - 'tooltool.py': '/tools/tooltool.py', 02:03:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:03:07 INFO - '/tools/misc-python/virtualenv.py')}, 02:03:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:03:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:03:07 INFO - 'installer_path': 'installer.exe', 02:03:07 INFO - 'log_level': 'info', 02:03:07 INFO - 'log_name': 'talos', 02:03:07 INFO - 'log_to_console': True, 02:03:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:03:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:03:07 INFO - 'opt_config_files': (), 02:03:07 INFO - 'pip_index': False, 02:03:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:03:07 INFO - 'cmd': ('bash', 02:03:07 INFO - '-c', 02:03:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:03:07 INFO - 'enabled': True, 02:03:07 INFO - 'halt_on_failure': False, 02:03:07 INFO - 'name': 'check_screen_resolution'},), 02:03:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:03:07 INFO - 'cmd': ('bash', 02:03:07 INFO - '-c', 02:03:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:03:07 INFO - 'enabled': True, 02:03:07 INFO - 'halt_on_failure': False, 02:03:07 INFO - 'name': 'check_screen_resolution'},), 02:03:07 INFO - 'run_cmd_checks_enabled': True, 02:03:07 INFO - 'sps_profile': False, 02:03:07 INFO - 'sps_profile_interval': 0, 02:03:07 INFO - 'suite': 'dromaeojs-e10s', 02:03:07 INFO - 'system_bits': '32', 02:03:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:03:07 INFO - 'title': 't-yosemite-r7-0199', 02:03:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:03:07 INFO - 'use_talos_json': True, 02:03:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:03:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:03:07 INFO - 'work_dir': 'build'} 02:03:07 INFO - ##### 02:03:07 INFO - ##### Running clobber step. 02:03:07 INFO - ##### 02:03:07 INFO - Running pre-action listener: _resource_record_pre_action 02:03:07 INFO - Running main action method: clobber 02:03:07 INFO - rmtree: /builds/slave/test/build 02:03:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:03:09 INFO - Running post-action listener: _resource_record_post_action 02:03:09 INFO - ##### 02:03:09 INFO - ##### Running read-buildbot-config step. 02:03:09 INFO - ##### 02:03:09 INFO - Running pre-action listener: _resource_record_pre_action 02:03:09 INFO - Running main action method: read_buildbot_config 02:03:09 INFO - Using buildbot properties: 02:03:09 INFO - { 02:03:09 INFO - "project": "", 02:03:09 INFO - "product": "firefox", 02:03:09 INFO - "script_repo_revision": "production", 02:03:09 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:03:09 INFO - "repository": "", 02:03:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:03:09 INFO - "buildid": "20160517004009", 02:03:09 INFO - "stage_platform": "macosx64", 02:03:09 INFO - "basedir": "/builds/slave/test", 02:03:09 INFO - "nightly_build": "True", 02:03:09 INFO - "buildnumber": 11, 02:03:09 INFO - "slavename": "t-yosemite-r7-0199", 02:03:09 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:03:09 INFO - "platform": "yosemite_r7", 02:03:09 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:03:09 INFO - "branch": "mozilla-aurora", 02:03:09 INFO - "repo_path": "releases/mozilla-aurora", 02:03:09 INFO - "revision": "17724486e1591858eea09dc327475a25883854a4", 02:03:09 INFO - "pgo_build": "False", 02:03:09 INFO - "builduid": "ef91a013044b49f4abd91345e579e6b6", 02:03:09 INFO - "slavebuilddir": "test" 02:03:09 INFO - } 02:03:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:03:09 INFO - Running post-action listener: _resource_record_post_action 02:03:09 INFO - ##### 02:03:09 INFO - ##### Running download-and-extract step. 02:03:09 INFO - ##### 02:03:09 INFO - Running pre-action listener: _resource_record_pre_action 02:03:09 INFO - Running main action method: download_and_extract 02:03:09 INFO - mkdir: /builds/slave/test/build/tests 02:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:09 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:03:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:03:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:03:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:03:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:03:10 INFO - Downloaded 1199 bytes. 02:03:10 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:03:10 INFO - Using the following test package requirements: 02:03:10 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:03:10 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:10 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:03:10 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:10 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:03:10 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:10 INFO - u'jsshell-mac.zip'], 02:03:10 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:10 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:03:10 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:03:10 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:10 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:03:10 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:10 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:03:10 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:10 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:03:10 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:10 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:03:10 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:03:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:10 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:03:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:03:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:03:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:03:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:03:11 INFO - Downloaded 18102489 bytes. 02:03:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:03:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:03:14 INFO - Return code: 0 02:03:14 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:03:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:14 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:03:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:03:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:03:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:03:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:03:15 INFO - Downloaded 18102489 bytes. 02:03:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:03:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:03:19 INFO - Return code: 0 02:03:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:19 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:03:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:03:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:03:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:03:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:03:20 INFO - Downloaded 10840145 bytes. 02:03:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:03:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:03:20 INFO - Return code: 0 02:03:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:20 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:03:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:03:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:03:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:03:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:03:23 INFO - Downloaded 114146064 bytes. 02:03:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:03:23 INFO - mkdir: /builds/slave/test/properties 02:03:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:03:23 INFO - Writing to file /builds/slave/test/properties/build_url 02:03:23 INFO - Contents: 02:03:23 INFO - build_url:https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:03:24 INFO - Running post-action listener: _resource_record_post_action 02:03:24 INFO - Running post-action listener: set_extra_try_arguments 02:03:24 INFO - ##### 02:03:24 INFO - ##### Running populate-webroot step. 02:03:24 INFO - ##### 02:03:24 INFO - Running pre-action listener: _resource_record_pre_action 02:03:24 INFO - Running main action method: populate_webroot 02:03:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:03:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:03:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:03:24 INFO - '--tppagecycles', 02:03:24 INFO - '7'], 02:03:24 INFO - 'tests': ['tsvgm']}}, 02:03:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:03:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:03:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:03:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:03:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:03:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:03:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:24 INFO - 'talos_options': ['--disable-e10s'], 02:03:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:03:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:03:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:24 INFO - 'talos_options': ['--disable-e10s'], 02:03:24 INFO - 'tests': ['damp', 'tps']}, 02:03:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:24 INFO - 'tests': ['damp', 'tps']}, 02:03:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:03:24 INFO - 'tests': ['dromaeo_dom']}, 02:03:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:03:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:03:24 INFO - 'tests': ['a11yr', 02:03:24 INFO - 'ts_paint', 02:03:24 INFO - 'tpaint', 02:03:24 INFO - 'sessionrestore', 02:03:24 INFO - 'sessionrestore_no_auto_restore', 02:03:24 INFO - 'tabpaint']}, 02:03:24 INFO - 'other-e10s': {'tests': ['a11yr', 02:03:24 INFO - 'ts_paint', 02:03:24 INFO - 'tpaint', 02:03:24 INFO - 'sessionrestore', 02:03:24 INFO - 'sessionrestore_no_auto_restore', 02:03:24 INFO - 'tabpaint']}, 02:03:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:03:24 INFO - 'ts_paint', 02:03:24 INFO - 'tpaint', 02:03:24 INFO - 'sessionrestore', 02:03:24 INFO - 'sessionrestore_no_auto_restore', 02:03:24 INFO - 'tabpaint']}, 02:03:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:03:24 INFO - 'ts_paint', 02:03:24 INFO - 'tpaint', 02:03:24 INFO - 'sessionrestore', 02:03:24 INFO - 'sessionrestore_no_auto_restore', 02:03:24 INFO - 'tabpaint']}, 02:03:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:03:24 INFO - 'tests': ['a11yr', 02:03:24 INFO - 'ts_paint', 02:03:24 INFO - 'tpaint', 02:03:24 INFO - 'sessionrestore', 02:03:24 INFO - 'sessionrestore_no_auto_restore', 02:03:24 INFO - 'tabpaint']}, 02:03:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:03:24 INFO - 'tests': ['a11yr', 02:03:24 INFO - 'ts_paint', 02:03:24 INFO - 'tpaint', 02:03:24 INFO - 'sessionrestore', 02:03:24 INFO - 'sessionrestore_no_auto_restore', 02:03:24 INFO - 'tabpaint']}, 02:03:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:03:24 INFO - 'tests': ['tsvgx', 02:03:24 INFO - 'tsvgr_opacity', 02:03:24 INFO - 'tart', 02:03:24 INFO - 'tscrollx', 02:03:24 INFO - 'cart']}, 02:03:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:03:24 INFO - 'tsvgr_opacity', 02:03:24 INFO - 'tart', 02:03:24 INFO - 'tscrollx', 02:03:24 INFO - 'cart']}, 02:03:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:24 INFO - 'talos_options': ['--disable-e10s'], 02:03:24 INFO - 'tests': ['tp5o']}, 02:03:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:24 INFO - 'tests': ['tp5o']}, 02:03:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:24 INFO - 'talos_options': ['--disable-e10s', 02:03:24 INFO - '--xperf_path', 02:03:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:03:24 INFO - 'tests': ['tp5n']}, 02:03:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:24 INFO - 'talos_options': ['--xperf_path', 02:03:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:03:24 INFO - 'tests': ['tp5n']}}, 02:03:24 INFO - 'talos.zip': {'path': '', 02:03:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:03:24 INFO - Running post-action listener: _resource_record_post_action 02:03:24 INFO - ##### 02:03:24 INFO - ##### Running create-virtualenv step. 02:03:24 INFO - ##### 02:03:24 INFO - Running pre-action listener: _resource_record_pre_action 02:03:24 INFO - Running main action method: create_virtualenv 02:03:24 INFO - Creating virtualenv /builds/slave/test/build/venv 02:03:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:03:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:03:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:03:24 INFO - Using real prefix '/tools/python27' 02:03:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:03:25 INFO - Installing distribute.............................................................................................................................................................................................done. 02:03:28 INFO - Installing pip.................done. 02:03:28 INFO - Return code: 0 02:03:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda28cb97d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f01e00>, '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 0x104d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104efcb20>, '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.nEhZiZes6Q/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.Vucxapimpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:03:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nEhZiZes6Q/Render', 02:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:28 INFO - 'HOME': '/Users/cltbld', 02:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:28 INFO - 'LOGNAME': 'cltbld', 02:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:28 INFO - 'MOZ_NO_REMOTE': '1', 02:03:28 INFO - 'NO_EM_RESTART': '1', 02:03:28 INFO - 'PAGER': '/bin/cat', 02:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:28 INFO - 'PWD': '/builds/slave/test', 02:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:28 INFO - 'SHELL': '/bin/bash', 02:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vucxapimpv/Listeners', 02:03:28 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 02:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:28 INFO - 'USER': 'cltbld', 02:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:28 INFO - 'XPC_FLAGS': '0x0', 02:03:28 INFO - 'XPC_SERVICE_NAME': '0', 02:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:03:28 INFO - Cleaning up... 02:03:28 INFO - Return code: 0 02:03:28 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda28cb97d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f01e00>, '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 0x104d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104efcb20>, '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.nEhZiZes6Q/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.Vucxapimpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:03:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nEhZiZes6Q/Render', 02:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:28 INFO - 'HOME': '/Users/cltbld', 02:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:28 INFO - 'LOGNAME': 'cltbld', 02:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:28 INFO - 'MOZ_NO_REMOTE': '1', 02:03:28 INFO - 'NO_EM_RESTART': '1', 02:03:28 INFO - 'PAGER': '/bin/cat', 02:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:28 INFO - 'PWD': '/builds/slave/test', 02:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:28 INFO - 'SHELL': '/bin/bash', 02:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vucxapimpv/Listeners', 02:03:28 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 02:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:28 INFO - 'USER': 'cltbld', 02:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:28 INFO - 'XPC_FLAGS': '0x0', 02:03:28 INFO - 'XPC_SERVICE_NAME': '0', 02:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:28 INFO - Downloading/unpacking psutil>=0.7.1 02:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:03:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:03:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:03:31 INFO - Installing collected packages: psutil 02:03:31 INFO - Running setup.py install for psutil 02:03:31 INFO - building 'psutil._psutil_osx' extension 02:03:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:03:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:03:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:03:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:03:32 INFO - building 'psutil._psutil_posix' extension 02:03:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:03:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:03:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:03:32 INFO - ^ 02:03:32 INFO - 1 warning generated. 02:03:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:03:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:03:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:03:32 INFO - Successfully installed psutil 02:03:32 INFO - Cleaning up... 02:03:32 INFO - Return code: 0 02:03:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03: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', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda28cb97d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f01e00>, '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 0x104d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104efcb20>, '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.nEhZiZes6Q/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.Vucxapimpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:03: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:03: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 mozsystemmonitor==0.0.0 02:03:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nEhZiZes6Q/Render', 02:03:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:32 INFO - 'HOME': '/Users/cltbld', 02:03:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:32 INFO - 'LOGNAME': 'cltbld', 02:03:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:32 INFO - 'MOZ_NO_REMOTE': '1', 02:03:32 INFO - 'NO_EM_RESTART': '1', 02:03:32 INFO - 'PAGER': '/bin/cat', 02:03:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:32 INFO - 'PWD': '/builds/slave/test', 02:03:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:32 INFO - 'SHELL': '/bin/bash', 02:03:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vucxapimpv/Listeners', 02:03:32 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 02:03:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:32 INFO - 'USER': 'cltbld', 02:03:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:32 INFO - 'XPC_FLAGS': '0x0', 02:03:32 INFO - 'XPC_SERVICE_NAME': '0', 02:03:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:33 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:35 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:03:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:03:35 INFO - Installing collected packages: mozsystemmonitor 02:03:35 INFO - Running setup.py install for mozsystemmonitor 02:03:35 INFO - Successfully installed mozsystemmonitor 02:03:35 INFO - Cleaning up... 02:03:35 INFO - Return code: 0 02:03:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:35 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 0x105062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda28cb97d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f01e00>, '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 0x104d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104efcb20>, '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.nEhZiZes6Q/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.Vucxapimpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:03:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:03:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:03:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nEhZiZes6Q/Render', 02:03:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:35 INFO - 'HOME': '/Users/cltbld', 02:03:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:35 INFO - 'LOGNAME': 'cltbld', 02:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:35 INFO - 'MOZ_NO_REMOTE': '1', 02:03:35 INFO - 'NO_EM_RESTART': '1', 02:03:35 INFO - 'PAGER': '/bin/cat', 02:03:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:35 INFO - 'PWD': '/builds/slave/test', 02:03:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:35 INFO - 'SHELL': '/bin/bash', 02:03:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vucxapimpv/Listeners', 02:03:35 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 02:03:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:35 INFO - 'USER': 'cltbld', 02:03:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:35 INFO - 'XPC_FLAGS': '0x0', 02:03:35 INFO - 'XPC_SERVICE_NAME': '0', 02:03:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:36 INFO - Downloading/unpacking blobuploader==1.2.4 02:03:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:38 INFO - Downloading blobuploader-1.2.4.tar.gz 02:03:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:03:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:03:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:03:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:39 INFO - Downloading docopt-0.6.1.tar.gz 02:03:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:03:39 INFO - Installing collected packages: blobuploader, requests, docopt 02:03:39 INFO - Running setup.py install for blobuploader 02:03:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:03:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:03:40 INFO - Running setup.py install for requests 02:03:40 INFO - Running setup.py install for docopt 02:03:40 INFO - Successfully installed blobuploader requests docopt 02:03:40 INFO - Cleaning up... 02:03:40 INFO - Return code: 0 02:03:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:40 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 0x105062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda28cb97d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f01e00>, '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 0x104d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104efcb20>, '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.nEhZiZes6Q/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.Vucxapimpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:03:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:03:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:03:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nEhZiZes6Q/Render', 02:03:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:40 INFO - 'HOME': '/Users/cltbld', 02:03:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:40 INFO - 'LOGNAME': 'cltbld', 02:03:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:40 INFO - 'MOZ_NO_REMOTE': '1', 02:03:40 INFO - 'NO_EM_RESTART': '1', 02:03:40 INFO - 'PAGER': '/bin/cat', 02:03:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:40 INFO - 'PWD': '/builds/slave/test', 02:03:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:40 INFO - 'SHELL': '/bin/bash', 02:03:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vucxapimpv/Listeners', 02:03:40 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 02:03:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:40 INFO - 'USER': 'cltbld', 02:03:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:40 INFO - 'XPC_FLAGS': '0x0', 02:03:40 INFO - 'XPC_SERVICE_NAME': '0', 02:03:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:03:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-6i8WF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:03:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-Q3EHvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:03:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-CjNiS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:03:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-VVsRKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:03:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-wsMR1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:03:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-w11OgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:03:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-oJBExy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:03:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-DLAEEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:03:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-UhUT_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:03:41 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-jOOWKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:03:42 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-pOOZmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:03:42 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-eHT4m8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:03:42 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-dYPEIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:03:42 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-nqFuta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:03:42 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-46Ze_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:03:42 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-81otDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:03:42 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-FeHcnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:03:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:03:42 INFO - Running setup.py install for manifestparser 02:03:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:03:42 INFO - Running setup.py install for mozcrash 02:03:43 INFO - Running setup.py install for mozdebug 02:03:43 INFO - Running setup.py install for mozdevice 02:03:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:03:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:03:43 INFO - Running setup.py install for mozfile 02:03:43 INFO - Running setup.py install for mozhttpd 02:03:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:03:43 INFO - Running setup.py install for mozinfo 02:03:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:03:43 INFO - Running setup.py install for mozInstall 02:03:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:03:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:03:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:03:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:03:43 INFO - Running setup.py install for mozleak 02:03:44 INFO - Running setup.py install for mozlog 02:03:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:03:44 INFO - Running setup.py install for moznetwork 02:03:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:03:44 INFO - Running setup.py install for mozprocess 02:03:44 INFO - Running setup.py install for mozprofile 02:03:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:03:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:03:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:03:44 INFO - Running setup.py install for mozrunner 02:03:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:03:44 INFO - Running setup.py install for mozscreenshot 02:03:45 INFO - Running setup.py install for moztest 02:03:45 INFO - Running setup.py install for mozversion 02:03:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:03:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:03:45 INFO - Cleaning up... 02:03:45 INFO - Return code: 0 02:03:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:03:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:45 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 0x105062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda28cb97d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f01e00>, '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 0x104d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104efcb20>, '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.nEhZiZes6Q/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.Vucxapimpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:03:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:03:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nEhZiZes6Q/Render', 02:03:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:45 INFO - 'HOME': '/Users/cltbld', 02:03:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:45 INFO - 'LOGNAME': 'cltbld', 02:03:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:45 INFO - 'MOZ_NO_REMOTE': '1', 02:03:45 INFO - 'NO_EM_RESTART': '1', 02:03:45 INFO - 'PAGER': '/bin/cat', 02:03:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:45 INFO - 'PWD': '/builds/slave/test', 02:03:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:45 INFO - 'SHELL': '/bin/bash', 02:03:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vucxapimpv/Listeners', 02:03:45 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 02:03:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:45 INFO - 'USER': 'cltbld', 02:03:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:45 INFO - 'XPC_FLAGS': '0x0', 02:03:45 INFO - 'XPC_SERVICE_NAME': '0', 02:03:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:03:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-qgE6vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:03:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-ZUXaXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:03:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-bShf8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:03:45 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-kMnRlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:03:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-sVv4tK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:03:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-Xq9f4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:03:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-3V8AXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:03:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-VXnYpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:03:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-BvE4Hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:03:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-wR96_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:03:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-8x5XD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:03:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-MskkNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:03:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-F0OfFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:03:46 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-DvKRk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:03:47 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-S2jzHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:03:47 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-RcE8Ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:03:47 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-MJGmSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:03:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:03:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:49 INFO - Downloading blessings-1.6.tar.gz 02:03:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:03:49 INFO - Installing collected packages: blessings 02:03:49 INFO - Running setup.py install for blessings 02:03:50 INFO - Successfully installed blessings 02:03:50 INFO - Cleaning up... 02:03:50 INFO - Return code: 0 02:03:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:03:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:03:50 INFO - Reading from file tmpfile_stdout 02:03:50 INFO - Current package versions: 02:03:50 INFO - blessings == 1.6 02:03:50 INFO - blobuploader == 1.2.4 02:03:50 INFO - docopt == 0.6.1 02:03:50 INFO - manifestparser == 1.1 02:03:50 INFO - mozInstall == 1.12 02:03:50 INFO - mozcrash == 0.17 02:03:50 INFO - mozdebug == 0.1 02:03:50 INFO - mozdevice == 0.48 02:03:50 INFO - mozfile == 1.2 02:03:50 INFO - mozhttpd == 0.7 02:03:50 INFO - mozinfo == 0.9 02:03:50 INFO - mozleak == 0.1 02:03:50 INFO - mozlog == 3.1 02:03:50 INFO - moznetwork == 0.27 02:03:50 INFO - mozprocess == 0.22 02:03:50 INFO - mozprofile == 0.28 02:03:50 INFO - mozrunner == 6.11 02:03:50 INFO - mozscreenshot == 0.1 02:03:50 INFO - mozsystemmonitor == 0.0 02:03:50 INFO - moztest == 0.7 02:03:50 INFO - mozversion == 1.4 02:03:50 INFO - psutil == 3.1.1 02:03:50 INFO - requests == 1.2.3 02:03:50 INFO - wsgiref == 0.1.2 02:03:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:50 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 0x105062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda28cb97d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f01e00>, '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 0x104d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104efcb20>, '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.nEhZiZes6Q/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.Vucxapimpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:03:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:03:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nEhZiZes6Q/Render', 02:03:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:50 INFO - 'HOME': '/Users/cltbld', 02:03:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:50 INFO - 'LOGNAME': 'cltbld', 02:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:50 INFO - 'MOZ_NO_REMOTE': '1', 02:03:50 INFO - 'NO_EM_RESTART': '1', 02:03:50 INFO - 'PAGER': '/bin/cat', 02:03:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:50 INFO - 'PWD': '/builds/slave/test', 02:03:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:50 INFO - 'SHELL': '/bin/bash', 02:03:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vucxapimpv/Listeners', 02:03:50 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 02:03:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:50 INFO - 'USER': 'cltbld', 02:03:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:50 INFO - 'XPC_FLAGS': '0x0', 02:03:50 INFO - 'XPC_SERVICE_NAME': '0', 02:03:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:03:50 INFO - Cleaning up... 02:03:50 INFO - Return code: 0 02:03:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda28cb97d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f01e00>, '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 0x104d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104efcb20>, '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.nEhZiZes6Q/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.Vucxapimpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:03:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:03:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nEhZiZes6Q/Render', 02:03:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:50 INFO - 'HOME': '/Users/cltbld', 02:03:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:50 INFO - 'LOGNAME': 'cltbld', 02:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:50 INFO - 'MOZ_NO_REMOTE': '1', 02:03:50 INFO - 'NO_EM_RESTART': '1', 02:03:50 INFO - 'PAGER': '/bin/cat', 02:03:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:50 INFO - 'PWD': '/builds/slave/test', 02:03:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:50 INFO - 'SHELL': '/bin/bash', 02:03:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vucxapimpv/Listeners', 02:03:50 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 02:03:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:50 INFO - 'USER': 'cltbld', 02:03:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:50 INFO - 'XPC_FLAGS': '0x0', 02:03:50 INFO - 'XPC_SERVICE_NAME': '0', 02:03:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:51 INFO - Downloading/unpacking jsonschema 02:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:03:53 INFO - Downloading/unpacking functools32 (from jsonschema) 02:03:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:53 INFO - Downloading functools32-3.2.3-2.tar.gz 02:03:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:03:54 INFO - warning: no files found matching '*.txt' 02:03:54 INFO - no previously-included directories found matching 'build' 02:03:54 INFO - no previously-included directories found matching 'dist' 02:03:54 INFO - no previously-included directories found matching '.git*' 02:03:54 INFO - Installing collected packages: jsonschema, functools32 02:03:54 INFO - Running setup.py install for functools32 02:03:54 INFO - warning: no files found matching '*.txt' 02:03:54 INFO - no previously-included directories found matching 'build' 02:03:54 INFO - no previously-included directories found matching 'dist' 02:03:54 INFO - no previously-included directories found matching '.git*' 02:03:54 INFO - Successfully installed jsonschema functools32 02:03:54 INFO - Cleaning up... 02:03:54 INFO - Return code: 0 02:03:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda28cb97d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f01e00>, '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 0x104d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104efcb20>, '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.nEhZiZes6Q/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.Vucxapimpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:03:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:03:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nEhZiZes6Q/Render', 02:03:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:54 INFO - 'HOME': '/Users/cltbld', 02:03:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:54 INFO - 'LOGNAME': 'cltbld', 02:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:54 INFO - 'MOZ_NO_REMOTE': '1', 02:03:54 INFO - 'NO_EM_RESTART': '1', 02:03:54 INFO - 'PAGER': '/bin/cat', 02:03:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:54 INFO - 'PWD': '/builds/slave/test', 02:03:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:54 INFO - 'SHELL': '/bin/bash', 02:03:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vucxapimpv/Listeners', 02:03:54 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 02:03:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:54 INFO - 'USER': 'cltbld', 02:03:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:54 INFO - 'XPC_FLAGS': '0x0', 02:03:54 INFO - 'XPC_SERVICE_NAME': '0', 02:03:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:54 INFO - Downloading/unpacking flake8 02:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:03:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:03:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:03:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:03:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:03:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:03:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:03:58 INFO - no previously-included directories found matching 'docs/_build' 02:03:58 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:03:58 INFO - Running setup.py install for pep8 02:03:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:03:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:03:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:03:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:03:58 INFO - no previously-included directories found matching 'docs/_build' 02:03:58 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:03:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:03:58 INFO - Cleaning up... 02:03:58 INFO - Return code: 0 02:03:58 INFO - Running post-action listener: _resource_record_post_action 02:03:58 INFO - Running post-action listener: _start_resource_monitoring 02:03:58 INFO - Starting resource monitoring. 02:03:58 INFO - ##### 02:03:58 INFO - ##### Running install step. 02:03:58 INFO - ##### 02:03:58 INFO - Running pre-action listener: _resource_record_pre_action 02:03:58 INFO - Running main action method: install 02:03:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:03:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:03:58 INFO - Reading from file tmpfile_stdout 02:03:58 INFO - Detecting whether we're running mozinstall >=1.0... 02:03:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:03:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:03:59 INFO - Reading from file tmpfile_stdout 02:03:59 INFO - Output received: 02:03:59 INFO - Usage: mozinstall [options] installer 02:03:59 INFO - Options: 02:03:59 INFO - -h, --help show this help message and exit 02:03:59 INFO - -d DEST, --destination=DEST 02:03:59 INFO - Directory to install application into. [default: 02:03:59 INFO - "/builds/slave/test"] 02:03:59 INFO - --app=APP Application being installed. [default: firefox] 02:03:59 INFO - mkdir: /builds/slave/test/build/application 02:03:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:03:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:04:31 INFO - Reading from file tmpfile_stdout 02:04:31 INFO - Output received: 02:04:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:04:31 INFO - Running post-action listener: _resource_record_post_action 02:04:31 INFO - ##### 02:04:31 INFO - ##### Running run-tests step. 02:04:31 INFO - ##### 02:04:31 INFO - Running pre-action listener: _resource_record_pre_action 02:04:31 INFO - Running main action method: run_tests 02:04:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:04:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:04:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:04:31 INFO - 2016-05-17 02:04:31.341 screenresolution[1639:7802] starting screenresolution argv=screenresolution get 02:04:31 INFO - 2016-05-17 02:04:31.343 screenresolution[1639:7802] Display 0: 1600x1200x32@60 02:04:31 INFO - 2016-05-17 02:04:31.349 screenresolution[1640:7804] starting screenresolution argv=screenresolution list 02:04:31 INFO - Available Modes on Display 0 02:04:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:04:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:04:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:04:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:04:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:04:31 INFO - Intel Iris: 02:04:31 INFO - Chipset Model: Intel Iris 02:04:31 INFO - Type: GPU 02:04:31 INFO - Bus: Built-In 02:04:31 INFO - VRAM (Dynamic, Max): 1536 MB 02:04:31 INFO - Vendor: Intel (0x8086) 02:04:31 INFO - Device ID: 0x0a2e 02:04:31 INFO - Revision ID: 0x0009 02:04:31 INFO - Displays: 02:04:31 INFO - Display: 02:04:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:04:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:04:31 INFO - Display Serial Number: 1600x1200 60 02:04:31 INFO - Main Display: Yes 02:04:31 INFO - Mirror: Off 02:04:31 INFO - Online: Yes 02:04:31 INFO - Rotation: Supported 02:04:31 INFO - Return code: 0 02:04:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:04:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:04:31 INFO - Python 2.7.3 02:04:31 INFO - Return code: 0 02:04:31 INFO - grabbing minidump binary from tooltool 02:04:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:04:31 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f01e00>, '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 0x104d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104efcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:04:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:04:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:04:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:04:31 INFO - Return code: 0 02:04:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:04:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:04:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:04:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:04:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:04:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:04:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:04:32 INFO - Return code: 0 02:04:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0199', '--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 02:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0199 --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 02:04:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nEhZiZes6Q/Render', 02:04:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:32 INFO - 'HOME': '/Users/cltbld', 02:04:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:32 INFO - 'LOGNAME': 'cltbld', 02:04:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:04:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:32 INFO - 'MOZ_NO_REMOTE': '1', 02:04:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:04:32 INFO - 'NO_EM_RESTART': '1', 02:04:32 INFO - 'PAGER': '/bin/cat', 02:04:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:32 INFO - 'PWD': '/builds/slave/test', 02:04:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:04:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:32 INFO - 'SHELL': '/bin/bash', 02:04:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vucxapimpv/Listeners', 02:04:32 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 02:04:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:32 INFO - 'USER': 'cltbld', 02:04:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:32 INFO - 'XPC_FLAGS': '0x0', 02:04:32 INFO - 'XPC_SERVICE_NAME': '0', 02:04:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0199', '--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 02:04:32 INFO - mozversion application_buildid: 20160517004009 02:04:32 INFO - mozversion application_changeset: 17724486e1591858eea09dc327475a25883854a4 02:04:32 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:04:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:04:32 INFO - mozversion application_name: Firefox 02:04:32 INFO - mozversion application_remotingname: firefox-dev 02:04:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:04:32 INFO - mozversion application_vendor: Mozilla 02:04:32 INFO - mozversion application_version: 48.0a2 02:04:32 INFO - mozversion platform_buildid: 20160517004009 02:04:32 INFO - mozversion platform_changeset: 17724486e1591858eea09dc327475a25883854a4 02:04:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:04:32 INFO - mozversion platform_version: 48.0a2 02:04:32 INFO - using testdate: 1463475872 02:04:32 INFO - actual date: 1463475872 02:04:32 INFO - starting webserver on 'localhost:49270' 02:04:32 INFO - SUITE-START | Running 2 tests 02:04:32 INFO - TEST-START | dromaeo_css 02:04:32 INFO - operating with platform_type : mac_ 02:04:32 INFO - Initialising browser for dromaeo_css test... 02:04:33 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpHRv00c/profile http://localhost:49270/getInfo.html) 02:04:36 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 02:04:36 INFO - PROCESS | 1658 | colorDepth:24 02:04:36 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 02:04:36 INFO - PROCESS | 1658 | __metrics 02:04:36 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 02:04:36 INFO - TEST-INFO | 1658: exit 0 02:04:36 INFO - Browser initialized. 02:04:36 INFO - Running cycle 1/1 for dromaeo_css test... 02:04:36 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpHRv00c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:04:48 INFO - PROCESS | 1662 | RSS: Main: 220934144 02:04:48 INFO - PROCESS | 1662 | 02:05:38 INFO - PROCESS | 1662 | 1463475938400 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:07:49 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 02:07:51 INFO - PROCESS | 1662 | RSS: Main: 231419904 02:07:51 INFO - PROCESS | 1662 | 02:10:53 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 02:10:53 INFO - PROCESS | 1662 | RSS: Main: 243077120 02:10:53 INFO - PROCESS | 1662 | 02:13:55 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 02:13:55 INFO - PROCESS | 1662 | RSS: Main: 245063680 02:13:55 INFO - PROCESS | 1662 | 02:16:57 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 02:16:58 INFO - PROCESS | 1662 | RSS: Main: 247300096 02:16:58 INFO - PROCESS | 1662 | 02:20:00 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 02:20:01 INFO - PROCESS | 1662 | RSS: Main: 249741312 02:20:01 INFO - PROCESS | 1662 | 02:23:03 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 02:23:04 INFO - PROCESS | 1662 | RSS: Main: 250638336 02:23:04 INFO - PROCESS | 1662 | 02:23:04 INFO - PROCESS | 1662 | __start_tp_report 02:23:04 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 02:23:04 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 02:23:04 INFO - PROCESS | 1662 | |i|pagename|runs| 02:23:04 INFO - PROCESS | 1662 | |0;dojo.html;3114.6106736657916;3528.3472557576843;3537.2571759930415;3566.1084728817796;3582.5521809773777;5844.707764611769;5906.261717285334;5923.9407605923925;5925.940740592593;5953.285605727318;5928.762849486026;5971.402859714027;5990.8502287442725;5992.3108842483125;6013.977623803953;5303.496167864057;5324.7071411072375;5411.296531450906;5416.052190866602;5423.105187644034;5245.711485868275;5344.973275133619;5382.085045542267;5470.9452905470835;5478.096114141182;5343.23673187849;5346;5385.865353366158;5444.7822087116465;5445.618806683534;4407.911841763179;5216.556592689626;5359.705216213107;5375.11310633745;5391.32608423947;5241.711705856176;5257.369115706101;5294.735263236823;5339.172428273624;5358.410574836765;5108.5402313791565;5195.506426889439;5624.015797235467;5686.516646085088;5842.211301474332;5442.374126975409;5591.9720401397735;6220.222472190976;6269.937300626981;6340.619562826245;6207.099970504297;6263.217097862767;6310.936890631081;6311.463525600329;6337.936620633781;5976.432238937294;5987.012142996444;6004.939950600526;6028;6046.123312119762;5347.406507843692;6263.279722831841;6337.8732425352155;6350.142730731384;6435.774747883802;4368.584984426474;5141.691498510101;6141.30190404341;6287;6363.309280617231;5345.599080069011;6248;6278.560500764903;6297.244330680256;6303.054541818672;6215.34738852423;6264.248290205204;6275.121482992385;6283.748650054038;6284.12022316876;6158.7228574714245;6201.596896201732;6209.379062093845;6241.687915604201;6256.280527739368;5015.924761128586;5016.122178618727;5039.470855560114;5048.823291184791;5067;4564.406627138517;4565.840195593139;4581.702189357748;4583.72497650142;4599.33309670096;4996.725180115129;5840.3575606683235;5890.705464726747;5909.2908850938165;6029.849253768603;6305.432511074026;6310.5267104967315;6313.463355614824;6319.957207060876;6345.841353966095;6015.669138197353;6029.186059881948;6037.909431358533;6040.879182416327;6055.7274922628585;5425.104857698515;5471.890562188734;5525.834224973257;5542.251796007568;5573.331200255995;6430.324815894451;6442.48460123189;6447.709853056717;6473.93180125274;6526.934730652728;5299.364076310867;6191.349908259748;6246.187995560548;6305.085762564494;6313.494920406265;6384.9042264367;6459.709313080829;6475.740970361133;6476.352364763486;6480.611163330215;5481.369642491165;5566.693831839126;6214.378562143749;6290.874182516232;6336.809895703136;6199.256089269316;6229.96885015572;6301.306856245901;6307.369263073656;6319.178506794087;6210.099535567408;6231.40801623845;6252.406021428139;6257.749690012349;6277.1525844010475;5377.596680248918;6215.285242197205;6242.719077641426;6255.124282600348;6265.937340626535;5941.287045554561;5988.37122102173;6100.725467353891;6141.109539116893;6153.38466153399;6279.529035322278;6281.214848143982;6326.335734747785;6328.462080723006;6347.39699728525;6174.9691251545255;6183.783567575113;6221.004639257697;6228;6234.064890266496 02:23:04 INFO - PROCESS | 1662 | |1;ext.html;495.1310450159966;523.2883278740912;568.8094488346404;586.5014737473147;617.9032217813381;38322.616773832255;39142.8042859786;39210.80394598027;39271.41092883602;39364.21271574567;40778;41402;41405.37891931616;41429.37855932163;41525.16949661005;9376.249900008;9418.340716149873;9539.80920381592;9602.855957160647;9621;9501.857472137923;9507.95246023769;9553.426794392359;9715.319927605071;9717.61129554817;9584.856227156599;9663.806723865519;9708.41749495029;9723.708288751348;9743.464109473976;9503.90496095037;9622.13400793932;9658.565364558577;9708.854367184498;9733.561989710444;9562;9661.806763864683;9677.612895484172;9690.75773105671;9701.320907536476;39368.80315598432;39762.403563946566;39800.004999874945;39911.60088399137;39946.800265998485;3138.482150445174;3231.757618178646;3302.4055669979393;3304.7686661933676;3639.963600363989;28407.85796071027;28926.566101508495;29007.129786106452;29260.70739292601;29396.118116456542;2792.539231026878;2792.567152091429;2793;2807.887684492618;2827.6606807183066;314654;365634;368735;370648.14675926446;371667;36172.91481255567;36837.89486315415;62978.68510657509;63551;63773.36226637767;34779.478307825404;34953.825230873685;35216.471752923724;35268.58925642997;35358.292834143685;15431.537053888402;15453.768193476883;15574.844251557566;15636.296366663528;15638.296276667577;13153.342332883318;13290.800637990438;13314.267715275753;13326.93336533311;13341.266230357423;7079.716811327489;7363.631818409058;8181.959090204511;8269.09040005599;8314.584270786558;2957.334599715047;3104.2549788051156;3451.171718787473;3481.3385456763135;3505.298940211968;978.8923218445965;1006.5822683586376;1006.7634105985088;1018.3838777539482;1048.1457612046158;27386.767595458252;28103;28531.001414950377;30356.696433035828;31029.534556981664;1116.9273997190157;1137.38581166171;1146.3236690352733;1192.4514723227412;1244.875512448766;13396.799048014287;13554.864451355557;15549;15640.3743850247;15730.764038539432;650410;651746;652702;658747;661501;60660.393396066356;61028.38971610227;61413.69293153506;61426.77146457135;61430;1475.0928179169734;1509.3660662521986;1517.7950976618235;1527.4806565767692;1595.4176725495342;25583.720813958855;29552.113436596937;29997.800087997108;30220.697793021787;30288.394232114795;52381.47618523766;52848.73575632251;53359;53510;53591.19613205883;562413;567873;568958;572456;577154;26239.212823615322;26747.732522674523;27256;27421.862890685417;27592;44050.89872753207;45491.31763023491;46073.07853842971;46185.845353866425;46346.07307853892;37215.88352349434;38032.23935521329;38351.23297534089;38489.845304640905;38621.4206786904;37480.06299842526;37947.051323717125;37958.62041379551;38391.6160838388;38474;32129.357412851143;37964.810175948944;38237.426438604;38910.221795564496;40704.38943415792;31204.37591248117;32387.676123238467;32672.673273267916;32711.67288327086;32983.83508082444;24647.630285545372;24807.87596062008;24815.255542333758;24974.750252497968;25216.369590760376 02:23:04 INFO - PROCESS | 1662 | |2;jquery.html;349.2752538482649;396.8055652730161;404.34899811303796;405.23737101017025;406.4107044785061;20031.99840008001;20286.28997985071;20664.17343306268;20700;21623.891880540614;21006.474838129016;21338.039788209528;21456.927153642333;21608.459788505257;21742.565148697016;5933.614315069528;5959.821205363824;6251.499880009611;6254.249490061176;6266.310705822356;6051.394860513958;6054.878902421971;6157.846053848667;6192.566520343556;6254.9374506255035;5984.311804142536;6057.757689692407;6082.239720034996;6133.693315334238;6174.691265436711;6031.336552979169;6065.665553578206;6106.511479081685;6146.231721034871;6159.199304090462;5136.794528218904;5298.258243845865;5363.410024897258;5393.97514472249;5399.974004939049;26335;26441.074562390415;26718.73281267182;26882;27046.5943010855;2203.0967303405528;2255.977440225593;2286.531261091472;2367.905283788664;2398.1606437746823;14988.625284367869;15322;15495.690086198325;15514.612134696723;15662.295196716175;1574.559123445437;1735.0717366209144;1752.1326943163087;1788.8121747216614;1794.7397627344092;99496.00503994939;104337;106368;111881;112696.43651781688;9931.404115753077;10801.027907488364;11908.702282442835;11937.343446110373;12118.091143164298;14539.491117811038;14555.41778328855;14576.562703118923;14583.343749531297;14618.926905365404;8911.287097032222;8994.28045756338;9061.501617410973;9077.863832042525;9106.49914254709;8962.910370896338;8988.370814042953;9052;9148.771280718036;9189.081091890757;6303.873922521616;6547.410733034049;6906.930930690629;7004.264552221944;7043.612601306977;1586.5002524204838;1880.9529761756066;1892.6593213221752;1910.5987742574216;1911.9426417207505;915.8809354783835;955.7945041815904;957.9473128977974;993.3791380387258;1028.9022542858415;20550.07524661364;21511;21618.56762864734;21700.023498942584;21892.89053554722;949.2832911152036;980.7106903463496;1014.923880708935;1019.4189312092185;1040.9843852342221;9704.980976997454;11082.61210857616;11728.061755059745;11731.47208375608;11741.647750567497;623550;626695;631582;639296;642087;111435;113721;115021;118226.27094916279;121246.3937680306;1060.6287799270349;1070.768616091501;1075.2043487818958;1090.5092708281304;1097.7859317433183;18330.08349582489;18835.529111772314;18947.147378367732;19040.238390464227;19259.51851203731;35756;47170.52829471799;48796.26805597848;49670;51019.2347114801;59264.92572759933;64293;72578;72628.63685681538;78915;8088.191180881865;8124.512529248264;8357.080721120794;8380.24577788002;8580.970283566012;11636.592719254786;12710.936445317715;12771.744565108831;13020.28388438607;13164.539241126515;5356.062689029326;5914;9485.478298693639;9485.810283794424;10469.31949423285;9647.807043858944;11004.339739615649;11163.553457861595;11305.71735706614;11614.88385116138;10909.781804363709;11242.606508772154;11288.887111128784;11550.249233799776;11933.164678472423;8492.532910689972;8500.404971651926;8550.486970781772;8571.271441927505;8715.607797649247;8470.280026197843;11051.005409513182;11187.105031597615;11343.48954297075;11503.079753619522 02:23:04 INFO - PROCESS | 1662 | |3;mootools.html;1389.944402223911;1582.2088955522238;1791.6774980503503;1906.3232552443883;1967.921283148674;7077.71689132434;7813.453058285922;8646.308295336374;8831.425957312771;8831.867521987167;8571.699990500334;8577.44246623969;8615;8675.349348798849;8693.47839129651;2410.4576470294173;2428.138011006085;2434.294054724128;2436;2457.7296497385273;2439.182873737291;2439.829211955164;2465.827392082555;2477.5664258754737;2489.0790407549143;2022.6460369435363;2438.0247900839718;2451.019592163131;2458.176510868861;2470.419451428913;2423.3941514621347;2442.9022839086415;2449.865257410842;2454.3005243505695;2463.8521688698734;2434.9026038958596;2464.4578192797558;2470.876456177184;2477.8389404688637;2482.354587807165;8752.47485150893;8783.121687831166;8802.207801297849;8831.3376496763;8855.911440885573;5357.732113394315;5477.67133971959;5485.862853428696;5516.255305533742;5523.28197334348;3513.846430049735;3578.2664553766417;3580.337637537038;3614.5120408744747;3652.2878038782446;1043.9478026098664;1050.99474502627;1053.6206965492418;1053.6733612580124;1056.4136904018267;11624;11819.172657913949;11823.645290641294;11833.644990650295;11843.881561184364;4176.394422808676;4812.975935120302;4850.1269771441475;4942.1104201244125;4949.57928576075;10078.697639070839;10109.443980581138;10110.292279540361;10115.696529104138;10149.086582207488;5478.150886612581;5493.862653433696;5510.91733623996;5511.173324001352;5524.364698059694;4408.713433626804;4822.590079843182;4884.8290309838985;4907.411110666742;4934.062528119646;4090.7750073745633;4114.4910488726355;4140.296149654566;4157.584241575879;4171.290880550314;1275.7943743162623;1306.4969986555245;1360.9452674177592;1367.2409078479589;1371.9176849389069;916.6058594804165;923.0769230769231;936.4381371177375;940.4686352210979;948.2224575847877;5873.970630146822;5884.47039766423;5884.529237660977;6008.03871380577;6025.6083354581815;451.22614715762626;460.36009946174846;463.77275135183515;473.2782506677345;484.7503535679128;3459.8789042383396;3880.7089468289428;3941.7634941903;3944.723869329177;3950.288947989332;10361.326513776581;10658.680239592824;10660.933906609276;10692.465376731132;10772.569097236023;9919.900800992042;10310.536025878853;10365.01532354425;10466.895331046591;10556.947215263874;6221.626702397871;8007.919920800718;8061.2744852963515;8132.918670813335;8237.752867413867;5554.750036248298;5676.77292908279;5677.148427735708;5706.80026199081;5781.768729250783;9331.906680933374;9349;9385;9390.201832844012;9489.335746497954;9324.067593240621;9516.429014259167;9731.026897310212;9733.707988760349;9811.018898110131;6762.729490820312;7260.382867456326;7428.702851885864;7486.737964171228;7572.697092116254;8234.917650823654;8284.83430331402;8307.833843322978;8309.750707478785;8346.540940248344;7189.496735228484;7339.853202935805;7345.963270183614;7444.367228785614;7444.590547519939;7154.801720019219;7202.351788339075;7290.9635451822405;7373.262673732798;7406;7032.296770322927;7043.929560704328;7078.823029424306;7080.362767350963;7102.786916392516;4896.338994235733;4899.853004409873;4909.533594308535;4976.154053810788;5017.422996355466;4873.220284754422;4889.584385327287;4903.632227582874;4979.526944940225;5108.667936584259 02:23:04 INFO - PROCESS | 1662 | |4;prototype.html;285.3778762298189;316.9223540232642;324.1927600275314;331.09280571234814;332.4796693175181;6644.667766611662;6727.562708423955;6785.6267905265195;6834.453243740501;6842.228897296596;6787.76242831501;6788.932110678892;6853.246142924287;6860.348266914648;6929.3417125373135;4058.269511487931;4988.052270068684;5025.522575355335;5033.094043072264;5043.1678773002595;4432.734035957837;4761.761911904391;5060.44335123138;5069.290299358093;5089.4910508949;5007.899842003158;5008;5009.148444764381;5011.198208286675;5037;4755.500672429414;4967.975160124212;5001.524855138756;5019;5028.044671512419;4171.582841715835;4185.937210941838;4190.937135942963;4199.265128602513;4200.957990420087;6546.770863019819;6567.211934567835;6576.243731970837;6583.242927063353;6608.735650573972;5865.120231965239;5877.05967045275;5879.617824841414;5893.99802033651;5898.8820223595285;7340.4127669786285;7363.742269020612;7364.815879602999;7369.926300736977;7375.373093287077;4477.597016268552;4682;4683.578477937002;4687.1328804171;4697.78859951303;7659.080910290745;7668.309852113281;7750.496217745885;7751.612419379008;7763.805904852367;4733.242681170997;4750.8337208197545;4755.904881902412;4759.714417134983;4761.095391875532;3928.9214215716097;4146.3158578834755;4260.957390426118;4325.961769175376;4657.208274593328;4323.0705398339505;5209.374875015023;5821.126830975388;5835.35811060783;5844.736986835603;5755.424457554211;7365.594892280868;7368.189499155089;7383.966244725742;7388.852222955511;1373.3957058894055;1633.6242664187303;1652.4381710218584;1664.1429663723193;1704.4290162795603;862.3230763850347;985.108476828457;1040.9947950260353;1061.7451811565322;1065.041462683591;6672.499562532829;6801.18385793708;6868.381845633917;6872.450203983669;6875.5530890491955;6149.538784591174;7669.808254793563;7708.961455192688;7742.961285193538;7750.418718596128;6842.247352791289;6863.519553631198;6874.587524748531;6874.621895795779;6888.521296745112;3244.5295432162675;3270;4050.0887300357185;4064.4309796628495;4066.7356621819486;7745.419093568005;8093.554854482943;8122.309603683635;8139.348852091818;8152.755417337489;7946.04647442299;7994.36045116401;7995.480293781002;7995.880061799077;8019.35845132388;8098.149694282133;8098.75703728889;8119.350451963829;8123.106458289702;8125.390595705346;7460.440466964891;7688.961555192188;7737.264959828807;7749.612519373896;7752.5348479091435;6066.029435290332;6627.668616569056;7179.246179151114;7568.978187944557;7593.8860917087395;8043.597820109088;8074.434789564674;8105.432619716563;8116;8123.593820308843;4938.9259161111895;4997.5002499749735;5007;5015.573676237561;5024.522670346311;7312.341889229995;7606.501009535676;7616.771496855104;7623.428242881917;7640.312371886557;7263.818404539928;7319.524230925186;7335.376492998155;7341.155767086853;7348.926510735038;7282.526635768658;7299.306565876233;7299.744508942162;7324.523905946096;7371.631418429164;5794.681292528783;7099.219085900443;7145.642717863982;7156.892646610201;7187.353138217585;7099.609521476368;7320.231375705687;7341.632918353954;7361.889571656537;7382.224866389167;6854.931450685629;6991.161060672752;7154.463415243774;7169.318914703095;7236.34872861445 02:23:04 INFO - PROCESS | 1662 | |5;yui.html;729.4930023633574;737.1375490675908;740.0822979505411;749.940004799616;763.4350580570377;14565;14776.852231477682;14915.850841491581;14918.850811491895;14960.625984350383;13524.661883452918;13585.932070339659;13638.454461821515;13640.045196836225;13649.45402183914;703.3458883238586;772.3319328780607;887.7691800131947;892.0722448653393;918.995405022974;852.5651917522047;866.1079088538816;888.5601627194516;895.5432729308067;896.7175339767984;961.0773657289011;962.3840741925173;963.1716723617683;965.8696076029752;968.2592816495387;908.1100521488912;908.7182973278304;933.2580598424236;954.7851733360008;957.5978089202551;659.5020759326726;660.1517050589969;698.9510734248605;709.6168069242602;721.7077083781077;14118.92940535301;14263.857361426357;14300.070495417765;14303.570892873231;14313.212773297464;12542.372881355896;12553.121281510297;12639.304838233893;12665.986721062292;12824.743505129845;9880.703578892644;10048.145907597864;10102.230297705846;10340.224483163793;10363.792724145474;3460.377132116218;3517.208628058692;3537.363274610569;3547.8758243461357;3665;74590.62704686442;75000.8749868747;75223;76436.23563764346;78353;9558.761030974281;9596.23230141587;9648.179904708039;9714.077162669535;9720.368176068674;18065.638687226183;21821.127154913625;21827.345179644635;21846.89076554607;21891.562168756536;6400.327965563642;7422.814429639194;7524.887126693104;7534.736284230025;7703.922960770321;4055.878323650295;4158.875233742992;4170.416141740159;4171.416001759756;4176.352665336878;3513.2622149348413;3569.428891377367;3573.9642603574152;3577.177249232691;3582.8387722552548;624.4317670919442;630.3066626710581;633.5375176121365;633.8732253549308;642.4635429416489;546.1943633141085;586.3432955090326;605.0802779774717;608.9543284253699;609.1624016976657;7755.650995705207;8017.278444939983;8044.919550804534;8074.232947869943;8174.223448772357;492.6452953873206;515.8246196293278;585.7042193692158;589.9823005309847;620.5376994139395;3081.6147981502313;3633.691136253395;3767.3972164453558;3829.617038296148;3884.2231553689376;87920.56039719761;89874;89921.65117523112;91093;92214;73778.63110684413;74610.62694686491;74779.50440991258;74804;76502.85245721319;64386.0342094869;64512;65540.67229663917;65702.67148664226;67803.66098169477;7607.277308655669;7625.23747625233;7673.884891726517;7694.115176754744;7784.805379865548;47948.2439926074;48277.5172248273;48621.5137848617;49898.75253118701;49926.75036624939;65784.34215657973;66140.67718645696;66331.66834165798;66961;67744.6612766933;8931.598078086632;10435.634752783617;10711.036006759428;11006.944965275123;11008.614698485515;11557.88442115568;11746.29522228669;11899.940500297444;11947.761044778881;12236.08229382782;11276.097912166842;11279.210455268052;11397.031252343311;11467.942660286646;11770.411479425824;10177.643782467578;10569.26015178961;10594.947025264824;10684.305520141166;11399.316041037564;11084.667459976214;11128.777424451628;11186.496607652838;11316.98147166759;11576.710582235512;8803.251723603566;8929.419587726778;9271.490068046322;9740.610375584898;9806.607735690492;9537.47543885093;9667.323287370098;9689.612415503301;9822.65620703272;10055.346402483816 02:23:04 INFO - PROCESS | 1662 | __end_tp_report 02:23:04 INFO - PROCESS | 1662 | __start_cc_report 02:23:04 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,415 02:23:04 INFO - PROCESS | 1662 | __end_cc_report 02:23:04 INFO - PROCESS | 1662 | __startTimestamp1463476984048__endTimestamp 02:23:04 INFO - PROCESS | 1662 | 02:23:04 INFO - PROCESS | 1662 | ------- Summary: start ------- 02:23:04 INFO - PROCESS | 1662 | Number of tests: 6 02:23:04 INFO - PROCESS | 1662 | 02:23:04 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5801.58 Median:6029.85 stddev:654.78 (10.9%) stddev-sans-first:621.93 02:23:04 INFO - PROCESS | 1662 | Values: 3114.6 3528.3 3537.3 3566.1 3582.6 5844.7 5906.3 5923.9 5925.9 5953.3 5928.8 5971.4 5990.9 5992.3 6014.0 5303.5 5324.7 5411.3 5416.1 5423.1 5245.7 5345.0 5382.1 5470.9 5478.1 5343.2 5346.0 5385.9 5444.8 5445.6 4407.9 5216.6 5359.7 5375.1 5391.3 5241.7 5257.4 5294.7 5339.2 5358.4 5108.5 5195.5 5624.0 5686.5 5842.2 5442.4 5592.0 6220.2 6269.9 6340.6 6207.1 6263.2 6310.9 6311.5 6337.9 5976.4 5987.0 6004.9 6028.0 6046.1 5347.4 6263.3 6337.9 6350.1 6435.8 4368.6 5141.7 6141.3 6287.0 6363.3 5345.6 6248.0 6278.6 6297.2 6303.1 6215.3 6264.2 6275.1 6283.7 6284.1 6158.7 6201.6 6209.4 6241.7 6256.3 5015.9 5016.1 5039.5 5048.8 5067.0 4564.4 4565.8 4581.7 4583.7 4599.3 4996.7 5840.4 5890.7 5909.3 6029.8 6305.4 6310.5 6313.5 6320.0 6345.8 6015.7 6029.2 6037.9 6040.9 6055.7 5425.1 5471.9 5525.8 5542.3 5573.3 6430.3 6442.5 6447.7 6473.9 6526.9 5299.4 6191.3 6246.2 6305.1 6313.5 6384.9 6459.7 6475.7 6476.4 6480.6 5481.4 5566.7 6214.4 6290.9 6336.8 6199.3 6230.0 6301.3 6307.4 6319.2 6210.1 6231.4 6252.4 6257.7 6277.2 5377.6 6215.3 6242.7 6255.1 6265.9 5941.3 5988.4 6100.7 6141.1 6153.4 6279.5 6281.2 6326.3 6328.5 6347.4 6175.0 6183.8 6221.0 6228.0 6234.1 02:23:04 INFO - PROCESS | 1662 | 02:23:04 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:65263.60 Median:37961.72 stddev:145446.88 (383.1%) stddev-sans-first:145773.60 02:23:04 INFO - PROCESS | 1662 | Values: 495.1 523.3 568.8 586.5 617.9 38322.6 39142.8 39210.8 39271.4 39364.2 40778.0 41402.0 41405.4 41429.4 41525.2 9376.2 9418.3 9539.8 9602.9 9621.0 9501.9 9508.0 9553.4 9715.3 9717.6 9584.9 9663.8 9708.4 9723.7 9743.5 9503.9 9622.1 9658.6 9708.9 9733.6 9562.0 9661.8 9677.6 9690.8 9701.3 39368.8 39762.4 39800.0 39911.6 39946.8 3138.5 3231.8 3302.4 3304.8 3640.0 28407.9 28926.6 29007.1 29260.7 29396.1 2792.5 2792.6 2793.0 2807.9 2827.7 314654.0 365634.0 368735.0 370648.1 371667.0 36172.9 36837.9 62978.7 63551.0 63773.4 34779.5 34953.8 35216.5 35268.6 35358.3 15431.5 15453.8 15574.8 15636.3 15638.3 13153.3 13290.8 13314.3 13326.9 13341.3 7079.7 7363.6 8182.0 8269.1 8314.6 2957.3 3104.3 3451.2 3481.3 3505.3 978.9 1006.6 1006.8 1018.4 1048.1 27386.8 28103.0 28531.0 30356.7 31029.5 1116.9 1137.4 1146.3 1192.5 1244.9 13396.8 13554.9 15549.0 15640.4 15730.8 650410.0 651746.0 652702.0 658747.0 661501.0 60660.4 61028.4 61413.7 61426.8 61430.0 1475.1 1509.4 1517.8 1527.5 1595.4 25583.7 29552.1 29997.8 30220.7 30288.4 52381.5 52848.7 53359.0 53510.0 53591.2 562413.0 567873.0 568958.0 572456.0 577154.0 26239.2 26747.7 27256.0 27421.9 27592.0 44050.9 45491.3 46073.1 46185.8 46346.1 37215.9 38032.2 38351.2 38489.8 38621.4 37480.1 37947.1 37958.6 38391.6 38474.0 32129.4 37964.8 38237.4 38910.2 40704.4 31204.4 32387.7 32672.7 32711.7 32983.8 24647.6 24807.9 24815.3 24974.8 25216.4 02:23:04 INFO - PROCESS | 1662 | 02:23:04 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:35217.75 Median:26388.04 stddev:104625.98 (396.5%) stddev-sans-first:104886.72 02:23:04 INFO - PROCESS | 1662 | Values: 349.3 396.8 404.3 405.2 406.4 20032.0 20286.3 20664.2 20700.0 21623.9 21006.5 21338.0 21456.9 21608.5 21742.6 5933.6 5959.8 6251.5 6254.2 6266.3 6051.4 6054.9 6157.8 6192.6 6254.9 5984.3 6057.8 6082.2 6133.7 6174.7 6031.3 6065.7 6106.5 6146.2 6159.2 5136.8 5298.3 5363.4 5394.0 5400.0 26335.0 26441.1 26718.7 26882.0 27046.6 2203.1 2256.0 2286.5 2367.9 2398.2 14988.6 15322.0 15495.7 15514.6 15662.3 1574.6 1735.1 1752.1 1788.8 1794.7 99496.0 104337.0 106368.0 111881.0 112696.4 9931.4 10801.0 11908.7 11937.3 12118.1 14539.5 14555.4 14576.6 14583.3 14618.9 8911.3 8994.3 9061.5 9077.9 9106.5 8962.9 8988.4 9052.0 9148.8 9189.1 6303.9 6547.4 6906.9 7004.3 7043.6 1586.5 1881.0 1892.7 1910.6 1911.9 915.9 955.8 957.9 993.4 1028.9 20550.1 21511.0 21618.6 21700.0 21892.9 949.3 980.7 1014.9 1019.4 1041.0 9705.0 11082.6 11728.1 11731.5 11741.6 623550.0 626695.0 631582.0 639296.0 642087.0 111435.0 113721.0 115021.0 118226.3 121246.4 1060.6 1070.8 1075.2 1090.5 1097.8 18330.1 18835.5 18947.1 19040.2 19259.5 35756.0 47170.5 48796.3 49670.0 51019.2 59264.9 64293.0 72578.0 72628.6 78915.0 8088.2 8124.5 8357.1 8380.2 8581.0 11636.6 12710.9 12771.7 13020.3 13164.5 5356.1 5914.0 9485.5 9485.8 10469.3 9647.8 11004.3 11163.6 11305.7 11614.9 10909.8 11242.6 11288.9 11550.2 11933.2 8492.5 8500.4 8550.5 8571.3 8715.6 8470.3 11051.0 11187.1 11343.5 11503.1 02:23:04 INFO - PROCESS | 1662 | 02:23:04 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5662.37 Median:4908.47 stddev:3131.96 (63.8%) stddev-sans-first:3124.28 02:23:04 INFO - PROCESS | 1662 | Values: 1389.9 1582.2 1791.7 1906.3 1967.9 7077.7 7813.5 8646.3 8831.4 8831.9 8571.7 8577.4 8615.0 8675.3 8693.5 2410.5 2428.1 2434.3 2436.0 2457.7 2439.2 2439.8 2465.8 2477.6 2489.1 2022.6 2438.0 2451.0 2458.2 2470.4 2423.4 2442.9 2449.9 2454.3 2463.9 2434.9 2464.5 2470.9 2477.8 2482.4 8752.5 8783.1 8802.2 8831.3 8855.9 5357.7 5477.7 5485.9 5516.3 5523.3 3513.8 3578.3 3580.3 3614.5 3652.3 1043.9 1051.0 1053.6 1053.7 1056.4 11624.0 11819.2 11823.6 11833.6 11843.9 4176.4 4813.0 4850.1 4942.1 4949.6 10078.7 10109.4 10110.3 10115.7 10149.1 5478.2 5493.9 5510.9 5511.2 5524.4 4408.7 4822.6 4884.8 4907.4 4934.1 4090.8 4114.5 4140.3 4157.6 4171.3 1275.8 1306.5 1360.9 1367.2 1371.9 916.6 923.1 936.4 940.5 948.2 5874.0 5884.5 5884.5 6008.0 6025.6 451.2 460.4 463.8 473.3 484.8 3459.9 3880.7 3941.8 3944.7 3950.3 10361.3 10658.7 10660.9 10692.5 10772.6 9919.9 10310.5 10365.0 10466.9 10556.9 6221.6 8007.9 8061.3 8132.9 8237.8 5554.8 5676.8 5677.1 5706.8 5781.8 9331.9 9349.0 9385.0 9390.2 9489.3 9324.1 9516.4 9731.0 9733.7 9811.0 6762.7 7260.4 7428.7 7486.7 7572.7 8234.9 8284.8 8307.8 8309.8 8346.5 7189.5 7339.9 7346.0 7444.4 7444.6 7154.8 7202.4 7291.0 7373.3 7406.0 7032.3 7043.9 7078.8 7080.4 7102.8 4896.3 4899.9 4909.5 4976.2 5017.4 4873.2 4889.6 4903.6 4979.5 5108.7 02:23:04 INFO - PROCESS | 1662 | 02:23:04 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5952.36 Median:6838.34 stddev:1984.80 (29.0%) stddev-sans-first:1944.26 02:23:04 INFO - PROCESS | 1662 | Values: 285.4 316.9 324.2 331.1 332.5 6644.7 6727.6 6785.6 6834.5 6842.2 6787.8 6788.9 6853.2 6860.3 6929.3 4058.3 4988.1 5025.5 5033.1 5043.2 4432.7 4761.8 5060.4 5069.3 5089.5 5007.9 5008.0 5009.1 5011.2 5037.0 4755.5 4968.0 5001.5 5019.0 5028.0 4171.6 4185.9 4190.9 4199.3 4201.0 6546.8 6567.2 6576.2 6583.2 6608.7 5865.1 5877.1 5879.6 5894.0 5898.9 7340.4 7363.7 7364.8 7369.9 7375.4 4477.6 4682.0 4683.6 4687.1 4697.8 7659.1 7668.3 7750.5 7751.6 7763.8 4733.2 4750.8 4755.9 4759.7 4761.1 3928.9 4146.3 4261.0 4326.0 4657.2 4323.1 5209.4 5821.1 5835.4 5844.7 5755.4 7365.6 7368.2 7384.0 7388.9 1373.4 1633.6 1652.4 1664.1 1704.4 862.3 985.1 1041.0 1061.7 1065.0 6672.5 6801.2 6868.4 6872.5 6875.6 6149.5 7669.8 7709.0 7743.0 7750.4 6842.2 6863.5 6874.6 6874.6 6888.5 3244.5 3270.0 4050.1 4064.4 4066.7 7745.4 8093.6 8122.3 8139.3 8152.8 7946.0 7994.4 7995.5 7995.9 8019.4 8098.1 8098.8 8119.4 8123.1 8125.4 7460.4 7689.0 7737.3 7749.6 7752.5 6066.0 6627.7 7179.2 7569.0 7593.9 8043.6 8074.4 8105.4 8116.0 8123.6 4938.9 4997.5 5007.0 5015.6 5024.5 7312.3 7606.5 7616.8 7623.4 7640.3 7263.8 7319.5 7335.4 7341.2 7348.9 7282.5 7299.3 7299.7 7324.5 7371.6 5794.7 7099.2 7145.6 7156.9 7187.4 7099.6 7320.2 7341.6 7361.9 7382.2 6854.9 6991.2 7154.5 7169.3 7236.3 02:23:04 INFO - PROCESS | 1662 | 02:23:04 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:17717.72 Median:631.92 stddev:24677.47 (3905.1%) stddev-sans-first:24713.72 02:23:04 INFO - PROCESS | 1662 | Values: 729.5 737.1 740.1 749.9 763.4 14565.0 14776.9 14915.9 14918.9 14960.6 13524.7 13585.9 13638.5 13640.0 13649.5 703.3 772.3 887.8 892.1 919.0 852.6 866.1 888.6 895.5 896.7 961.1 962.4 963.2 965.9 968.3 908.1 908.7 933.3 954.8 957.6 659.5 660.2 699.0 709.6 721.7 14118.9 14263.9 14300.1 14303.6 14313.2 12542.4 12553.1 12639.3 12666.0 12824.7 9880.7 10048.1 10102.2 10340.2 10363.8 3460.4 3517.2 3537.4 3547.9 3665.0 74590.6 75000.9 75223.0 76436.2 78353.0 9558.8 9596.2 9648.2 9714.1 9720.4 18065.6 21821.1 21827.3 21846.9 21891.6 6400.3 7422.8 7524.9 7534.7 7703.9 4055.9 4158.9 4170.4 4171.4 4176.4 3513.3 3569.4 3574.0 3577.2 3582.8 624.4 630.3 633.5 633.9 642.5 546.2 586.3 605.1 609.0 609.2 7755.7 8017.3 8044.9 8074.2 8174.2 492.6 515.8 585.7 590.0 620.5 3081.6 3633.7 3767.4 3829.6 3884.2 87920.6 89874.0 89921.7 91093.0 92214.0 73778.6 74610.6 74779.5 74804.0 76502.9 64386.0 64512.0 65540.7 65702.7 67803.7 7607.3 7625.2 7673.9 7694.1 7784.8 47948.2 48277.5 48621.5 49898.8 49926.8 65784.3 66140.7 66331.7 66961.0 67744.7 8931.6 10435.6 10711.0 11006.9 11008.6 11557.9 11746.3 11899.9 11947.8 12236.1 11276.1 11279.2 11397.0 11467.9 11770.4 10177.6 10569.3 10594.9 10684.3 11399.3 11084.7 11128.8 11186.5 11317.0 11576.7 8803.3 8929.4 9271.5 9740.6 9806.6 9537.5 9667.3 9689.6 9822.7 10055.3 02:23:04 INFO - PROCESS | 1662 | -------- Summary: end -------- 02:23:04 INFO - PROCESS | 1662 | 02:23:04 INFO - TEST-INFO | 1662: exit 0 02:23:04 INFO - TEST-OK | dromaeo_css | took 1111611ms 02:23:04 INFO - TEST-START | kraken 02:23:04 INFO - operating with platform_type : mac_ 02:23:04 INFO - Initialising browser for kraken test... 02:23:04 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmp0hfygX/profile http://localhost:49270/getInfo.html) 02:23:07 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 02:23:07 INFO - PROCESS | 1670 | colorDepth:24 02:23:07 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 02:23:07 INFO - PROCESS | 1670 | __metrics 02:23:07 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 02:23:07 INFO - TEST-INFO | 1670: exit 0 02:23:07 INFO - Browser initialized. 02:23:07 INFO - Running cycle 1/1 for kraken test... 02:23:07 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmp0hfygX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:23:20 INFO - PROCESS | 1674 | RSS: Main: 221036544 02:23:20 INFO - PROCESS | 1674 | 02:23:22 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 02:23:23 INFO - PROCESS | 1674 | RSS: Main: 233336832 02:23:23 INFO - PROCESS | 1674 | 02:23:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 02:23:26 INFO - PROCESS | 1674 | RSS: Main: 242171904 02:23:26 INFO - PROCESS | 1674 | 02:23:30 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 02:23:30 INFO - PROCESS | 1674 | RSS: Main: 246996992 02:23:30 INFO - PROCESS | 1674 | 02:23:32 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 02:23:33 INFO - PROCESS | 1674 | RSS: Main: 246800384 02:23:33 INFO - PROCESS | 1674 | 02:23:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:23:36 INFO - PROCESS | 1674 | RSS: Main: 248385536 02:23:36 INFO - PROCESS | 1674 | 02:23:39 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 02:23:39 INFO - PROCESS | 1674 | RSS: Main: 248020992 02:23:39 INFO - PROCESS | 1674 | 02:23:42 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 02:23:43 INFO - PROCESS | 1674 | RSS: Main: 248078336 02:23:43 INFO - PROCESS | 1674 | 02:23:46 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 02:23:46 INFO - PROCESS | 1674 | RSS: Main: 248291328 02:23:46 INFO - PROCESS | 1674 | 02:23:48 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:23:49 INFO - PROCESS | 1674 | RSS: Main: 248041472 02:23:49 INFO - PROCESS | 1674 | 02:23:50 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:23:51 INFO - PROCESS | 1674 | RSS: Main: 248053760 02:23:51 INFO - PROCESS | 1674 | 02:23:53 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:23:54 INFO - PROCESS | 1674 | RSS: Main: 248172544 02:23:54 INFO - PROCESS | 1674 | 02:23:57 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:23:57 INFO - PROCESS | 1674 | RSS: Main: 248209408 02:23:57 INFO - PROCESS | 1674 | 02:24:00 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:24:01 INFO - PROCESS | 1674 | RSS: Main: 248209408 02:24:01 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 02:24:03 INFO - PROCESS | 1674 | RSS: Main: 250118144 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | __start_tp_report 02:24:03 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 02:24:03 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 02:24:03 INFO - PROCESS | 1674 | |i|pagename|runs| 02:24:03 INFO - PROCESS | 1674 | |0;ai-astar;116;119;108;118;119;118;110;117;116;117 02:24:03 INFO - PROCESS | 1674 | |1;audio-beat-detection;151;121;148;173;161;135;145;154;194;109 02:24:03 INFO - PROCESS | 1674 | |2;audio-dft;265;239;196;195;202;197;194;194;195;205 02:24:03 INFO - PROCESS | 1674 | |3;audio-fft;68;87;68;184;56;69;87;79;117;61 02:24:03 INFO - PROCESS | 1674 | |4;audio-oscillator;80;86;134;77;78;87;123;82;79;123 02:24:03 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;93;89;90;87;92;85;118;129;123;87 02:24:03 INFO - PROCESS | 1674 | |6;imaging-darkroom;96;96;100;147;123;95;96;95;94;94 02:24:03 INFO - PROCESS | 1674 | |7;imaging-desaturate;90;95;91;89;150;146;89;91;91;92 02:24:03 INFO - PROCESS | 1674 | |8;json-parse-financial;70;83;64;65;64;64;64;83;63;62 02:24:03 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;67;65;71;71;73;77;64;68;66;68 02:24:03 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;82;82;82;80;106;95;83;83;85;83 02:24:03 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;153;138;126;130;126;135;151;138;162;173 02:24:03 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;170;165;180;170;168;165;170;221;164;166 02:24:03 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;69;69;69;66;69;66;68;68;66;68 02:24:03 INFO - PROCESS | 1674 | __end_tp_report 02:24:03 INFO - PROCESS | 1674 | __start_cc_report 02:24:03 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1085 02:24:03 INFO - PROCESS | 1674 | __end_cc_report 02:24:03 INFO - PROCESS | 1674 | __startTimestamp1463477043250__endTimestamp 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | ------- Summary: start ------- 02:24:03 INFO - PROCESS | 1674 | Number of tests: 14 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:115.80 Median:117.50 stddev:3.77 (3.2%) stddev-sans-first:3.99 02:24:03 INFO - PROCESS | 1674 | Values: 116.0 119.0 108.0 118.0 119.0 118.0 110.0 117.0 116.0 117.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:149.10 Median:152.50 stddev:24.43 (16.0%) stddev-sans-first:25.90 02:24:03 INFO - PROCESS | 1674 | Values: 151.0 121.0 148.0 173.0 161.0 135.0 145.0 154.0 194.0 109.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:208.20 Median:199.50 stddev:24.15 (12.1%) stddev-sans-first:14.43 02:24:03 INFO - PROCESS | 1674 | Values: 265.0 239.0 196.0 195.0 202.0 197.0 194.0 194.0 195.0 205.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:87.60 Median:68.50 stddev:38.08 (55.6%) stddev-sans-first:39.73 02:24:03 INFO - PROCESS | 1674 | Values: 68.0 87.0 68.0 184.0 56.0 69.0 87.0 79.0 117.0 61.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:79.50 stddev:22.35 (28.1%) stddev-sans-first:23.05 02:24:03 INFO - PROCESS | 1674 | Values: 80.0 86.0 134.0 77.0 78.0 87.0 123.0 82.0 79.0 123.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:99.30 Median:88.00 stddev:16.95 (19.3%) stddev-sans-first:17.83 02:24:03 INFO - PROCESS | 1674 | Values: 93.0 89.0 90.0 87.0 92.0 85.0 118.0 129.0 123.0 87.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:103.60 Median:95.00 stddev:17.57 (18.5%) stddev-sans-first:18.42 02:24:03 INFO - PROCESS | 1674 | Values: 96.0 96.0 100.0 147.0 123.0 95.0 96.0 95.0 94.0 94.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:102.40 Median:91.00 stddev:24.11 (26.5%) stddev-sans-first:25.15 02:24:03 INFO - PROCESS | 1674 | Values: 90.0 95.0 91.0 89.0 150.0 146.0 89.0 91.0 91.0 92.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:68.20 Median:64.50 stddev:8.08 (12.5%) stddev-sans-first:8.54 02:24:03 INFO - PROCESS | 1674 | Values: 70.0 83.0 64.0 65.0 64.0 64.0 64.0 83.0 63.0 62.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:69.50 stddev:4.00 (5.8%) stddev-sans-first:4.18 02:24:03 INFO - PROCESS | 1674 | Values: 67.0 65.0 71.0 71.0 73.0 77.0 64.0 68.0 66.0 68.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:83.00 stddev:8.12 (9.8%) stddev-sans-first:8.47 02:24:03 INFO - PROCESS | 1674 | Values: 82.0 82.0 82.0 80.0 106.0 95.0 83.0 83.0 85.0 83.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:143.20 Median:144.50 stddev:15.94 (11.0%) stddev-sans-first:16.50 02:24:03 INFO - PROCESS | 1674 | Values: 153.0 138.0 126.0 130.0 126.0 135.0 151.0 138.0 162.0 173.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:170.00 stddev:17.18 (10.1%) stddev-sans-first:18.16 02:24:03 INFO - PROCESS | 1674 | Values: 170.0 165.0 180.0 170.0 168.0 165.0 170.0 221.0 164.0 166.0 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.50 stddev:1.32 (1.9%) stddev-sans-first:1.32 02:24:03 INFO - PROCESS | 1674 | Values: 69.0 69.0 69.0 66.0 69.0 66.0 68.0 68.0 66.0 68.0 02:24:03 INFO - PROCESS | 1674 | -------- Summary: end -------- 02:24:03 INFO - PROCESS | 1674 | 02:24:03 INFO - TEST-INFO | 1674: exit 0 02:24:03 INFO - TEST-OK | kraken | took 59389ms 02:24:03 INFO - SUITE-END | took 1171s 02:24:04 INFO - Completed test suite (00:19:31) 02:24:04 INFO - javascript benchmark 02:24:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3114.6106736657916, 3528.3472557576843, 3537.2571759930415, 3566.1084728817796, 3582.5521809773777, 5844.707764611769, 5906.261717285334, 5923.9407605923925, 5925.940740592593, 5953.285605727318, 5928.762849486026, 5971.402859714027, 5990.8502287442725, 5992.3108842483125, 6013.977623803953, 5303.496167864057, 5324.7071411072375, 5411.296531450906, 5416.052190866602, 5423.105187644034, 5245.711485868275, 5344.973275133619, 5382.085045542267, 5470.9452905470835, 5478.096114141182, 5343.23673187849, 5346.0, 5385.865353366158, 5444.7822087116465, 5445.618806683534, 4407.911841763179, 5216.556592689626, 5359.705216213107, 5375.11310633745, 5391.32608423947, 5241.711705856176, 5257.369115706101, 5294.735263236823, 5339.172428273624, 5358.410574836765, 5108.5402313791565, 5195.506426889439, 5624.015797235467, 5686.516646085088, 5842.211301474332, 5442.374126975409, 5591.9720401397735, 6220.222472190976, 6269.937300626981, 6340.619562826245, 6207.099970504297, 6263.217097862767, 6310.936890631081, 6311.463525600329, 6337.936620633781, 5976.432238937294, 5987.012142996444, 6004.939950600526, 6028.0, 6046.123312119762, 5347.406507843692, 6263.279722831841, 6337.8732425352155, 6350.142730731384, 6435.774747883802, 4368.584984426474, 5141.691498510101, 6141.30190404341, 6287.0, 6363.309280617231, 5345.599080069011, 6248.0, 6278.560500764903, 6297.244330680256, 6303.054541818672, 6215.34738852423, 6264.248290205204, 6275.121482992385, 6283.748650054038, 6284.12022316876, 6158.7228574714245, 6201.596896201732, 6209.379062093845, 6241.687915604201, 6256.280527739368, 5015.924761128586, 5016.122178618727, 5039.470855560114, 5048.823291184791, 5067.0, 4564.406627138517, 4565.840195593139, 4581.702189357748, 4583.72497650142, 4599.33309670096, 4996.725180115129, 5840.3575606683235, 5890.705464726747, 5909.2908850938165, 6029.849253768603, 6305.432511074026, 6310.5267104967315, 6313.463355614824, 6319.957207060876, 6345.841353966095, 6015.669138197353, 6029.186059881948, 6037.909431358533, 6040.879182416327, 6055.7274922628585, 5425.104857698515, 5471.890562188734, 5525.834224973257, 5542.251796007568, 5573.331200255995, 6430.324815894451, 6442.48460123189, 6447.709853056717, 6473.93180125274, 6526.934730652728, 5299.364076310867, 6191.349908259748, 6246.187995560548, 6305.085762564494, 6313.494920406265, 6384.9042264367, 6459.709313080829, 6475.740970361133, 6476.352364763486, 6480.611163330215, 5481.369642491165, 5566.693831839126, 6214.378562143749, 6290.874182516232, 6336.809895703136, 6199.256089269316, 6229.96885015572, 6301.306856245901, 6307.369263073656, 6319.178506794087, 6210.099535567408, 6231.40801623845, 6252.406021428139, 6257.749690012349, 6277.1525844010475, 5377.596680248918, 6215.285242197205, 6242.719077641426, 6255.124282600348, 6265.937340626535, 5941.287045554561, 5988.37122102173, 6100.725467353891, 6141.109539116893, 6153.38466153399, 6279.529035322278, 6281.214848143982, 6326.335734747785, 6328.462080723006, 6347.39699728525, 6174.9691251545255, 6183.783567575113, 6221.004639257697, 6228.0, 6234.064890266496], "value": 5763.825765768344, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.1310450159966, 523.2883278740912, 568.8094488346404, 586.5014737473147, 617.9032217813381, 38322.616773832255, 39142.8042859786, 39210.80394598027, 39271.41092883602, 39364.21271574567, 40778.0, 41402.0, 41405.37891931616, 41429.37855932163, 41525.16949661005, 9376.249900008, 9418.340716149873, 9539.80920381592, 9602.855957160647, 9621.0, 9501.857472137923, 9507.95246023769, 9553.426794392359, 9715.319927605071, 9717.61129554817, 9584.856227156599, 9663.806723865519, 9708.41749495029, 9723.708288751348, 9743.464109473976, 9503.90496095037, 9622.13400793932, 9658.565364558577, 9708.854367184498, 9733.561989710444, 9562.0, 9661.806763864683, 9677.612895484172, 9690.75773105671, 9701.320907536476, 39368.80315598432, 39762.403563946566, 39800.004999874945, 39911.60088399137, 39946.800265998485, 3138.482150445174, 3231.757618178646, 3302.4055669979393, 3304.7686661933676, 3639.963600363989, 28407.85796071027, 28926.566101508495, 29007.129786106452, 29260.70739292601, 29396.118116456542, 2792.539231026878, 2792.567152091429, 2793.0, 2807.887684492618, 2827.6606807183066, 314654.0, 365634.0, 368735.0, 370648.14675926446, 371667.0, 36172.91481255567, 36837.89486315415, 62978.68510657509, 63551.0, 63773.36226637767, 34779.478307825404, 34953.825230873685, 35216.471752923724, 35268.58925642997, 35358.292834143685, 15431.537053888402, 15453.768193476883, 15574.844251557566, 15636.296366663528, 15638.296276667577, 13153.342332883318, 13290.800637990438, 13314.267715275753, 13326.93336533311, 13341.266230357423, 7079.716811327489, 7363.631818409058, 8181.959090204511, 8269.09040005599, 8314.584270786558, 2957.334599715047, 3104.2549788051156, 3451.171718787473, 3481.3385456763135, 3505.298940211968, 978.8923218445965, 1006.5822683586376, 1006.7634105985088, 1018.3838777539482, 1048.1457612046158, 27386.767595458252, 28103.0, 28531.001414950377, 30356.696433035828, 31029.534556981664, 1116.9273997190157, 1137.38581166171, 1146.3236690352733, 1192.4514723227412, 1244.875512448766, 13396.799048014287, 13554.864451355557, 15549.0, 15640.3743850247, 15730.764038539432, 650410.0, 651746.0, 652702.0, 658747.0, 661501.0, 60660.393396066356, 61028.38971610227, 61413.69293153506, 61426.77146457135, 61430.0, 1475.0928179169734, 1509.3660662521986, 1517.7950976618235, 1527.4806565767692, 1595.4176725495342, 25583.720813958855, 29552.113436596937, 29997.800087997108, 30220.697793021787, 30288.394232114795, 52381.47618523766, 52848.73575632251, 53359.0, 53510.0, 53591.19613205883, 562413.0, 567873.0, 568958.0, 572456.0, 577154.0, 26239.212823615322, 26747.732522674523, 27256.0, 27421.862890685417, 27592.0, 44050.89872753207, 45491.31763023491, 46073.07853842971, 46185.845353866425, 46346.07307853892, 37215.88352349434, 38032.23935521329, 38351.23297534089, 38489.845304640905, 38621.4206786904, 37480.06299842526, 37947.051323717125, 37958.62041379551, 38391.6160838388, 38474.0, 32129.357412851143, 37964.810175948944, 38237.426438604, 38910.221795564496, 40704.38943415792, 31204.37591248117, 32387.676123238467, 32672.673273267916, 32711.67288327086, 32983.83508082444, 24647.630285545372, 24807.87596062008, 24815.255542333758, 24974.750252497968, 25216.369590760376], "value": 18507.54314282868, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.2752538482649, 396.8055652730161, 404.34899811303796, 405.23737101017025, 406.4107044785061, 20031.99840008001, 20286.28997985071, 20664.17343306268, 20700.0, 21623.891880540614, 21006.474838129016, 21338.039788209528, 21456.927153642333, 21608.459788505257, 21742.565148697016, 5933.614315069528, 5959.821205363824, 6251.499880009611, 6254.249490061176, 6266.310705822356, 6051.394860513958, 6054.878902421971, 6157.846053848667, 6192.566520343556, 6254.9374506255035, 5984.311804142536, 6057.757689692407, 6082.239720034996, 6133.693315334238, 6174.691265436711, 6031.336552979169, 6065.665553578206, 6106.511479081685, 6146.231721034871, 6159.199304090462, 5136.794528218904, 5298.258243845865, 5363.410024897258, 5393.97514472249, 5399.974004939049, 26335.0, 26441.074562390415, 26718.73281267182, 26882.0, 27046.5943010855, 2203.0967303405528, 2255.977440225593, 2286.531261091472, 2367.905283788664, 2398.1606437746823, 14988.625284367869, 15322.0, 15495.690086198325, 15514.612134696723, 15662.295196716175, 1574.559123445437, 1735.0717366209144, 1752.1326943163087, 1788.8121747216614, 1794.7397627344092, 99496.00503994939, 104337.0, 106368.0, 111881.0, 112696.43651781688, 9931.404115753077, 10801.027907488364, 11908.702282442835, 11937.343446110373, 12118.091143164298, 14539.491117811038, 14555.41778328855, 14576.562703118923, 14583.343749531297, 14618.926905365404, 8911.287097032222, 8994.28045756338, 9061.501617410973, 9077.863832042525, 9106.49914254709, 8962.910370896338, 8988.370814042953, 9052.0, 9148.771280718036, 9189.081091890757, 6303.873922521616, 6547.410733034049, 6906.930930690629, 7004.264552221944, 7043.612601306977, 1586.5002524204838, 1880.9529761756066, 1892.6593213221752, 1910.5987742574216, 1911.9426417207505, 915.8809354783835, 955.7945041815904, 957.9473128977974, 993.3791380387258, 1028.9022542858415, 20550.07524661364, 21511.0, 21618.56762864734, 21700.023498942584, 21892.89053554722, 949.2832911152036, 980.7106903463496, 1014.923880708935, 1019.4189312092185, 1040.9843852342221, 9704.980976997454, 11082.61210857616, 11728.061755059745, 11731.47208375608, 11741.647750567497, 623550.0, 626695.0, 631582.0, 639296.0, 642087.0, 111435.0, 113721.0, 115021.0, 118226.27094916279, 121246.3937680306, 1060.6287799270349, 1070.768616091501, 1075.2043487818958, 1090.5092708281304, 1097.7859317433183, 18330.08349582489, 18835.529111772314, 18947.147378367732, 19040.238390464227, 19259.51851203731, 35756.0, 47170.52829471799, 48796.26805597848, 49670.0, 51019.2347114801, 59264.92572759933, 64293.0, 72578.0, 72628.63685681538, 78915.0, 8088.191180881865, 8124.512529248264, 8357.080721120794, 8380.24577788002, 8580.970283566012, 11636.592719254786, 12710.936445317715, 12771.744565108831, 13020.28388438607, 13164.539241126515, 5356.062689029326, 5914.0, 9485.478298693639, 9485.810283794424, 10469.31949423285, 9647.807043858944, 11004.339739615649, 11163.553457861595, 11305.71735706614, 11614.88385116138, 10909.781804363709, 11242.606508772154, 11288.887111128784, 11550.249233799776, 11933.164678472423, 8492.532910689972, 8500.404971651926, 8550.486970781772, 8571.271441927505, 8715.607797649247, 8470.280026197843, 11051.005409513182, 11187.105031597615, 11343.48954297075, 11503.079753619522], "value": 9763.160248201953, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.944402223911, 1582.2088955522238, 1791.6774980503503, 1906.3232552443883, 1967.921283148674, 7077.71689132434, 7813.453058285922, 8646.308295336374, 8831.425957312771, 8831.867521987167, 8571.699990500334, 8577.44246623969, 8615.0, 8675.349348798849, 8693.47839129651, 2410.4576470294173, 2428.138011006085, 2434.294054724128, 2436.0, 2457.7296497385273, 2439.182873737291, 2439.829211955164, 2465.827392082555, 2477.5664258754737, 2489.0790407549143, 2022.6460369435363, 2438.0247900839718, 2451.019592163131, 2458.176510868861, 2470.419451428913, 2423.3941514621347, 2442.9022839086415, 2449.865257410842, 2454.3005243505695, 2463.8521688698734, 2434.9026038958596, 2464.4578192797558, 2470.876456177184, 2477.8389404688637, 2482.354587807165, 8752.47485150893, 8783.121687831166, 8802.207801297849, 8831.3376496763, 8855.911440885573, 5357.732113394315, 5477.67133971959, 5485.862853428696, 5516.255305533742, 5523.28197334348, 3513.846430049735, 3578.2664553766417, 3580.337637537038, 3614.5120408744747, 3652.2878038782446, 1043.9478026098664, 1050.99474502627, 1053.6206965492418, 1053.6733612580124, 1056.4136904018267, 11624.0, 11819.172657913949, 11823.645290641294, 11833.644990650295, 11843.881561184364, 4176.394422808676, 4812.975935120302, 4850.1269771441475, 4942.1104201244125, 4949.57928576075, 10078.697639070839, 10109.443980581138, 10110.292279540361, 10115.696529104138, 10149.086582207488, 5478.150886612581, 5493.862653433696, 5510.91733623996, 5511.173324001352, 5524.364698059694, 4408.713433626804, 4822.590079843182, 4884.8290309838985, 4907.411110666742, 4934.062528119646, 4090.7750073745633, 4114.4910488726355, 4140.296149654566, 4157.584241575879, 4171.290880550314, 1275.7943743162623, 1306.4969986555245, 1360.9452674177592, 1367.2409078479589, 1371.9176849389069, 916.6058594804165, 923.0769230769231, 936.4381371177375, 940.4686352210979, 948.2224575847877, 5873.970630146822, 5884.47039766423, 5884.529237660977, 6008.03871380577, 6025.6083354581815, 451.22614715762626, 460.36009946174846, 463.77275135183515, 473.2782506677345, 484.7503535679128, 3459.8789042383396, 3880.7089468289428, 3941.7634941903, 3944.723869329177, 3950.288947989332, 10361.326513776581, 10658.680239592824, 10660.933906609276, 10692.465376731132, 10772.569097236023, 9919.900800992042, 10310.536025878853, 10365.01532354425, 10466.895331046591, 10556.947215263874, 6221.626702397871, 8007.919920800718, 8061.2744852963515, 8132.918670813335, 8237.752867413867, 5554.750036248298, 5676.77292908279, 5677.148427735708, 5706.80026199081, 5781.768729250783, 9331.906680933374, 9349.0, 9385.0, 9390.201832844012, 9489.335746497954, 9324.067593240621, 9516.429014259167, 9731.026897310212, 9733.707988760349, 9811.018898110131, 6762.729490820312, 7260.382867456326, 7428.702851885864, 7486.737964171228, 7572.697092116254, 8234.917650823654, 8284.83430331402, 8307.833843322978, 8309.750707478785, 8346.540940248344, 7189.496735228484, 7339.853202935805, 7345.963270183614, 7444.367228785614, 7444.590547519939, 7154.801720019219, 7202.351788339075, 7290.9635451822405, 7373.262673732798, 7406.0, 7032.296770322927, 7043.929560704328, 7078.823029424306, 7080.362767350963, 7102.786916392516, 4896.338994235733, 4899.853004409873, 4909.533594308535, 4976.154053810788, 5017.422996355466, 4873.220284754422, 4889.584385327287, 4903.632227582874, 4979.526944940225, 5108.667936584259], "value": 4519.489369033565, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.3778762298189, 316.9223540232642, 324.1927600275314, 331.09280571234814, 332.4796693175181, 6644.667766611662, 6727.562708423955, 6785.6267905265195, 6834.453243740501, 6842.228897296596, 6787.76242831501, 6788.932110678892, 6853.246142924287, 6860.348266914648, 6929.3417125373135, 4058.269511487931, 4988.052270068684, 5025.522575355335, 5033.094043072264, 5043.1678773002595, 4432.734035957837, 4761.761911904391, 5060.44335123138, 5069.290299358093, 5089.4910508949, 5007.899842003158, 5008.0, 5009.148444764381, 5011.198208286675, 5037.0, 4755.500672429414, 4967.975160124212, 5001.524855138756, 5019.0, 5028.044671512419, 4171.582841715835, 4185.937210941838, 4190.937135942963, 4199.265128602513, 4200.957990420087, 6546.770863019819, 6567.211934567835, 6576.243731970837, 6583.242927063353, 6608.735650573972, 5865.120231965239, 5877.05967045275, 5879.617824841414, 5893.99802033651, 5898.8820223595285, 7340.4127669786285, 7363.742269020612, 7364.815879602999, 7369.926300736977, 7375.373093287077, 4477.597016268552, 4682.0, 4683.578477937002, 4687.1328804171, 4697.78859951303, 7659.080910290745, 7668.309852113281, 7750.496217745885, 7751.612419379008, 7763.805904852367, 4733.242681170997, 4750.8337208197545, 4755.904881902412, 4759.714417134983, 4761.095391875532, 3928.9214215716097, 4146.3158578834755, 4260.957390426118, 4325.961769175376, 4657.208274593328, 4323.0705398339505, 5209.374875015023, 5821.126830975388, 5835.35811060783, 5844.736986835603, 5755.424457554211, 7365.594892280868, 7368.189499155089, 7383.966244725742, 7388.852222955511, 1373.3957058894055, 1633.6242664187303, 1652.4381710218584, 1664.1429663723193, 1704.4290162795603, 862.3230763850347, 985.108476828457, 1040.9947950260353, 1061.7451811565322, 1065.041462683591, 6672.499562532829, 6801.18385793708, 6868.381845633917, 6872.450203983669, 6875.5530890491955, 6149.538784591174, 7669.808254793563, 7708.961455192688, 7742.961285193538, 7750.418718596128, 6842.247352791289, 6863.519553631198, 6874.587524748531, 6874.621895795779, 6888.521296745112, 3244.5295432162675, 3270.0, 4050.0887300357185, 4064.4309796628495, 4066.7356621819486, 7745.419093568005, 8093.554854482943, 8122.309603683635, 8139.348852091818, 8152.755417337489, 7946.04647442299, 7994.36045116401, 7995.480293781002, 7995.880061799077, 8019.35845132388, 8098.149694282133, 8098.75703728889, 8119.350451963829, 8123.106458289702, 8125.390595705346, 7460.440466964891, 7688.961555192188, 7737.264959828807, 7749.612519373896, 7752.5348479091435, 6066.029435290332, 6627.668616569056, 7179.246179151114, 7568.978187944557, 7593.8860917087395, 8043.597820109088, 8074.434789564674, 8105.432619716563, 8116.0, 8123.593820308843, 4938.9259161111895, 4997.5002499749735, 5007.0, 5015.573676237561, 5024.522670346311, 7312.341889229995, 7606.501009535676, 7616.771496855104, 7623.428242881917, 7640.312371886557, 7263.818404539928, 7319.524230925186, 7335.376492998155, 7341.155767086853, 7348.926510735038, 7282.526635768658, 7299.306565876233, 7299.744508942162, 7324.523905946096, 7371.631418429164, 5794.681292528783, 7099.219085900443, 7145.642717863982, 7156.892646610201, 7187.353138217585, 7099.609521476368, 7320.231375705687, 7341.632918353954, 7361.889571656537, 7382.224866389167, 6854.931450685629, 6991.161060672752, 7154.463415243774, 7169.318914703095, 7236.34872861445], "value": 5271.591276439067, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.4930023633574, 737.1375490675908, 740.0822979505411, 749.940004799616, 763.4350580570377, 14565.0, 14776.852231477682, 14915.850841491581, 14918.850811491895, 14960.625984350383, 13524.661883452918, 13585.932070339659, 13638.454461821515, 13640.045196836225, 13649.45402183914, 703.3458883238586, 772.3319328780607, 887.7691800131947, 892.0722448653393, 918.995405022974, 852.5651917522047, 866.1079088538816, 888.5601627194516, 895.5432729308067, 896.7175339767984, 961.0773657289011, 962.3840741925173, 963.1716723617683, 965.8696076029752, 968.2592816495387, 908.1100521488912, 908.7182973278304, 933.2580598424236, 954.7851733360008, 957.5978089202551, 659.5020759326726, 660.1517050589969, 698.9510734248605, 709.6168069242602, 721.7077083781077, 14118.92940535301, 14263.857361426357, 14300.070495417765, 14303.570892873231, 14313.212773297464, 12542.372881355896, 12553.121281510297, 12639.304838233893, 12665.986721062292, 12824.743505129845, 9880.703578892644, 10048.145907597864, 10102.230297705846, 10340.224483163793, 10363.792724145474, 3460.377132116218, 3517.208628058692, 3537.363274610569, 3547.8758243461357, 3665.0, 74590.62704686442, 75000.8749868747, 75223.0, 76436.23563764346, 78353.0, 9558.761030974281, 9596.23230141587, 9648.179904708039, 9714.077162669535, 9720.368176068674, 18065.638687226183, 21821.127154913625, 21827.345179644635, 21846.89076554607, 21891.562168756536, 6400.327965563642, 7422.814429639194, 7524.887126693104, 7534.736284230025, 7703.922960770321, 4055.878323650295, 4158.875233742992, 4170.416141740159, 4171.416001759756, 4176.352665336878, 3513.2622149348413, 3569.428891377367, 3573.9642603574152, 3577.177249232691, 3582.8387722552548, 624.4317670919442, 630.3066626710581, 633.5375176121365, 633.8732253549308, 642.4635429416489, 546.1943633141085, 586.3432955090326, 605.0802779774717, 608.9543284253699, 609.1624016976657, 7755.650995705207, 8017.278444939983, 8044.919550804534, 8074.232947869943, 8174.223448772357, 492.6452953873206, 515.8246196293278, 585.7042193692158, 589.9823005309847, 620.5376994139395, 3081.6147981502313, 3633.691136253395, 3767.3972164453558, 3829.617038296148, 3884.2231553689376, 87920.56039719761, 89874.0, 89921.65117523112, 91093.0, 92214.0, 73778.63110684413, 74610.62694686491, 74779.50440991258, 74804.0, 76502.85245721319, 64386.0342094869, 64512.0, 65540.67229663917, 65702.67148664226, 67803.66098169477, 7607.277308655669, 7625.23747625233, 7673.884891726517, 7694.115176754744, 7784.805379865548, 47948.2439926074, 48277.5172248273, 48621.5137848617, 49898.75253118701, 49926.75036624939, 65784.34215657973, 66140.67718645696, 66331.66834165798, 66961.0, 67744.6612766933, 8931.598078086632, 10435.634752783617, 10711.036006759428, 11006.944965275123, 11008.614698485515, 11557.88442115568, 11746.29522228669, 11899.940500297444, 11947.761044778881, 12236.08229382782, 11276.097912166842, 11279.210455268052, 11397.031252343311, 11467.942660286646, 11770.411479425824, 10177.643782467578, 10569.26015178961, 10594.947025264824, 10684.305520141166, 11399.316041037564, 11084.667459976214, 11128.777424451628, 11186.496607652838, 11316.98147166759, 11576.710582235512, 8803.251723603566, 8929.419587726778, 9271.490068046322, 9740.610375584898, 9806.607735690492, 9537.47543885093, 9667.323287370098, 9689.612415503301, 9822.65620703272, 10055.346402483816], "value": 6767.344505950174, "unit": "score"}], "name": "dromaeo_css", "value": 7427.723986444576, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 119.0, 108.0, 118.0, 119.0, 118.0, 110.0, 117.0, 116.0, 117.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 121.0, 148.0, 173.0, 161.0, 135.0, 145.0, 154.0, 194.0, 109.0], "value": 149.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [265.0, 239.0, 196.0, 195.0, 202.0, 197.0, 194.0, 194.0, 195.0, 205.0], "value": 208.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 87.0, 68.0, 184.0, 56.0, 69.0, 87.0, 79.0, 117.0, 61.0], "value": 87.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 86.0, 134.0, 77.0, 78.0, 87.0, 123.0, 82.0, 79.0, 123.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 90.0, 87.0, 92.0, 85.0, 118.0, 129.0, 123.0, 87.0], "value": 99.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 100.0, 147.0, 123.0, 95.0, 96.0, 95.0, 94.0, 94.0], "value": 103.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 91.0, 89.0, 150.0, 146.0, 89.0, 91.0, 91.0, 92.0], "value": 102.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 83.0, 64.0, 65.0, 64.0, 64.0, 64.0, 83.0, 63.0, 62.0], "value": 68.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 71.0, 71.0, 73.0, 77.0, 64.0, 68.0, 66.0, 68.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 80.0, 106.0, 95.0, 83.0, 83.0, 85.0, 83.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 138.0, 126.0, 130.0, 126.0, 135.0, 151.0, 138.0, 162.0, 173.0], "value": 143.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 165.0, 180.0, 170.0, 168.0, 165.0, 170.0, 221.0, 164.0, 166.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 66.0, 69.0, 66.0, 68.0, 68.0, 66.0, 68.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1569.1, "alertThreshold": 2.0}]} 02:24:04 INFO - Return code: 0 02:24:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:24:04 ERROR - # TBPL SUCCESS # 02:24:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:24:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:24:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:24:04 INFO - 2016-05-17 02:24:04.585 screenresolution[1679:10536] starting screenresolution argv=screenresolution get 02:24:04 INFO - 2016-05-17 02:24:04.588 screenresolution[1679:10536] Display 0: 1600x1200x32@60 02:24:04 INFO - 2016-05-17 02:24:04.594 screenresolution[1680:10539] starting screenresolution argv=screenresolution list 02:24:04 INFO - Available Modes on Display 0 02:24:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:24:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:24:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:24:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:24:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:24:04 INFO - Intel Iris: 02:24:04 INFO - Chipset Model: Intel Iris 02:24:04 INFO - Type: GPU 02:24:04 INFO - Bus: Built-In 02:24:04 INFO - VRAM (Dynamic, Max): 1536 MB 02:24:04 INFO - Vendor: Intel (0x8086) 02:24:04 INFO - Device ID: 0x0a2e 02:24:04 INFO - Revision ID: 0x0009 02:24:04 INFO - Displays: 02:24:04 INFO - Display: 02:24:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:24:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:24:04 INFO - Display Serial Number: 1600x1200 60 02:24:04 INFO - Main Display: Yes 02:24:04 INFO - Mirror: Off 02:24:04 INFO - Online: Yes 02:24:04 INFO - Rotation: Supported 02:24:04 INFO - Return code: 0 02:24:04 INFO - Running post-action listener: _resource_record_post_action 02:24:04 INFO - Running post-run listener: _resource_record_post_run 02:24:05 INFO - Total resource usage - Wall time: 1205s; CPU: 54.0%; Read bytes: 37099008; Write bytes: 654549504; Read time: 386; Write time: 1879 02:24:05 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 351894016; Write bytes: 346572288; Read time: 27543; Write time: 641 02:24:05 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 30088704; Write bytes: 297184256; Read time: 300; Write time: 1204 02:24:05 INFO - Running post-run listener: _upload_blobber_files 02:24:05 INFO - Blob upload gear active. 02:24:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:24:05 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 02:24:05 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:24:05 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:24:06 INFO - (blobuploader) - INFO - Open directory for files ... 02:24:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:24:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:24:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:07 INFO - (blobuploader) - INFO - Done attempting. 02:24:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:24:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:24:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:08 INFO - (blobuploader) - INFO - Done attempting. 02:24:08 INFO - (blobuploader) - INFO - Iteration through files over. 02:24:08 INFO - Return code: 0 02:24:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:24:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:24:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68e314c7d5f1f699c802a7477f16548c4ff450c93baa5287388edf5811e2aa70583be1dd020d778dda24efd175f044aa2c36890ddad4c5cf451b0acfb4556c78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a7b9c34d6813d268361b6373b16ee95ff188e8ba7b93e2c2159c0f6f3729b532c3e51f43ecccf5e8b390946ba9f3ef24c1839a8872fae67c7efa2bef1af1bb8"} 02:24:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:24:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:24:08 INFO - Contents: 02:24:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68e314c7d5f1f699c802a7477f16548c4ff450c93baa5287388edf5811e2aa70583be1dd020d778dda24efd175f044aa2c36890ddad4c5cf451b0acfb4556c78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a7b9c34d6813d268361b6373b16ee95ff188e8ba7b93e2c2159c0f6f3729b532c3e51f43ecccf5e8b390946ba9f3ef24c1839a8872fae67c7efa2bef1af1bb8"} 02:24:08 INFO - Running post-run listener: copy_logs_to_upload_dir 02:24:08 INFO - Copying logs to upload dir... 02:24:08 INFO - mkdir: /builds/slave/test/build/upload/logs 02:24:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.003151 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-17 02:24:08.335031) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 02:24:08.338954) ========= 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.nEhZiZes6Q/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.Vucxapimpv/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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/Mozilla-Aurora/sha512/68e314c7d5f1f699c802a7477f16548c4ff450c93baa5287388edf5811e2aa70583be1dd020d778dda24efd175f044aa2c36890ddad4c5cf451b0acfb4556c78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a7b9c34d6813d268361b6373b16ee95ff188e8ba7b93e2c2159c0f6f3729b532c3e51f43ecccf5e8b390946ba9f3ef24c1839a8872fae67c7efa2bef1af1bb8"} build_url:https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011113 build_url: 'https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68e314c7d5f1f699c802a7477f16548c4ff450c93baa5287388edf5811e2aa70583be1dd020d778dda24efd175f044aa2c36890ddad4c5cf451b0acfb4556c78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a7b9c34d6813d268361b6373b16ee95ff188e8ba7b93e2c2159c0f6f3729b532c3e51f43ecccf5e8b390946ba9f3ef24c1839a8872fae67c7efa2bef1af1bb8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 02:24:08.366485) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 02:24:08.366789) ========= 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.nEhZiZes6Q/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.Vucxapimpv/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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.004939 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 02:24:08.451221) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 02:24:08.451528) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 02:24:08.452529) ========= ========= Total master_lag: 0.19 =========