builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0284 starttime: 1463686417.78 results: success (0) buildid: 20160519112644 builduid: 2cc4a461a08945b0bc74af7d59616906 revision: 133e9db008eadefc4426a3731d30f60f0e290a76 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 12:33:37.784325) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 12:33:37.784836) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 12:33:37.785314) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 12:33:37.806502) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:33:37.806849) ========= 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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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-19 12:33:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 80.4M=0s 2016-05-19 12:33:38 (80.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.354089 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:33:38.180294) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:33:38.180622) ========= 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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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.025660 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:33:38.231312) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 12:33:38.231677) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 133e9db008eadefc4426a3731d30f60f0e290a76 --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 133e9db008eadefc4426a3731d30f60f0e290a76 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KhEgB9yE4l/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.u8L3fnMIZz/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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-19 12:33:38,314 truncating revision to first 12 chars 2016-05-19 12:33:38,314 Setting DEBUG logging. 2016-05-19 12:33:38,314 attempt 1/10 2016-05-19 12:33:38,314 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/133e9db008ea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 12:33:39,335 unpacking tar archive at: mozilla-aurora-133e9db008ea/testing/mozharness/ program finished with exit code 0 elapsedTime=1.277310 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 12:33:39.527584) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 12:33:39.527909) ========= script_repo_revision: 133e9db008eadefc4426a3731d30f60f0e290a76 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 12:33:39.528334) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 12:33:39.528616) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 12:33:39.548071) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 27 secs) (at 2016-05-19 12:33:39.548365) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 12:33:39 INFO - MultiFileLogger online at 20160519 12:33:39 in /builds/slave/test 12:33:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:33:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:33:39 INFO - {'append_to_log': False, 12:33:39 INFO - 'base_work_dir': '/builds/slave/test', 12:33:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:33:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:33:39 INFO - 'branch': 'Mozilla-Aurora', 12:33:39 INFO - 'buildbot_json_path': 'buildprops.json', 12:33:39 INFO - 'config_files': ('talos/mac_config.py',), 12:33:39 INFO - 'default_actions': ('clobber', 12:33:39 INFO - 'read-buildbot-config', 12:33:39 INFO - 'download-and-extract', 12:33:39 INFO - 'populate-webroot', 12:33:39 INFO - 'create-virtualenv', 12:33:39 INFO - 'install', 12:33:39 INFO - 'run-tests'), 12:33:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:33:39 INFO - 'download_minidump_stackwalk': True, 12:33:39 INFO - 'download_symbols': 'ondemand', 12:33:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:33:39 INFO - 'tooltool.py': '/tools/tooltool.py', 12:33:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:33:39 INFO - '/tools/misc-python/virtualenv.py')}, 12:33:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:33:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:33:39 INFO - 'installer_path': 'installer.exe', 12:33:39 INFO - 'log_level': 'info', 12:33:39 INFO - 'log_name': 'talos', 12:33:39 INFO - 'log_to_console': True, 12:33:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:33:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:33:39 INFO - 'opt_config_files': (), 12:33:39 INFO - 'pip_index': False, 12:33:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:33:39 INFO - 'cmd': ('bash', 12:33:39 INFO - '-c', 12:33:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:33:39 INFO - 'enabled': True, 12:33:39 INFO - 'halt_on_failure': False, 12:33:39 INFO - 'name': 'check_screen_resolution'},), 12:33:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:33:39 INFO - 'cmd': ('bash', 12:33:39 INFO - '-c', 12:33:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:33:39 INFO - 'enabled': True, 12:33:39 INFO - 'halt_on_failure': False, 12:33:39 INFO - 'name': 'check_screen_resolution'},), 12:33:39 INFO - 'run_cmd_checks_enabled': True, 12:33:39 INFO - 'sps_profile': False, 12:33:39 INFO - 'sps_profile_interval': 0, 12:33:39 INFO - 'suite': 'dromaeojs', 12:33:39 INFO - 'system_bits': '32', 12:33:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:33:39 INFO - 'title': 't-yosemite-r7-0284', 12:33:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:33:39 INFO - 'use_talos_json': True, 12:33:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:33:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:33:39 INFO - 'work_dir': 'build'} 12:33:39 INFO - ##### 12:33:39 INFO - ##### Running clobber step. 12:33:39 INFO - ##### 12:33:39 INFO - Running pre-action listener: _resource_record_pre_action 12:33:39 INFO - Running main action method: clobber 12:33:39 INFO - rmtree: /builds/slave/test/build 12:33:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:33:41 INFO - Running post-action listener: _resource_record_post_action 12:33:41 INFO - ##### 12:33:41 INFO - ##### Running read-buildbot-config step. 12:33:41 INFO - ##### 12:33:41 INFO - Running pre-action listener: _resource_record_pre_action 12:33:41 INFO - Running main action method: read_buildbot_config 12:33:41 INFO - Using buildbot properties: 12:33:41 INFO - { 12:33:41 INFO - "project": "", 12:33:41 INFO - "product": "firefox", 12:33:41 INFO - "script_repo_revision": "production", 12:33:41 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:33:41 INFO - "repository": "", 12:33:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:33:41 INFO - "buildid": "20160519112644", 12:33:41 INFO - "pgo_build": "False", 12:33:41 INFO - "basedir": "/builds/slave/test", 12:33:41 INFO - "buildnumber": 244, 12:33:41 INFO - "slavename": "t-yosemite-r7-0284", 12:33:41 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:33:41 INFO - "platform": "yosemite_r7", 12:33:41 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:33:41 INFO - "branch": "mozilla-aurora", 12:33:41 INFO - "repo_path": "releases/mozilla-aurora", 12:33:41 INFO - "revision": "133e9db008eadefc4426a3731d30f60f0e290a76", 12:33:41 INFO - "stage_platform": "macosx64", 12:33:41 INFO - "builduid": "2cc4a461a08945b0bc74af7d59616906", 12:33:41 INFO - "slavebuilddir": "test" 12:33:41 INFO - } 12:33:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 12:33:41 INFO - Running post-action listener: _resource_record_post_action 12:33:41 INFO - ##### 12:33:41 INFO - ##### Running download-and-extract step. 12:33:41 INFO - ##### 12:33:41 INFO - Running pre-action listener: _resource_record_pre_action 12:33:41 INFO - Running main action method: download_and_extract 12:33:41 INFO - mkdir: /builds/slave/test/build/tests 12:33:41 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')]} 12:33:41 INFO - https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:33:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:33:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:33:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/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 12:33:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/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 12:33:44 INFO - Downloaded 1199 bytes. 12:33:44 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:33:44 INFO - Using the following test package requirements: 12:33:44 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:33:44 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:33:44 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 12:33:44 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:33:44 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 12:33:44 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:33:44 INFO - u'jsshell-mac.zip'], 12:33:44 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:33:44 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 12:33:44 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:33:44 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:33:44 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 12:33:44 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:33:44 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 12:33:44 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:33:44 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 12:33:44 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:33:44 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 12:33:44 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:33:44 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')]} 12:33:44 INFO - https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:33:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:33:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:33:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/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 12:33:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/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 12:33:46 INFO - Downloaded 18102376 bytes. 12:33:46 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'] 12:33:46 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 12:33:49 INFO - Return code: 0 12:33:49 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 12:33:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:33:49 INFO - https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:33:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:33:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:33:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/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 12:33:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/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 12:33:52 INFO - Downloaded 18102376 bytes. 12:33:52 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'] 12:33:52 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 12:33:56 INFO - Return code: 0 12:33:56 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')]} 12:33:56 INFO - https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:33:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:33:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:33:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/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 12:33:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/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 12:35:06 INFO - Downloaded 10840145 bytes. 12:35:06 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'] 12:35:06 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 12:35:06 INFO - Return code: 0 12:35:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:35:06 INFO - https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 12:35:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:35:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:35:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 12:35:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/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 12:35:19 INFO - Downloaded 114098841 bytes. 12:35:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:35:19 INFO - mkdir: /builds/slave/test/properties 12:35:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:35:19 INFO - Writing to file /builds/slave/test/properties/build_url 12:35:19 INFO - Contents: 12:35:19 INFO - build_url:https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:35:21 INFO - Running post-action listener: _resource_record_post_action 12:35:21 INFO - Running post-action listener: set_extra_try_arguments 12:35:21 INFO - ##### 12:35:21 INFO - ##### Running populate-webroot step. 12:35:21 INFO - ##### 12:35:21 INFO - Running pre-action listener: _resource_record_pre_action 12:35:21 INFO - Running main action method: populate_webroot 12:35:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:35:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:35:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:35:21 INFO - '--tppagecycles', 12:35:21 INFO - '7'], 12:35:21 INFO - 'tests': ['tsvgm']}}, 12:35:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:35:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:35:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:35:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:35:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:35:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:35:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:35:21 INFO - 'talos_options': ['--disable-e10s'], 12:35:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:35:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:35:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:35:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:35:21 INFO - 'talos_options': ['--disable-e10s'], 12:35:21 INFO - 'tests': ['damp', 'tps']}, 12:35:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:35:21 INFO - 'tests': ['damp', 'tps']}, 12:35:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:35:21 INFO - 'tests': ['dromaeo_dom']}, 12:35:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:35:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:35:21 INFO - 'tests': ['a11yr', 12:35:21 INFO - 'ts_paint', 12:35:21 INFO - 'tpaint', 12:35:21 INFO - 'sessionrestore', 12:35:21 INFO - 'sessionrestore_no_auto_restore', 12:35:21 INFO - 'tabpaint']}, 12:35:21 INFO - 'other-e10s': {'tests': ['a11yr', 12:35:21 INFO - 'ts_paint', 12:35:21 INFO - 'tpaint', 12:35:21 INFO - 'sessionrestore', 12:35:21 INFO - 'sessionrestore_no_auto_restore', 12:35:21 INFO - 'tabpaint']}, 12:35:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:35:21 INFO - 'ts_paint', 12:35:21 INFO - 'tpaint', 12:35:21 INFO - 'sessionrestore', 12:35:21 INFO - 'sessionrestore_no_auto_restore', 12:35:21 INFO - 'tabpaint']}, 12:35:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:35:21 INFO - 'ts_paint', 12:35:21 INFO - 'tpaint', 12:35:21 INFO - 'sessionrestore', 12:35:21 INFO - 'sessionrestore_no_auto_restore', 12:35:21 INFO - 'tabpaint']}, 12:35:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:35:21 INFO - 'tests': ['a11yr', 12:35:21 INFO - 'ts_paint', 12:35:21 INFO - 'tpaint', 12:35:21 INFO - 'sessionrestore', 12:35:21 INFO - 'sessionrestore_no_auto_restore', 12:35:21 INFO - 'tabpaint']}, 12:35:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:35:21 INFO - 'tests': ['a11yr', 12:35:21 INFO - 'ts_paint', 12:35:21 INFO - 'tpaint', 12:35:21 INFO - 'sessionrestore', 12:35:21 INFO - 'sessionrestore_no_auto_restore', 12:35:21 INFO - 'tabpaint']}, 12:35:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:35:21 INFO - 'tests': ['tsvgx', 12:35:21 INFO - 'tsvgr_opacity', 12:35:21 INFO - 'tart', 12:35:21 INFO - 'tscrollx', 12:35:21 INFO - 'cart']}, 12:35:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:35:21 INFO - 'tsvgr_opacity', 12:35:21 INFO - 'tart', 12:35:21 INFO - 'tscrollx', 12:35:21 INFO - 'cart']}, 12:35:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:35:21 INFO - 'talos_options': ['--disable-e10s'], 12:35:21 INFO - 'tests': ['tp5o']}, 12:35:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:35:21 INFO - 'tests': ['tp5o']}, 12:35:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:35:21 INFO - 'talos_options': ['--disable-e10s', 12:35:21 INFO - '--xperf_path', 12:35:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:35:21 INFO - 'tests': ['tp5n']}, 12:35:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:35:21 INFO - 'talos_options': ['--xperf_path', 12:35:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:35:21 INFO - 'tests': ['tp5n']}}, 12:35:21 INFO - 'talos.zip': {'path': '', 12:35:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:35:21 INFO - Running post-action listener: _resource_record_post_action 12:35:21 INFO - ##### 12:35:21 INFO - ##### Running create-virtualenv step. 12:35:21 INFO - ##### 12:35:21 INFO - Running pre-action listener: _resource_record_pre_action 12:35:21 INFO - Running main action method: create_virtualenv 12:35:21 INFO - Creating virtualenv /builds/slave/test/build/venv 12:35:21 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 12:35:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:35:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:35:22 INFO - Using real prefix '/tools/python27' 12:35:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:35:22 INFO - Installing distribute.............................................................................................................................................................................................done. 12:35:25 INFO - Installing pip.................done. 12:35:25 INFO - Return code: 0 12:35:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:35:25 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')]} 12:35:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:25 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')]} 12:35:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:25 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 0x10f303a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe708d6faf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1a2e00>, '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 0x10f00bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f19db20>, '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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 12:35:25 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 12:35:25 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 12:35:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhEgB9yE4l/Render', 12:35:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:25 INFO - 'HOME': '/Users/cltbld', 12:35:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:25 INFO - 'LOGNAME': 'cltbld', 12:35:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:25 INFO - 'MOZ_NO_REMOTE': '1', 12:35:25 INFO - 'NO_EM_RESTART': '1', 12:35:25 INFO - 'PAGER': '/bin/cat', 12:35:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:25 INFO - 'PWD': '/builds/slave/test', 12:35:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:25 INFO - 'SHELL': '/bin/bash', 12:35:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u8L3fnMIZz/Listeners', 12:35:25 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 12:35:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:25 INFO - 'USER': 'cltbld', 12:35:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:25 INFO - 'XPC_FLAGS': '0x0', 12:35:25 INFO - 'XPC_SERVICE_NAME': '0', 12:35:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:25 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 12:35:25 INFO - Cleaning up... 12:35:25 INFO - Return code: 0 12:35:25 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:35:25 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')]} 12:35:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:25 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')]} 12:35:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:25 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 0x10f303a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe708d6faf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1a2e00>, '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 0x10f00bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f19db20>, '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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 12:35:25 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 12:35:25 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 12:35:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhEgB9yE4l/Render', 12:35:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:25 INFO - 'HOME': '/Users/cltbld', 12:35:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:25 INFO - 'LOGNAME': 'cltbld', 12:35:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:25 INFO - 'MOZ_NO_REMOTE': '1', 12:35:25 INFO - 'NO_EM_RESTART': '1', 12:35:25 INFO - 'PAGER': '/bin/cat', 12:35:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:25 INFO - 'PWD': '/builds/slave/test', 12:35:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:25 INFO - 'SHELL': '/bin/bash', 12:35:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u8L3fnMIZz/Listeners', 12:35:25 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 12:35:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:25 INFO - 'USER': 'cltbld', 12:35:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:25 INFO - 'XPC_FLAGS': '0x0', 12:35:25 INFO - 'XPC_SERVICE_NAME': '0', 12:35:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:26 INFO - Downloading/unpacking psutil>=0.7.1 12:35:26 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 12:35:26 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 12:35:26 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 12:35:26 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 12:35:26 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 12:35:26 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 12:35:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:35:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:35:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:35:29 INFO - Installing collected packages: psutil 12:35:29 INFO - Running setup.py install for psutil 12:35:29 INFO - building 'psutil._psutil_osx' extension 12:35:29 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 12:35:29 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 12:35:29 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 12:35:29 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 12:35:30 INFO - building 'psutil._psutil_posix' extension 12:35:30 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 12:35:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:35:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:35:30 INFO - ^ 12:35:30 INFO - 1 warning generated. 12:35:30 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 12:35:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:35:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:35:30 INFO - Successfully installed psutil 12:35:30 INFO - Cleaning up... 12:35:30 INFO - Return code: 0 12:35:30 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:35:30 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')]} 12:35:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:30 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')]} 12:35:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:30 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 0x10f303a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe708d6faf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1a2e00>, '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 0x10f00bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f19db20>, '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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 12:35:30 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 12:35:30 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 12:35:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhEgB9yE4l/Render', 12:35:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:30 INFO - 'HOME': '/Users/cltbld', 12:35:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:30 INFO - 'LOGNAME': 'cltbld', 12:35:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:30 INFO - 'MOZ_NO_REMOTE': '1', 12:35:30 INFO - 'NO_EM_RESTART': '1', 12:35:30 INFO - 'PAGER': '/bin/cat', 12:35:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:30 INFO - 'PWD': '/builds/slave/test', 12:35:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:30 INFO - 'SHELL': '/bin/bash', 12:35:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u8L3fnMIZz/Listeners', 12:35:30 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 12:35:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:30 INFO - 'USER': 'cltbld', 12:35:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:30 INFO - 'XPC_FLAGS': '0x0', 12:35:30 INFO - 'XPC_SERVICE_NAME': '0', 12:35:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:35:30 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 12:35:30 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 12:35:30 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 12:35:30 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 12:35:30 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 12:35:30 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 12:35:32 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:35:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:35:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:35:33 INFO - Installing collected packages: mozsystemmonitor 12:35:33 INFO - Running setup.py install for mozsystemmonitor 12:35:33 INFO - Successfully installed mozsystemmonitor 12:35:33 INFO - Cleaning up... 12:35:33 INFO - Return code: 0 12:35:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:35:33 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')]} 12:35:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:33 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')]} 12:35:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:33 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 0x10f303a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe708d6faf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1a2e00>, '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 0x10f00bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f19db20>, '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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 12:35:33 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 12:35:33 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 12:35:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhEgB9yE4l/Render', 12:35:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:33 INFO - 'HOME': '/Users/cltbld', 12:35:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:33 INFO - 'LOGNAME': 'cltbld', 12:35:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:33 INFO - 'MOZ_NO_REMOTE': '1', 12:35:33 INFO - 'NO_EM_RESTART': '1', 12:35:33 INFO - 'PAGER': '/bin/cat', 12:35:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:33 INFO - 'PWD': '/builds/slave/test', 12:35:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:33 INFO - 'SHELL': '/bin/bash', 12:35:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u8L3fnMIZz/Listeners', 12:35:33 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 12:35:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:33 INFO - 'USER': 'cltbld', 12:35:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:33 INFO - 'XPC_FLAGS': '0x0', 12:35:33 INFO - 'XPC_SERVICE_NAME': '0', 12:35:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:33 INFO - Downloading/unpacking blobuploader==1.2.4 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35:36 INFO - Downloading blobuploader-1.2.4.tar.gz 12:35:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:35:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:35:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35:37 INFO - Downloading docopt-0.6.1.tar.gz 12:35:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:35:37 INFO - Installing collected packages: blobuploader, requests, docopt 12:35:37 INFO - Running setup.py install for blobuploader 12:35:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:35:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:35:37 INFO - Running setup.py install for requests 12:35:38 INFO - Running setup.py install for docopt 12:35:38 INFO - Successfully installed blobuploader requests docopt 12:35:38 INFO - Cleaning up... 12:35:38 INFO - Return code: 0 12:35:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:35:38 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')]} 12:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:38 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')]} 12:35:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:38 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 0x10f303a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe708d6faf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1a2e00>, '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 0x10f00bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f19db20>, '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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 12:35:38 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 12:35:38 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 12:35:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhEgB9yE4l/Render', 12:35:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:38 INFO - 'HOME': '/Users/cltbld', 12:35:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:38 INFO - 'LOGNAME': 'cltbld', 12:35:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:38 INFO - 'MOZ_NO_REMOTE': '1', 12:35:38 INFO - 'NO_EM_RESTART': '1', 12:35:38 INFO - 'PAGER': '/bin/cat', 12:35:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:38 INFO - 'PWD': '/builds/slave/test', 12:35:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:38 INFO - 'SHELL': '/bin/bash', 12:35:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u8L3fnMIZz/Listeners', 12:35:38 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 12:35:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:38 INFO - 'USER': 'cltbld', 12:35:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:38 INFO - 'XPC_FLAGS': '0x0', 12:35:38 INFO - 'XPC_SERVICE_NAME': '0', 12:35:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:35:38 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-cMSUQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:35:38 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-xD4Skl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:35:38 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-BSNsYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:35:38 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-iNEPZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:35:38 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-hiCfXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:35:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-iuNOTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:35:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-I13Yyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:35:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-ZGJNu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:35:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-1Pmtnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:35:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-TdA9va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:35:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-kh9pE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:35:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-N9P_wM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:35:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-UmanvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:35:40 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-XqvPzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:35:40 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-avzAKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:35:40 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-GXj5pD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:35:40 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-X_AsmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:35:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:35:40 INFO - Running setup.py install for manifestparser 12:35:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:35:40 INFO - Running setup.py install for mozcrash 12:35:40 INFO - Running setup.py install for mozdebug 12:35:40 INFO - Running setup.py install for mozdevice 12:35:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:35:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:35:41 INFO - Running setup.py install for mozfile 12:35:41 INFO - Running setup.py install for mozhttpd 12:35:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:35:41 INFO - Running setup.py install for mozinfo 12:35:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:35:41 INFO - Running setup.py install for mozInstall 12:35:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:35:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:35:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:35:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:35:41 INFO - Running setup.py install for mozleak 12:35:41 INFO - Running setup.py install for mozlog 12:35:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:35:42 INFO - Running setup.py install for moznetwork 12:35:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:35:42 INFO - Running setup.py install for mozprocess 12:35:42 INFO - Running setup.py install for mozprofile 12:35:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:35:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:35:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:35:42 INFO - Running setup.py install for mozrunner 12:35:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:35:42 INFO - Running setup.py install for mozscreenshot 12:35:42 INFO - Running setup.py install for moztest 12:35:42 INFO - Running setup.py install for mozversion 12:35:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:35:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:35:43 INFO - Cleaning up... 12:35:43 INFO - Return code: 0 12:35:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:35:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:35:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:35:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:43 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 0x10f303a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe708d6faf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1a2e00>, '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 0x10f00bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f19db20>, '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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 12:35:43 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 12:35:43 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 12:35:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhEgB9yE4l/Render', 12:35:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:43 INFO - 'HOME': '/Users/cltbld', 12:35:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:43 INFO - 'LOGNAME': 'cltbld', 12:35:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:43 INFO - 'MOZ_NO_REMOTE': '1', 12:35:43 INFO - 'NO_EM_RESTART': '1', 12:35:43 INFO - 'PAGER': '/bin/cat', 12:35:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:43 INFO - 'PWD': '/builds/slave/test', 12:35:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:43 INFO - 'SHELL': '/bin/bash', 12:35:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u8L3fnMIZz/Listeners', 12:35:43 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 12:35:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:43 INFO - 'USER': 'cltbld', 12:35:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:43 INFO - 'XPC_FLAGS': '0x0', 12:35:43 INFO - 'XPC_SERVICE_NAME': '0', 12:35:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:35:43 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-fLDtP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:35:43 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)) 12:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:35:43 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-yHZWys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:35:43 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)) 12:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:35:43 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-Es_WnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:35:43 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)) 12:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:35:43 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-wsl4YH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:35:43 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)) 12:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:35:43 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-KkqQrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:35:43 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)) 12:35:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:35:43 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-zZynBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:35:44 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)) 12:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:35:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-6mf_xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:35:44 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)) 12:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:35:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-FJh6ZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:35:44 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)) 12:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:35:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-_hedTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:35:44 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)) 12:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:35:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-R3H2FP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:35:44 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)) 12:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:35:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-MgP1hS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:35:44 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)) 12:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:35:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-et9E9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:35:44 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)) 12:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:35:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-IewJzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:35:44 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)) 12:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:35:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-9sLuYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:35:44 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)) 12:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:35:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-PLXdK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:35:44 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)) 12:35:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:35:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-rMmDdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:35:45 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)) 12:35:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:35:45 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-XxX_33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:35:45 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)) 12:35:45 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)) 12:35:45 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)) 12:35:45 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)) 12:35:45 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)) 12:35:45 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)) 12:35:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:35:45 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 12:35:45 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 12:35:45 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 12:35:45 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 12:35:45 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 12:35:45 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 12:35:47 INFO - Downloading blessings-1.6.tar.gz 12:35:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:35:47 INFO - Installing collected packages: blessings 12:35:47 INFO - Running setup.py install for blessings 12:35:47 INFO - Successfully installed blessings 12:35:47 INFO - Cleaning up... 12:35:47 INFO - Return code: 0 12:35:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:35:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:35:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:35:48 INFO - Reading from file tmpfile_stdout 12:35:48 INFO - Current package versions: 12:35:48 INFO - blessings == 1.6 12:35:48 INFO - blobuploader == 1.2.4 12:35:48 INFO - docopt == 0.6.1 12:35:48 INFO - manifestparser == 1.1 12:35:48 INFO - mozInstall == 1.12 12:35:48 INFO - mozcrash == 0.17 12:35:48 INFO - mozdebug == 0.1 12:35:48 INFO - mozdevice == 0.48 12:35:48 INFO - mozfile == 1.2 12:35:48 INFO - mozhttpd == 0.7 12:35:48 INFO - mozinfo == 0.9 12:35:48 INFO - mozleak == 0.1 12:35:48 INFO - mozlog == 3.1 12:35:48 INFO - moznetwork == 0.27 12:35:48 INFO - mozprocess == 0.22 12:35:48 INFO - mozprofile == 0.28 12:35:48 INFO - mozrunner == 6.11 12:35:48 INFO - mozscreenshot == 0.1 12:35:48 INFO - mozsystemmonitor == 0.0 12:35:48 INFO - moztest == 0.7 12:35:48 INFO - mozversion == 1.4 12:35:48 INFO - psutil == 3.1.1 12:35:48 INFO - requests == 1.2.3 12:35:48 INFO - wsgiref == 0.1.2 12:35:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:35:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:35:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:35:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:48 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 0x10f303a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe708d6faf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1a2e00>, '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 0x10f00bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f19db20>, '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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 12:35:48 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 12:35:48 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 12:35:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhEgB9yE4l/Render', 12:35:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:48 INFO - 'HOME': '/Users/cltbld', 12:35:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:48 INFO - 'LOGNAME': 'cltbld', 12:35:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:48 INFO - 'MOZ_NO_REMOTE': '1', 12:35:48 INFO - 'NO_EM_RESTART': '1', 12:35:48 INFO - 'PAGER': '/bin/cat', 12:35:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:48 INFO - 'PWD': '/builds/slave/test', 12:35:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:48 INFO - 'SHELL': '/bin/bash', 12:35:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u8L3fnMIZz/Listeners', 12:35:48 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 12:35:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:48 INFO - 'USER': 'cltbld', 12:35:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:48 INFO - 'XPC_FLAGS': '0x0', 12:35:48 INFO - 'XPC_SERVICE_NAME': '0', 12:35:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:48 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)) 12:35:48 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)) 12:35:48 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)) 12:35:48 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)) 12:35:48 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)) 12:35:48 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)) 12:35:48 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)) 12:35:48 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)) 12:35:48 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)) 12:35:48 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)) 12:35:48 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)) 12:35:48 INFO - Cleaning up... 12:35:48 INFO - Return code: 0 12:35:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:35:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:35:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:35:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:48 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 0x10f303a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe708d6faf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1a2e00>, '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 0x10f00bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f19db20>, '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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 12:35:48 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 12:35:48 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 12:35:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhEgB9yE4l/Render', 12:35:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:48 INFO - 'HOME': '/Users/cltbld', 12:35:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:48 INFO - 'LOGNAME': 'cltbld', 12:35:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:48 INFO - 'MOZ_NO_REMOTE': '1', 12:35:48 INFO - 'NO_EM_RESTART': '1', 12:35:48 INFO - 'PAGER': '/bin/cat', 12:35:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:48 INFO - 'PWD': '/builds/slave/test', 12:35:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:48 INFO - 'SHELL': '/bin/bash', 12:35:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u8L3fnMIZz/Listeners', 12:35:48 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 12:35:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:48 INFO - 'USER': 'cltbld', 12:35:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:48 INFO - 'XPC_FLAGS': '0x0', 12:35:48 INFO - 'XPC_SERVICE_NAME': '0', 12:35:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:48 INFO - Downloading/unpacking jsonschema 12:35:48 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 12:35:48 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 12:35:48 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 12:35:48 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 12:35:48 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 12:35:48 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 12:35:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:35:51 INFO - Downloading/unpacking functools32 (from jsonschema) 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35:51 INFO - Downloading functools32-3.2.3-2.tar.gz 12:35:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:35:51 INFO - warning: no files found matching '*.txt' 12:35:51 INFO - no previously-included directories found matching 'build' 12:35:51 INFO - no previously-included directories found matching 'dist' 12:35:51 INFO - no previously-included directories found matching '.git*' 12:35:51 INFO - Installing collected packages: jsonschema, functools32 12:35:51 INFO - Running setup.py install for functools32 12:35:51 INFO - warning: no files found matching '*.txt' 12:35:51 INFO - no previously-included directories found matching 'build' 12:35:51 INFO - no previously-included directories found matching 'dist' 12:35:51 INFO - no previously-included directories found matching '.git*' 12:35:51 INFO - Successfully installed jsonschema functools32 12:35:51 INFO - Cleaning up... 12:35:51 INFO - Return code: 0 12:35:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:35:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:35:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:51 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 0x10f303a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f313420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe708d6faf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1a2e00>, '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 0x10f00bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f19db20>, '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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 12:35:51 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 12:35:51 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 12:35:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhEgB9yE4l/Render', 12:35:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:35:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:35:51 INFO - 'HOME': '/Users/cltbld', 12:35:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:35:51 INFO - 'LOGNAME': 'cltbld', 12:35:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:51 INFO - 'MOZ_NO_REMOTE': '1', 12:35:51 INFO - 'NO_EM_RESTART': '1', 12:35:51 INFO - 'PAGER': '/bin/cat', 12:35:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:35:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:51 INFO - 'PWD': '/builds/slave/test', 12:35:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:35:51 INFO - 'SHELL': '/bin/bash', 12:35:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u8L3fnMIZz/Listeners', 12:35:51 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 12:35:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:35:51 INFO - 'USER': 'cltbld', 12:35:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:35:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:35:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:51 INFO - 'XPC_FLAGS': '0x0', 12:35:51 INFO - 'XPC_SERVICE_NAME': '0', 12:35:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:35:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:52 INFO - Downloading/unpacking flake8 12:35:52 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 12:35:52 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 12:35:52 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 12:35:52 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 12:35:52 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 12:35:52 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 12:35:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:35:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:35:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35: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 12:35:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:35:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:35:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:35:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:35:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:35:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:35:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:35:55 INFO - no previously-included directories found matching 'docs/_build' 12:35:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:35:55 INFO - Running setup.py install for pep8 12:35:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:35:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:35:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:35:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:35:56 INFO - no previously-included directories found matching 'docs/_build' 12:35:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:35:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:35:56 INFO - Cleaning up... 12:35:56 INFO - Return code: 0 12:35:56 INFO - Running post-action listener: _resource_record_post_action 12:35:56 INFO - Running post-action listener: _start_resource_monitoring 12:35:56 INFO - Starting resource monitoring. 12:35:56 INFO - ##### 12:35:56 INFO - ##### Running install step. 12:35:56 INFO - ##### 12:35:56 INFO - Running pre-action listener: _resource_record_pre_action 12:35:56 INFO - Running main action method: install 12:35:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:35:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:35:56 INFO - Reading from file tmpfile_stdout 12:35:56 INFO - Detecting whether we're running mozinstall >=1.0... 12:35:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:35:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:35:56 INFO - Reading from file tmpfile_stdout 12:35:56 INFO - Output received: 12:35:56 INFO - Usage: mozinstall [options] installer 12:35:56 INFO - Options: 12:35:56 INFO - -h, --help show this help message and exit 12:35:56 INFO - -d DEST, --destination=DEST 12:35:56 INFO - Directory to install application into. [default: 12:35:56 INFO - "/builds/slave/test"] 12:35:56 INFO - --app=APP Application being installed. [default: firefox] 12:35:56 INFO - mkdir: /builds/slave/test/build/application 12:35:56 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'] 12:35:56 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 12:36:27 INFO - Reading from file tmpfile_stdout 12:36:27 INFO - Output received: 12:36:27 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:36:27 INFO - Running post-action listener: _resource_record_post_action 12:36:27 INFO - ##### 12:36:27 INFO - ##### Running run-tests step. 12:36:27 INFO - ##### 12:36:27 INFO - Running pre-action listener: _resource_record_pre_action 12:36:27 INFO - Running main action method: run_tests 12:36:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:36:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:36:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:36:28 INFO - 2016-05-19 12:36:28.000 screenresolution[1649:7805] starting screenresolution argv=screenresolution get 12:36:28 INFO - 2016-05-19 12:36:28.002 screenresolution[1649:7805] Display 0: 1600x1200x32@60 12:36:28 INFO - 2016-05-19 12:36:28.008 screenresolution[1650:7808] starting screenresolution argv=screenresolution list 12:36:28 INFO - Available Modes on Display 0 12:36:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:36:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:36:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:36:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:36:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:36:28 INFO - Intel Iris: 12:36:28 INFO - Chipset Model: Intel Iris 12:36:28 INFO - Type: GPU 12:36:28 INFO - Bus: Built-In 12:36:28 INFO - VRAM (Dynamic, Max): 1536 MB 12:36:28 INFO - Vendor: Intel (0x8086) 12:36:28 INFO - Device ID: 0x0a2e 12:36:28 INFO - Revision ID: 0x0009 12:36:28 INFO - Displays: 12:36:28 INFO - Display: 12:36:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:36:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:36:28 INFO - Display Serial Number: 1600x1200 60 12:36:28 INFO - Main Display: Yes 12:36:28 INFO - Mirror: Off 12:36:28 INFO - Online: Yes 12:36:28 INFO - Rotation: Supported 12:36:28 INFO - Return code: 0 12:36:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:36:28 INFO - Python 2.7.3 12:36:28 INFO - Return code: 0 12:36:28 INFO - grabbing minidump binary from tooltool 12:36: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')]} 12:36:28 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 0x10f1a2e00>, '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 0x10f00bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f19db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:36:28 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 12:36:28 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 12:36:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:36:28 INFO - Return code: 0 12:36:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:36:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:36:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:36:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:36:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:36:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:36:29 INFO - Return code: 0 12:36:29 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0284', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:36:29 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0284 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:36:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhEgB9yE4l/Render', 12:36:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:29 INFO - 'HOME': '/Users/cltbld', 12:36:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:29 INFO - 'LOGNAME': 'cltbld', 12:36:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:36:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:36:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:29 INFO - 'MOZ_NO_REMOTE': '1', 12:36:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:36:29 INFO - 'NO_EM_RESTART': '1', 12:36:29 INFO - 'PAGER': '/bin/cat', 12:36:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:29 INFO - 'PWD': '/builds/slave/test', 12:36:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:36:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:29 INFO - 'SHELL': '/bin/bash', 12:36:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u8L3fnMIZz/Listeners', 12:36:29 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 12:36:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:29 INFO - 'USER': 'cltbld', 12:36:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:29 INFO - 'XPC_FLAGS': '0x0', 12:36:29 INFO - 'XPC_SERVICE_NAME': '0', 12:36:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0284', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:36:29 INFO - mozversion application_buildid: 20160519112644 12:36:29 INFO - mozversion application_changeset: 133e9db008eadefc4426a3731d30f60f0e290a76 12:36:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:36:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:36:29 INFO - mozversion application_name: Firefox 12:36:29 INFO - mozversion application_remotingname: firefox-dev 12:36:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:36:29 INFO - mozversion application_vendor: Mozilla 12:36:29 INFO - mozversion application_version: 48.0a2 12:36:29 INFO - mozversion platform_buildid: 20160519112644 12:36:29 INFO - mozversion platform_changeset: 133e9db008eadefc4426a3731d30f60f0e290a76 12:36:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:36:29 INFO - mozversion platform_version: 48.0a2 12:36:29 INFO - using testdate: 1463686589 12:36:29 INFO - actual date: 1463686589 12:36:29 INFO - starting webserver on 'localhost:49278' 12:36:29 INFO - SUITE-START | Running 2 tests 12:36:29 INFO - TEST-START | dromaeo_css 12:36:29 INFO - operating with platform_type : mac_ 12:36:29 INFO - Initialising browser for dromaeo_css test... 12:36:29 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpBg35Hl/profile http://localhost:49278/getInfo.html) 12:36:32 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 12:36:32 INFO - PROCESS | 1668 | colorDepth:24 12:36:32 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 12:36:32 INFO - PROCESS | 1668 | __metrics 12:36:32 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:36:32 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:36:32 INFO - TEST-INFO | 1668: exit 0 12:36:32 INFO - Browser initialized. 12:36:32 INFO - Running cycle 1/1 for dromaeo_css test... 12:36:32 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpBg35Hl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:36:44 INFO - PROCESS | 1670 | RSS: Main: 211066880 12:36:44 INFO - PROCESS | 1670 | 12:37:36 INFO - PROCESS | 1670 | 1463686656185 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] 12:39:46 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 12:39:48 INFO - PROCESS | 1670 | RSS: Main: 321024000 12:39:48 INFO - PROCESS | 1670 | 12:42:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 12:42:51 INFO - PROCESS | 1670 | RSS: Main: 357752832 12:42:51 INFO - PROCESS | 1670 | 12:45:53 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 12:45:54 INFO - PROCESS | 1670 | RSS: Main: 361779200 12:45:54 INFO - PROCESS | 1670 | 12:48:56 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 12:48:57 INFO - PROCESS | 1670 | RSS: Main: 355864576 12:48:57 INFO - PROCESS | 1670 | 12:52:00 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 12:52:01 INFO - PROCESS | 1670 | RSS: Main: 320909312 12:52:01 INFO - PROCESS | 1670 | 12:55:03 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 12:55:03 INFO - PROCESS | 1670 | RSS: Main: 369868800 12:55:03 INFO - PROCESS | 1670 | 12:55:03 INFO - PROCESS | 1670 | __start_tp_report 12:55:03 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 12:55:03 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 12:55:03 INFO - PROCESS | 1670 | |i|pagename|runs| 12:55:03 INFO - PROCESS | 1670 | |0;dojo.html;3379.831008449578;3447.5690538682666;3464.3764122457956;3475.339685459764;3501.334746398184;5639.915401268984;5665.886682266352;5689.0328644130495;5694.715264236787;5697.373288938214;5621.747021384037;5686.715664216793;5689.175069614904;5695.914561281584;5699.544036477082;5060.114479965991;5098.770555325019;5108.208227724708;5219.530242278194;5234.476552344773;5185.144451165553;5195.584353251731;5207.088759467097;5208.270842082112;5212.139996900526;5164.9483505165035;5184.300119483878;5212.504812042869;5220.92168617471;5230.21546767984;5208.192730126818;5226.163813789775;5232.8691782705355;5239.083160446907;5281.207818827168;5065.898682026375;5091.847244582668;5104.744762761884;5168.586513078944;5171.095058364771;5507.201455788888;5553.250311207976;5559.054960656657;5591.916121258144;5593.300837395325;5086.440491545927;6126.019836826086;6196.628202307831;6206.25524937006;6217.0363593642605;5289.841304760864;6082.284463459512;6183.907241391383;6232.750689972396;6257.87484250317;5775.8556036099435;5791.710414479344;5811.3316968548315;5811.651300921958;5837.416258374171;6214.067889816563;6237.532185086136;6246.031865060922;6248.281447633581;6249.468795152372;6001.369856165127;6062.666553339521;6074.4532992030045;6117.571769976059;6136.85608443425;6110.649890384107;6128.380904898417;6128.839250099703;6133.793750436688;6143.266246520256;6116.688983687855;6118.663473509;6128.167033931239;6136.825445958321;6148.9692551536955;6078.544109191828;6118.694065296718;6133.854967165038;6136.365905528278;6147.354527774609;4871.853844384674;4915.68048076881;4918.852434426973;4944;4953.034158339161;4396.60430561251;4422.800973956116;4424.557544245614;4433;4451.265541185733;5582.24639673647;5782.219400380979;5784.942150578525;5801.158831969341;5842.415758424208;6056.515478761688;6124.234470691164;6128.387161283836;6131.049687298476;6134.294556126102;5605.5515558755205;5725.713714314267;5780.364159942403;5839.737211825478;5841.766329346779;5330.626856120112;5364.463553644604;5380.354357477049;5389.353277606712;5416.539594134464;6261.24865016201;6263.311035786151;6264.467520260738;6280.811575652738;6281.497480201664;6117.235345581802;6155.599886007395;6206.06908963641;6255.56211065221;6277.874442511033;6229.595076320024;6245.7189426476825;6248.843778905381;6251.156093927443;6267.811965641038;6090;6144.600600960924;6147.784827531015;6151.84620384494;6163.599366041193;6111.266648002268;6137.35557766446;6159.692015399122;6159.969200153971;6241.96879015602;6066.4540191382985;6072.757089716361;6089.634621922699;6090.665013424305;6127.264728232641;6038.637681738934;6040.456358927718;6040.667763272886;6053.092036194606;6059.575829691879;5046.697198168122;5720.027595308724;5769.336526299361;5884.264466941632;5885.588008839341;5777.94222057774;6079.848003799941;6081.939180608138;6084.847878803065;6106.596914875623;5893.528517718658;5909.70451477433;5914.083317085859;5925.318588362221;5939.732712027882 12:55:03 INFO - PROCESS | 1670 | |1;ext.html;512.1421618788528;536.7369988705535;560.0031943141208;602.8312072619668;625.5402279324695;31597.526037109434;32328.515072273934;40131.39802902955;40584.98537536563;40604.59395406045;45313;45940.770296148476;45946;46082.539174608246;46107;8934.73195804127;9446.291528135418;9677.806443871135;9900.108990190882;9900.910899801016;8816.250618697419;9053.818923621524;11751.647450576496;11940.223885447462;11969.222000569978;11604.361760103191;11660.125490588198;11663.94168029163;11665.825012624775;11668.41657917105;8694.826103477959;11722.06223502122;11838.99368553674;11846.7630647387;11894.643160705193;11747.29516229029;11877.821832672516;11883.168178227528;11883.465244063951;11896.167268291223;40058.59941400578;40387.778672173015;40478.190436191115;40494.79752601247;40623.59376406228;3276.410246155691;3279.2785587171024;3283.441814891474;3320.850561724704;3339.782914110575;28145;29851.20508510719;30025.849870750724;30075.699243007726;30148.54777178344;2772.6395568575954;2788.1774876411305;2808.5787131930374;2822.788290878192;2851.3156842357894;491928;494553;495808;496616.5169174167;497149;37006.814965925;65302;66408.33591664118;66408.33591664118;66636.00045999314;36485.63514364875;36568.63431365652;36597.451038234445;36616.08459788527;36628.633713663054;16301;16629.50111496657;16642.251098700344;16659;16678.58303542421;12746.872531274754;12980.935095324463;13895.722085558233;14037.087589306662;14125.646858828406;8301.252887240178;8312.376571757142;8312.8753068704;8406;8407.579621018924;3150.8739650414186;3241.9351612967607;3455.1189446691437;3455.2571197192724;3492.89521314361;872.2367928062945;957.4733896356976;1013.4122209118693;1030.8814935794596;1060.2207377577474;29459.558106628418;29587.556186657217;29793.85103074514;29983.70016299809;30228.546571801442;1054.0776820282254;1060.8567843341204;1065.6110519660424;1110.6779034080025;1171.226990186473;14957.326920288613;15091.698166036618;15168.696626067416;15382.556058480091;15679.608009799846;642770;650797;651714;653023;655066;62963.425914352505;63169;63978.36021639817;63986.68006659937;64240.036399454046;1359.9184048957293;1392.7005693775886;1530.0207866965152;1545.5672411724736;1553.906765594068;30873.536896946116;32250.516242255915;32256;32432.675673242964;32988.835055824566;59301;59421.10868337065;59978;60400;60423;354270;467065;467653;468533;468826;37236;43244.91887702833;44926.55073449355;45671.54328456673;45982.310265345375;40848.59151408448;45128.0974380504;45453;45826.625201244016;47069.52930470651;37773.62226377811;37971.05072373102;38405.61594384132;39129;39295.80352098221;37361.62638373581;37558.06104847401;37754.24491510099;37830.43254351132;38514.422283665204;39159.41260881032;39319.80340098281;39516.40725389179;39838.60161398349;39853.40219896762;25050.874745626155;28686.713132869238;29561.556576651798;29762.404751905273;29835.70164298416;25780.8710956444;25989.480210395308;26213.606795898457;26256.844940437244;26488.470230594896 12:55:03 INFO - PROCESS | 1670 | |2;jquery.html;401.77701375736456;402.61550219540334;404.68838993974657;409.1857204163715;418.5626020808255;14020.933929160532;20223;20551.177952881902;20870.895645521752;20891.059902304394;16968.915155424238;19215;20617.690734638956;20771.27300544481;21319.36041918741;5969.671668058266;6146.7233974471255;6192.907106393408;6194.070889366586;6210.223722034746;5794.565407594426;6131.938680613203;6171.290301615327;6184.876302473948;6185.381461853801;3979.084810493588;4171.06151115998;4775.522447755231;6044.697765111749;6147.047207682794;6101.511879049665;6112.297085835097;6119.908201376983;6126.8161955141195;6128.38716128388;5534.723263836832;5830.038043722823;5843.5033022193165;5845.06478963368;5945.286565612111;13398.866011339956;13592.728145437135;13633.250171240577;20170.69743953842;25123;2279.9772002280097;2358.445765245166;2594.805389595788;2807.536756435186;2870.8708108134974;9785.02149785023;9847.55685994132;9876.35803672759;10116.291859569834;10122.190224782;1702.5062731807761;1718.9084931068737;1776.9911150444166;1824.5621050947677;1917.2043601905193;105838;109074.45462772714;112435;114959.42520287345;115656;11448.54205831774;11487.885121148849;11730.413479326;11741;11879.76240475203;15061.548153555412;15119.54641360761;15172.241387930559;15208.011479253813;15214.087154770748;7272.63636818157;8746.518941458153;8902.554872256362;8979;8996.190342869042;9027.413218140931;9034.593443295067;9080.81838363229;9082.45505269686;9432.669856555125;4588.587027167571;4660.743659098782;4665.3934988451965;4716.95283047172;4724.88187795301;1927.4699457648978;1980.4850738808013;1990.1740777577143;2027.3005812994493;2030.644637188486;865.2774932930947;941.1764705882352;950.3870003847479;969.5007071358256;1113.9443027848574;16294.42969496062;16612.50162495103;19782.01089945497;20381;20608.27871024507;977.2670497127154;990.8315586350337;1023.9129673977795;1040.2977989856815;1063.6383629565985;8793.516356600321;10530.210234232463;10833.83749243762;10886.891131088747;10909.945450272857;520094;612146;614172;616302;640995;117623.41188294004;119815;122901;124076;127371;1041.2138835179392;1043.2488608202082;1065.1319174872453;1065.832912960307;1068.631322193842;14163.64590885236;17460.91269543644;17648.64702705913;18082.276708931495;18518.16668249905;49982.75008624933;50564.74717626388;51783.22325165201;51925.22112168245;53071;77125.07187320138;77919.2208077912;78621.60689196517;78661;79277.20722792929;8080.0707918590115;8284.798704187739;8348.666053357798;8464.66141354339;8693.826123477622;10861.728456788644;12543.372831358212;12746;12927.030472714396;12989.675258118623;10847.620333288298;11033.724156896142;11116.333020018501;11145.164112691418;11162.665120046411;10768.3538987661;10837.241393102722;10840.349579025284;10970.835437468604;11214.55141794319;10932.398718070583;11199.440027998404;11311.886881131084;11548.42257887119;11592.246503977214;8271.379646526415;8293.21214484623;8347.248747612744;8349.290310323691;8456.450330728483;10352.223583231138;10754.247202695737;11296.096312295162;11323.980841724284;11352.54589816398 12:55:03 INFO - PROCESS | 1670 | |3;mootools.html;1350.372076984202;1585.0093691442848;1587.2381256996641;1812.3475548802437;1857.4241984984646;7876.25175608317;8674.262687671548;8817.162369574897;8841.33689973253;8848.690295839648;8500.447470914387;8736.475811451317;8738.781530461742;8754.781130471742;8758.91241087589;2420.6732091167733;2446.9967313401507;2448.644946482768;2452.619843924195;2453.4234454903085;2383.2731017039796;2426.0659646036315;2431.9513609727796;2435.0016493237777;2435.622478515833;2106.9894650526726;2400.7479214682467;2423.866687332196;2435.6468312094735;2441.523902838947;2357.457784709518;2378.4410663494064;2419.2742177346813;2424.9393765155837;2428.113738485458;2383.7377888432256;2422.067504010951;2429.6234083717054;2432.7688869557364;2432.8418652787514;8467.576621168917;8893;8894.59974301158;8903.955480222558;8946.418482798597;5295.232191332235;5443.129099344125;5447.291852059247;5454.154606036071;5489.752961116759;3539.8407071681836;3617.801020943847;3660.68884144848;3667.1198912261134;3672.192117734121;1040.1626690514133;1058.1164727452585;1066.6213494209599;1067.909227715645;1089.0688461365498;11624;11698.824517632242;11778.528858845635;11782.234154779997;11816.881831181665;4537.863864084083;4583.931241031387;4676.742779147112;4698.929516057262;4699.224627936353;9355.532223388804;9357.298202634829;9361.531923403803;9368.812623747486;9397.154256116983;5333.386660534011;5377.838664840061;5379.35447746273;5379.623426360117;5396.892062158735;4451.866444006685;4548.0221752323405;4596.4024676791805;4605.65457590682;4950.554450099508;3212.1969507623094;3616;3907.023244188953;3913.1977944521304;3964.72246942717;1178.8644305904932;1220.0727447140237;1221.2977537915733;1231.4643130238196;1231.940531143216;817.8672538534054;864.8270345930839;913.8263729891299;926.2404828041076;959.5490119643756;5599.524040456526;5653.095504719226;5766.365699773021;5782;5870.941290587125;932.5430539035824;939.7133874168444;966.3380584299778;968.3850754770772;989.094978095035;3798.506194194737;3867.8839634811;3891.0272431892026;3942.40863870416;3975.8210880510446;10443.268971172098;10584.153267738562;10800.513976870903;10883.945580272048;10952.835707464394;9449.291303152291;10462.581496740197;10732.926707329205;10747.516361763583;10902.890971090188;8385.035720892054;8395.748127556182;8397.6221070052;8481.872771908425;8588.828223435496;4875.488073752205;5611.635243709309;5667.2197438622225;5701.942980570308;5777.335606405317;9463.38487998278;9496.002919693332;9644.421334719938;9665.661701840401;9669.371490853073;9672.177864881563;9884.85172722424;9982;10024.24818138657;10182.18542516582;6963.1296087989;6993.650317484207;7051.224365319913;7130.857382852418;7135.072440582691;7530.623468826428;7671.38628909697;8181.550014749246;8396;8407;7406.407487401103;7412.555246685216;7416.8887466686965;7509.098908130841;7529.435292353205;7266.346028857428;7350.77947661571;7361.668724907285;7403.333699967029;7467.141278753013;6805.65971701403;6870.965645171742;6940.09778728762;6981.0924579804305;6984.895226571504;4609.861704148881;4690.6247500199215;4711.15199264122;4756.9762151189025;4761.262004389325;4782.856514304576;4788.904221915473;4823;4841.903161936812;5034.270030845581 12:55:03 INFO - PROCESS | 1670 | |4;prototype.html;321.88573056564917;324.1394098668036;332.1695760598504;333.6346700363103;339.0489676457536;6550.836229094269;6615.272320044791;6620;6628.76799312023;6636.502262330332;6505.837354066139;6547;6554.6067235965875;6588.571742836724;6753.7636182733495;4653.650976176792;4712.811487540494;4758.428988521382;4798.280257961316;4837.201861692816;4743.620510359163;4769.475357710649;4810.518948105197;4818.975905120469;4823.710577365369;3899.317619416591;4734.810607575693;4778.761061946906;4789.1140139074205;4827.927581086286;4575.633949284049;4649.093426781779;4750.762461876892;4764.880877978044;4806.0628177505405;4130.524989626201;4143.502779666429;4148.377743338493;4152.584741525853;4157.397177409289;6304.558680892339;6339;6345.238571371418;6360.55476116672;6364.522660800459;5687.033204355228;5701.572382071361;5795.333536643256;5807.709614519257;5818.272715910512;6717.798466046026;6972.546784458994;6977.232504424509;6983.511154219207;7003.92996070043;4662.067586482717;4668.416447944007;4690.343351930733;4694.57748802606;4708.576228139484;7313.451491138133;7349.742759003409;7366.300201480904;7378.077740282465;7380.335769780691;4746.121967436035;4765.833195838129;4770.546798054249;4771.570558649669;4773.307870358779;4828.758562071883;4832.589229915427;4833.178359678863;4837.080954618611;4845.515448455197;4681.976590117028;5690.089585666274;5698.544116470673;5701.914471282934;5711.400302968212;6670.966000269966;7034.331738484836;7060.92939070613;7067.611281379471;7093.1488221413765;1650.3316156956453;1670.9749353759703;1672.1639180409795;1678.5719641491341;1681.3022595622924;1017.5878769098526;1028.953697083633;1061.559452827068;1069.4545781651414;1092.6503518874042;5653.434656534314;6420.390062944106;6461.06314584392;6651.966740166365;6655.700493477805;7312.597807120659;7313.268673132644;7320.341169294789;7326.120865496175;7333.779986600411;6556.377144171296;6578.835529111715;6580.243272023778;6597.703103360264;6604;3809.6761775249415;3846.442265871433;3848.5766565677754;3853.3641949078087;3855.9614403856162;7287.562746235243;7555.546667199986;7593.240675932473;7594.164641889388;7603.847923041508;7513.2862378074;7564.130125035691;7583.544987300779;7596.24037596247;7603.771886843404;6554.852900742351;7301.561906285534;7648.42636802242;7651.655675494505;7663.195364486649;7087.893681594677;7323.890141647773;7335.1197856257595;7343.559386436831;7348.301911318416;5902.4097590240635;5972.731227094704;6470.932296171079;6704.463642908653;7260.67326970277;7664.156942736406;7668.80827979305;7677.385809135368;7679.462437629312;7688.884666730115;4579.793909274024;4595.609373203181;4627.6066534344955;4632.953670463252;4645.279981602857;5465.9726701366235;6407.743690252338;7351.264873512606;7364.926350736424;7371.483996120434;6703;6967.79096627102;6982.685779140053;6998.370146686822;7080.362767350963;6956.617386043817;6996.650167491707;7005.789826305219;7012.474064445085;7013.368796808311;5585.609007369445;6857.417119544894;6858.348456896587;6878.55289406187;6900.930990690029;6762.830929226809;7029.402500787286;7038.260982596754;7063.823404414725;7072.434205263669;6649.534532582673;6733.7306507741105;6818.079559259437;6844.965775171291;6863.828404289933 12:55:03 INFO - PROCESS | 1670 | |5;yui.html;683.2416018219776;752.4018405367735;757.9128400233975;772.3743767548283;776.809681628001;14995.775063374058;15120.848791512082;15214.847851521481;15225.162616056112;15231.009984351009;12828.27930000348;13906.374213160407;14112.15327080376;14119.858801411983;14133.363998620061;919.9632014719404;938.8028514012066;941.1106504353389;941.1623654947102;942.79258563116;900.6127365232948;922.1239822168933;925.4632313258294;925.9675911343104;926.1479438916214;964.6768332608584;974.410481658597;977.218225419665;977.7751117243039;981.8036392721449;958.8801399825022;968.4867020479157;969.3021024862123;970.2189737261501;972.3388096094653;724.337231433238;726.4079774983371;727.2799928071224;728.1807966038207;728.613834667627;12949.54676586315;13398.861096806782;13474.326283685776;13494.257815820127;13514.59456216315;11723.648290551297;11837.585684501;11907.642770716893;11926.403679815974;12125.211861228992;9544.61821527138;9546.809063818684;9561.713148605553;9909;10262.179025678002;3347.1130150510135;3383.8984830455124;3393.389189945809;3439.9828000860084;3589.4436362363876;67638;73262;74745.25254747491;80674.99999999942;81068;9755.170810481046;9890.851637225447;10041.09630133277;10062.899371006342;10421.583136674448;22062.11751529953;22136;22139.778602213773;22189.889050554644;22241.22155724542;7565.167831538526;7727.806804829812;7732.188120247405;7776.7278145264645;7863.213678632091;4093.692973027035;4102.733322334038;4145.79271036447;4197.538270790272;4216.978915105405;3580.0154957386926;3629.7822130672243;3633.4004889193516;3636.581793093775;3655.3603119453987;632.3139393757824;644.1818890009755;645.6061802300593;652.9281779004306;662.5296039811788;517.3378076062646;583.6789765628887;592.3839207224438;595.4640823259015;624.5784095735361;7684.3084122429245;7972;8035.357171426389;8326.583670816555;8354.081051084377;547.1437200780765;580.4021857486711;588.717415640495;604.9062395328731;620.1689735754021;3151.432742106397;3665.4685070664605;3711.350513660099;3785.6025117362833;3995.7574195594575;61995.070073948926;82589.17410825935;83727.58136209402;83997.58001210078;84553.57723211344;70637;72604.27395726081;75422.62288688631;76158.6192069036;76460;48302;63283.367166328666;65377.673111635086;66205;66772;7588.658510366937;7702.730404435818;7841.059072911287;7897.210278972057;7907.762767116996;46715.53284467112;46759.29861052019;46789.53210467852;47098.293525596455;48422.5157748418;50238.2464263029;58058;63516.72966540736;63738;65033.34966650462;10023.69928902134;10372.170226382023;10394.948025260128;10516.421596811942;10530.157587392838;10847.945760271148;10904.236703430684;10921.235513513977;11089.667309980714;11158.44207789591;10304.330218535772;10306.33008854422;10443.321184123008;10651.30766500175;10781.730456738644;10152.76449968129;10299.948500257751;10504.789904202025;10535.104516116216;11027.062699670618;9901.05939935705;10291.691249262534;10390.532426040998;10516.369017858648;10548;8866.42368246062;8873.600687968928;8907.866382004146;9184.12750788674;9219.723408297761;8916.06381329951;8947.955260223658;8976.730698079067;9042.231410329932;9090.13643703847 12:55:03 INFO - PROCESS | 1670 | __end_tp_report 12:55:03 INFO - PROCESS | 1670 | __start_cc_report 12:55:03 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1075 12:55:03 INFO - PROCESS | 1670 | __end_cc_report 12:55:03 INFO - PROCESS | 1670 | __startTimestamp1463687703843__endTimestamp 12:55:03 INFO - PROCESS | 1670 | 12:55:03 INFO - PROCESS | 1670 | ------- Summary: start ------- 12:55:03 INFO - PROCESS | 1670 | Number of tests: 6 12:55:03 INFO - PROCESS | 1670 | 12:55:03 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5693.19 Median:5885.59 stddev:612.09 (10.4%) stddev-sans-first:586.45 12:55:03 INFO - PROCESS | 1670 | Values: 3379.8 3447.6 3464.4 3475.3 3501.3 5639.9 5665.9 5689.0 5694.7 5697.4 5621.7 5686.7 5689.2 5695.9 5699.5 5060.1 5098.8 5108.2 5219.5 5234.5 5185.1 5195.6 5207.1 5208.3 5212.1 5164.9 5184.3 5212.5 5220.9 5230.2 5208.2 5226.2 5232.9 5239.1 5281.2 5065.9 5091.8 5104.7 5168.6 5171.1 5507.2 5553.3 5559.1 5591.9 5593.3 5086.4 6126.0 6196.6 6206.3 6217.0 5289.8 6082.3 6183.9 6232.8 6257.9 5775.9 5791.7 5811.3 5811.7 5837.4 6214.1 6237.5 6246.0 6248.3 6249.5 6001.4 6062.7 6074.5 6117.6 6136.9 6110.6 6128.4 6128.8 6133.8 6143.3 6116.7 6118.7 6128.2 6136.8 6149.0 6078.5 6118.7 6133.9 6136.4 6147.4 4871.9 4915.7 4918.9 4944.0 4953.0 4396.6 4422.8 4424.6 4433.0 4451.3 5582.2 5782.2 5784.9 5801.2 5842.4 6056.5 6124.2 6128.4 6131.0 6134.3 5605.6 5725.7 5780.4 5839.7 5841.8 5330.6 5364.5 5380.4 5389.4 5416.5 6261.2 6263.3 6264.5 6280.8 6281.5 6117.2 6155.6 6206.1 6255.6 6277.9 6229.6 6245.7 6248.8 6251.2 6267.8 6090.0 6144.6 6147.8 6151.8 6163.6 6111.3 6137.4 6159.7 6160.0 6242.0 6066.5 6072.8 6089.6 6090.7 6127.3 6038.6 6040.5 6040.7 6053.1 6059.6 5046.7 5720.0 5769.3 5884.3 5885.6 5777.9 6079.8 6081.9 6084.8 6106.6 5893.5 5909.7 5914.1 5925.3 5939.7 12:55:03 INFO - PROCESS | 1670 | 12:55:03 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:66883.20 Median:36606.77 stddev:143763.97 (392.7%) stddev-sans-first:144080.90 12:55:03 INFO - PROCESS | 1670 | Values: 512.1 536.7 560.0 602.8 625.5 31597.5 32328.5 40131.4 40585.0 40604.6 45313.0 45940.8 45946.0 46082.5 46107.0 8934.7 9446.3 9677.8 9900.1 9900.9 8816.3 9053.8 11751.6 11940.2 11969.2 11604.4 11660.1 11663.9 11665.8 11668.4 8694.8 11722.1 11839.0 11846.8 11894.6 11747.3 11877.8 11883.2 11883.5 11896.2 40058.6 40387.8 40478.2 40494.8 40623.6 3276.4 3279.3 3283.4 3320.9 3339.8 28145.0 29851.2 30025.8 30075.7 30148.5 2772.6 2788.2 2808.6 2822.8 2851.3 491928.0 494553.0 495808.0 496616.5 497149.0 37006.8 65302.0 66408.3 66408.3 66636.0 36485.6 36568.6 36597.5 36616.1 36628.6 16301.0 16629.5 16642.3 16659.0 16678.6 12746.9 12980.9 13895.7 14037.1 14125.6 8301.3 8312.4 8312.9 8406.0 8407.6 3150.9 3241.9 3455.1 3455.3 3492.9 872.2 957.5 1013.4 1030.9 1060.2 29459.6 29587.6 29793.9 29983.7 30228.5 1054.1 1060.9 1065.6 1110.7 1171.2 14957.3 15091.7 15168.7 15382.6 15679.6 642770.0 650797.0 651714.0 653023.0 655066.0 62963.4 63169.0 63978.4 63986.7 64240.0 1359.9 1392.7 1530.0 1545.6 1553.9 30873.5 32250.5 32256.0 32432.7 32988.8 59301.0 59421.1 59978.0 60400.0 60423.0 354270.0 467065.0 467653.0 468533.0 468826.0 37236.0 43244.9 44926.6 45671.5 45982.3 40848.6 45128.1 45453.0 45826.6 47069.5 37773.6 37971.1 38405.6 39129.0 39295.8 37361.6 37558.1 37754.2 37830.4 38514.4 39159.4 39319.8 39516.4 39838.6 39853.4 25050.9 28686.7 29561.6 29762.4 29835.7 25780.9 25989.5 26213.6 26256.8 26488.5 12:55:03 INFO - PROCESS | 1670 | 12:55:03 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:34406.64 Median:20821.08 stddev:100239.01 (481.4%) stddev-sans-first:100487.69 12:55:03 INFO - PROCESS | 1670 | Values: 401.8 402.6 404.7 409.2 418.6 14020.9 20223.0 20551.2 20870.9 20891.1 16968.9 19215.0 20617.7 20771.3 21319.4 5969.7 6146.7 6192.9 6194.1 6210.2 5794.6 6131.9 6171.3 6184.9 6185.4 3979.1 4171.1 4775.5 6044.7 6147.0 6101.5 6112.3 6119.9 6126.8 6128.4 5534.7 5830.0 5843.5 5845.1 5945.3 13398.9 13592.7 13633.3 20170.7 25123.0 2280.0 2358.4 2594.8 2807.5 2870.9 9785.0 9847.6 9876.4 10116.3 10122.2 1702.5 1718.9 1777.0 1824.6 1917.2 105838.0 109074.5 112435.0 114959.4 115656.0 11448.5 11487.9 11730.4 11741.0 11879.8 15061.5 15119.5 15172.2 15208.0 15214.1 7272.6 8746.5 8902.6 8979.0 8996.2 9027.4 9034.6 9080.8 9082.5 9432.7 4588.6 4660.7 4665.4 4717.0 4724.9 1927.5 1980.5 1990.2 2027.3 2030.6 865.3 941.2 950.4 969.5 1113.9 16294.4 16612.5 19782.0 20381.0 20608.3 977.3 990.8 1023.9 1040.3 1063.6 8793.5 10530.2 10833.8 10886.9 10909.9 520094.0 612146.0 614172.0 616302.0 640995.0 117623.4 119815.0 122901.0 124076.0 127371.0 1041.2 1043.2 1065.1 1065.8 1068.6 14163.6 17460.9 17648.6 18082.3 18518.2 49982.8 50564.7 51783.2 51925.2 53071.0 77125.1 77919.2 78621.6 78661.0 79277.2 8080.1 8284.8 8348.7 8464.7 8693.8 10861.7 12543.4 12746.0 12927.0 12989.7 10847.6 11033.7 11116.3 11145.2 11162.7 10768.4 10837.2 10840.3 10970.8 11214.6 10932.4 11199.4 11311.9 11548.4 11592.2 8271.4 8293.2 8347.2 8349.3 8456.5 10352.2 10754.2 11296.1 11324.0 11352.5 12:55:03 INFO - PROCESS | 1670 | 12:55:03 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5644.89 Median:4992.41 stddev:3148.01 (63.1%) stddev-sans-first:3140.30 12:55:03 INFO - PROCESS | 1670 | Values: 1350.4 1585.0 1587.2 1812.3 1857.4 7876.3 8674.3 8817.2 8841.3 8848.7 8500.4 8736.5 8738.8 8754.8 8758.9 2420.7 2447.0 2448.6 2452.6 2453.4 2383.3 2426.1 2432.0 2435.0 2435.6 2107.0 2400.7 2423.9 2435.6 2441.5 2357.5 2378.4 2419.3 2424.9 2428.1 2383.7 2422.1 2429.6 2432.8 2432.8 8467.6 8893.0 8894.6 8904.0 8946.4 5295.2 5443.1 5447.3 5454.2 5489.8 3539.8 3617.8 3660.7 3667.1 3672.2 1040.2 1058.1 1066.6 1067.9 1089.1 11624.0 11698.8 11778.5 11782.2 11816.9 4537.9 4583.9 4676.7 4698.9 4699.2 9355.5 9357.3 9361.5 9368.8 9397.2 5333.4 5377.8 5379.4 5379.6 5396.9 4451.9 4548.0 4596.4 4605.7 4950.6 3212.2 3616.0 3907.0 3913.2 3964.7 1178.9 1220.1 1221.3 1231.5 1231.9 817.9 864.8 913.8 926.2 959.5 5599.5 5653.1 5766.4 5782.0 5870.9 932.5 939.7 966.3 968.4 989.1 3798.5 3867.9 3891.0 3942.4 3975.8 10443.3 10584.2 10800.5 10883.9 10952.8 9449.3 10462.6 10732.9 10747.5 10902.9 8385.0 8395.7 8397.6 8481.9 8588.8 4875.5 5611.6 5667.2 5701.9 5777.3 9463.4 9496.0 9644.4 9665.7 9669.4 9672.2 9884.9 9982.0 10024.2 10182.2 6963.1 6993.7 7051.2 7130.9 7135.1 7530.6 7671.4 8181.6 8396.0 8407.0 7406.4 7412.6 7416.9 7509.1 7529.4 7266.3 7350.8 7361.7 7403.3 7467.1 6805.7 6871.0 6940.1 6981.1 6984.9 4609.9 4690.6 4711.2 4757.0 4761.3 4782.9 4788.9 4823.0 4841.9 5034.3 12:55:03 INFO - PROCESS | 1670 | 12:55:03 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:5726.99 Median:6548.92 stddev:1820.32 (27.8%) stddev-sans-first:1779.64 12:55:03 INFO - PROCESS | 1670 | Values: 321.9 324.1 332.2 333.6 339.0 6550.8 6615.3 6620.0 6628.8 6636.5 6505.8 6547.0 6554.6 6588.6 6753.8 4653.7 4712.8 4758.4 4798.3 4837.2 4743.6 4769.5 4810.5 4819.0 4823.7 3899.3 4734.8 4778.8 4789.1 4827.9 4575.6 4649.1 4750.8 4764.9 4806.1 4130.5 4143.5 4148.4 4152.6 4157.4 6304.6 6339.0 6345.2 6360.6 6364.5 5687.0 5701.6 5795.3 5807.7 5818.3 6717.8 6972.5 6977.2 6983.5 7003.9 4662.1 4668.4 4690.3 4694.6 4708.6 7313.5 7349.7 7366.3 7378.1 7380.3 4746.1 4765.8 4770.5 4771.6 4773.3 4828.8 4832.6 4833.2 4837.1 4845.5 4682.0 5690.1 5698.5 5701.9 5711.4 6671.0 7034.3 7060.9 7067.6 7093.1 1650.3 1671.0 1672.2 1678.6 1681.3 1017.6 1029.0 1061.6 1069.5 1092.7 5653.4 6420.4 6461.1 6652.0 6655.7 7312.6 7313.3 7320.3 7326.1 7333.8 6556.4 6578.8 6580.2 6597.7 6604.0 3809.7 3846.4 3848.6 3853.4 3856.0 7287.6 7555.5 7593.2 7594.2 7603.8 7513.3 7564.1 7583.5 7596.2 7603.8 6554.9 7301.6 7648.4 7651.7 7663.2 7087.9 7323.9 7335.1 7343.6 7348.3 5902.4 5972.7 6470.9 6704.5 7260.7 7664.2 7668.8 7677.4 7679.5 7688.9 4579.8 4595.6 4627.6 4633.0 4645.3 5466.0 6407.7 7351.3 7364.9 7371.5 6703.0 6967.8 6982.7 6998.4 7080.4 6956.6 6996.7 7005.8 7012.5 7013.4 5585.6 6857.4 6858.3 6878.6 6900.9 6762.8 7029.4 7038.3 7063.8 7072.4 6649.5 6733.7 6818.1 6845.0 6863.8 12:55:03 INFO - PROCESS | 1670 | 12:55:03 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:16967.14 Median:622.37 stddev:23282.08 (3740.9%) stddev-sans-first:23315.29 12:55:03 INFO - PROCESS | 1670 | Values: 683.2 752.4 757.9 772.4 776.8 14995.8 15120.8 15214.8 15225.2 15231.0 12828.3 13906.4 14112.2 14119.9 14133.4 920.0 938.8 941.1 941.2 942.8 900.6 922.1 925.5 926.0 926.1 964.7 974.4 977.2 977.8 981.8 958.9 968.5 969.3 970.2 972.3 724.3 726.4 727.3 728.2 728.6 12949.5 13398.9 13474.3 13494.3 13514.6 11723.6 11837.6 11907.6 11926.4 12125.2 9544.6 9546.8 9561.7 9909.0 10262.2 3347.1 3383.9 3393.4 3440.0 3589.4 67638.0 73262.0 74745.3 80675.0 81068.0 9755.2 9890.9 10041.1 10062.9 10421.6 22062.1 22136.0 22139.8 22189.9 22241.2 7565.2 7727.8 7732.2 7776.7 7863.2 4093.7 4102.7 4145.8 4197.5 4217.0 3580.0 3629.8 3633.4 3636.6 3655.4 632.3 644.2 645.6 652.9 662.5 517.3 583.7 592.4 595.5 624.6 7684.3 7972.0 8035.4 8326.6 8354.1 547.1 580.4 588.7 604.9 620.2 3151.4 3665.5 3711.4 3785.6 3995.8 61995.1 82589.2 83727.6 83997.6 84553.6 70637.0 72604.3 75422.6 76158.6 76460.0 48302.0 63283.4 65377.7 66205.0 66772.0 7588.7 7702.7 7841.1 7897.2 7907.8 46715.5 46759.3 46789.5 47098.3 48422.5 50238.2 58058.0 63516.7 63738.0 65033.3 10023.7 10372.2 10394.9 10516.4 10530.2 10847.9 10904.2 10921.2 11089.7 11158.4 10304.3 10306.3 10443.3 10651.3 10781.7 10152.8 10299.9 10504.8 10535.1 11027.1 9901.1 10291.7 10390.5 10516.4 10548.0 8866.4 8873.6 8907.9 9184.1 9219.7 8916.1 8948.0 8976.7 9042.2 9090.1 12:55:03 INFO - PROCESS | 1670 | -------- Summary: end -------- 12:55:03 INFO - PROCESS | 1670 | 12:55:04 INFO - TEST-INFO | 1670: exit 0 12:55:04 INFO - TEST-OK | dromaeo_css | took 1114801ms 12:55:04 INFO - TEST-START | kraken 12:55:04 INFO - operating with platform_type : mac_ 12:55:04 INFO - Initialising browser for kraken test... 12:55:04 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmplVIuVN/profile http://localhost:49278/getInfo.html) 12:55:06 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 12:55:06 INFO - PROCESS | 1677 | colorDepth:24 12:55:06 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 12:55:06 INFO - PROCESS | 1677 | __metrics 12:55:06 INFO - PROCESS | 1677 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:55:06 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:55:07 INFO - TEST-INFO | 1677: exit 0 12:55:07 INFO - Browser initialized. 12:55:07 INFO - Running cycle 1/1 for kraken test... 12:55:07 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmplVIuVN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:55:19 INFO - PROCESS | 1679 | RSS: Main: 211402752 12:55:19 INFO - PROCESS | 1679 | 12:55:21 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 12:55:22 INFO - PROCESS | 1679 | RSS: Main: 271691776 12:55:22 INFO - PROCESS | 1679 | 12:55:25 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 12:55:25 INFO - PROCESS | 1679 | RSS: Main: 286355456 12:55:25 INFO - PROCESS | 1679 | 12:55:29 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 12:55:29 INFO - PROCESS | 1679 | RSS: Main: 373837824 12:55:29 INFO - PROCESS | 1679 | 12:55:31 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 12:55:32 INFO - PROCESS | 1679 | RSS: Main: 278302720 12:55:32 INFO - PROCESS | 1679 | 12:55:34 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:55:34 INFO - PROCESS | 1679 | RSS: Main: 279887872 12:55:34 INFO - PROCESS | 1679 | 12:55:37 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 12:55:38 INFO - PROCESS | 1679 | RSS: Main: 317018112 12:55:38 INFO - PROCESS | 1679 | 12:55:41 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 12:55:41 INFO - PROCESS | 1679 | RSS: Main: 318570496 12:55:41 INFO - PROCESS | 1679 | 12:55:44 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 12:55:45 INFO - PROCESS | 1679 | RSS: Main: 317562880 12:55:45 INFO - PROCESS | 1679 | 12:55:47 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:55:47 INFO - PROCESS | 1679 | RSS: Main: 309121024 12:55:47 INFO - PROCESS | 1679 | 12:55:49 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:55:50 INFO - PROCESS | 1679 | RSS: Main: 316440576 12:55:50 INFO - PROCESS | 1679 | 12:55:52 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:55:53 INFO - PROCESS | 1679 | RSS: Main: 318808064 12:55:53 INFO - PROCESS | 1679 | 12:55:55 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:55:56 INFO - PROCESS | 1679 | RSS: Main: 323424256 12:55:56 INFO - PROCESS | 1679 | 12:55:59 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:56:00 INFO - PROCESS | 1679 | RSS: Main: 321576960 12:56:00 INFO - PROCESS | 1679 | 12:56:01 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 12:56:02 INFO - PROCESS | 1679 | RSS: Main: 385642496 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | __start_tp_report 12:56:02 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 12:56:02 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 12:56:02 INFO - PROCESS | 1679 | |i|pagename|runs| 12:56:02 INFO - PROCESS | 1679 | |0;ai-astar;114;113;121;112;111;113;113;114;114;133 12:56:02 INFO - PROCESS | 1679 | |1;audio-beat-detection;155;121;145;167;121;139;120;156;158;121 12:56:02 INFO - PROCESS | 1679 | |2;audio-dft;182;184;184;184;182;183;182;183;181;187 12:56:02 INFO - PROCESS | 1679 | |3;audio-fft;73;79;74;153;56;55;57;64;130;56 12:56:02 INFO - PROCESS | 1679 | |4;audio-oscillator;89;88;142;77;80;83;125;80;79;123 12:56:02 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;86;84;86;83;86;83;86;83;86;83 12:56:02 INFO - PROCESS | 1679 | |6;imaging-darkroom;92;92;106;142;106;92;96;94;93;92 12:56:02 INFO - PROCESS | 1679 | |7;imaging-desaturate;83;101;130;140;83;83;84;91;130;120 12:56:02 INFO - PROCESS | 1679 | |8;json-parse-financial;57;63;63;63;63;63;63;82;57;59 12:56:02 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;62;63;87;108;103;72;72;69;67;67 12:56:02 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;91;95;104;103;80;81;81;79;80;86 12:56:02 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;130;116;142;143;145;127;129;128;147;138 12:56:02 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;164;218;165;176;168;169;166;165;164;164 12:56:02 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;67;66;69;67;68;67;67;68;67;66 12:56:02 INFO - PROCESS | 1679 | __end_tp_report 12:56:02 INFO - PROCESS | 1679 | __start_cc_report 12:56:02 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,2008 12:56:02 INFO - PROCESS | 1679 | __end_cc_report 12:56:02 INFO - PROCESS | 1679 | __startTimestamp1463687762044__endTimestamp 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | ------- Summary: start ------- 12:56:02 INFO - PROCESS | 1679 | Number of tests: 14 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:115.80 Median:114.00 stddev:6.61 (5.8%) stddev-sans-first:6.98 12:56:02 INFO - PROCESS | 1679 | Values: 114.0 113.0 121.0 112.0 111.0 113.0 113.0 114.0 114.0 133.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:150.00 stddev:18.39 (12.3%) stddev-sans-first:18.71 12:56:02 INFO - PROCESS | 1679 | Values: 155.0 121.0 145.0 167.0 121.0 139.0 120.0 156.0 158.0 121.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.50 stddev:1.69 (0.9%) stddev-sans-first:1.73 12:56:02 INFO - PROCESS | 1679 | Values: 182.0 184.0 184.0 184.0 182.0 183.0 182.0 183.0 181.0 187.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:79.70 Median:60.50 stddev:34.12 (56.4%) stddev-sans-first:36.10 12:56:02 INFO - PROCESS | 1679 | Values: 73.0 79.0 74.0 153.0 56.0 55.0 57.0 64.0 130.0 56.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:80.00 stddev:23.87 (29.8%) stddev-sans-first:25.16 12:56:02 INFO - PROCESS | 1679 | Values: 89.0 88.0 142.0 77.0 80.0 83.0 125.0 80.0 79.0 123.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:84.60 Median:86.00 stddev:1.51 (1.8%) stddev-sans-first:1.51 12:56:02 INFO - PROCESS | 1679 | Values: 86.0 84.0 86.0 83.0 86.0 83.0 86.0 83.0 86.0 83.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:92.00 stddev:15.60 (17.0%) stddev-sans-first:16.24 12:56:02 INFO - PROCESS | 1679 | Values: 92.0 92.0 106.0 142.0 106.0 92.0 96.0 94.0 93.0 92.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:104.50 Median:83.00 stddev:23.10 (27.8%) stddev-sans-first:23.15 12:56:02 INFO - PROCESS | 1679 | Values: 83.0 101.0 130.0 140.0 83.0 83.0 84.0 91.0 130.0 120.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:63.00 stddev:7.06 (11.2%) stddev-sans-first:7.11 12:56:02 INFO - PROCESS | 1679 | Values: 57.0 63.0 63.0 63.0 63.0 63.0 63.0 82.0 57.0 59.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:77.00 Median:68.00 stddev:16.57 (24.4%) stddev-sans-first:16.67 12:56:02 INFO - PROCESS | 1679 | Values: 62.0 63.0 87.0 108.0 103.0 72.0 72.0 69.0 67.0 67.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:81.00 stddev:9.72 (12.0%) stddev-sans-first:10.25 12:56:02 INFO - PROCESS | 1679 | Values: 91.0 95.0 104.0 103.0 80.0 81.0 81.0 79.0 80.0 86.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:134.50 Median:140.00 stddev:9.99 (7.1%) stddev-sans-first:10.46 12:56:02 INFO - PROCESS | 1679 | Values: 130.0 116.0 142.0 143.0 145.0 127.0 129.0 128.0 147.0 138.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:167.00 stddev:16.61 (9.9%) stddev-sans-first:17.37 12:56:02 INFO - PROCESS | 1679 | Values: 164.0 218.0 165.0 176.0 168.0 169.0 166.0 165.0 164.0 164.0 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 12:56:02 INFO - PROCESS | 1679 | Values: 67.0 66.0 69.0 67.0 68.0 67.0 67.0 68.0 67.0 66.0 12:56:02 INFO - PROCESS | 1679 | -------- Summary: end -------- 12:56:02 INFO - PROCESS | 1679 | 12:56:02 INFO - TEST-INFO | 1679: exit 0 12:56:02 INFO - TEST-OK | kraken | took 58379ms 12:56:02 INFO - SUITE-END | took 1173s 12:56:03 INFO - Completed test suite (00:19:33) 12:56:03 INFO - javascript benchmark 12:56:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3379.831008449578, 3447.5690538682666, 3464.3764122457956, 3475.339685459764, 3501.334746398184, 5639.915401268984, 5665.886682266352, 5689.0328644130495, 5694.715264236787, 5697.373288938214, 5621.747021384037, 5686.715664216793, 5689.175069614904, 5695.914561281584, 5699.544036477082, 5060.114479965991, 5098.770555325019, 5108.208227724708, 5219.530242278194, 5234.476552344773, 5185.144451165553, 5195.584353251731, 5207.088759467097, 5208.270842082112, 5212.139996900526, 5164.9483505165035, 5184.300119483878, 5212.504812042869, 5220.92168617471, 5230.21546767984, 5208.192730126818, 5226.163813789775, 5232.8691782705355, 5239.083160446907, 5281.207818827168, 5065.898682026375, 5091.847244582668, 5104.744762761884, 5168.586513078944, 5171.095058364771, 5507.201455788888, 5553.250311207976, 5559.054960656657, 5591.916121258144, 5593.300837395325, 5086.440491545927, 6126.019836826086, 6196.628202307831, 6206.25524937006, 6217.0363593642605, 5289.841304760864, 6082.284463459512, 6183.907241391383, 6232.750689972396, 6257.87484250317, 5775.8556036099435, 5791.710414479344, 5811.3316968548315, 5811.651300921958, 5837.416258374171, 6214.067889816563, 6237.532185086136, 6246.031865060922, 6248.281447633581, 6249.468795152372, 6001.369856165127, 6062.666553339521, 6074.4532992030045, 6117.571769976059, 6136.85608443425, 6110.649890384107, 6128.380904898417, 6128.839250099703, 6133.793750436688, 6143.266246520256, 6116.688983687855, 6118.663473509, 6128.167033931239, 6136.825445958321, 6148.9692551536955, 6078.544109191828, 6118.694065296718, 6133.854967165038, 6136.365905528278, 6147.354527774609, 4871.853844384674, 4915.68048076881, 4918.852434426973, 4944.0, 4953.034158339161, 4396.60430561251, 4422.800973956116, 4424.557544245614, 4433.0, 4451.265541185733, 5582.24639673647, 5782.219400380979, 5784.942150578525, 5801.158831969341, 5842.415758424208, 6056.515478761688, 6124.234470691164, 6128.387161283836, 6131.049687298476, 6134.294556126102, 5605.5515558755205, 5725.713714314267, 5780.364159942403, 5839.737211825478, 5841.766329346779, 5330.626856120112, 5364.463553644604, 5380.354357477049, 5389.353277606712, 5416.539594134464, 6261.24865016201, 6263.311035786151, 6264.467520260738, 6280.811575652738, 6281.497480201664, 6117.235345581802, 6155.599886007395, 6206.06908963641, 6255.56211065221, 6277.874442511033, 6229.595076320024, 6245.7189426476825, 6248.843778905381, 6251.156093927443, 6267.811965641038, 6090.0, 6144.600600960924, 6147.784827531015, 6151.84620384494, 6163.599366041193, 6111.266648002268, 6137.35557766446, 6159.692015399122, 6159.969200153971, 6241.96879015602, 6066.4540191382985, 6072.757089716361, 6089.634621922699, 6090.665013424305, 6127.264728232641, 6038.637681738934, 6040.456358927718, 6040.667763272886, 6053.092036194606, 6059.575829691879, 5046.697198168122, 5720.027595308724, 5769.336526299361, 5884.264466941632, 5885.588008839341, 5777.94222057774, 6079.848003799941, 6081.939180608138, 6084.847878803065, 6106.596914875623, 5893.528517718658, 5909.70451477433, 5914.083317085859, 5925.318588362221, 5939.732712027882], "value": 5656.13946869576, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.1421618788528, 536.7369988705535, 560.0031943141208, 602.8312072619668, 625.5402279324695, 31597.526037109434, 32328.515072273934, 40131.39802902955, 40584.98537536563, 40604.59395406045, 45313.0, 45940.770296148476, 45946.0, 46082.539174608246, 46107.0, 8934.73195804127, 9446.291528135418, 9677.806443871135, 9900.108990190882, 9900.910899801016, 8816.250618697419, 9053.818923621524, 11751.647450576496, 11940.223885447462, 11969.222000569978, 11604.361760103191, 11660.125490588198, 11663.94168029163, 11665.825012624775, 11668.41657917105, 8694.826103477959, 11722.06223502122, 11838.99368553674, 11846.7630647387, 11894.643160705193, 11747.29516229029, 11877.821832672516, 11883.168178227528, 11883.465244063951, 11896.167268291223, 40058.59941400578, 40387.778672173015, 40478.190436191115, 40494.79752601247, 40623.59376406228, 3276.410246155691, 3279.2785587171024, 3283.441814891474, 3320.850561724704, 3339.782914110575, 28145.0, 29851.20508510719, 30025.849870750724, 30075.699243007726, 30148.54777178344, 2772.6395568575954, 2788.1774876411305, 2808.5787131930374, 2822.788290878192, 2851.3156842357894, 491928.0, 494553.0, 495808.0, 496616.5169174167, 497149.0, 37006.814965925, 65302.0, 66408.33591664118, 66408.33591664118, 66636.00045999314, 36485.63514364875, 36568.63431365652, 36597.451038234445, 36616.08459788527, 36628.633713663054, 16301.0, 16629.50111496657, 16642.251098700344, 16659.0, 16678.58303542421, 12746.872531274754, 12980.935095324463, 13895.722085558233, 14037.087589306662, 14125.646858828406, 8301.252887240178, 8312.376571757142, 8312.8753068704, 8406.0, 8407.579621018924, 3150.8739650414186, 3241.9351612967607, 3455.1189446691437, 3455.2571197192724, 3492.89521314361, 872.2367928062945, 957.4733896356976, 1013.4122209118693, 1030.8814935794596, 1060.2207377577474, 29459.558106628418, 29587.556186657217, 29793.85103074514, 29983.70016299809, 30228.546571801442, 1054.0776820282254, 1060.8567843341204, 1065.6110519660424, 1110.6779034080025, 1171.226990186473, 14957.326920288613, 15091.698166036618, 15168.696626067416, 15382.556058480091, 15679.608009799846, 642770.0, 650797.0, 651714.0, 653023.0, 655066.0, 62963.425914352505, 63169.0, 63978.36021639817, 63986.68006659937, 64240.036399454046, 1359.9184048957293, 1392.7005693775886, 1530.0207866965152, 1545.5672411724736, 1553.906765594068, 30873.536896946116, 32250.516242255915, 32256.0, 32432.675673242964, 32988.835055824566, 59301.0, 59421.10868337065, 59978.0, 60400.0, 60423.0, 354270.0, 467065.0, 467653.0, 468533.0, 468826.0, 37236.0, 43244.91887702833, 44926.55073449355, 45671.54328456673, 45982.310265345375, 40848.59151408448, 45128.0974380504, 45453.0, 45826.625201244016, 47069.52930470651, 37773.62226377811, 37971.05072373102, 38405.61594384132, 39129.0, 39295.80352098221, 37361.62638373581, 37558.06104847401, 37754.24491510099, 37830.43254351132, 38514.422283665204, 39159.41260881032, 39319.80340098281, 39516.40725389179, 39838.60161398349, 39853.40219896762, 25050.874745626155, 28686.713132869238, 29561.556576651798, 29762.404751905273, 29835.70164298416, 25780.8710956444, 25989.480210395308, 26213.606795898457, 26256.844940437244, 26488.470230594896], "value": 19453.579010797886, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.77701375736456, 402.61550219540334, 404.68838993974657, 409.1857204163715, 418.5626020808255, 14020.933929160532, 20223.0, 20551.177952881902, 20870.895645521752, 20891.059902304394, 16968.915155424238, 19215.0, 20617.690734638956, 20771.27300544481, 21319.36041918741, 5969.671668058266, 6146.7233974471255, 6192.907106393408, 6194.070889366586, 6210.223722034746, 5794.565407594426, 6131.938680613203, 6171.290301615327, 6184.876302473948, 6185.381461853801, 3979.084810493588, 4171.06151115998, 4775.522447755231, 6044.697765111749, 6147.047207682794, 6101.511879049665, 6112.297085835097, 6119.908201376983, 6126.8161955141195, 6128.38716128388, 5534.723263836832, 5830.038043722823, 5843.5033022193165, 5845.06478963368, 5945.286565612111, 13398.866011339956, 13592.728145437135, 13633.250171240577, 20170.69743953842, 25123.0, 2279.9772002280097, 2358.445765245166, 2594.805389595788, 2807.536756435186, 2870.8708108134974, 9785.02149785023, 9847.55685994132, 9876.35803672759, 10116.291859569834, 10122.190224782, 1702.5062731807761, 1718.9084931068737, 1776.9911150444166, 1824.5621050947677, 1917.2043601905193, 105838.0, 109074.45462772714, 112435.0, 114959.42520287345, 115656.0, 11448.54205831774, 11487.885121148849, 11730.413479326, 11741.0, 11879.76240475203, 15061.548153555412, 15119.54641360761, 15172.241387930559, 15208.011479253813, 15214.087154770748, 7272.63636818157, 8746.518941458153, 8902.554872256362, 8979.0, 8996.190342869042, 9027.413218140931, 9034.593443295067, 9080.81838363229, 9082.45505269686, 9432.669856555125, 4588.587027167571, 4660.743659098782, 4665.3934988451965, 4716.95283047172, 4724.88187795301, 1927.4699457648978, 1980.4850738808013, 1990.1740777577143, 2027.3005812994493, 2030.644637188486, 865.2774932930947, 941.1764705882352, 950.3870003847479, 969.5007071358256, 1113.9443027848574, 16294.42969496062, 16612.50162495103, 19782.01089945497, 20381.0, 20608.27871024507, 977.2670497127154, 990.8315586350337, 1023.9129673977795, 1040.2977989856815, 1063.6383629565985, 8793.516356600321, 10530.210234232463, 10833.83749243762, 10886.891131088747, 10909.945450272857, 520094.0, 612146.0, 614172.0, 616302.0, 640995.0, 117623.41188294004, 119815.0, 122901.0, 124076.0, 127371.0, 1041.2138835179392, 1043.2488608202082, 1065.1319174872453, 1065.832912960307, 1068.631322193842, 14163.64590885236, 17460.91269543644, 17648.64702705913, 18082.276708931495, 18518.16668249905, 49982.75008624933, 50564.74717626388, 51783.22325165201, 51925.22112168245, 53071.0, 77125.07187320138, 77919.2208077912, 78621.60689196517, 78661.0, 79277.20722792929, 8080.0707918590115, 8284.798704187739, 8348.666053357798, 8464.66141354339, 8693.826123477622, 10861.728456788644, 12543.372831358212, 12746.0, 12927.030472714396, 12989.675258118623, 10847.620333288298, 11033.724156896142, 11116.333020018501, 11145.164112691418, 11162.665120046411, 10768.3538987661, 10837.241393102722, 10840.349579025284, 10970.835437468604, 11214.55141794319, 10932.398718070583, 11199.440027998404, 11311.886881131084, 11548.42257887119, 11592.246503977214, 8271.379646526415, 8293.21214484623, 8347.248747612744, 8349.290310323691, 8456.450330728483, 10352.223583231138, 10754.247202695737, 11296.096312295162, 11323.980841724284, 11352.54589816398], "value": 9503.416017184374, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.372076984202, 1585.0093691442848, 1587.2381256996641, 1812.3475548802437, 1857.4241984984646, 7876.25175608317, 8674.262687671548, 8817.162369574897, 8841.33689973253, 8848.690295839648, 8500.447470914387, 8736.475811451317, 8738.781530461742, 8754.781130471742, 8758.91241087589, 2420.6732091167733, 2446.9967313401507, 2448.644946482768, 2452.619843924195, 2453.4234454903085, 2383.2731017039796, 2426.0659646036315, 2431.9513609727796, 2435.0016493237777, 2435.622478515833, 2106.9894650526726, 2400.7479214682467, 2423.866687332196, 2435.6468312094735, 2441.523902838947, 2357.457784709518, 2378.4410663494064, 2419.2742177346813, 2424.9393765155837, 2428.113738485458, 2383.7377888432256, 2422.067504010951, 2429.6234083717054, 2432.7688869557364, 2432.8418652787514, 8467.576621168917, 8893.0, 8894.59974301158, 8903.955480222558, 8946.418482798597, 5295.232191332235, 5443.129099344125, 5447.291852059247, 5454.154606036071, 5489.752961116759, 3539.8407071681836, 3617.801020943847, 3660.68884144848, 3667.1198912261134, 3672.192117734121, 1040.1626690514133, 1058.1164727452585, 1066.6213494209599, 1067.909227715645, 1089.0688461365498, 11624.0, 11698.824517632242, 11778.528858845635, 11782.234154779997, 11816.881831181665, 4537.863864084083, 4583.931241031387, 4676.742779147112, 4698.929516057262, 4699.224627936353, 9355.532223388804, 9357.298202634829, 9361.531923403803, 9368.812623747486, 9397.154256116983, 5333.386660534011, 5377.838664840061, 5379.35447746273, 5379.623426360117, 5396.892062158735, 4451.866444006685, 4548.0221752323405, 4596.4024676791805, 4605.65457590682, 4950.554450099508, 3212.1969507623094, 3616.0, 3907.023244188953, 3913.1977944521304, 3964.72246942717, 1178.8644305904932, 1220.0727447140237, 1221.2977537915733, 1231.4643130238196, 1231.940531143216, 817.8672538534054, 864.8270345930839, 913.8263729891299, 926.2404828041076, 959.5490119643756, 5599.524040456526, 5653.095504719226, 5766.365699773021, 5782.0, 5870.941290587125, 932.5430539035824, 939.7133874168444, 966.3380584299778, 968.3850754770772, 989.094978095035, 3798.506194194737, 3867.8839634811, 3891.0272431892026, 3942.40863870416, 3975.8210880510446, 10443.268971172098, 10584.153267738562, 10800.513976870903, 10883.945580272048, 10952.835707464394, 9449.291303152291, 10462.581496740197, 10732.926707329205, 10747.516361763583, 10902.890971090188, 8385.035720892054, 8395.748127556182, 8397.6221070052, 8481.872771908425, 8588.828223435496, 4875.488073752205, 5611.635243709309, 5667.2197438622225, 5701.942980570308, 5777.335606405317, 9463.38487998278, 9496.002919693332, 9644.421334719938, 9665.661701840401, 9669.371490853073, 9672.177864881563, 9884.85172722424, 9982.0, 10024.24818138657, 10182.18542516582, 6963.1296087989, 6993.650317484207, 7051.224365319913, 7130.857382852418, 7135.072440582691, 7530.623468826428, 7671.38628909697, 8181.550014749246, 8396.0, 8407.0, 7406.407487401103, 7412.555246685216, 7416.8887466686965, 7509.098908130841, 7529.435292353205, 7266.346028857428, 7350.77947661571, 7361.668724907285, 7403.333699967029, 7467.141278753013, 6805.65971701403, 6870.965645171742, 6940.09778728762, 6981.0924579804305, 6984.895226571504, 4609.861704148881, 4690.6247500199215, 4711.15199264122, 4756.9762151189025, 4761.262004389325, 4782.856514304576, 4788.904221915473, 4823.0, 4841.903161936812, 5034.270030845581], "value": 4553.8659842343595, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.88573056564917, 324.1394098668036, 332.1695760598504, 333.6346700363103, 339.0489676457536, 6550.836229094269, 6615.272320044791, 6620.0, 6628.76799312023, 6636.502262330332, 6505.837354066139, 6547.0, 6554.6067235965875, 6588.571742836724, 6753.7636182733495, 4653.650976176792, 4712.811487540494, 4758.428988521382, 4798.280257961316, 4837.201861692816, 4743.620510359163, 4769.475357710649, 4810.518948105197, 4818.975905120469, 4823.710577365369, 3899.317619416591, 4734.810607575693, 4778.761061946906, 4789.1140139074205, 4827.927581086286, 4575.633949284049, 4649.093426781779, 4750.762461876892, 4764.880877978044, 4806.0628177505405, 4130.524989626201, 4143.502779666429, 4148.377743338493, 4152.584741525853, 4157.397177409289, 6304.558680892339, 6339.0, 6345.238571371418, 6360.55476116672, 6364.522660800459, 5687.033204355228, 5701.572382071361, 5795.333536643256, 5807.709614519257, 5818.272715910512, 6717.798466046026, 6972.546784458994, 6977.232504424509, 6983.511154219207, 7003.92996070043, 4662.067586482717, 4668.416447944007, 4690.343351930733, 4694.57748802606, 4708.576228139484, 7313.451491138133, 7349.742759003409, 7366.300201480904, 7378.077740282465, 7380.335769780691, 4746.121967436035, 4765.833195838129, 4770.546798054249, 4771.570558649669, 4773.307870358779, 4828.758562071883, 4832.589229915427, 4833.178359678863, 4837.080954618611, 4845.515448455197, 4681.976590117028, 5690.089585666274, 5698.544116470673, 5701.914471282934, 5711.400302968212, 6670.966000269966, 7034.331738484836, 7060.92939070613, 7067.611281379471, 7093.1488221413765, 1650.3316156956453, 1670.9749353759703, 1672.1639180409795, 1678.5719641491341, 1681.3022595622924, 1017.5878769098526, 1028.953697083633, 1061.559452827068, 1069.4545781651414, 1092.6503518874042, 5653.434656534314, 6420.390062944106, 6461.06314584392, 6651.966740166365, 6655.700493477805, 7312.597807120659, 7313.268673132644, 7320.341169294789, 7326.120865496175, 7333.779986600411, 6556.377144171296, 6578.835529111715, 6580.243272023778, 6597.703103360264, 6604.0, 3809.6761775249415, 3846.442265871433, 3848.5766565677754, 3853.3641949078087, 3855.9614403856162, 7287.562746235243, 7555.546667199986, 7593.240675932473, 7594.164641889388, 7603.847923041508, 7513.2862378074, 7564.130125035691, 7583.544987300779, 7596.24037596247, 7603.771886843404, 6554.852900742351, 7301.561906285534, 7648.42636802242, 7651.655675494505, 7663.195364486649, 7087.893681594677, 7323.890141647773, 7335.1197856257595, 7343.559386436831, 7348.301911318416, 5902.4097590240635, 5972.731227094704, 6470.932296171079, 6704.463642908653, 7260.67326970277, 7664.156942736406, 7668.80827979305, 7677.385809135368, 7679.462437629312, 7688.884666730115, 4579.793909274024, 4595.609373203181, 4627.6066534344955, 4632.953670463252, 4645.279981602857, 5465.9726701366235, 6407.743690252338, 7351.264873512606, 7364.926350736424, 7371.483996120434, 6703.0, 6967.79096627102, 6982.685779140053, 6998.370146686822, 7080.362767350963, 6956.617386043817, 6996.650167491707, 7005.789826305219, 7012.474064445085, 7013.368796808311, 5585.609007369445, 6857.417119544894, 6858.348456896587, 6878.55289406187, 6900.930990690029, 6762.830929226809, 7029.402500787286, 7038.260982596754, 7063.823404414725, 7072.434205263669, 6649.534532582673, 6733.7306507741105, 6818.079559259437, 6844.965775171291, 6863.828404289933], "value": 5120.986635755647, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.2416018219776, 752.4018405367735, 757.9128400233975, 772.3743767548283, 776.809681628001, 14995.775063374058, 15120.848791512082, 15214.847851521481, 15225.162616056112, 15231.009984351009, 12828.27930000348, 13906.374213160407, 14112.15327080376, 14119.858801411983, 14133.363998620061, 919.9632014719404, 938.8028514012066, 941.1106504353389, 941.1623654947102, 942.79258563116, 900.6127365232948, 922.1239822168933, 925.4632313258294, 925.9675911343104, 926.1479438916214, 964.6768332608584, 974.410481658597, 977.218225419665, 977.7751117243039, 981.8036392721449, 958.8801399825022, 968.4867020479157, 969.3021024862123, 970.2189737261501, 972.3388096094653, 724.337231433238, 726.4079774983371, 727.2799928071224, 728.1807966038207, 728.613834667627, 12949.54676586315, 13398.861096806782, 13474.326283685776, 13494.257815820127, 13514.59456216315, 11723.648290551297, 11837.585684501, 11907.642770716893, 11926.403679815974, 12125.211861228992, 9544.61821527138, 9546.809063818684, 9561.713148605553, 9909.0, 10262.179025678002, 3347.1130150510135, 3383.8984830455124, 3393.389189945809, 3439.9828000860084, 3589.4436362363876, 67638.0, 73262.0, 74745.25254747491, 80674.99999999942, 81068.0, 9755.170810481046, 9890.851637225447, 10041.09630133277, 10062.899371006342, 10421.583136674448, 22062.11751529953, 22136.0, 22139.778602213773, 22189.889050554644, 22241.22155724542, 7565.167831538526, 7727.806804829812, 7732.188120247405, 7776.7278145264645, 7863.213678632091, 4093.692973027035, 4102.733322334038, 4145.79271036447, 4197.538270790272, 4216.978915105405, 3580.0154957386926, 3629.7822130672243, 3633.4004889193516, 3636.581793093775, 3655.3603119453987, 632.3139393757824, 644.1818890009755, 645.6061802300593, 652.9281779004306, 662.5296039811788, 517.3378076062646, 583.6789765628887, 592.3839207224438, 595.4640823259015, 624.5784095735361, 7684.3084122429245, 7972.0, 8035.357171426389, 8326.583670816555, 8354.081051084377, 547.1437200780765, 580.4021857486711, 588.717415640495, 604.9062395328731, 620.1689735754021, 3151.432742106397, 3665.4685070664605, 3711.350513660099, 3785.6025117362833, 3995.7574195594575, 61995.070073948926, 82589.17410825935, 83727.58136209402, 83997.58001210078, 84553.57723211344, 70637.0, 72604.27395726081, 75422.62288688631, 76158.6192069036, 76460.0, 48302.0, 63283.367166328666, 65377.673111635086, 66205.0, 66772.0, 7588.658510366937, 7702.730404435818, 7841.059072911287, 7897.210278972057, 7907.762767116996, 46715.53284467112, 46759.29861052019, 46789.53210467852, 47098.293525596455, 48422.5157748418, 50238.2464263029, 58058.0, 63516.72966540736, 63738.0, 65033.34966650462, 10023.69928902134, 10372.170226382023, 10394.948025260128, 10516.421596811942, 10530.157587392838, 10847.945760271148, 10904.236703430684, 10921.235513513977, 11089.667309980714, 11158.44207789591, 10304.330218535772, 10306.33008854422, 10443.321184123008, 10651.30766500175, 10781.730456738644, 10152.76449968129, 10299.948500257751, 10504.789904202025, 10535.104516116216, 11027.062699670618, 9901.05939935705, 10291.691249262534, 10390.532426040998, 10516.369017858648, 10548.0, 8866.42368246062, 8873.600687968928, 8907.866382004146, 9184.12750788674, 9219.723408297761, 8916.06381329951, 8947.955260223658, 8976.730698079067, 9042.231410329932, 9090.13643703847], "value": 6698.107194032564, "unit": "score"}], "name": "dromaeo_css", "value": 7393.5631470322905, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 121.0, 112.0, 111.0, 113.0, 113.0, 114.0, 114.0, 133.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 121.0, 145.0, 167.0, 121.0, 139.0, 120.0, 156.0, 158.0, 121.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 184.0, 184.0, 182.0, 183.0, 182.0, 183.0, 181.0, 187.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 79.0, 74.0, 153.0, 56.0, 55.0, 57.0, 64.0, 130.0, 56.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 142.0, 77.0, 80.0, 83.0, 125.0, 80.0, 79.0, 123.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 83.0, 86.0, 83.0, 86.0, 83.0, 86.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 106.0, 142.0, 106.0, 92.0, 96.0, 94.0, 93.0, 92.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 101.0, 130.0, 140.0, 83.0, 83.0, 84.0, 91.0, 130.0, 120.0], "value": 104.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 82.0, 57.0, 59.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 87.0, 108.0, 103.0, 72.0, 72.0, 69.0, 67.0, 67.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 104.0, 103.0, 80.0, 81.0, 81.0, 79.0, 80.0, 86.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 116.0, 142.0, 143.0, 145.0, 127.0, 129.0, 128.0, 147.0, 138.0], "value": 134.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 218.0, 165.0, 176.0, 168.0, 169.0, 166.0, 165.0, 164.0, 164.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 67.0, 68.0, 67.0, 67.0, 68.0, 67.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1507.1000000000001, "alertThreshold": 2.0}]} 12:56:03 INFO - Return code: 0 12:56:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:56:03 ERROR - # TBPL SUCCESS # 12:56:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:56:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:56:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:56:03 INFO - 2016-05-19 12:56:03.378 screenresolution[1683:10168] starting screenresolution argv=screenresolution get 12:56:03 INFO - 2016-05-19 12:56:03.380 screenresolution[1683:10168] Display 0: 1600x1200x32@60 12:56:03 INFO - 2016-05-19 12:56:03.386 screenresolution[1684:10171] starting screenresolution argv=screenresolution list 12:56:03 INFO - Available Modes on Display 0 12:56:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:56:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:56:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:56:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:56:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:56:03 INFO - Intel Iris: 12:56:03 INFO - Chipset Model: Intel Iris 12:56:03 INFO - Type: GPU 12:56:03 INFO - Bus: Built-In 12:56:03 INFO - VRAM (Dynamic, Max): 1536 MB 12:56:03 INFO - Vendor: Intel (0x8086) 12:56:03 INFO - Device ID: 0x0a2e 12:56:03 INFO - Revision ID: 0x0009 12:56:03 INFO - Displays: 12:56:03 INFO - Display: 12:56:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:56:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:56:03 INFO - Display Serial Number: 1600x1200 60 12:56:03 INFO - Main Display: Yes 12:56:03 INFO - Mirror: Off 12:56:03 INFO - Online: Yes 12:56:03 INFO - Rotation: Supported 12:56:03 INFO - Return code: 0 12:56:03 INFO - Running post-action listener: _resource_record_post_action 12:56:03 INFO - Running post-run listener: _resource_record_post_run 12:56:04 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 31399424; Write bytes: 652292096; Read time: 197; Write time: 1826 12:56:04 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345772544; Write bytes: 345323008; Read time: 26591; Write time: 681 12:56:04 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30477824; Write bytes: 295342592; Read time: 186; Write time: 1101 12:56:04 INFO - Running post-run listener: _upload_blobber_files 12:56:04 INFO - Blob upload gear active. 12:56:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:56:04 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 12:56:04 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'] 12:56:04 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 12:56:05 INFO - (blobuploader) - INFO - Open directory for files ... 12:56:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:56:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:56:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:56:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:56:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:56:05 INFO - (blobuploader) - INFO - Done attempting. 12:56:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:56:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:56:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:56:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:56:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:56:06 INFO - (blobuploader) - INFO - Done attempting. 12:56:06 INFO - (blobuploader) - INFO - Iteration through files over. 12:56:06 INFO - Return code: 0 12:56:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:56:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:56:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/826e611710c739249b871cc35349a4a793398d3aae1789efadd2b56ee7593e926175ecb26a23b9585c288dec8de72869511fb382e453c553be86e3b8f2047627", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04c327c5855ecdca5360a7d807cd66582cacbeebd4cecf36cf4e006f86cbbe3051590624fed2d993407096b19e20ca0fd91b633dde2898ac362aa5cd6aa99628"} 12:56:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:56:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:56:06 INFO - Contents: 12:56:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/826e611710c739249b871cc35349a4a793398d3aae1789efadd2b56ee7593e926175ecb26a23b9585c288dec8de72869511fb382e453c553be86e3b8f2047627", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04c327c5855ecdca5360a7d807cd66582cacbeebd4cecf36cf4e006f86cbbe3051590624fed2d993407096b19e20ca0fd91b633dde2898ac362aa5cd6aa99628"} 12:56:06 INFO - Running post-run listener: copy_logs_to_upload_dir 12:56:06 INFO - Copying logs to upload dir... 12:56:06 INFO - mkdir: /builds/slave/test/build/upload/logs 12:56:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1347.305111 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 27 secs) (at 2016-05-19 12:56:06.888626) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 12:56:06.893833) ========= 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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/826e611710c739249b871cc35349a4a793398d3aae1789efadd2b56ee7593e926175ecb26a23b9585c288dec8de72869511fb382e453c553be86e3b8f2047627", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04c327c5855ecdca5360a7d807cd66582cacbeebd4cecf36cf4e006f86cbbe3051590624fed2d993407096b19e20ca0fd91b633dde2898ac362aa5cd6aa99628"} build_url:https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011112 build_url: 'https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/826e611710c739249b871cc35349a4a793398d3aae1789efadd2b56ee7593e926175ecb26a23b9585c288dec8de72869511fb382e453c553be86e3b8f2047627", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04c327c5855ecdca5360a7d807cd66582cacbeebd4cecf36cf4e006f86cbbe3051590624fed2d993407096b19e20ca0fd91b633dde2898ac362aa5cd6aa99628"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 12:56:06.923336) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:56:06.923742) ========= 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.KhEgB9yE4l/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.u8L3fnMIZz/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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.005075 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:56:06.972111) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 12:56:06.972419) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 12:56:06.972739) ========= ========= Total master_lag: 0.16 =========