builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0195 starttime: 1463507489.09 results: success (0) buildid: 20160517004009 builduid: ef91a013044b49f4abd91345e579e6b6 revision: 17724486e1591858eea09dc327475a25883854a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.093520) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.094240) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.094620) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.227070) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.227684) ========= 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.MevBT0d1aU/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.XftzkWknSV/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-17 10:51:29-- 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% 8.56M=0.001s 2016-05-17 10:51:29 (8.56 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151349 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.398352) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.398676) ========= 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.MevBT0d1aU/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.XftzkWknSV/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.019833 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:29.447098) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 10:51:29.447454) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 17724486e1591858eea09dc327475a25883854a4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 17724486e1591858eea09dc327475a25883854a4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MevBT0d1aU/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.XftzkWknSV/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-17 10:51:29,514 truncating revision to first 12 chars 2016-05-17 10:51:29,514 Setting DEBUG logging. 2016-05-17 10:51:29,514 attempt 1/10 2016-05-17 10:51:29,514 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/17724486e159?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 10:51:30,342 unpacking tar archive at: mozilla-aurora-17724486e159/testing/mozharness/ program finished with exit code 0 elapsedTime=1.075502 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 10:51:30.541923) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:30.542295) ========= script_repo_revision: 17724486e1591858eea09dc327475a25883854a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:30.542968) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:30.543241) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 10:51:30.557329) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-17 10:51:30.557688) ========= /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.MevBT0d1aU/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.XftzkWknSV/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:51:30 INFO - MultiFileLogger online at 20160517 10:51:30 in /builds/slave/test 10:51:30 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 10:51:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:51:30 INFO - {'append_to_log': False, 10:51:30 INFO - 'base_work_dir': '/builds/slave/test', 10:51:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:51:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:51:30 INFO - 'branch': 'Mozilla-Aurora', 10:51:30 INFO - 'buildbot_json_path': 'buildprops.json', 10:51:30 INFO - 'config_files': ('talos/mac_config.py',), 10:51:30 INFO - 'default_actions': ('clobber', 10:51:30 INFO - 'read-buildbot-config', 10:51:30 INFO - 'download-and-extract', 10:51:30 INFO - 'populate-webroot', 10:51:30 INFO - 'create-virtualenv', 10:51:30 INFO - 'install', 10:51:30 INFO - 'run-tests'), 10:51:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:51:30 INFO - 'download_minidump_stackwalk': True, 10:51:30 INFO - 'download_symbols': 'ondemand', 10:51:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:51:30 INFO - 'tooltool.py': '/tools/tooltool.py', 10:51:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:51:30 INFO - '/tools/misc-python/virtualenv.py')}, 10:51:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:51:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:51:30 INFO - 'installer_path': 'installer.exe', 10:51:30 INFO - 'log_level': 'info', 10:51:30 INFO - 'log_name': 'talos', 10:51:30 INFO - 'log_to_console': True, 10:51:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:51:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:51:30 INFO - 'opt_config_files': (), 10:51:30 INFO - 'pip_index': False, 10:51:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:51:30 INFO - 'cmd': ('bash', 10:51:30 INFO - '-c', 10:51:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:51:30 INFO - 'enabled': True, 10:51:30 INFO - 'halt_on_failure': False, 10:51:30 INFO - 'name': 'check_screen_resolution'},), 10:51:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:51:30 INFO - 'cmd': ('bash', 10:51:30 INFO - '-c', 10:51:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:51:30 INFO - 'enabled': True, 10:51:30 INFO - 'halt_on_failure': False, 10:51:30 INFO - 'name': 'check_screen_resolution'},), 10:51:30 INFO - 'run_cmd_checks_enabled': True, 10:51:30 INFO - 'sps_profile': False, 10:51:30 INFO - 'sps_profile_interval': 0, 10:51:30 INFO - 'suite': 'dromaeojs', 10:51:30 INFO - 'system_bits': '32', 10:51:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:51:30 INFO - 'title': 't-yosemite-r7-0195', 10:51:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:51:30 INFO - 'use_talos_json': True, 10:51:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:51:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:51:30 INFO - 'work_dir': 'build'} 10:51:30 INFO - ##### 10:51:30 INFO - ##### Running clobber step. 10:51:30 INFO - ##### 10:51:30 INFO - Running pre-action listener: _resource_record_pre_action 10:51:30 INFO - Running main action method: clobber 10:51:30 INFO - rmtree: /builds/slave/test/build 10:51:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:51:32 INFO - Running post-action listener: _resource_record_post_action 10:51:32 INFO - ##### 10:51:32 INFO - ##### Running read-buildbot-config step. 10:51:32 INFO - ##### 10:51:32 INFO - Running pre-action listener: _resource_record_pre_action 10:51:32 INFO - Running main action method: read_buildbot_config 10:51:32 INFO - Using buildbot properties: 10:51:32 INFO - { 10:51:32 INFO - "project": "", 10:51:32 INFO - "product": "firefox", 10:51:32 INFO - "script_repo_revision": "production", 10:51:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:51:32 INFO - "repository": "", 10:51:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:51:32 INFO - "buildid": "20160517004009", 10:51:32 INFO - "stage_platform": "macosx64", 10:51:32 INFO - "basedir": "/builds/slave/test", 10:51:32 INFO - "nightly_build": "True", 10:51:32 INFO - "buildnumber": 237, 10:51:32 INFO - "slavename": "t-yosemite-r7-0195", 10:51:32 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:51:32 INFO - "platform": "yosemite_r7", 10:51:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:51:32 INFO - "branch": "mozilla-aurora", 10:51:32 INFO - "repo_path": "releases/mozilla-aurora", 10:51:32 INFO - "revision": "17724486e1591858eea09dc327475a25883854a4", 10:51:32 INFO - "pgo_build": "False", 10:51:32 INFO - "builduid": "ef91a013044b49f4abd91345e579e6b6", 10:51:32 INFO - "slavebuilddir": "test" 10:51:32 INFO - } 10:51:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 10:51:32 INFO - Running post-action listener: _resource_record_post_action 10:51:32 INFO - ##### 10:51:32 INFO - ##### Running download-and-extract step. 10:51:32 INFO - ##### 10:51:32 INFO - Running pre-action listener: _resource_record_pre_action 10:51:32 INFO - Running main action method: download_and_extract 10:51:32 INFO - mkdir: /builds/slave/test/build/tests 10:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:32 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:51:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:51:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:51:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 10:51:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 10:51:33 INFO - Downloaded 1199 bytes. 10:51:33 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 10:51:33 INFO - Using the following test package requirements: 10:51:33 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:51:33 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:33 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 10:51:33 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:33 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 10:51:33 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:33 INFO - u'jsshell-mac.zip'], 10:51:33 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:33 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 10:51:33 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:51:33 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:33 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 10:51:33 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:33 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 10:51:33 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:33 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 10:51:33 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:51:33 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 10:51:33 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:51: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')]} 10:51:33 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:51:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:51:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:51:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:51:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:51:35 INFO - Downloaded 18102489 bytes. 10:51:35 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'] 10:51:35 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 10:51:38 INFO - Return code: 0 10:51:38 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 10:51: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')]} 10:51:38 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:51:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:51:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:51:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:51:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:51:41 INFO - Downloaded 18102489 bytes. 10:51:41 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'] 10:51:41 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 10:51:45 INFO - Return code: 0 10:51:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:45 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:51:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:51:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:51:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:51:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:51:46 INFO - Downloaded 10840145 bytes. 10:51:46 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'] 10:51:46 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 10:51:46 INFO - Return code: 0 10:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:51:46 INFO - https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 10:51:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:51:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:51:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 10:51:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 10:51:51 INFO - Downloaded 114146064 bytes. 10:51:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:51:51 INFO - mkdir: /builds/slave/test/properties 10:51:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:51:51 INFO - Writing to file /builds/slave/test/properties/build_url 10:51:51 INFO - Contents: 10:51:51 INFO - build_url:https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:51:52 INFO - Running post-action listener: _resource_record_post_action 10:51:52 INFO - Running post-action listener: set_extra_try_arguments 10:51:52 INFO - ##### 10:51:52 INFO - ##### Running populate-webroot step. 10:51:52 INFO - ##### 10:51:52 INFO - Running pre-action listener: _resource_record_pre_action 10:51:52 INFO - Running main action method: populate_webroot 10:51:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:51:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:51:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:51:52 INFO - '--tppagecycles', 10:51:52 INFO - '7'], 10:51:52 INFO - 'tests': ['tsvgm']}}, 10:51:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:51:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:51:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:51:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:51:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:51:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:51:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:52 INFO - 'talos_options': ['--disable-e10s'], 10:51:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:51:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:51:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:52 INFO - 'talos_options': ['--disable-e10s'], 10:51:52 INFO - 'tests': ['damp', 'tps']}, 10:51:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:52 INFO - 'tests': ['damp', 'tps']}, 10:51:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:51:52 INFO - 'tests': ['dromaeo_dom']}, 10:51:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:51:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:51:52 INFO - 'tests': ['a11yr', 10:51:52 INFO - 'ts_paint', 10:51:52 INFO - 'tpaint', 10:51:52 INFO - 'sessionrestore', 10:51:52 INFO - 'sessionrestore_no_auto_restore', 10:51:52 INFO - 'tabpaint']}, 10:51:52 INFO - 'other-e10s': {'tests': ['a11yr', 10:51:52 INFO - 'ts_paint', 10:51:52 INFO - 'tpaint', 10:51:52 INFO - 'sessionrestore', 10:51:52 INFO - 'sessionrestore_no_auto_restore', 10:51:52 INFO - 'tabpaint']}, 10:51:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:51:52 INFO - 'ts_paint', 10:51:52 INFO - 'tpaint', 10:51:52 INFO - 'sessionrestore', 10:51:52 INFO - 'sessionrestore_no_auto_restore', 10:51:52 INFO - 'tabpaint']}, 10:51:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:51:52 INFO - 'ts_paint', 10:51:52 INFO - 'tpaint', 10:51:52 INFO - 'sessionrestore', 10:51:52 INFO - 'sessionrestore_no_auto_restore', 10:51:52 INFO - 'tabpaint']}, 10:51:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:51:52 INFO - 'tests': ['a11yr', 10:51:52 INFO - 'ts_paint', 10:51:52 INFO - 'tpaint', 10:51:52 INFO - 'sessionrestore', 10:51:52 INFO - 'sessionrestore_no_auto_restore', 10:51:52 INFO - 'tabpaint']}, 10:51:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:51:52 INFO - 'tests': ['a11yr', 10:51:52 INFO - 'ts_paint', 10:51:52 INFO - 'tpaint', 10:51:52 INFO - 'sessionrestore', 10:51:52 INFO - 'sessionrestore_no_auto_restore', 10:51:52 INFO - 'tabpaint']}, 10:51:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:51:52 INFO - 'tests': ['tsvgx', 10:51:52 INFO - 'tsvgr_opacity', 10:51:52 INFO - 'tart', 10:51:52 INFO - 'tscrollx', 10:51:52 INFO - 'cart']}, 10:51:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:51:52 INFO - 'tsvgr_opacity', 10:51:52 INFO - 'tart', 10:51:52 INFO - 'tscrollx', 10:51:52 INFO - 'cart']}, 10:51:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:52 INFO - 'talos_options': ['--disable-e10s'], 10:51:52 INFO - 'tests': ['tp5o']}, 10:51:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:52 INFO - 'tests': ['tp5o']}, 10:51:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:52 INFO - 'talos_options': ['--disable-e10s', 10:51:52 INFO - '--xperf_path', 10:51:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:51:52 INFO - 'tests': ['tp5n']}, 10:51:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:51:52 INFO - 'talos_options': ['--xperf_path', 10:51:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:51:52 INFO - 'tests': ['tp5n']}}, 10:51:52 INFO - 'talos.zip': {'path': '', 10:51:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:51:52 INFO - Running post-action listener: _resource_record_post_action 10:51:52 INFO - ##### 10:51:52 INFO - ##### Running create-virtualenv step. 10:51:52 INFO - ##### 10:51:52 INFO - Running pre-action listener: _resource_record_pre_action 10:51:52 INFO - Running main action method: create_virtualenv 10:51:52 INFO - Creating virtualenv /builds/slave/test/build/venv 10:51:52 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 10:51:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:51:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:51:52 INFO - Using real prefix '/tools/python27' 10:51:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:51:53 INFO - Installing distribute.............................................................................................................................................................................................done. 10:51:56 INFO - Installing pip.................done. 10:51:56 INFO - Return code: 0 10:51:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:51: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')]} 10:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51: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')]} 10:51:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:56 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 0x105828a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105838420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81ea581a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c7e00>, '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 0x105530db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c2b20>, '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.MevBT0d1aU/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.XftzkWknSV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:51:56 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 10:51:56 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 10:51:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MevBT0d1aU/Render', 10:51:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:56 INFO - 'HOME': '/Users/cltbld', 10:51:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:56 INFO - 'LOGNAME': 'cltbld', 10:51:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:56 INFO - 'MOZ_NO_REMOTE': '1', 10:51:56 INFO - 'NO_EM_RESTART': '1', 10:51:56 INFO - 'PAGER': '/bin/cat', 10:51:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:56 INFO - 'PWD': '/builds/slave/test', 10:51:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:56 INFO - 'SHELL': '/bin/bash', 10:51:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XftzkWknSV/Listeners', 10:51:56 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:51:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:56 INFO - 'USER': 'cltbld', 10:51:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:56 INFO - 'XPC_FLAGS': '0x0', 10:51:56 INFO - 'XPC_SERVICE_NAME': '0', 10:51:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:56 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 10:51:56 INFO - Cleaning up... 10:51:56 INFO - Return code: 0 10:51:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:51: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')]} 10:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51: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')]} 10:51:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:56 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 0x105828a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105838420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81ea581a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c7e00>, '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 0x105530db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c2b20>, '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.MevBT0d1aU/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.XftzkWknSV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:51:56 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 10:51:56 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 10:51:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MevBT0d1aU/Render', 10:51:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:56 INFO - 'HOME': '/Users/cltbld', 10:51:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:56 INFO - 'LOGNAME': 'cltbld', 10:51:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:56 INFO - 'MOZ_NO_REMOTE': '1', 10:51:56 INFO - 'NO_EM_RESTART': '1', 10:51:56 INFO - 'PAGER': '/bin/cat', 10:51:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:56 INFO - 'PWD': '/builds/slave/test', 10:51:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:56 INFO - 'SHELL': '/bin/bash', 10:51:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XftzkWknSV/Listeners', 10:51:56 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:51:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:56 INFO - 'USER': 'cltbld', 10:51:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:56 INFO - 'XPC_FLAGS': '0x0', 10:51:56 INFO - 'XPC_SERVICE_NAME': '0', 10:51:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:56 INFO - Downloading/unpacking psutil>=0.7.1 10:51:56 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 10:51:56 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 10:51:56 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 10:51:56 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 10:51:56 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 10:51:56 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 10:52:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:52:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:52:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:52:00 INFO - Installing collected packages: psutil 10:52:00 INFO - Running setup.py install for psutil 10:52:00 INFO - building 'psutil._psutil_osx' extension 10:52:00 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 10:52:00 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 10:52:00 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 10:52:00 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 10:52:00 INFO - building 'psutil._psutil_posix' extension 10:52:00 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 10:52:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:52:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:52:00 INFO - ^ 10:52:00 INFO - 1 warning generated. 10:52:00 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 10:52:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:52:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:52:01 INFO - Successfully installed psutil 10:52:01 INFO - Cleaning up... 10:52:01 INFO - Return code: 0 10:52:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105828a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105838420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81ea581a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c7e00>, '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 0x105530db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c2b20>, '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.MevBT0d1aU/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.XftzkWknSV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:52:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 10:52:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 10:52:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MevBT0d1aU/Render', 10:52:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:01 INFO - 'HOME': '/Users/cltbld', 10:52:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:01 INFO - 'LOGNAME': 'cltbld', 10:52:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:01 INFO - 'MOZ_NO_REMOTE': '1', 10:52:01 INFO - 'NO_EM_RESTART': '1', 10:52:01 INFO - 'PAGER': '/bin/cat', 10:52:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:01 INFO - 'PWD': '/builds/slave/test', 10:52:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:01 INFO - 'SHELL': '/bin/bash', 10:52:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XftzkWknSV/Listeners', 10:52:01 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:52:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:01 INFO - 'USER': 'cltbld', 10:52:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:01 INFO - 'XPC_FLAGS': '0x0', 10:52:01 INFO - 'XPC_SERVICE_NAME': '0', 10:52:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:52:01 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 10:52:01 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 10:52:01 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 10:52:01 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 10:52:01 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 10:52:01 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 10:52:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:52:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:52:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:52:03 INFO - Installing collected packages: mozsystemmonitor 10:52:03 INFO - Running setup.py install for mozsystemmonitor 10:52:04 INFO - Successfully installed mozsystemmonitor 10:52:04 INFO - Cleaning up... 10:52:04 INFO - Return code: 0 10:52:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:52:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:04 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 0x105828a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105838420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81ea581a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c7e00>, '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 0x105530db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c2b20>, '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.MevBT0d1aU/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.XftzkWknSV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:52:04 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 10:52:04 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 10:52:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MevBT0d1aU/Render', 10:52:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:04 INFO - 'HOME': '/Users/cltbld', 10:52:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:04 INFO - 'LOGNAME': 'cltbld', 10:52:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:04 INFO - 'MOZ_NO_REMOTE': '1', 10:52:04 INFO - 'NO_EM_RESTART': '1', 10:52:04 INFO - 'PAGER': '/bin/cat', 10:52:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:04 INFO - 'PWD': '/builds/slave/test', 10:52:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:04 INFO - 'SHELL': '/bin/bash', 10:52:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XftzkWknSV/Listeners', 10:52:04 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:52:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:04 INFO - 'USER': 'cltbld', 10:52:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:04 INFO - 'XPC_FLAGS': '0x0', 10:52:04 INFO - 'XPC_SERVICE_NAME': '0', 10:52:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:04 INFO - Downloading/unpacking blobuploader==1.2.4 10:52:04 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 10:52:04 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 10:52:04 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 10:52:04 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 10:52:04 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 10:52:04 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 10:52:06 INFO - Downloading blobuploader-1.2.4.tar.gz 10:52:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:52:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:52:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:52:07 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 10:52:07 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 10:52:07 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 10:52:07 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 10:52:07 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 10:52:07 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 10:52:07 INFO - Downloading docopt-0.6.1.tar.gz 10:52:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:52:08 INFO - Installing collected packages: blobuploader, requests, docopt 10:52:08 INFO - Running setup.py install for blobuploader 10:52:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:52:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:52:08 INFO - Running setup.py install for requests 10:52:08 INFO - Running setup.py install for docopt 10:52:08 INFO - Successfully installed blobuploader requests docopt 10:52:08 INFO - Cleaning up... 10:52:08 INFO - Return code: 0 10:52:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:08 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')]} 10:52:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:08 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')]} 10:52:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:08 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 0x105828a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105838420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81ea581a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c7e00>, '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 0x105530db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c2b20>, '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.MevBT0d1aU/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.XftzkWknSV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:52:08 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 10:52:08 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 10:52:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MevBT0d1aU/Render', 10:52:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:08 INFO - 'HOME': '/Users/cltbld', 10:52:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:08 INFO - 'LOGNAME': 'cltbld', 10:52:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:08 INFO - 'MOZ_NO_REMOTE': '1', 10:52:08 INFO - 'NO_EM_RESTART': '1', 10:52:08 INFO - 'PAGER': '/bin/cat', 10:52:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:08 INFO - 'PWD': '/builds/slave/test', 10:52:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:08 INFO - 'SHELL': '/bin/bash', 10:52:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XftzkWknSV/Listeners', 10:52:08 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:52:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:08 INFO - 'USER': 'cltbld', 10:52:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:08 INFO - 'XPC_FLAGS': '0x0', 10:52:08 INFO - 'XPC_SERVICE_NAME': '0', 10:52:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:52:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Ah0xN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:52:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-y3mTkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:52:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-ALxrxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:52:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-46VDzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:52:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-glTb9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:52:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-d9gMEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:52:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-m15zV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:52:09 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-WQsHQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:52:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-WkkhFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:52:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-WCJ9Rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:52:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-T24wO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:52:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-z2Ajjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:52:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-T47Q13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:52:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-7JjHjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-xcqO7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:52:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-0jWlwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:52:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:52:10 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-GZSgeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:52:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:52:10 INFO - Running setup.py install for manifestparser 10:52:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:52:11 INFO - Running setup.py install for mozcrash 10:52:11 INFO - Running setup.py install for mozdebug 10:52:11 INFO - Running setup.py install for mozdevice 10:52:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:52:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:52:11 INFO - Running setup.py install for mozfile 10:52:11 INFO - Running setup.py install for mozhttpd 10:52:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:52:11 INFO - Running setup.py install for mozinfo 10:52:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:52:12 INFO - Running setup.py install for mozInstall 10:52:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:52:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:52:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:52:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:52:12 INFO - Running setup.py install for mozleak 10:52:12 INFO - Running setup.py install for mozlog 10:52:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:52:12 INFO - Running setup.py install for moznetwork 10:52:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:52:12 INFO - Running setup.py install for mozprocess 10:52:12 INFO - Running setup.py install for mozprofile 10:52:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:52:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:52:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:52:12 INFO - Running setup.py install for mozrunner 10:52:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:52:13 INFO - Running setup.py install for mozscreenshot 10:52:13 INFO - Running setup.py install for moztest 10:52:13 INFO - Running setup.py install for mozversion 10:52:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:52:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:52:13 INFO - Cleaning up... 10:52:13 INFO - Return code: 0 10:52:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:13 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 0x105828a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105838420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81ea581a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c7e00>, '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 0x105530db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c2b20>, '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.MevBT0d1aU/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.XftzkWknSV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:52:13 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 10:52:13 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 10:52:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MevBT0d1aU/Render', 10:52:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:13 INFO - 'HOME': '/Users/cltbld', 10:52:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:13 INFO - 'LOGNAME': 'cltbld', 10:52:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:13 INFO - 'MOZ_NO_REMOTE': '1', 10:52:13 INFO - 'NO_EM_RESTART': '1', 10:52:13 INFO - 'PAGER': '/bin/cat', 10:52:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:13 INFO - 'PWD': '/builds/slave/test', 10:52:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:13 INFO - 'SHELL': '/bin/bash', 10:52:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XftzkWknSV/Listeners', 10:52:13 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:52:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:13 INFO - 'USER': 'cltbld', 10:52:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:13 INFO - 'XPC_FLAGS': '0x0', 10:52:13 INFO - 'XPC_SERVICE_NAME': '0', 10:52:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:52:13 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-LRgbTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:52:13 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)) 10:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:52:13 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-hiQ2v9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:52:14 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)) 10:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:52:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-IrtHNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:52:14 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)) 10:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:52:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-JVMiD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:52:14 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)) 10:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:52:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-wfM9Gb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:52:14 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)) 10:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:52:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-RMWx2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:52:14 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)) 10:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:52:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-r0wq1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:52:14 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)) 10:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:52:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-TvhRle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:52:14 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)) 10:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:52:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Po8hf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:52:14 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)) 10:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:52:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-90FkiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:52:14 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)) 10:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:52:14 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-EIAvJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:52:15 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)) 10:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:52:15 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-CuEreN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:52:15 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)) 10:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:52:15 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-pgoaE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:52:15 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)) 10:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:52:15 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-0hWo5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:52:15 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)) 10:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:15 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-kMASwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:52:15 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)) 10:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:52:15 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-KsATJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:52:15 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)) 10:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:52:15 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-3lIPpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:52:15 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)) 10:52:15 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)) 10:52:15 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)) 10:52:15 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)) 10:52:15 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)) 10:52:15 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)) 10:52:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:52:15 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 10:52:15 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 10:52:15 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 10:52:15 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 10:52:15 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 10:52:15 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 10:52:18 INFO - Downloading blessings-1.6.tar.gz 10:52:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:52:18 INFO - Installing collected packages: blessings 10:52:18 INFO - Running setup.py install for blessings 10:52:18 INFO - Successfully installed blessings 10:52:18 INFO - Cleaning up... 10:52:18 INFO - Return code: 0 10:52:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:52:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:52:18 INFO - Reading from file tmpfile_stdout 10:52:18 INFO - Current package versions: 10:52:18 INFO - blessings == 1.6 10:52:18 INFO - blobuploader == 1.2.4 10:52:18 INFO - docopt == 0.6.1 10:52:18 INFO - manifestparser == 1.1 10:52:18 INFO - mozInstall == 1.12 10:52:18 INFO - mozcrash == 0.17 10:52:18 INFO - mozdebug == 0.1 10:52:18 INFO - mozdevice == 0.48 10:52:18 INFO - mozfile == 1.2 10:52:18 INFO - mozhttpd == 0.7 10:52:18 INFO - mozinfo == 0.9 10:52:18 INFO - mozleak == 0.1 10:52:18 INFO - mozlog == 3.1 10:52:18 INFO - moznetwork == 0.27 10:52:18 INFO - mozprocess == 0.22 10:52:18 INFO - mozprofile == 0.28 10:52:18 INFO - mozrunner == 6.11 10:52:18 INFO - mozscreenshot == 0.1 10:52:18 INFO - mozsystemmonitor == 0.0 10:52:18 INFO - moztest == 0.7 10:52:18 INFO - mozversion == 1.4 10:52:18 INFO - psutil == 3.1.1 10:52:18 INFO - requests == 1.2.3 10:52:18 INFO - wsgiref == 0.1.2 10:52:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:18 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 0x105828a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105838420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81ea581a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c7e00>, '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 0x105530db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c2b20>, '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.MevBT0d1aU/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.XftzkWknSV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:52:18 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 10:52:18 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 10:52:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MevBT0d1aU/Render', 10:52:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:18 INFO - 'HOME': '/Users/cltbld', 10:52:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:18 INFO - 'LOGNAME': 'cltbld', 10:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:18 INFO - 'MOZ_NO_REMOTE': '1', 10:52:18 INFO - 'NO_EM_RESTART': '1', 10:52:18 INFO - 'PAGER': '/bin/cat', 10:52:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:18 INFO - 'PWD': '/builds/slave/test', 10:52:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:18 INFO - 'SHELL': '/bin/bash', 10:52:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XftzkWknSV/Listeners', 10:52:18 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:52:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:18 INFO - 'USER': 'cltbld', 10:52:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:18 INFO - 'XPC_FLAGS': '0x0', 10:52:18 INFO - 'XPC_SERVICE_NAME': '0', 10:52:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:18 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)) 10:52:18 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)) 10:52:18 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)) 10:52:18 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)) 10:52:18 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)) 10:52:18 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)) 10:52:18 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)) 10:52:18 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)) 10:52:18 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)) 10:52:18 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)) 10:52:18 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)) 10:52:18 INFO - Cleaning up... 10:52:18 INFO - Return code: 0 10:52:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:18 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 0x105828a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105838420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81ea581a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c7e00>, '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 0x105530db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c2b20>, '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.MevBT0d1aU/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.XftzkWknSV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:52:18 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 10:52:18 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 10:52:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MevBT0d1aU/Render', 10:52:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:18 INFO - 'HOME': '/Users/cltbld', 10:52:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:18 INFO - 'LOGNAME': 'cltbld', 10:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:18 INFO - 'MOZ_NO_REMOTE': '1', 10:52:18 INFO - 'NO_EM_RESTART': '1', 10:52:18 INFO - 'PAGER': '/bin/cat', 10:52:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:18 INFO - 'PWD': '/builds/slave/test', 10:52:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:18 INFO - 'SHELL': '/bin/bash', 10:52:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XftzkWknSV/Listeners', 10:52:18 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:52:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:18 INFO - 'USER': 'cltbld', 10:52:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:18 INFO - 'XPC_FLAGS': '0x0', 10:52:18 INFO - 'XPC_SERVICE_NAME': '0', 10:52:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:19 INFO - Downloading/unpacking jsonschema 10:52:19 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 10:52:19 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 10:52:19 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 10:52:19 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 10:52:19 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 10:52:19 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 10:52:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:52:21 INFO - Downloading/unpacking functools32 (from jsonschema) 10:52:21 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 10:52:21 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 10:52:21 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 10:52:21 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 10:52:21 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 10:52:21 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 10:52:21 INFO - Downloading functools32-3.2.3-2.tar.gz 10:52:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:52:22 INFO - warning: no files found matching '*.txt' 10:52:22 INFO - no previously-included directories found matching 'build' 10:52:22 INFO - no previously-included directories found matching 'dist' 10:52:22 INFO - no previously-included directories found matching '.git*' 10:52:22 INFO - Installing collected packages: jsonschema, functools32 10:52:22 INFO - Running setup.py install for functools32 10:52:22 INFO - warning: no files found matching '*.txt' 10:52:22 INFO - no previously-included directories found matching 'build' 10:52:22 INFO - no previously-included directories found matching 'dist' 10:52:22 INFO - no previously-included directories found matching '.git*' 10:52:22 INFO - Successfully installed jsonschema functools32 10:52:22 INFO - Cleaning up... 10:52:22 INFO - Return code: 0 10:52:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:22 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 0x105828a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105838420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81ea581a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056c7e00>, '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 0x105530db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c2b20>, '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.MevBT0d1aU/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.XftzkWknSV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:52:22 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 10:52:22 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 10:52:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MevBT0d1aU/Render', 10:52:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:22 INFO - 'HOME': '/Users/cltbld', 10:52:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:22 INFO - 'LOGNAME': 'cltbld', 10:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:22 INFO - 'MOZ_NO_REMOTE': '1', 10:52:22 INFO - 'NO_EM_RESTART': '1', 10:52:22 INFO - 'PAGER': '/bin/cat', 10:52:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:22 INFO - 'PWD': '/builds/slave/test', 10:52:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:22 INFO - 'SHELL': '/bin/bash', 10:52:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XftzkWknSV/Listeners', 10:52:22 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:52:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:22 INFO - 'USER': 'cltbld', 10:52:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:22 INFO - 'XPC_FLAGS': '0x0', 10:52:22 INFO - 'XPC_SERVICE_NAME': '0', 10:52:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:22 INFO - Downloading/unpacking flake8 10:52:22 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 10:52:22 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 10:52:22 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 10:52:22 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 10:52:22 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 10:52:22 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 10:52:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:52:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:52:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:52:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:52:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:52:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:52:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:52:26 INFO - no previously-included directories found matching 'docs/_build' 10:52:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:52:26 INFO - Running setup.py install for pep8 10:52:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:52:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:52:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:52:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:52:26 INFO - no previously-included directories found matching 'docs/_build' 10:52:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:52:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:52:26 INFO - Cleaning up... 10:52:26 INFO - Return code: 0 10:52:26 INFO - Running post-action listener: _resource_record_post_action 10:52:26 INFO - Running post-action listener: _start_resource_monitoring 10:52:26 INFO - Starting resource monitoring. 10:52:26 INFO - ##### 10:52:26 INFO - ##### Running install step. 10:52:26 INFO - ##### 10:52:26 INFO - Running pre-action listener: _resource_record_pre_action 10:52:26 INFO - Running main action method: install 10:52:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:52:27 INFO - Reading from file tmpfile_stdout 10:52:27 INFO - Detecting whether we're running mozinstall >=1.0... 10:52:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:52:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:52:27 INFO - Reading from file tmpfile_stdout 10:52:27 INFO - Output received: 10:52:27 INFO - Usage: mozinstall [options] installer 10:52:27 INFO - Options: 10:52:27 INFO - -h, --help show this help message and exit 10:52:27 INFO - -d DEST, --destination=DEST 10:52:27 INFO - Directory to install application into. [default: 10:52:27 INFO - "/builds/slave/test"] 10:52:27 INFO - --app=APP Application being installed. [default: firefox] 10:52:27 INFO - mkdir: /builds/slave/test/build/application 10:52:27 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'] 10:52:27 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 10:52:58 INFO - Reading from file tmpfile_stdout 10:52:58 INFO - Output received: 10:52:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:52:58 INFO - Running post-action listener: _resource_record_post_action 10:52:58 INFO - ##### 10:52:58 INFO - ##### Running run-tests step. 10:52:58 INFO - ##### 10:52:58 INFO - Running pre-action listener: _resource_record_pre_action 10:52:58 INFO - Running main action method: run_tests 10:52:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:52:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:52:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:52:58 INFO - 2016-05-17 10:52:58.923 screenresolution[1939:18009] starting screenresolution argv=screenresolution get 10:52:58 INFO - 2016-05-17 10:52:58.925 screenresolution[1939:18009] Display 0: 1600x1200x32@60 10:52:58 INFO - 2016-05-17 10:52:58.931 screenresolution[1940:18011] starting screenresolution argv=screenresolution list 10:52:58 INFO - Available Modes on Display 0 10:52:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:52:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:52:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:52:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:52:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:52:59 INFO - Intel Iris: 10:52:59 INFO - Chipset Model: Intel Iris 10:52:59 INFO - Type: GPU 10:52:59 INFO - Bus: Built-In 10:52:59 INFO - VRAM (Dynamic, Max): 1536 MB 10:52:59 INFO - Vendor: Intel (0x8086) 10:52:59 INFO - Device ID: 0x0a2e 10:52:59 INFO - Revision ID: 0x0009 10:52:59 INFO - Displays: 10:52:59 INFO - Display: 10:52:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:52:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:52:59 INFO - Display Serial Number: 1600x1200 60 10:52:59 INFO - Main Display: Yes 10:52:59 INFO - Mirror: Off 10:52:59 INFO - Online: Yes 10:52:59 INFO - Rotation: Supported 10:52:59 INFO - Return code: 0 10:52:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:52:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:52:59 INFO - Python 2.7.3 10:52:59 INFO - Return code: 0 10:52:59 INFO - grabbing minidump binary from tooltool 10:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:52:59 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 0x1056c7e00>, '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 0x105530db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056c2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:52:59 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 10:52:59 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 10:52:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:52:59 INFO - Return code: 0 10:52:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:52:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:52:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:52:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:52:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:52:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:52:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:53:00 INFO - Return code: 0 10:53:00 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/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--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 10:53:00 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/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0195 --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 10:53:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MevBT0d1aU/Render', 10:53:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:00 INFO - 'HOME': '/Users/cltbld', 10:53:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:00 INFO - 'LOGNAME': 'cltbld', 10:53:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:53:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:00 INFO - 'MOZ_NO_REMOTE': '1', 10:53:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:53:00 INFO - 'NO_EM_RESTART': '1', 10:53:00 INFO - 'PAGER': '/bin/cat', 10:53:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:00 INFO - 'PWD': '/builds/slave/test', 10:53:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:53:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:00 INFO - 'SHELL': '/bin/bash', 10:53:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XftzkWknSV/Listeners', 10:53:00 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:53:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:00 INFO - 'USER': 'cltbld', 10:53:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:00 INFO - 'XPC_FLAGS': '0x0', 10:53:00 INFO - 'XPC_SERVICE_NAME': '0', 10:53:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:00 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/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--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 10:53:00 INFO - mozversion application_buildid: 20160517004009 10:53:00 INFO - mozversion application_changeset: 17724486e1591858eea09dc327475a25883854a4 10:53:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:53:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:53:00 INFO - mozversion application_name: Firefox 10:53:00 INFO - mozversion application_remotingname: firefox-dev 10:53:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:53:00 INFO - mozversion application_vendor: Mozilla 10:53:00 INFO - mozversion application_version: 48.0a2 10:53:00 INFO - mozversion platform_buildid: 20160517004009 10:53:00 INFO - mozversion platform_changeset: 17724486e1591858eea09dc327475a25883854a4 10:53:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:53:00 INFO - mozversion platform_version: 48.0a2 10:53:00 INFO - using testdate: 1463507580 10:53:00 INFO - actual date: 1463507580 10:53:00 INFO - starting webserver on 'localhost:52354' 10:53:00 INFO - SUITE-START | Running 2 tests 10:53:00 INFO - TEST-START | dromaeo_css 10:53:00 INFO - operating with platform_type : mac_ 10:53:00 INFO - Initialising browser for dromaeo_css test... 10:53:00 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpULYDix/profile http://localhost:52354/getInfo.html) 10:53:02 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 10:53:02 INFO - PROCESS | 1958 | colorDepth:24 10:53:02 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 10:53:02 INFO - PROCESS | 1958 | __metrics 10:53:02 INFO - PROCESS | 1958 | 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" 10:53:02 ERROR - PROCESS | 1958 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:53:03 INFO - TEST-INFO | 1958: exit 0 10:53:03 INFO - Browser initialized. 10:53:03 INFO - Running cycle 1/1 for dromaeo_css test... 10:53:03 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpULYDix/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:53:15 INFO - PROCESS | 1961 | RSS: Main: 211124224 10:53:15 INFO - PROCESS | 1961 | 10:54:07 INFO - PROCESS | 1961 | 1463507647179 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] 10:56:18 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52354/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52354/tests/dromaeo/cssquery-ext.html) 10:56:19 INFO - PROCESS | 1961 | RSS: Main: 322355200 10:56:19 INFO - PROCESS | 1961 | 10:59:22 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52354/tests/dromaeo/cssquery-ext.html (next: http://localhost:52354/tests/dromaeo/cssquery-jquery.html) 10:59:22 INFO - PROCESS | 1961 | RSS: Main: 359305216 10:59:22 INFO - PROCESS | 1961 | 11:02:25 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52354/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52354/tests/dromaeo/cssquery-mootools.html) 11:02:25 INFO - PROCESS | 1961 | RSS: Main: 361062400 11:02:25 INFO - PROCESS | 1961 | 11:05:28 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52354/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52354/tests/dromaeo/cssquery-prototype.html) 11:05:28 INFO - PROCESS | 1961 | RSS: Main: 362831872 11:05:28 INFO - PROCESS | 1961 | 11:08:31 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52354/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52354/tests/dromaeo/cssquery-yui.html) 11:08:32 INFO - PROCESS | 1961 | RSS: Main: 326332416 11:08:32 INFO - PROCESS | 1961 | 11:11:35 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:52354/tests/dromaeo/cssquery-yui.html (next: http://localhost:52354/tests/dromaeo/cssquery-dojo.html) 11:11:35 INFO - PROCESS | 1961 | RSS: Main: 368222208 11:11:35 INFO - PROCESS | 1961 | 11:11:35 INFO - PROCESS | 1961 | __start_tp_report 11:11:35 INFO - PROCESS | 1961 | _x_x_mozilla_page_load 11:11:35 INFO - PROCESS | 1961 | _x_x_mozilla_page_load_details 11:11:35 INFO - PROCESS | 1961 | |i|pagename|runs| 11:11:35 INFO - PROCESS | 1961 | |0;dojo.html;3330.7168890644293;3410.5736782902136;3411.505331726901;3411.8635254589817;3463.7055850252727;5596.992541342562;5598.888022239553;5607.523360514361;5623.184638227454;5623.746931388092;5574.968630803304;5631.971840140794;5633.549316054716;5641.323041235048;5645.15322701595;5031.723255220962;5047.520485553867;5079.466656001122;5085.008423357447;5119.206522988943;5132.743362831862;5139.897202055975;5150.587952963772;5155.432902380735;5158.43257241705;5063.518965698253;5080.390353157626;5130.9994551062555;5132.23016547519;5167.147420675584;5113.872153196163;5132.512411320956;5135.037058762127;5138.100832354323;5171.534561889448;4886.633502487328;4998.850034498971;5029.044481548531;5052.216906379517;5053.671511351788;5463.836084917459;5485.204645326406;5539.390667026624;5548;5551.056320425538;6022.759089636409;6038.547108966845;6041.335453100155;6043.848903777441;6045.576809623328;6005.459508644243;6061.424164704346;6106.938930610681;6115.392460733998;6116.235470566179;5721.313442386857;5739.024365857731;5742.368339482654;5748.281464816898;5766.884662306772;6214.223222097238;6268.561200715997;6273;6281.685915704288;6292.622442653455;5247.9737601311745;5754.338251101093;6070.0591408331775;6078.392160783975;6085.391460853879;5900.055991041499;6021.518278537794;6042.39576042401;6054.243219597551;6058.4244496772735;6117.84705382369;6131.724072416752;6145.8156255312415;6160.9383906160365;6167.167432396659;5913.201717768132;6133.233345831771;6173.969130154321;6178.938210617836;6181.598196117238;4827.961988172489;4843.636727245471;4850.53919877611;4861.708297502161;4862.635302352338;4315.546867578922;4420.955790442119;4424.84513042042;4439.822407103679;4456.799444025027;5575.665460072409;5610.551155907599;5613.46672066153;5630.74661640219;5645.322561292671;6124.724387402578;6203.410675985774;6215.409536094064;6216.191895053705;6216.316205217422;5486;5582.190582365534;5583.944160558423;5615.017371959891;5620.3255609326325;5278.683279003272;5288.180332048539;5302.734863256822;5304.257403963448;5309.946900530946;6247.718852651551;6259.092431597484;6271.059341098871;6277.7175027123885;6294.402031806971;6003.549733770072;6037.45662890342;6136.5090792737365;6192.380761923771;6199.039148931923;5164.922526162186;6257.217847769029;6257.78097766576;6259.467945224706;6271.090691849748;5908.143319218775;6039.75840966374;6061.7575296987625;6067.059605761114;6078.3921607838865;5917.082852157922;6031.09533569968;6049.0926361046195;6140.478059365004;6153.569250152446;4647.86056418308;5958.225430693982;5975.880482390241;6001.339852616122;6019.638821670714;5933.406659334205;5946.910796337972;5979.940200598112;5985.760569577168;6002.609830361012;5654.886902262014;5750.482456578928;5820.73806678692;5821.243238378984;5842.853928651648;5998.580099393001;6016.639001659914;6023.27720673522;6028.90956635642;6045.6070355426755;5833.183354330312;5853.64878107315;5866.765329386947;5877.970610146922;5897.469227769521 11:11:35 INFO - PROCESS | 1961 | |1;ext.html;507.7283653245512;520.1962967215652;522.7908836465411;544.2081771023161;655.8588056781199;30590;31344.52983205251;31407;31409.685903140962;31512.36975260499;32124.67875321246;38045;39003.60996390035;39099;39257.21485570287;9518.57166427509;9817.312788104835;9835;9852.901470985305;9859.704208873709;8648.567571621425;9630.374025688343;9765.707028789113;9770.120689137977;9775.364601300927;8730.345224108185;11640.06879449642;11825.40872956353;11826.467808948575;11870.99096576792;10122.746931326703;11161.609343672973;11539.942300288445;11543.307401555849;11561.364124973123;8562.52906090171;11333.716657083556;11495.080393568494;11507.712307192303;11790.823137652857;38186.42720359197;38637.42043869344;38666.806665966775;38685.419718704245;38868;3301.339732053575;3304.240024794287;3436.7937923724407;3513.9297214055578;3758.4550240215017;27245.318867028283;27633.309167270778;27712.445751085066;27827.860860695568;28038.439231215263;2738.52075886719;2760.6963234044238;2782.373965857686;2798.6361772969385;2841.275474753945;488369;488889;489227.99999999645;490397;491810;60319;60810.78378432495;60819.69590152021;60848.69575652182;61052.38947610467;64216;64430;64457.03314450287;64577.354226458076;64635.06094817163;16286.755698664529;16782.496525104267;16783.909045911976;16802.99182049081;16804.495865124067;13080.018998575146;13160.60518184456;13171.934140329236;13182.604521864168;13198.538051168163;13858.722825543635;14236.715265694835;15550.533483995498;15669.216539173223;15916.363345466052;6165.32181460039;6734.215432910282;6859.142607174103;6974.4420446364165;6975;932.8647346134773;955.3790036476207;966.246327864267;1033.957227241482;1159.96520104397;29081.563776543368;29640;29810.55284170739;30082.84958575237;30096.84951575228;1104.8563686720674;1159.7970355187808;1189.7501524679742;1217.993910030444;1263.4882872436679;15172.468963586442;15260.694786104437;15324.6001735988;15385.999910006038;15454.072755634474;605279;616144;616279;616361;616886;51362.74318628383;52030.21954670683;63568.68215658892;64897.351026490076;64989.350106499274;1520.5514373259693;1526.9007514511743;1536.4238410596026;1536.5159974607964;1539.8460153984736;28734;29913;29941.101766947027;30353.392932141676;30626.234344141576;48745.268820967;48970.51029489802;49432.50567494279;49564.50435495743;49573;493220;495539;496171;497066;498081;36253.45619815652;37681;37699;37703.81148094242;38117.23765524729;45072.323915140645;45783.54216457793;45884.770576146904;47351;47964.040719186116;34910.301793964485;35597.28805423929;37383.626163738016;37491.43762843614;38119.04702382463;36205.0948726284;36480.72300548897;36615.81692091629;36724.08189795277;37417;29168.27079323034;29489.26276843096;37250;37638.435423469215;37667.43498847574;30841.22896927505;31595.052148435585;31631.73473061144;31648.208794780316;31744.523832142073;23587.52824943457;23903.52192956166;24342.51314973655;24362.51274974455;24431.26706198817 11:11:35 INFO - PROCESS | 1961 | |2;jquery.html;382.49128658883654;395.42663138449245;396.0929471510242;399.3271337795814;411.0381706806075;13515.91872650188;15072.6985460291;16894.57763555912;20289.391318260456;20295.39113826586;20011.399658010247;20353.49116272094;20696.275630352942;21211.469713257175;21280.14879404826;5583.274174357348;5784.103463963082;5926.614770039933;5978.940210597903;6110.236220472441;3949.881503554898;5507.9449205508035;5873.676947767871;5881.2354393928945;5922.407759224065;5865.061590145578;5868.6478811271245;5877.2065771121;5877.412258774109;5884.146798714184;5759.510441612468;5833.970830145843;5863.5016023638045;6659.86680266392;6687.933120668828;5929.288485381739;5956.285245770535;5962.403759624003;5972.343042265347;5975.342712301644;10451;20199.8990005049;26000.73999260002;26373.945042198287;26380.20859374222;1986.860919735619;2139.7432308123125;2197.9560408791735;2350.6474028895805;2712.51174788538;9568.473733944631;9595.952020239854;9826.263030272758;9982.101610854985;10253.538590763434;1719.6904557179703;1792.6952418088827;1813.0662708705026;1819.263198404635;1906.9427917162507;100985;107517.46241268821;113140.86859131386;113457;116573.41713291379;9917.256205784595;11942.462589183508;11954.880451195551;11956.76086478283;11988;13781.724365512633;14218.928905355408;15388.615284617974;15409.614759630875;15443;7795.688172473151;8203.58982050895;8776.60505277264;8891.866622000674;8920.73237802867;7198.92801071993;7740.690372385042;8177.304929081095;8680.826383472422;8760.904886889139;4275.935860962088;4358.975640724428;4400.361947517656;4655.86032419028;4736.100140973273;1731.2728653965114;1744.756023055027;1861.1717785585556;1947.5033866363985;1975.4097951149463;872.9476231426007;910.740438974889;990.9504524773732;1009.7627057641448;1037.5745944162857;18997.24011039543;20606.866622335616;20879.791202088087;20891.477713056993;20920.47698807542;954.708813811794;959.5729900194486;978.9265805064649;987.0573602209872;1012.1497941728983;10943.45282735876;11147.777044459066;11168.609098681507;11170.720731981766;11209.383483908463;603266;611784;624456;628469;637487;111526;115892.26161607583;118631.8136818638;118895;124249;877.8507653699014;1006.4213077480478;1009.4952523738131;1028.275066078413;1029.330934892311;14863.554093377215;15775.659068979265;17860.017699026677;18196.636067278316;18568.44294671162;50741;51018.72453188551;51143;51896;53763;57398.7130064347;74180.62909685417;74470.2552974485;74828;75295;7770.689172433039;8117.756467305991;8337.791555211168;8474.1949514796;8477.915220847712;12124.818127728267;12459.563915262923;12533.310667913353;12783.424745886272;12806.35968201605;10510.527026283988;10941.343519388862;10949.83575246356;11153.330800152016;11208.159388045766;10624.150067994697;10654.147668186371;10831.404272764757;10912.12325775317;11012.614558490415;9452.10205030521;10650.8934910653;11285.040771534183;11365.602203922823;11555.942220288845;8117.431779775359;8181;8226.259636632492;8227.382946279173;8458.154184581492;8375;10950.502849563925;11416.029637480575;11528;11572.132090093108 11:11:35 INFO - PROCESS | 1961 | |3;mootools.html;1417.4755340524007;1542.714597799407;1559.9376024959;1792.2651712797747;1949.2787668562635;8014.078380986181;8633.913660863398;8794.91205087949;8800.823983520326;8819.559022048905;8471.745847624581;8713.259372953307;8714.215720585162;8721.127887211276;8769.517676527805;2175.4778853075227;2217.800397964183;2226.3636227372867;2422.1280339077916;2449.2040086971715;2239.8320125990535;2429.0405289910473;2495.4510007798344;2495.70051593809;2497.625356196567;2410.3251089694913;2442.7679370459778;2445.6942882139733;2447.1190371466346;2449.8040156787415;2151.967720484194;2440.45089854783;2444.083468699238;2449.742777008415;2455.54572404104;2375.133076427109;2426.2236084452984;2429.781319681237;2433.2943446400523;2434.8417352872184;8582.613782379807;8627.22354988054;8639.827203455958;8644.567771611395;8680.262177714838;5199.688018718889;5334.466553344673;5348;5371.731413429313;5392.164214546751;3481.9129521762156;3556.448750443685;3601.135727425425;3616;3629.4555816627453;1029.6756521695643;1047.7747284333905;1049.9580016799318;1066.344198318029;1084.5228099636213;9944.850827237597;11994.040476761838;12058.879411205864;12094.334811585359;12100.697482563006;4788.928166077511;4812.687175333592;4873.31773551703;4883.633727470454;4939.382577177853;10068.043535864228;10097.040781125928;10118.898811011943;10124.13944814699;10134.797304054026;5524.254225679482;5529.502344788988;5533.22534845122;5535.778568857281;5557.249771280825;4411.426514553087;4584.908301833944;4969.676970996874;4973.925391119136;5035.043341765053;4025.5370632377067;4081.3265811141423;4083.1629515949157;4114.444549985774;4165.625093741517;1225.9579357546193;1318.2815365625756;1348.0967751606447;1360.9721514942664;1365.3855764905832;784.2862994674819;910.4628269321132;936.765808547863;939.3847030195233;958.8561715742555;5800.767969281182;5901.203337549376;5911.7635294587735;5916.408359164049;6084.269887613426;969.8836139663285;974.498133461268;1006.1397505133152;1006.3609607899036;1033.896610338975;3822.140018495872;3931.7444366116683;3953.2686453006286;4023.7384570002855;4039.8384064637085;10370.27408081442;10711.410872402093;10815.945920270506;10839.566417343218;10908.236423450282;9376.95311523438;10274.486275686186;10610.045095941248;10727.83908241377;10963.671089867317;8317.792055198668;8397.790055248546;8524.06235314115;8552.743417697604;8553;5622.409646987171;5684.261046063985;5712.685802280915;5852.268466441695;5862.091375836752;9568.4737339447;9570;9716.659916902874;9854.655087071918;9864.21086313079;9523.428594284365;10050.346727462691;10069.496525173565;10148.898511014795;10270.743231419274;6242.5942313749465;6459.935400645933;7090.184628767758;7112.537685050455;7234.74678386254;7039.472039596948;7598.12621548529;8202.917970820454;8208.876866847122;8221.17788221197;7324.4140468763435;7386.926130738624;7415.184329723834;7503.962480187564;7514.0607424071995;7301.525400848928;7339.302766237199;7381.372583330262;7435.256474352522;7446.813829654302;6877.243503214542;7008.194057683431;7017.684204210721;7017.824554386181;7024.859502810017;4862.3922009748785;4925.876853078702;4938.654294199515;5013.899722005613;5060.114479965918;4963.528464795839;4998.400191976841;5015.22264049073;5048.7728052237;5225.608079393985 11:11:35 INFO - PROCESS | 1961 | |4;prototype.html;318.76889255289916;322.8305139801604;326.6390638344629;326.86414708886605;330.2420943933673;5830.387809280023;6633.204015518145;6645.634490103049;6650.168728908887;6652.401283884449;6535.967320163404;6616.801495955129;6623.668816559177;6642.202935647729;6651;4637.652176086798;4760.85717428476;4762.499937506564;4763.14263432582;4765.451973023095;4740.834070807522;4760.714357138566;4787.49731278216;4833.468318484982;4865.902681946377;4759.547842954931;4835.298881662157;4853.781579828898;4860.829870954517;4860.878478038059;4818.34952281441;4821.662483626147;4821.686590371614;4840.588549973257;4860.951390486085;3986.2426139033782;4050.6151915567975;4141.171765646883;4143.70994030418;4173.499180098377;6226.315105338409;6263.937360626381;6294.968525157391;6352.6823658816875;6367.6497792621385;5750.424957504258;5768.077107662796;5768.134779783024;5770.79802206925;5773.624714393593;7026.332498412643;7059.188193357727;7068.3285087916565;7074.858502829966;7077.681504332318;4699.67102302839;4709.434867815884;4710.599599034086;4715.245560710304;4716.551927566876;6161.459635091227;7475.215102414277;7483.513571617827;7486.363659088984;7489.400847932152;4556.567126123013;4643.721376717408;4646.3495110684535;4650.790714417859;4656.952185758245;4713.787879545429;4717.43390793107;4723.929141062887;4725.5510726480925;4731.1720448921305;5591.329040515164;5593.664380137205;5618.353889302701;5620.91568626471;5622.57830662702;6884.345987131314;7098.36114749675;7117.6085315308155;7124.8575028499135;7129.607871567114;1612.9677406451806;1617.4258138360947;1634.648550561635;1636.5172274178908;1644.925978330954;1022.1771473963382;1026.440589878518;1040.7918416316768;1041.890601486833;1041.9166466682648;6423.229212494531;6620.801375958729;6623.105880706139;6626.271110177877;6629.5359324846795;7458.4033277337685;7461.776146715607;7467.962660186664;7475.327220550176;7489.550626962399;6669.333066693292;6696.46428285736;6705;6708.2956289589865;6708.396244338033;3197.7441804655573;3868.806559672061;3912.315344814703;3929.3713005918917;3938.8424463021734;5590.385057643656;6515.6742162892615;7212.350888420067;7739.148693643695;7757.146713861471;7022.719091236396;7023.367896889305;7089.326513981164;7117.786466406016;7130.857382852314;6081.695915204311;6976.476764242702;7193.352598266077;7203.603801790847;7205.315495027964;6905.033295338556;6916.3429474199875;6932.341427564374;6942.583444993316;6954.165500140016;6534.934650653433;6780.898286525608;6794.76218332356;6805.217399999064;6815.829604259933;7162.391196748126;7185.425165986604;7207.8558428832175;7231.168415632059;7251.854962900818;4534.342520334599;4579.404677391918;4589.196890544208;4589.839355622537;4591.403117594691;6798.368391586083;6805.761798337035;6819.215790183994;6820.113385259884;6835.213950395768;6346.682665866781;6567.146270984933;6576.736930522726;6589.0775291460195;6593.868122637425;6532.510061745293;6558.639274839929;6571.3100124488155;6576.934230657632;6612.272650008592;5503.724813759216;6180.949238629354;6407.231132264158;6419.165508483868;6452.806415807533;6560.147180866456;6597.010448432773;6597.340265973365;6604.801855944329;6605.966970165311;6171.938280617136;6210.161628180319;6408.58344207625;6422.807315780534;6445.2587952386075 11:11:35 INFO - PROCESS | 1961 | |5;yui.html;688.6659969914592;735.6873328835244;756.9318761311478;768.0898135709182;791.8930944322517;14991.775123373158;15158.84841151588;15204.695906081886;15216.315265813037;15286.082835029905;13871.514496992608;13918.721625567483;13927;13932.930335348334;15647.139407332621;904.6381447421035;930.4789317981918;930.6649606141783;930.6882194464862;931.948742819148;900.0504467786483;922.1055576091181;924.2559739409772;924.7179610218881;924.9260059195249;977.2035790830486;982.9361091529064;983.5623147699276;983.6360546597733;992.4839083676484;974.1962880623479;977.3451787302489;977.5845265762023;981.6024510073361;981.842905135175;725.9818504537376;726.3172617739308;729.3946024799404;729.7883613752006;731.0313834169747;15192;15258.542243732705;15261.771073433907;15291.388344466208;15315.540533784004;12205.93897030518;12564.811527826998;12607.243565386014;12674;12858.742825143538;10010.699679009642;10144;10157.136643385322;10346.379217246915;10390.324628899098;3496.8426420811124;3515.402381595135;3629.364861149288;3662.08447888028;3772.4529943158086;48270;64477;76236;76724.99999999945;77169.84245236326;9330.906690933003;9369.109934556207;9430.575624096931;9441.716748497556;9479.715608531755;17995.550111247325;20655.483612909797;20655.79344206569;20979.265725699526;21237;6520.510961677893;6992.440604751608;7310.780676579711;7436.0333156689285;7494.5128566643;3723;4107.116969851496;4109.465769450012;4120.896977575585;4134.338505839051;3560.1989552350515;3603.0632035670915;3614.3494171048937;3638.9636103638622;3645.3985092459448;653.539254825347;661.6890061671007;661.9735210591523;668.238208442376;669.6752075243503;503.8614381045169;521.1557277210943;523.2726510150893;590.2032256453753;594.5540844366725;8154.14381489935;8254.759116263427;8256.174382561816;8290.917090829014;8298.958505207434;502.8441183233209;535.7696190638063;538.3862396867574;604.9493157252232;609.2780055634088;3425.5889293284467;3744.494493243432;3792.4500947362476;3881.514810648669;3945.881623551298;94510.5274473637;96508.51745741366;96854;97346;97692.51153744328;52318.47681523136;68336.6583167081;71432;75837;76727;61595.38404615986;62097.37902621007;65169.674151629886;66573.66852663016;70383.64808175927;7620.3522700571075;7692;8307.418480706534;8338.37462190326;8567.143285671384;47602.76198618985;48071.278930815366;49580.25629615631;49839;49980.750096249285;66048.33951660422;66231.66884165548;67859.3214067853;68787;72061;10898.564057437614;11125.165612578927;11385.316880987168;11501.597444089417;11544.422778861192;11741.47163377633;12021.699457513632;12097;12102.334371609646;12327;11059.50232239535;11418.029467495357;11680.824787628362;11733.178677492493;11745.882541174478;11354.205205635526;11543.422828858691;11595.420228988685;11891.524339026342;12008.159428839897;11350.375729334966;11366.943165284121;11592.246503977214;11652.53389864396;11715.121365897763;9675.689631203724;9802.313838031268;9808.117269445784;9833;10058.396496210251;9424.151826335663;9667.854982175413;9743.058207408058;9782.461964592016;9826.213902887896 11:11:35 INFO - PROCESS | 1961 | __end_tp_report 11:11:35 INFO - PROCESS | 1961 | __start_cc_report 11:11:35 INFO - PROCESS | 1961 | _x_x_mozilla_cycle_collect,1068 11:11:35 INFO - PROCESS | 1961 | __end_cc_report 11:11:35 INFO - PROCESS | 1961 | __startTimestamp1463508695513__endTimestamp 11:11:35 INFO - PROCESS | 1961 | 11:11:35 INFO - PROCESS | 1961 | ------- Summary: start ------- 11:11:35 INFO - PROCESS | 1961 | Number of tests: 6 11:11:35 INFO - PROCESS | 1961 | 11:11:35 INFO - PROCESS | 1961 | [#0] dojo.html Cycles:165 Average:5627.45 Median:5821.24 stddev:619.13 (10.6%) stddev-sans-first:594.24 11:11:35 INFO - PROCESS | 1961 | Values: 3330.7 3410.6 3411.5 3411.9 3463.7 5597.0 5598.9 5607.5 5623.2 5623.7 5575.0 5632.0 5633.5 5641.3 5645.2 5031.7 5047.5 5079.5 5085.0 5119.2 5132.7 5139.9 5150.6 5155.4 5158.4 5063.5 5080.4 5131.0 5132.2 5167.1 5113.9 5132.5 5135.0 5138.1 5171.5 4886.6 4998.9 5029.0 5052.2 5053.7 5463.8 5485.2 5539.4 5548.0 5551.1 6022.8 6038.5 6041.3 6043.8 6045.6 6005.5 6061.4 6106.9 6115.4 6116.2 5721.3 5739.0 5742.4 5748.3 5766.9 6214.2 6268.6 6273.0 6281.7 6292.6 5248.0 5754.3 6070.1 6078.4 6085.4 5900.1 6021.5 6042.4 6054.2 6058.4 6117.8 6131.7 6145.8 6160.9 6167.2 5913.2 6133.2 6174.0 6178.9 6181.6 4828.0 4843.6 4850.5 4861.7 4862.6 4315.5 4421.0 4424.8 4439.8 4456.8 5575.7 5610.6 5613.5 5630.7 5645.3 6124.7 6203.4 6215.4 6216.2 6216.3 5486.0 5582.2 5583.9 5615.0 5620.3 5278.7 5288.2 5302.7 5304.3 5309.9 6247.7 6259.1 6271.1 6277.7 6294.4 6003.5 6037.5 6136.5 6192.4 6199.0 5164.9 6257.2 6257.8 6259.5 6271.1 5908.1 6039.8 6061.8 6067.1 6078.4 5917.1 6031.1 6049.1 6140.5 6153.6 4647.9 5958.2 5975.9 6001.3 6019.6 5933.4 5946.9 5979.9 5985.8 6002.6 5654.9 5750.5 5820.7 5821.2 5842.9 5998.6 6016.6 6023.3 6028.9 6045.6 5833.2 5853.6 5866.8 5878.0 5897.5 11:11:35 INFO - PROCESS | 1961 | 11:11:35 INFO - PROCESS | 1961 | [#1] ext.html Cycles:180 Average:66826.44 Median:36367.09 stddev:143108.95 (393.5%) stddev-sans-first:143423.79 11:11:35 INFO - PROCESS | 1961 | Values: 507.7 520.2 522.8 544.2 655.9 30590.0 31344.5 31407.0 31409.7 31512.4 32124.7 38045.0 39003.6 39099.0 39257.2 9518.6 9817.3 9835.0 9852.9 9859.7 8648.6 9630.4 9765.7 9770.1 9775.4 8730.3 11640.1 11825.4 11826.5 11871.0 10122.7 11161.6 11539.9 11543.3 11561.4 8562.5 11333.7 11495.1 11507.7 11790.8 38186.4 38637.4 38666.8 38685.4 38868.0 3301.3 3304.2 3436.8 3513.9 3758.5 27245.3 27633.3 27712.4 27827.9 28038.4 2738.5 2760.7 2782.4 2798.6 2841.3 488369.0 488889.0 489228.0 490397.0 491810.0 60319.0 60810.8 60819.7 60848.7 61052.4 64216.0 64430.0 64457.0 64577.4 64635.1 16286.8 16782.5 16783.9 16803.0 16804.5 13080.0 13160.6 13171.9 13182.6 13198.5 13858.7 14236.7 15550.5 15669.2 15916.4 6165.3 6734.2 6859.1 6974.4 6975.0 932.9 955.4 966.2 1034.0 1160.0 29081.6 29640.0 29810.6 30082.8 30096.8 1104.9 1159.8 1189.8 1218.0 1263.5 15172.5 15260.7 15324.6 15386.0 15454.1 605279.0 616144.0 616279.0 616361.0 616886.0 51362.7 52030.2 63568.7 64897.4 64989.4 1520.6 1526.9 1536.4 1536.5 1539.8 28734.0 29913.0 29941.1 30353.4 30626.2 48745.3 48970.5 49432.5 49564.5 49573.0 493220.0 495539.0 496171.0 497066.0 498081.0 36253.5 37681.0 37699.0 37703.8 38117.2 45072.3 45783.5 45884.8 47351.0 47964.0 34910.3 35597.3 37383.6 37491.4 38119.0 36205.1 36480.7 36615.8 36724.1 37417.0 29168.3 29489.3 37250.0 37638.4 37667.4 30841.2 31595.1 31631.7 31648.2 31744.5 23587.5 23903.5 24342.5 24362.5 24431.3 11:11:35 INFO - PROCESS | 1961 | 11:11:35 INFO - PROCESS | 1961 | [#2] jquery.html Cycles:180 Average:34694.33 Median:14175.69 stddev:103043.94 (726.9%) stddev-sans-first:103300.80 11:11:35 INFO - PROCESS | 1961 | Values: 382.5 395.4 396.1 399.3 411.0 13515.9 15072.7 16894.6 20289.4 20295.4 20011.4 20353.5 20696.3 21211.5 21280.1 5583.3 5784.1 5926.6 5978.9 6110.2 3949.9 5507.9 5873.7 5881.2 5922.4 5865.1 5868.6 5877.2 5877.4 5884.1 5759.5 5834.0 5863.5 6659.9 6687.9 5929.3 5956.3 5962.4 5972.3 5975.3 10451.0 20199.9 26000.7 26373.9 26380.2 1986.9 2139.7 2198.0 2350.6 2712.5 9568.5 9596.0 9826.3 9982.1 10253.5 1719.7 1792.7 1813.1 1819.3 1906.9 100985.0 107517.5 113140.9 113457.0 116573.4 9917.3 11942.5 11954.9 11956.8 11988.0 13781.7 14218.9 15388.6 15409.6 15443.0 7795.7 8203.6 8776.6 8891.9 8920.7 7198.9 7740.7 8177.3 8680.8 8760.9 4275.9 4359.0 4400.4 4655.9 4736.1 1731.3 1744.8 1861.2 1947.5 1975.4 872.9 910.7 991.0 1009.8 1037.6 18997.2 20606.9 20879.8 20891.5 20920.5 954.7 959.6 978.9 987.1 1012.1 10943.5 11147.8 11168.6 11170.7 11209.4 603266.0 611784.0 624456.0 628469.0 637487.0 111526.0 115892.3 118631.8 118895.0 124249.0 877.9 1006.4 1009.5 1028.3 1029.3 14863.6 15775.7 17860.0 18196.6 18568.4 50741.0 51018.7 51143.0 51896.0 53763.0 57398.7 74180.6 74470.3 74828.0 75295.0 7770.7 8117.8 8337.8 8474.2 8477.9 12124.8 12459.6 12533.3 12783.4 12806.4 10510.5 10941.3 10949.8 11153.3 11208.2 10624.2 10654.1 10831.4 10912.1 11012.6 9452.1 10650.9 11285.0 11365.6 11555.9 8117.4 8181.0 8226.3 8227.4 8458.2 8375.0 10950.5 11416.0 11528.0 11572.1 11:11:35 INFO - PROCESS | 1961 | 11:11:35 INFO - PROCESS | 1961 | [#3] mootools.html Cycles:180 Average:5700.22 Median:4966.60 stddev:3150.04 (63.4%) stddev-sans-first:3142.43 11:11:35 INFO - PROCESS | 1961 | Values: 1417.5 1542.7 1559.9 1792.3 1949.3 8014.1 8633.9 8794.9 8800.8 8819.6 8471.7 8713.3 8714.2 8721.1 8769.5 2175.5 2217.8 2226.4 2422.1 2449.2 2239.8 2429.0 2495.5 2495.7 2497.6 2410.3 2442.8 2445.7 2447.1 2449.8 2152.0 2440.5 2444.1 2449.7 2455.5 2375.1 2426.2 2429.8 2433.3 2434.8 8582.6 8627.2 8639.8 8644.6 8680.3 5199.7 5334.5 5348.0 5371.7 5392.2 3481.9 3556.4 3601.1 3616.0 3629.5 1029.7 1047.8 1050.0 1066.3 1084.5 9944.9 11994.0 12058.9 12094.3 12100.7 4788.9 4812.7 4873.3 4883.6 4939.4 10068.0 10097.0 10118.9 10124.1 10134.8 5524.3 5529.5 5533.2 5535.8 5557.2 4411.4 4584.9 4969.7 4973.9 5035.0 4025.5 4081.3 4083.2 4114.4 4165.6 1226.0 1318.3 1348.1 1361.0 1365.4 784.3 910.5 936.8 939.4 958.9 5800.8 5901.2 5911.8 5916.4 6084.3 969.9 974.5 1006.1 1006.4 1033.9 3822.1 3931.7 3953.3 4023.7 4039.8 10370.3 10711.4 10815.9 10839.6 10908.2 9377.0 10274.5 10610.0 10727.8 10963.7 8317.8 8397.8 8524.1 8552.7 8553.0 5622.4 5684.3 5712.7 5852.3 5862.1 9568.5 9570.0 9716.7 9854.7 9864.2 9523.4 10050.3 10069.5 10148.9 10270.7 6242.6 6459.9 7090.2 7112.5 7234.7 7039.5 7598.1 8202.9 8208.9 8221.2 7324.4 7386.9 7415.2 7504.0 7514.1 7301.5 7339.3 7381.4 7435.3 7446.8 6877.2 7008.2 7017.7 7017.8 7024.9 4862.4 4925.9 4938.7 5013.9 5060.1 4963.5 4998.4 5015.2 5048.8 5225.6 11:11:35 INFO - PROCESS | 1961 | 11:11:35 INFO - PROCESS | 1961 | [#4] prototype.html Cycles:180 Average:5624.21 Median:6413.87 stddev:1746.72 (27.2%) stddev-sans-first:1705.62 11:11:35 INFO - PROCESS | 1961 | Values: 318.8 322.8 326.6 326.9 330.2 5830.4 6633.2 6645.6 6650.2 6652.4 6536.0 6616.8 6623.7 6642.2 6651.0 4637.7 4760.9 4762.5 4763.1 4765.5 4740.8 4760.7 4787.5 4833.5 4865.9 4759.5 4835.3 4853.8 4860.8 4860.9 4818.3 4821.7 4821.7 4840.6 4861.0 3986.2 4050.6 4141.2 4143.7 4173.5 6226.3 6263.9 6295.0 6352.7 6367.6 5750.4 5768.1 5768.1 5770.8 5773.6 7026.3 7059.2 7068.3 7074.9 7077.7 4699.7 4709.4 4710.6 4715.2 4716.6 6161.5 7475.2 7483.5 7486.4 7489.4 4556.6 4643.7 4646.3 4650.8 4657.0 4713.8 4717.4 4723.9 4725.6 4731.2 5591.3 5593.7 5618.4 5620.9 5622.6 6884.3 7098.4 7117.6 7124.9 7129.6 1613.0 1617.4 1634.6 1636.5 1644.9 1022.2 1026.4 1040.8 1041.9 1041.9 6423.2 6620.8 6623.1 6626.3 6629.5 7458.4 7461.8 7468.0 7475.3 7489.6 6669.3 6696.5 6705.0 6708.3 6708.4 3197.7 3868.8 3912.3 3929.4 3938.8 5590.4 6515.7 7212.4 7739.1 7757.1 7022.7 7023.4 7089.3 7117.8 7130.9 6081.7 6976.5 7193.4 7203.6 7205.3 6905.0 6916.3 6932.3 6942.6 6954.2 6534.9 6780.9 6794.8 6805.2 6815.8 7162.4 7185.4 7207.9 7231.2 7251.9 4534.3 4579.4 4589.2 4589.8 4591.4 6798.4 6805.8 6819.2 6820.1 6835.2 6346.7 6567.1 6576.7 6589.1 6593.9 6532.5 6558.6 6571.3 6576.9 6612.3 5503.7 6180.9 6407.2 6419.2 6452.8 6560.1 6597.0 6597.3 6604.8 6606.0 6171.9 6210.2 6408.6 6422.8 6445.3 11:11:35 INFO - PROCESS | 1961 | 11:11:35 INFO - PROCESS | 1961 | [#5] yui.html Cycles:180 Average:17690.67 Median:599.75 stddev:24552.89 (4093.8%) stddev-sans-first:24588.58 11:11:35 INFO - PROCESS | 1961 | Values: 688.7 735.7 756.9 768.1 791.9 14991.8 15158.8 15204.7 15216.3 15286.1 13871.5 13918.7 13927.0 13932.9 15647.1 904.6 930.5 930.7 930.7 931.9 900.1 922.1 924.3 924.7 924.9 977.2 982.9 983.6 983.6 992.5 974.2 977.3 977.6 981.6 981.8 726.0 726.3 729.4 729.8 731.0 15192.0 15258.5 15261.8 15291.4 15315.5 12205.9 12564.8 12607.2 12674.0 12858.7 10010.7 10144.0 10157.1 10346.4 10390.3 3496.8 3515.4 3629.4 3662.1 3772.5 48270.0 64477.0 76236.0 76725.0 77169.8 9330.9 9369.1 9430.6 9441.7 9479.7 17995.6 20655.5 20655.8 20979.3 21237.0 6520.5 6992.4 7310.8 7436.0 7494.5 3723.0 4107.1 4109.5 4120.9 4134.3 3560.2 3603.1 3614.3 3639.0 3645.4 653.5 661.7 662.0 668.2 669.7 503.9 521.2 523.3 590.2 594.6 8154.1 8254.8 8256.2 8290.9 8299.0 502.8 535.8 538.4 604.9 609.3 3425.6 3744.5 3792.5 3881.5 3945.9 94510.5 96508.5 96854.0 97346.0 97692.5 52318.5 68336.7 71432.0 75837.0 76727.0 61595.4 62097.4 65169.7 66573.7 70383.6 7620.4 7692.0 8307.4 8338.4 8567.1 47602.8 48071.3 49580.3 49839.0 49980.8 66048.3 66231.7 67859.3 68787.0 72061.0 10898.6 11125.2 11385.3 11501.6 11544.4 11741.5 12021.7 12097.0 12102.3 12327.0 11059.5 11418.0 11680.8 11733.2 11745.9 11354.2 11543.4 11595.4 11891.5 12008.2 11350.4 11366.9 11592.2 11652.5 11715.1 9675.7 9802.3 9808.1 9833.0 10058.4 9424.2 9667.9 9743.1 9782.5 9826.2 11:11:35 INFO - PROCESS | 1961 | -------- Summary: end -------- 11:11:35 INFO - PROCESS | 1961 | 11:11:36 INFO - TEST-INFO | 1961: exit 0 11:11:36 INFO - TEST-OK | dromaeo_css | took 1115495ms 11:11:36 INFO - TEST-START | kraken 11:11:36 INFO - operating with platform_type : mac_ 11:11:36 INFO - Initialising browser for kraken test... 11:11:36 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpP3Rvr5/profile http://localhost:52354/getInfo.html) 11:11:38 INFO - PROCESS | 1965 | __metrics Screen width/height:1600/1200 11:11:38 INFO - PROCESS | 1965 | colorDepth:24 11:11:38 INFO - PROCESS | 1965 | Browser inner width/height: 1024/689 11:11:38 INFO - PROCESS | 1965 | __metrics 11:11:38 INFO - PROCESS | 1965 | 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" 11:11:38 ERROR - PROCESS | 1965 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:11:38 INFO - TEST-INFO | 1965: exit 0 11:11:38 INFO - Browser initialized. 11:11:38 INFO - Running cycle 1/1 for kraken test... 11:11:38 INFO - TEST-INFO | started process 1968 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpP3Rvr5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:11:51 INFO - PROCESS | 1968 | RSS: Main: 211304448 11:11:51 INFO - PROCESS | 1968 | 11:11:53 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52354/tests/kraken/driver.html?testName=audio-beat-detection) 11:11:54 INFO - PROCESS | 1968 | RSS: Main: 271458304 11:11:54 INFO - PROCESS | 1968 | 11:11:57 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52354/tests/kraken/driver.html?testName=audio-dft) 11:11:57 INFO - PROCESS | 1968 | RSS: Main: 285999104 11:11:57 INFO - PROCESS | 1968 | 11:12:01 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52354/tests/kraken/driver.html?testName=audio-fft) 11:12:01 INFO - PROCESS | 1968 | RSS: Main: 374030336 11:12:01 INFO - PROCESS | 1968 | 11:12:03 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52354/tests/kraken/driver.html?testName=audio-oscillator) 11:12:04 INFO - PROCESS | 1968 | RSS: Main: 278151168 11:12:04 INFO - PROCESS | 1968 | 11:12:06 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52354/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:12:07 INFO - PROCESS | 1968 | RSS: Main: 278892544 11:12:07 INFO - PROCESS | 1968 | 11:12:10 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52354/tests/kraken/driver.html?testName=imaging-darkroom) 11:12:10 INFO - PROCESS | 1968 | RSS: Main: 316948480 11:12:10 INFO - PROCESS | 1968 | 11:12:13 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52354/tests/kraken/driver.html?testName=imaging-desaturate) 11:12:14 INFO - PROCESS | 1968 | RSS: Main: 315838464 11:12:14 INFO - PROCESS | 1968 | 11:12:17 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52354/tests/kraken/driver.html?testName=json-parse-financial) 11:12:17 INFO - PROCESS | 1968 | RSS: Main: 318742528 11:12:17 INFO - PROCESS | 1968 | 11:12:19 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52354/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:12:20 INFO - PROCESS | 1968 | RSS: Main: 309108736 11:12:20 INFO - PROCESS | 1968 | 11:12:21 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52354/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:12:22 INFO - PROCESS | 1968 | RSS: Main: 315772928 11:12:22 INFO - PROCESS | 1968 | 11:12:24 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52354/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:12:25 INFO - PROCESS | 1968 | RSS: Main: 318398464 11:12:25 INFO - PROCESS | 1968 | 11:12:28 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52354/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:12:28 INFO - PROCESS | 1968 | RSS: Main: 320831488 11:12:28 INFO - PROCESS | 1968 | 11:12:31 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52354/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:12:32 INFO - PROCESS | 1968 | RSS: Main: 318578688 11:12:32 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:52354/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52354/tests/kraken/driver.html?testName=ai-astar) 11:12:34 INFO - PROCESS | 1968 | RSS: Main: 383672320 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | __start_tp_report 11:12:34 INFO - PROCESS | 1968 | _x_x_mozilla_page_load 11:12:34 INFO - PROCESS | 1968 | _x_x_mozilla_page_load_details 11:12:34 INFO - PROCESS | 1968 | |i|pagename|runs| 11:12:34 INFO - PROCESS | 1968 | |0;ai-astar;112;112;120;111;111;111;122;118;115;135 11:12:34 INFO - PROCESS | 1968 | |1;audio-beat-detection;158;120;189;192;149;141;118;151;157;120 11:12:34 INFO - PROCESS | 1968 | |2;audio-dft;187;187;186;186;188;186;191;262;203;185 11:12:34 INFO - PROCESS | 1968 | |3;audio-fft;70;80;78;158;57;59;60;67;140;56 11:12:34 INFO - PROCESS | 1968 | |4;audio-oscillator;90;88;144;76;80;83;157;116;113;156 11:12:34 INFO - PROCESS | 1968 | |5;imaging-gaussian-blur;88;86;89;86;88;85;89;87;88;85 11:12:34 INFO - PROCESS | 1968 | |6;imaging-darkroom;101;95;96;94;94;93;93;94;93;94 11:12:34 INFO - PROCESS | 1968 | |7;imaging-desaturate;87;96;136;144;86;83;84;85;86;84 11:12:34 INFO - PROCESS | 1968 | |8;json-parse-financial;61;64;65;63;64;64;64;80;60;57 11:12:34 INFO - PROCESS | 1968 | |9;json-stringify-tinderbox;63;66;67;68;78;75;71;67;67;69 11:12:34 INFO - PROCESS | 1968 | |10;stanford-crypto-aes;81;81;81;80;79;80;81;79;81;86 11:12:34 INFO - PROCESS | 1968 | |11;stanford-crypto-ccm;142;153;131;147;143;154;113;136;163;188 11:12:34 INFO - PROCESS | 1968 | |12;stanford-crypto-pbkdf2;166;168;167;164;167;165;166;165;163;161 11:12:34 INFO - PROCESS | 1968 | |13;stanford-crypto-sha256-iterative;67;67;68;69;67;69;67;66;66;68 11:12:34 INFO - PROCESS | 1968 | __end_tp_report 11:12:34 INFO - PROCESS | 1968 | __start_cc_report 11:12:34 INFO - PROCESS | 1968 | _x_x_mozilla_cycle_collect,2045 11:12:34 INFO - PROCESS | 1968 | __end_cc_report 11:12:34 INFO - PROCESS | 1968 | __startTimestamp1463508754341__endTimestamp 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | ------- Summary: start ------- 11:12:34 INFO - PROCESS | 1968 | Number of tests: 14 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#0] ai-astar Cycles:10 Average:116.70 Median:116.50 stddev:7.60 (6.5%) stddev-sans-first:7.87 11:12:34 INFO - PROCESS | 1968 | Values: 112.0 112.0 120.0 111.0 111.0 111.0 122.0 118.0 115.0 135.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#1] audio-beat-detection Cycles:10 Average:149.50 Median:154.00 stddev:26.46 (17.2%) stddev-sans-first:27.89 11:12:34 INFO - PROCESS | 1968 | Values: 158.0 120.0 189.0 192.0 149.0 141.0 118.0 151.0 157.0 120.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#2] audio-dft Cycles:10 Average:196.10 Median:187.50 stddev:23.75 (12.7%) stddev-sans-first:24.96 11:12:34 INFO - PROCESS | 1968 | Values: 187.0 187.0 186.0 186.0 188.0 186.0 191.0 262.0 203.0 185.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#3] audio-fft Cycles:10 Average:82.50 Median:63.50 stddev:36.27 (57.1%) stddev-sans-first:38.19 11:12:34 INFO - PROCESS | 1968 | Values: 70.0 80.0 78.0 158.0 57.0 59.0 60.0 67.0 140.0 56.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#4] audio-oscillator Cycles:10 Average:110.30 Median:78.00 stddev:31.96 (41.0%) stddev-sans-first:33.05 11:12:34 INFO - PROCESS | 1968 | Values: 90.0 88.0 144.0 76.0 80.0 83.0 157.0 116.0 113.0 156.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:88.00 stddev:1.52 (1.7%) stddev-sans-first:1.58 11:12:34 INFO - PROCESS | 1968 | Values: 88.0 86.0 89.0 86.0 88.0 85.0 89.0 87.0 88.0 85.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:94.00 stddev:2.41 (2.6%) stddev-sans-first:1.00 11:12:34 INFO - PROCESS | 1968 | Values: 101.0 95.0 96.0 94.0 94.0 93.0 93.0 94.0 93.0 94.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:85.50 stddev:22.98 (26.9%) stddev-sans-first:24.08 11:12:34 INFO - PROCESS | 1968 | Values: 87.0 96.0 136.0 144.0 86.0 83.0 84.0 85.0 86.0 84.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.00 stddev:6.07 (9.5%) stddev-sans-first:6.33 11:12:34 INFO - PROCESS | 1968 | Values: 61.0 64.0 65.0 63.0 64.0 64.0 64.0 80.0 60.0 57.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:68.50 stddev:4.46 (6.5%) stddev-sans-first:4.15 11:12:34 INFO - PROCESS | 1968 | Values: 63.0 66.0 67.0 68.0 78.0 75.0 71.0 67.0 67.0 69.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:1.97 (2.4%) stddev-sans-first:2.09 11:12:34 INFO - PROCESS | 1968 | Values: 81.0 81.0 81.0 80.0 79.0 80.0 81.0 79.0 81.0 86.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:150.00 stddev:19.99 (13.3%) stddev-sans-first:21.12 11:12:34 INFO - PROCESS | 1968 | Values: 142.0 153.0 131.0 147.0 143.0 154.0 113.0 136.0 163.0 188.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:166.00 stddev:2.10 (1.3%) stddev-sans-first:2.20 11:12:34 INFO - PROCESS | 1968 | Values: 166.0 168.0 167.0 164.0 167.0 165.0 166.0 165.0 163.0 161.0 11:12:34 INFO - PROCESS | 1968 | 11:12:34 INFO - PROCESS | 1968 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.07 (1.6%) stddev-sans-first:1.13 11:12:34 INFO - PROCESS | 1968 | Values: 67.0 67.0 68.0 69.0 67.0 69.0 67.0 66.0 66.0 68.0 11:12:34 INFO - PROCESS | 1968 | -------- Summary: end -------- 11:12:34 INFO - PROCESS | 1968 | 11:12:35 INFO - TEST-INFO | 1968: exit 0 11:12:35 INFO - TEST-OK | kraken | took 59071ms 11:12:35 INFO - SUITE-END | took 1174s 11:12:35 INFO - Completed test suite (00:19:34) 11:12:35 INFO - javascript benchmark 11:12:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.7168890644293, 3410.5736782902136, 3411.505331726901, 3411.8635254589817, 3463.7055850252727, 5596.992541342562, 5598.888022239553, 5607.523360514361, 5623.184638227454, 5623.746931388092, 5574.968630803304, 5631.971840140794, 5633.549316054716, 5641.323041235048, 5645.15322701595, 5031.723255220962, 5047.520485553867, 5079.466656001122, 5085.008423357447, 5119.206522988943, 5132.743362831862, 5139.897202055975, 5150.587952963772, 5155.432902380735, 5158.43257241705, 5063.518965698253, 5080.390353157626, 5130.9994551062555, 5132.23016547519, 5167.147420675584, 5113.872153196163, 5132.512411320956, 5135.037058762127, 5138.100832354323, 5171.534561889448, 4886.633502487328, 4998.850034498971, 5029.044481548531, 5052.216906379517, 5053.671511351788, 5463.836084917459, 5485.204645326406, 5539.390667026624, 5548.0, 5551.056320425538, 6022.759089636409, 6038.547108966845, 6041.335453100155, 6043.848903777441, 6045.576809623328, 6005.459508644243, 6061.424164704346, 6106.938930610681, 6115.392460733998, 6116.235470566179, 5721.313442386857, 5739.024365857731, 5742.368339482654, 5748.281464816898, 5766.884662306772, 6214.223222097238, 6268.561200715997, 6273.0, 6281.685915704288, 6292.622442653455, 5247.9737601311745, 5754.338251101093, 6070.0591408331775, 6078.392160783975, 6085.391460853879, 5900.055991041499, 6021.518278537794, 6042.39576042401, 6054.243219597551, 6058.4244496772735, 6117.84705382369, 6131.724072416752, 6145.8156255312415, 6160.9383906160365, 6167.167432396659, 5913.201717768132, 6133.233345831771, 6173.969130154321, 6178.938210617836, 6181.598196117238, 4827.961988172489, 4843.636727245471, 4850.53919877611, 4861.708297502161, 4862.635302352338, 4315.546867578922, 4420.955790442119, 4424.84513042042, 4439.822407103679, 4456.799444025027, 5575.665460072409, 5610.551155907599, 5613.46672066153, 5630.74661640219, 5645.322561292671, 6124.724387402578, 6203.410675985774, 6215.409536094064, 6216.191895053705, 6216.316205217422, 5486.0, 5582.190582365534, 5583.944160558423, 5615.017371959891, 5620.3255609326325, 5278.683279003272, 5288.180332048539, 5302.734863256822, 5304.257403963448, 5309.946900530946, 6247.718852651551, 6259.092431597484, 6271.059341098871, 6277.7175027123885, 6294.402031806971, 6003.549733770072, 6037.45662890342, 6136.5090792737365, 6192.380761923771, 6199.039148931923, 5164.922526162186, 6257.217847769029, 6257.78097766576, 6259.467945224706, 6271.090691849748, 5908.143319218775, 6039.75840966374, 6061.7575296987625, 6067.059605761114, 6078.3921607838865, 5917.082852157922, 6031.09533569968, 6049.0926361046195, 6140.478059365004, 6153.569250152446, 4647.86056418308, 5958.225430693982, 5975.880482390241, 6001.339852616122, 6019.638821670714, 5933.406659334205, 5946.910796337972, 5979.940200598112, 5985.760569577168, 6002.609830361012, 5654.886902262014, 5750.482456578928, 5820.73806678692, 5821.243238378984, 5842.853928651648, 5998.580099393001, 6016.639001659914, 6023.27720673522, 6028.90956635642, 6045.6070355426755, 5833.183354330312, 5853.64878107315, 5866.765329386947, 5877.970610146922, 5897.469227769521], "value": 5589.800528787936, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.7283653245512, 520.1962967215652, 522.7908836465411, 544.2081771023161, 655.8588056781199, 30590.0, 31344.52983205251, 31407.0, 31409.685903140962, 31512.36975260499, 32124.67875321246, 38045.0, 39003.60996390035, 39099.0, 39257.21485570287, 9518.57166427509, 9817.312788104835, 9835.0, 9852.901470985305, 9859.704208873709, 8648.567571621425, 9630.374025688343, 9765.707028789113, 9770.120689137977, 9775.364601300927, 8730.345224108185, 11640.06879449642, 11825.40872956353, 11826.467808948575, 11870.99096576792, 10122.746931326703, 11161.609343672973, 11539.942300288445, 11543.307401555849, 11561.364124973123, 8562.52906090171, 11333.716657083556, 11495.080393568494, 11507.712307192303, 11790.823137652857, 38186.42720359197, 38637.42043869344, 38666.806665966775, 38685.419718704245, 38868.0, 3301.339732053575, 3304.240024794287, 3436.7937923724407, 3513.9297214055578, 3758.4550240215017, 27245.318867028283, 27633.309167270778, 27712.445751085066, 27827.860860695568, 28038.439231215263, 2738.52075886719, 2760.6963234044238, 2782.373965857686, 2798.6361772969385, 2841.275474753945, 488369.0, 488889.0, 489227.99999999645, 490397.0, 491810.0, 60319.0, 60810.78378432495, 60819.69590152021, 60848.69575652182, 61052.38947610467, 64216.0, 64430.0, 64457.03314450287, 64577.354226458076, 64635.06094817163, 16286.755698664529, 16782.496525104267, 16783.909045911976, 16802.99182049081, 16804.495865124067, 13080.018998575146, 13160.60518184456, 13171.934140329236, 13182.604521864168, 13198.538051168163, 13858.722825543635, 14236.715265694835, 15550.533483995498, 15669.216539173223, 15916.363345466052, 6165.32181460039, 6734.215432910282, 6859.142607174103, 6974.4420446364165, 6975.0, 932.8647346134773, 955.3790036476207, 966.246327864267, 1033.957227241482, 1159.96520104397, 29081.563776543368, 29640.0, 29810.55284170739, 30082.84958575237, 30096.84951575228, 1104.8563686720674, 1159.7970355187808, 1189.7501524679742, 1217.993910030444, 1263.4882872436679, 15172.468963586442, 15260.694786104437, 15324.6001735988, 15385.999910006038, 15454.072755634474, 605279.0, 616144.0, 616279.0, 616361.0, 616886.0, 51362.74318628383, 52030.21954670683, 63568.68215658892, 64897.351026490076, 64989.350106499274, 1520.5514373259693, 1526.9007514511743, 1536.4238410596026, 1536.5159974607964, 1539.8460153984736, 28734.0, 29913.0, 29941.101766947027, 30353.392932141676, 30626.234344141576, 48745.268820967, 48970.51029489802, 49432.50567494279, 49564.50435495743, 49573.0, 493220.0, 495539.0, 496171.0, 497066.0, 498081.0, 36253.45619815652, 37681.0, 37699.0, 37703.81148094242, 38117.23765524729, 45072.323915140645, 45783.54216457793, 45884.770576146904, 47351.0, 47964.040719186116, 34910.301793964485, 35597.28805423929, 37383.626163738016, 37491.43762843614, 38119.04702382463, 36205.0948726284, 36480.72300548897, 36615.81692091629, 36724.08189795277, 37417.0, 29168.27079323034, 29489.26276843096, 37250.0, 37638.435423469215, 37667.43498847574, 30841.22896927505, 31595.052148435585, 31631.73473061144, 31648.208794780316, 31744.523832142073, 23587.52824943457, 23903.52192956166, 24342.51314973655, 24362.51274974455, 24431.26706198817], "value": 19817.20584405505, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.49128658883654, 395.42663138449245, 396.0929471510242, 399.3271337795814, 411.0381706806075, 13515.91872650188, 15072.6985460291, 16894.57763555912, 20289.391318260456, 20295.39113826586, 20011.399658010247, 20353.49116272094, 20696.275630352942, 21211.469713257175, 21280.14879404826, 5583.274174357348, 5784.103463963082, 5926.614770039933, 5978.940210597903, 6110.236220472441, 3949.881503554898, 5507.9449205508035, 5873.676947767871, 5881.2354393928945, 5922.407759224065, 5865.061590145578, 5868.6478811271245, 5877.2065771121, 5877.412258774109, 5884.146798714184, 5759.510441612468, 5833.970830145843, 5863.5016023638045, 6659.86680266392, 6687.933120668828, 5929.288485381739, 5956.285245770535, 5962.403759624003, 5972.343042265347, 5975.342712301644, 10451.0, 20199.8990005049, 26000.73999260002, 26373.945042198287, 26380.20859374222, 1986.860919735619, 2139.7432308123125, 2197.9560408791735, 2350.6474028895805, 2712.51174788538, 9568.473733944631, 9595.952020239854, 9826.263030272758, 9982.101610854985, 10253.538590763434, 1719.6904557179703, 1792.6952418088827, 1813.0662708705026, 1819.263198404635, 1906.9427917162507, 100985.0, 107517.46241268821, 113140.86859131386, 113457.0, 116573.41713291379, 9917.256205784595, 11942.462589183508, 11954.880451195551, 11956.76086478283, 11988.0, 13781.724365512633, 14218.928905355408, 15388.615284617974, 15409.614759630875, 15443.0, 7795.688172473151, 8203.58982050895, 8776.60505277264, 8891.866622000674, 8920.73237802867, 7198.92801071993, 7740.690372385042, 8177.304929081095, 8680.826383472422, 8760.904886889139, 4275.935860962088, 4358.975640724428, 4400.361947517656, 4655.86032419028, 4736.100140973273, 1731.2728653965114, 1744.756023055027, 1861.1717785585556, 1947.5033866363985, 1975.4097951149463, 872.9476231426007, 910.740438974889, 990.9504524773732, 1009.7627057641448, 1037.5745944162857, 18997.24011039543, 20606.866622335616, 20879.791202088087, 20891.477713056993, 20920.47698807542, 954.708813811794, 959.5729900194486, 978.9265805064649, 987.0573602209872, 1012.1497941728983, 10943.45282735876, 11147.777044459066, 11168.609098681507, 11170.720731981766, 11209.383483908463, 603266.0, 611784.0, 624456.0, 628469.0, 637487.0, 111526.0, 115892.26161607583, 118631.8136818638, 118895.0, 124249.0, 877.8507653699014, 1006.4213077480478, 1009.4952523738131, 1028.275066078413, 1029.330934892311, 14863.554093377215, 15775.659068979265, 17860.017699026677, 18196.636067278316, 18568.44294671162, 50741.0, 51018.72453188551, 51143.0, 51896.0, 53763.0, 57398.7130064347, 74180.62909685417, 74470.2552974485, 74828.0, 75295.0, 7770.689172433039, 8117.756467305991, 8337.791555211168, 8474.1949514796, 8477.915220847712, 12124.818127728267, 12459.563915262923, 12533.310667913353, 12783.424745886272, 12806.35968201605, 10510.527026283988, 10941.343519388862, 10949.83575246356, 11153.330800152016, 11208.159388045766, 10624.150067994697, 10654.147668186371, 10831.404272764757, 10912.12325775317, 11012.614558490415, 9452.10205030521, 10650.8934910653, 11285.040771534183, 11365.602203922823, 11555.942220288845, 8117.431779775359, 8181.0, 8226.259636632492, 8227.382946279173, 8458.154184581492, 8375.0, 10950.502849563925, 11416.029637480575, 11528.0, 11572.132090093108], "value": 9402.64550303116, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.4755340524007, 1542.714597799407, 1559.9376024959, 1792.2651712797747, 1949.2787668562635, 8014.078380986181, 8633.913660863398, 8794.91205087949, 8800.823983520326, 8819.559022048905, 8471.745847624581, 8713.259372953307, 8714.215720585162, 8721.127887211276, 8769.517676527805, 2175.4778853075227, 2217.800397964183, 2226.3636227372867, 2422.1280339077916, 2449.2040086971715, 2239.8320125990535, 2429.0405289910473, 2495.4510007798344, 2495.70051593809, 2497.625356196567, 2410.3251089694913, 2442.7679370459778, 2445.6942882139733, 2447.1190371466346, 2449.8040156787415, 2151.967720484194, 2440.45089854783, 2444.083468699238, 2449.742777008415, 2455.54572404104, 2375.133076427109, 2426.2236084452984, 2429.781319681237, 2433.2943446400523, 2434.8417352872184, 8582.613782379807, 8627.22354988054, 8639.827203455958, 8644.567771611395, 8680.262177714838, 5199.688018718889, 5334.466553344673, 5348.0, 5371.731413429313, 5392.164214546751, 3481.9129521762156, 3556.448750443685, 3601.135727425425, 3616.0, 3629.4555816627453, 1029.6756521695643, 1047.7747284333905, 1049.9580016799318, 1066.344198318029, 1084.5228099636213, 9944.850827237597, 11994.040476761838, 12058.879411205864, 12094.334811585359, 12100.697482563006, 4788.928166077511, 4812.687175333592, 4873.31773551703, 4883.633727470454, 4939.382577177853, 10068.043535864228, 10097.040781125928, 10118.898811011943, 10124.13944814699, 10134.797304054026, 5524.254225679482, 5529.502344788988, 5533.22534845122, 5535.778568857281, 5557.249771280825, 4411.426514553087, 4584.908301833944, 4969.676970996874, 4973.925391119136, 5035.043341765053, 4025.5370632377067, 4081.3265811141423, 4083.1629515949157, 4114.444549985774, 4165.625093741517, 1225.9579357546193, 1318.2815365625756, 1348.0967751606447, 1360.9721514942664, 1365.3855764905832, 784.2862994674819, 910.4628269321132, 936.765808547863, 939.3847030195233, 958.8561715742555, 5800.767969281182, 5901.203337549376, 5911.7635294587735, 5916.408359164049, 6084.269887613426, 969.8836139663285, 974.498133461268, 1006.1397505133152, 1006.3609607899036, 1033.896610338975, 3822.140018495872, 3931.7444366116683, 3953.2686453006286, 4023.7384570002855, 4039.8384064637085, 10370.27408081442, 10711.410872402093, 10815.945920270506, 10839.566417343218, 10908.236423450282, 9376.95311523438, 10274.486275686186, 10610.045095941248, 10727.83908241377, 10963.671089867317, 8317.792055198668, 8397.790055248546, 8524.06235314115, 8552.743417697604, 8553.0, 5622.409646987171, 5684.261046063985, 5712.685802280915, 5852.268466441695, 5862.091375836752, 9568.4737339447, 9570.0, 9716.659916902874, 9854.655087071918, 9864.21086313079, 9523.428594284365, 10050.346727462691, 10069.496525173565, 10148.898511014795, 10270.743231419274, 6242.5942313749465, 6459.935400645933, 7090.184628767758, 7112.537685050455, 7234.74678386254, 7039.472039596948, 7598.12621548529, 8202.917970820454, 8208.876866847122, 8221.17788221197, 7324.4140468763435, 7386.926130738624, 7415.184329723834, 7503.962480187564, 7514.0607424071995, 7301.525400848928, 7339.302766237199, 7381.372583330262, 7435.256474352522, 7446.813829654302, 6877.243503214542, 7008.194057683431, 7017.684204210721, 7017.824554386181, 7024.859502810017, 4862.3922009748785, 4925.876853078702, 4938.654294199515, 5013.899722005613, 5060.114479965918, 4963.528464795839, 4998.400191976841, 5015.22264049073, 5048.7728052237, 5225.608079393985], "value": 4613.267069484074, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.76889255289916, 322.8305139801604, 326.6390638344629, 326.86414708886605, 330.2420943933673, 5830.387809280023, 6633.204015518145, 6645.634490103049, 6650.168728908887, 6652.401283884449, 6535.967320163404, 6616.801495955129, 6623.668816559177, 6642.202935647729, 6651.0, 4637.652176086798, 4760.85717428476, 4762.499937506564, 4763.14263432582, 4765.451973023095, 4740.834070807522, 4760.714357138566, 4787.49731278216, 4833.468318484982, 4865.902681946377, 4759.547842954931, 4835.298881662157, 4853.781579828898, 4860.829870954517, 4860.878478038059, 4818.34952281441, 4821.662483626147, 4821.686590371614, 4840.588549973257, 4860.951390486085, 3986.2426139033782, 4050.6151915567975, 4141.171765646883, 4143.70994030418, 4173.499180098377, 6226.315105338409, 6263.937360626381, 6294.968525157391, 6352.6823658816875, 6367.6497792621385, 5750.424957504258, 5768.077107662796, 5768.134779783024, 5770.79802206925, 5773.624714393593, 7026.332498412643, 7059.188193357727, 7068.3285087916565, 7074.858502829966, 7077.681504332318, 4699.67102302839, 4709.434867815884, 4710.599599034086, 4715.245560710304, 4716.551927566876, 6161.459635091227, 7475.215102414277, 7483.513571617827, 7486.363659088984, 7489.400847932152, 4556.567126123013, 4643.721376717408, 4646.3495110684535, 4650.790714417859, 4656.952185758245, 4713.787879545429, 4717.43390793107, 4723.929141062887, 4725.5510726480925, 4731.1720448921305, 5591.329040515164, 5593.664380137205, 5618.353889302701, 5620.91568626471, 5622.57830662702, 6884.345987131314, 7098.36114749675, 7117.6085315308155, 7124.8575028499135, 7129.607871567114, 1612.9677406451806, 1617.4258138360947, 1634.648550561635, 1636.5172274178908, 1644.925978330954, 1022.1771473963382, 1026.440589878518, 1040.7918416316768, 1041.890601486833, 1041.9166466682648, 6423.229212494531, 6620.801375958729, 6623.105880706139, 6626.271110177877, 6629.5359324846795, 7458.4033277337685, 7461.776146715607, 7467.962660186664, 7475.327220550176, 7489.550626962399, 6669.333066693292, 6696.46428285736, 6705.0, 6708.2956289589865, 6708.396244338033, 3197.7441804655573, 3868.806559672061, 3912.315344814703, 3929.3713005918917, 3938.8424463021734, 5590.385057643656, 6515.6742162892615, 7212.350888420067, 7739.148693643695, 7757.146713861471, 7022.719091236396, 7023.367896889305, 7089.326513981164, 7117.786466406016, 7130.857382852314, 6081.695915204311, 6976.476764242702, 7193.352598266077, 7203.603801790847, 7205.315495027964, 6905.033295338556, 6916.3429474199875, 6932.341427564374, 6942.583444993316, 6954.165500140016, 6534.934650653433, 6780.898286525608, 6794.76218332356, 6805.217399999064, 6815.829604259933, 7162.391196748126, 7185.425165986604, 7207.8558428832175, 7231.168415632059, 7251.854962900818, 4534.342520334599, 4579.404677391918, 4589.196890544208, 4589.839355622537, 4591.403117594691, 6798.368391586083, 6805.761798337035, 6819.215790183994, 6820.113385259884, 6835.213950395768, 6346.682665866781, 6567.146270984933, 6576.736930522726, 6589.0775291460195, 6593.868122637425, 6532.510061745293, 6558.639274839929, 6571.3100124488155, 6576.934230657632, 6612.272650008592, 5503.724813759216, 6180.949238629354, 6407.231132264158, 6419.165508483868, 6452.806415807533, 6560.147180866456, 6597.010448432773, 6597.340265973365, 6604.801855944329, 6605.966970165311, 6171.938280617136, 6210.161628180319, 6408.58344207625, 6422.807315780534, 6445.2587952386075], "value": 5038.754499924483, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.6659969914592, 735.6873328835244, 756.9318761311478, 768.0898135709182, 791.8930944322517, 14991.775123373158, 15158.84841151588, 15204.695906081886, 15216.315265813037, 15286.082835029905, 13871.514496992608, 13918.721625567483, 13927.0, 13932.930335348334, 15647.139407332621, 904.6381447421035, 930.4789317981918, 930.6649606141783, 930.6882194464862, 931.948742819148, 900.0504467786483, 922.1055576091181, 924.2559739409772, 924.7179610218881, 924.9260059195249, 977.2035790830486, 982.9361091529064, 983.5623147699276, 983.6360546597733, 992.4839083676484, 974.1962880623479, 977.3451787302489, 977.5845265762023, 981.6024510073361, 981.842905135175, 725.9818504537376, 726.3172617739308, 729.3946024799404, 729.7883613752006, 731.0313834169747, 15192.0, 15258.542243732705, 15261.771073433907, 15291.388344466208, 15315.540533784004, 12205.93897030518, 12564.811527826998, 12607.243565386014, 12674.0, 12858.742825143538, 10010.699679009642, 10144.0, 10157.136643385322, 10346.379217246915, 10390.324628899098, 3496.8426420811124, 3515.402381595135, 3629.364861149288, 3662.08447888028, 3772.4529943158086, 48270.0, 64477.0, 76236.0, 76724.99999999945, 77169.84245236326, 9330.906690933003, 9369.109934556207, 9430.575624096931, 9441.716748497556, 9479.715608531755, 17995.550111247325, 20655.483612909797, 20655.79344206569, 20979.265725699526, 21237.0, 6520.510961677893, 6992.440604751608, 7310.780676579711, 7436.0333156689285, 7494.5128566643, 3723.0, 4107.116969851496, 4109.465769450012, 4120.896977575585, 4134.338505839051, 3560.1989552350515, 3603.0632035670915, 3614.3494171048937, 3638.9636103638622, 3645.3985092459448, 653.539254825347, 661.6890061671007, 661.9735210591523, 668.238208442376, 669.6752075243503, 503.8614381045169, 521.1557277210943, 523.2726510150893, 590.2032256453753, 594.5540844366725, 8154.14381489935, 8254.759116263427, 8256.174382561816, 8290.917090829014, 8298.958505207434, 502.8441183233209, 535.7696190638063, 538.3862396867574, 604.9493157252232, 609.2780055634088, 3425.5889293284467, 3744.494493243432, 3792.4500947362476, 3881.514810648669, 3945.881623551298, 94510.5274473637, 96508.51745741366, 96854.0, 97346.0, 97692.51153744328, 52318.47681523136, 68336.6583167081, 71432.0, 75837.0, 76727.0, 61595.38404615986, 62097.37902621007, 65169.674151629886, 66573.66852663016, 70383.64808175927, 7620.3522700571075, 7692.0, 8307.418480706534, 8338.37462190326, 8567.143285671384, 47602.76198618985, 48071.278930815366, 49580.25629615631, 49839.0, 49980.750096249285, 66048.33951660422, 66231.66884165548, 67859.3214067853, 68787.0, 72061.0, 10898.564057437614, 11125.165612578927, 11385.316880987168, 11501.597444089417, 11544.422778861192, 11741.47163377633, 12021.699457513632, 12097.0, 12102.334371609646, 12327.0, 11059.50232239535, 11418.029467495357, 11680.824787628362, 11733.178677492493, 11745.882541174478, 11354.205205635526, 11543.422828858691, 11595.420228988685, 11891.524339026342, 12008.159428839897, 11350.375729334966, 11366.943165284121, 11592.246503977214, 11652.53389864396, 11715.121365897763, 9675.689631203724, 9802.313838031268, 9808.117269445784, 9833.0, 10058.396496210251, 9424.151826335663, 9667.854982175413, 9743.058207408058, 9782.461964592016, 9826.213902887896], "value": 6877.46310788714, "unit": "score"}], "name": "dromaeo_css", "value": 7417.33344442492, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 120.0, 111.0, 111.0, 111.0, 122.0, 118.0, 115.0, 135.0], "value": 116.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 120.0, 189.0, 192.0, 149.0, 141.0, 118.0, 151.0, 157.0, 120.0], "value": 149.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 186.0, 186.0, 188.0, 186.0, 191.0, 262.0, 203.0, 185.0], "value": 196.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 80.0, 78.0, 158.0, 57.0, 59.0, 60.0, 67.0, 140.0, 56.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 144.0, 76.0, 80.0, 83.0, 157.0, 116.0, 113.0, 156.0], "value": 110.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 88.0, 85.0, 89.0, 87.0, 88.0, 85.0], "value": 87.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 95.0, 96.0, 94.0, 94.0, 93.0, 93.0, 94.0, 93.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 96.0, 136.0, 144.0, 86.0, 83.0, 84.0, 85.0, 86.0, 84.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 65.0, 63.0, 64.0, 64.0, 64.0, 80.0, 60.0, 57.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 67.0, 68.0, 78.0, 75.0, 71.0, 67.0, 67.0, 69.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 80.0, 79.0, 80.0, 81.0, 79.0, 81.0, 86.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 153.0, 131.0, 147.0, 143.0, 154.0, 113.0, 136.0, 163.0, 188.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 167.0, 164.0, 167.0, 165.0, 166.0, 165.0, 163.0, 161.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 69.0, 67.0, 69.0, 67.0, 66.0, 66.0, 68.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1527.8000000000002, "alertThreshold": 2.0}]} 11:12:35 INFO - Return code: 0 11:12:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:12:35 ERROR - # TBPL SUCCESS # 11:12:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:12:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:12:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:12:35 INFO - 2016-05-17 11:12:35.679 screenresolution[1972:20028] starting screenresolution argv=screenresolution get 11:12:35 INFO - 2016-05-17 11:12:35.682 screenresolution[1972:20028] Display 0: 1600x1200x32@60 11:12:35 INFO - 2016-05-17 11:12:35.688 screenresolution[1973:20031] starting screenresolution argv=screenresolution list 11:12:35 INFO - Available Modes on Display 0 11:12:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:12:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:12:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:12:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:12:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:12:35 INFO - Intel Iris: 11:12:35 INFO - Chipset Model: Intel Iris 11:12:35 INFO - Type: GPU 11:12:35 INFO - Bus: Built-In 11:12:35 INFO - VRAM (Dynamic, Max): 1536 MB 11:12:35 INFO - Vendor: Intel (0x8086) 11:12:35 INFO - Device ID: 0x0a2e 11:12:35 INFO - Revision ID: 0x0009 11:12:35 INFO - Displays: 11:12:35 INFO - Display: 11:12:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:12:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:12:35 INFO - Display Serial Number: 1600x1200 60 11:12:35 INFO - Main Display: Yes 11:12:35 INFO - Mirror: Off 11:12:35 INFO - Online: Yes 11:12:35 INFO - Rotation: Supported 11:12:35 INFO - Return code: 0 11:12:35 INFO - Running post-action listener: _resource_record_post_action 11:12:35 INFO - Running post-run listener: _resource_record_post_run 11:12:36 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 638976; Write bytes: 654029824; Read time: 10; Write time: 1802 11:12:36 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 354169344; Write bytes: 355878400; Read time: 27639; Write time: 670 11:12:36 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 638976; Write bytes: 297291264; Read time: 10; Write time: 1129 11:12:36 INFO - Running post-run listener: _upload_blobber_files 11:12:36 INFO - Blob upload gear active. 11:12:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:12:36 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 11:12:36 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'] 11:12:36 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 11:12:37 INFO - (blobuploader) - INFO - Open directory for files ... 11:12:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:12:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:12:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:12:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:12:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:12:38 INFO - (blobuploader) - INFO - Done attempting. 11:12:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:12:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:12:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:12:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:12:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:12:39 INFO - (blobuploader) - INFO - Done attempting. 11:12:39 INFO - (blobuploader) - INFO - Iteration through files over. 11:12:39 INFO - Return code: 0 11:12:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:12:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:12:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/356df6e53cea3df4eb2640b93269458536e73ff65cf2920b7833dc0f1a17a3f4e7ca58908a7e2d0e7f1c7a0e059ca1b48d0d05fd10378e252f1131cbdabf75fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33b52b28b3c63f8491735b745e5796eeab5fd1640881bfdc1b1ae84c68a93b72b55a99efd310c8f003a6485c0f46952a2306a880eb5a21b3b22c60761fdc29c4"} 11:12:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:12:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:12:39 INFO - Contents: 11:12:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/356df6e53cea3df4eb2640b93269458536e73ff65cf2920b7833dc0f1a17a3f4e7ca58908a7e2d0e7f1c7a0e059ca1b48d0d05fd10378e252f1131cbdabf75fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33b52b28b3c63f8491735b745e5796eeab5fd1640881bfdc1b1ae84c68a93b72b55a99efd310c8f003a6485c0f46952a2306a880eb5a21b3b22c60761fdc29c4"} 11:12:39 INFO - Running post-run listener: copy_logs_to_upload_dir 11:12:39 INFO - Copying logs to upload dir... 11:12:39 INFO - mkdir: /builds/slave/test/build/upload/logs 11:12:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.024682 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-17 11:12:39.617351) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 11:12:39.618134) ========= 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.MevBT0d1aU/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.XftzkWknSV/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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/356df6e53cea3df4eb2640b93269458536e73ff65cf2920b7833dc0f1a17a3f4e7ca58908a7e2d0e7f1c7a0e059ca1b48d0d05fd10378e252f1131cbdabf75fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33b52b28b3c63f8491735b745e5796eeab5fd1640881bfdc1b1ae84c68a93b72b55a99efd310c8f003a6485c0f46952a2306a880eb5a21b3b22c60761fdc29c4"} build_url:https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011101 build_url: 'https://queue.taskcluster.net/v1/task/DXX_clYaTMGMU4sb9zK0Dg/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/356df6e53cea3df4eb2640b93269458536e73ff65cf2920b7833dc0f1a17a3f4e7ca58908a7e2d0e7f1c7a0e059ca1b48d0d05fd10378e252f1131cbdabf75fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33b52b28b3c63f8491735b745e5796eeab5fd1640881bfdc1b1ae84c68a93b72b55a99efd310c8f003a6485c0f46952a2306a880eb5a21b3b22c60761fdc29c4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 11:12:39.653587) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 11:12:39.653910) ========= 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.MevBT0d1aU/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.XftzkWknSV/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.005266 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 11:12:39.702267) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 11:12:39.702584) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 11:12:39.703028) ========= ========= Total master_lag: 0.17 =========