builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0081 starttime: 1461753572.14 results: success (0) buildid: 20160425062348 builduid: abe90fc74b164da98c10377025c22763 revision: 8551b253f4061db31e4be7829c2f70c2610caf42 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 03:39:32.141488) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 03:39:32.141937) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 03:39:32.142224) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 03:39:32.388042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:39:32.391313) ========= 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.vjNI71fmam/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.gc16BxJFau/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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-04-27 03:39:32-- 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% 18.6M=0.001s 2016-04-27 03:39:32 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109424 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:39:32.529424) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:39:32.529690) ========= 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.vjNI71fmam/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.gc16BxJFau/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.027107 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:39:32.587338) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 03:39:32.587636) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8551b253f4061db31e4be7829c2f70c2610caf42 --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 8551b253f4061db31e4be7829c2f70c2610caf42 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vjNI71fmam/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.gc16BxJFau/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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-04-27 03:39:32,672 truncating revision to first 12 chars 2016-04-27 03:39:32,672 Setting DEBUG logging. 2016-04-27 03:39:32,672 attempt 1/10 2016-04-27 03:39:32,672 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8551b253f406?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 03:39:34,070 unpacking tar archive at: mozilla-aurora-8551b253f406/testing/mozharness/ program finished with exit code 0 elapsedTime=1.649568 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 03:39:34.257798) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 03:39:34.261156) ========= script_repo_revision: 8551b253f4061db31e4be7829c2f70c2610caf42 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 03:39:34.261570) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 03:39:34.261833) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 03:39:34.279899) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-04-27 03:39:34.280230) ========= /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.vjNI71fmam/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.gc16BxJFau/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:39:34 INFO - MultiFileLogger online at 20160427 03:39:34 in /builds/slave/test 03:39:34 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 03:39:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:39:34 INFO - {'append_to_log': False, 03:39:34 INFO - 'base_work_dir': '/builds/slave/test', 03:39:34 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:39:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:39:34 INFO - 'branch': 'Mozilla-Aurora', 03:39:34 INFO - 'buildbot_json_path': 'buildprops.json', 03:39:34 INFO - 'config_files': ('talos/mac_config.py',), 03:39:34 INFO - 'default_actions': ('clobber', 03:39:34 INFO - 'read-buildbot-config', 03:39:34 INFO - 'download-and-extract', 03:39:34 INFO - 'populate-webroot', 03:39:34 INFO - 'create-virtualenv', 03:39:34 INFO - 'install', 03:39:34 INFO - 'run-tests'), 03:39:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:39:34 INFO - 'download_minidump_stackwalk': True, 03:39:34 INFO - 'download_symbols': 'ondemand', 03:39:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:39:34 INFO - 'tooltool.py': '/tools/tooltool.py', 03:39:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:39:34 INFO - '/tools/misc-python/virtualenv.py')}, 03:39:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:39:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:39:34 INFO - 'installer_path': 'installer.exe', 03:39:34 INFO - 'log_level': 'info', 03:39:34 INFO - 'log_name': 'talos', 03:39:34 INFO - 'log_to_console': True, 03:39:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:39:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:39:34 INFO - 'opt_config_files': (), 03:39:34 INFO - 'pip_index': False, 03:39:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:39:34 INFO - 'cmd': ('bash', 03:39:34 INFO - '-c', 03:39:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:39:34 INFO - 'enabled': True, 03:39:34 INFO - 'halt_on_failure': False, 03:39:34 INFO - 'name': 'check_screen_resolution'},), 03:39:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:39:34 INFO - 'cmd': ('bash', 03:39:34 INFO - '-c', 03:39:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:39:34 INFO - 'enabled': True, 03:39:34 INFO - 'halt_on_failure': False, 03:39:34 INFO - 'name': 'check_screen_resolution'},), 03:39:34 INFO - 'run_cmd_checks_enabled': True, 03:39:34 INFO - 'sps_profile': False, 03:39:34 INFO - 'sps_profile_interval': 0, 03:39:34 INFO - 'suite': 'dromaeojs', 03:39:34 INFO - 'system_bits': '32', 03:39:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:39:34 INFO - 'title': 't-yosemite-r7-0081', 03:39:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:39:34 INFO - 'use_talos_json': True, 03:39:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:39:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:39:34 INFO - 'work_dir': 'build'} 03:39:34 INFO - ##### 03:39:34 INFO - ##### Running clobber step. 03:39:34 INFO - ##### 03:39:34 INFO - Running pre-action listener: _resource_record_pre_action 03:39:34 INFO - Running main action method: clobber 03:39:34 INFO - rmtree: /builds/slave/test/build 03:39:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:39:36 INFO - Running post-action listener: _resource_record_post_action 03:39:36 INFO - ##### 03:39:36 INFO - ##### Running read-buildbot-config step. 03:39:36 INFO - ##### 03:39:36 INFO - Running pre-action listener: _resource_record_pre_action 03:39:36 INFO - Running main action method: read_buildbot_config 03:39:36 INFO - Using buildbot properties: 03:39:36 INFO - { 03:39:36 INFO - "project": "", 03:39:36 INFO - "product": "firefox", 03:39:36 INFO - "script_repo_revision": "production", 03:39:36 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:39:36 INFO - "repository": "", 03:39:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:39:36 INFO - "buildid": "20160425062348", 03:39:36 INFO - "pgo_build": "False", 03:39:36 INFO - "basedir": "/builds/slave/test", 03:39:36 INFO - "buildnumber": 297, 03:39:36 INFO - "slavename": "t-yosemite-r7-0081", 03:39:36 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:39:36 INFO - "platform": "yosemite_r7", 03:39:36 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:39:36 INFO - "branch": "mozilla-aurora", 03:39:36 INFO - "repo_path": "releases/mozilla-aurora", 03:39:36 INFO - "revision": "8551b253f4061db31e4be7829c2f70c2610caf42", 03:39:36 INFO - "stage_platform": "macosx64", 03:39:36 INFO - "builduid": "abe90fc74b164da98c10377025c22763", 03:39:36 INFO - "slavebuilddir": "test" 03:39:36 INFO - } 03:39:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 03:39:36 INFO - Running post-action listener: _resource_record_post_action 03:39:36 INFO - ##### 03:39:36 INFO - ##### Running download-and-extract step. 03:39:36 INFO - ##### 03:39:36 INFO - Running pre-action listener: _resource_record_pre_action 03:39:36 INFO - Running main action method: download_and_extract 03:39:36 INFO - mkdir: /builds/slave/test/build/tests 03:39:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:39:36 INFO - https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:39:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:39:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:39:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:39:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 03:39:40 INFO - Downloaded 1277 bytes. 03:39:40 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:39:40 INFO - Using the following test package requirements: 03:39:40 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:39:40 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:39:40 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 03:39:40 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:39:40 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 03:39:40 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:39:40 INFO - u'jsshell-mac.zip'], 03:39:40 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:39:40 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 03:39:40 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:39:40 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:39:40 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 03:39:40 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:39:40 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 03:39:40 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:39:40 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 03:39:40 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:39:40 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:39:40 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 03:39:40 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:39:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:39:40 INFO - https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:39:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:39:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:39:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:39:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:39:44 INFO - Downloaded 17919011 bytes. 03:39:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:39:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:39:47 INFO - Return code: 0 03:39:47 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:39:47 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')]} 03:39:47 INFO - https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:39:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:39:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:39:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:39:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:39:50 INFO - Downloaded 17919011 bytes. 03:39:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:39:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:39:54 INFO - Return code: 0 03:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:39:54 INFO - https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:39:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:39:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:39:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:39:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:39:55 INFO - Downloaded 10845194 bytes. 03:39:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:39:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:39:55 INFO - Return code: 0 03:39:55 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')]} 03:39:55 INFO - https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:39:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:39:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:39:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 03:39:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 03:39:58 INFO - Downloaded 115767664 bytes. 03:39:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:39:58 INFO - mkdir: /builds/slave/test/properties 03:39:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:39:58 INFO - Writing to file /builds/slave/test/properties/build_url 03:39:58 INFO - Contents: 03:39:58 INFO - build_url:https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:39:59 INFO - Running post-action listener: _resource_record_post_action 03:39:59 INFO - Running post-action listener: set_extra_try_arguments 03:39:59 INFO - ##### 03:39:59 INFO - ##### Running populate-webroot step. 03:39:59 INFO - ##### 03:39:59 INFO - Running pre-action listener: _resource_record_pre_action 03:39:59 INFO - Running main action method: populate_webroot 03:39:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:39:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:39:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:39:59 INFO - '--tppagecycles', 03:39:59 INFO - '7'], 03:39:59 INFO - 'tests': ['tsvgm']}}, 03:39:59 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:39:59 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:39:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:39:59 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 03:39:59 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:39:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:39:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:39:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:59 INFO - 'talos_options': ['--e10s'], 03:39:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:39:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:59 INFO - 'tests': ['damp', 'tps']}, 03:39:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:59 INFO - 'talos_options': ['--e10s'], 03:39:59 INFO - 'tests': ['damp', 'tps']}, 03:39:59 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:39:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:39:59 INFO - 'other': {'tests': ['a11yr', 03:39:59 INFO - 'ts_paint', 03:39:59 INFO - 'tpaint', 03:39:59 INFO - 'sessionrestore', 03:39:59 INFO - 'sessionrestore_no_auto_restore', 03:39:59 INFO - 'tabpaint']}, 03:39:59 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:39:59 INFO - 'tests': ['a11yr', 03:39:59 INFO - 'ts_paint', 03:39:59 INFO - 'tpaint', 03:39:59 INFO - 'sessionrestore', 03:39:59 INFO - 'sessionrestore_no_auto_restore', 03:39:59 INFO - 'tabpaint']}, 03:39:59 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:39:59 INFO - 'tests': ['a11yr', 03:39:59 INFO - 'ts_paint', 03:39:59 INFO - 'tpaint', 03:39:59 INFO - 'sessionrestore', 03:39:59 INFO - 'sessionrestore_no_auto_restore', 03:39:59 INFO - 'tabpaint']}, 03:39:59 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:39:59 INFO - 'tests': ['a11yr', 03:39:59 INFO - 'ts_paint', 03:39:59 INFO - 'tpaint', 03:39:59 INFO - 'sessionrestore', 03:39:59 INFO - 'sessionrestore_no_auto_restore', 03:39:59 INFO - 'tabpaint']}, 03:39:59 INFO - 'other_l64': {'tests': ['a11yr', 03:39:59 INFO - 'ts_paint', 03:39:59 INFO - 'tpaint', 03:39:59 INFO - 'sessionrestore', 03:39:59 INFO - 'sessionrestore_no_auto_restore', 03:39:59 INFO - 'tabpaint']}, 03:39:59 INFO - 'other_nol64': {'tests': ['a11yr', 03:39:59 INFO - 'ts_paint', 03:39:59 INFO - 'tpaint', 03:39:59 INFO - 'sessionrestore', 03:39:59 INFO - 'sessionrestore_no_auto_restore', 03:39:59 INFO - 'tabpaint']}, 03:39:59 INFO - 'svgr': {'tests': ['tsvgx', 03:39:59 INFO - 'tsvgr_opacity', 03:39:59 INFO - 'tart', 03:39:59 INFO - 'tscrollx', 03:39:59 INFO - 'cart']}, 03:39:59 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:39:59 INFO - 'tests': ['tsvgx', 03:39:59 INFO - 'tsvgr_opacity', 03:39:59 INFO - 'tart', 03:39:59 INFO - 'tscrollx', 03:39:59 INFO - 'cart']}, 03:39:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:59 INFO - 'tests': ['tp5o']}, 03:39:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:59 INFO - 'talos_options': ['--e10s'], 03:39:59 INFO - 'tests': ['tp5o']}, 03:39:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:59 INFO - 'talos_options': ['--xperf_path', 03:39:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:39:59 INFO - 'tests': ['tp5n']}, 03:39:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:59 INFO - 'talos_options': ['--e10s', 03:39:59 INFO - '--xperf_path', 03:39:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:39:59 INFO - 'tests': ['tp5n']}}, 03:39:59 INFO - 'talos.zip': {'path': '', 03:39:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:39:59 INFO - Running post-action listener: _resource_record_post_action 03:39:59 INFO - ##### 03:39:59 INFO - ##### Running create-virtualenv step. 03:39:59 INFO - ##### 03:39:59 INFO - Running pre-action listener: _resource_record_pre_action 03:39:59 INFO - Running main action method: create_virtualenv 03:39:59 INFO - Creating virtualenv /builds/slave/test/build/venv 03:39:59 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 03:39:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:39:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:39:59 INFO - Using real prefix '/tools/python27' 03:39:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:40:00 INFO - Installing distribute.............................................................................................................................................................................................done. 03:40:02 INFO - Installing pip.................done. 03:40:02 INFO - Return code: 0 03:40:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:40:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:40:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be654c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9476cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd02d30>, '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 0x10bb66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9b20>, '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.vjNI71fmam/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.gc16BxJFau/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:40:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:40:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:40:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vjNI71fmam/Render', 03:40:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:40:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:40:02 INFO - 'HOME': '/Users/cltbld', 03:40:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:40:02 INFO - 'LOGNAME': 'cltbld', 03:40:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:02 INFO - 'MOZ_NO_REMOTE': '1', 03:40:02 INFO - 'NO_EM_RESTART': '1', 03:40:02 INFO - 'PAGER': '/bin/cat', 03:40:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:40:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:02 INFO - 'PWD': '/builds/slave/test', 03:40:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:40:02 INFO - 'SHELL': '/bin/bash', 03:40:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gc16BxJFau/Listeners', 03:40:02 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:40:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:40:02 INFO - 'USER': 'cltbld', 03:40:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:40:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:40:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:02 INFO - 'XPC_FLAGS': '0x0', 03:40:02 INFO - 'XPC_SERVICE_NAME': '0', 03:40:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:03 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 03:40:03 INFO - Cleaning up... 03:40:03 INFO - Return code: 0 03:40:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:40:03 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')]} 03:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:03 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')]} 03:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:03 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 0x10be5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be654c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9476cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd02d30>, '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 0x10bb66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9b20>, '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.vjNI71fmam/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.gc16BxJFau/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:40:03 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 03:40:03 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 03:40:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vjNI71fmam/Render', 03:40:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:40:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:40:03 INFO - 'HOME': '/Users/cltbld', 03:40:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:40:03 INFO - 'LOGNAME': 'cltbld', 03:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:03 INFO - 'MOZ_NO_REMOTE': '1', 03:40:03 INFO - 'NO_EM_RESTART': '1', 03:40:03 INFO - 'PAGER': '/bin/cat', 03:40:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:03 INFO - 'PWD': '/builds/slave/test', 03:40:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:40:03 INFO - 'SHELL': '/bin/bash', 03:40:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gc16BxJFau/Listeners', 03:40:03 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:40:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:40:03 INFO - 'USER': 'cltbld', 03:40:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:40:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:03 INFO - 'XPC_FLAGS': '0x0', 03:40:03 INFO - 'XPC_SERVICE_NAME': '0', 03:40:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:03 INFO - Downloading/unpacking psutil>=0.7.1 03:40:03 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 03:40:03 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 03:40:03 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 03:40:03 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 03:40:03 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 03:40:03 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 03:40:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:40:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:40:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:40:06 INFO - Installing collected packages: psutil 03:40:06 INFO - Running setup.py install for psutil 03:40:06 INFO - building 'psutil._psutil_osx' extension 03:40:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:40:07 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 03:40:07 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 03:40:07 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 03:40:07 INFO - building 'psutil._psutil_posix' extension 03:40:07 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 03:40:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:40:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:40:07 INFO - ^ 03:40:07 INFO - 1 warning generated. 03:40:07 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 03:40:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:40:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:40:07 INFO - Successfully installed psutil 03:40:07 INFO - Cleaning up... 03:40:07 INFO - Return code: 0 03:40:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:40:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:07 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 0x10be5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be654c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9476cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd02d30>, '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 0x10bb66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9b20>, '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.vjNI71fmam/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.gc16BxJFau/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:40:07 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 03:40:07 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 03:40:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vjNI71fmam/Render', 03:40:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:40:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:40:07 INFO - 'HOME': '/Users/cltbld', 03:40:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:40:07 INFO - 'LOGNAME': 'cltbld', 03:40:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:07 INFO - 'MOZ_NO_REMOTE': '1', 03:40:07 INFO - 'NO_EM_RESTART': '1', 03:40:07 INFO - 'PAGER': '/bin/cat', 03:40:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:40:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:07 INFO - 'PWD': '/builds/slave/test', 03:40:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:40:07 INFO - 'SHELL': '/bin/bash', 03:40:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gc16BxJFau/Listeners', 03:40:07 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:40:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:40:07 INFO - 'USER': 'cltbld', 03:40:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:40:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:40:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:07 INFO - 'XPC_FLAGS': '0x0', 03:40:07 INFO - 'XPC_SERVICE_NAME': '0', 03:40:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:40:08 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 03:40:08 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 03:40:08 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 03:40:08 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 03:40:08 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 03:40:08 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 03:40:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:40:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:40:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:40:10 INFO - Installing collected packages: mozsystemmonitor 03:40:10 INFO - Running setup.py install for mozsystemmonitor 03:40:10 INFO - Successfully installed mozsystemmonitor 03:40:10 INFO - Cleaning up... 03:40:10 INFO - Return code: 0 03:40:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:40:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:10 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 0x10be5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be654c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9476cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd02d30>, '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 0x10bb66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9b20>, '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.vjNI71fmam/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.gc16BxJFau/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:40:10 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 03:40:10 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 03:40:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vjNI71fmam/Render', 03:40:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:40:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:40:10 INFO - 'HOME': '/Users/cltbld', 03:40:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:40:10 INFO - 'LOGNAME': 'cltbld', 03:40:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:10 INFO - 'MOZ_NO_REMOTE': '1', 03:40:10 INFO - 'NO_EM_RESTART': '1', 03:40:10 INFO - 'PAGER': '/bin/cat', 03:40:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:40:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:10 INFO - 'PWD': '/builds/slave/test', 03:40:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:40:10 INFO - 'SHELL': '/bin/bash', 03:40:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gc16BxJFau/Listeners', 03:40:10 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:40:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:40:10 INFO - 'USER': 'cltbld', 03:40:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:40:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:40:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:10 INFO - 'XPC_FLAGS': '0x0', 03:40:10 INFO - 'XPC_SERVICE_NAME': '0', 03:40:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:40:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:10 INFO - Downloading/unpacking blobuploader==1.2.4 03:40:10 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 03:40:10 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 03:40:10 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 03:40:10 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 03:40:10 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 03:40:10 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 03:40:13 INFO - Downloading blobuploader-1.2.4.tar.gz 03:40:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:40:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:40:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:40:14 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 03:40:14 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 03:40:14 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 03:40:14 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 03:40:14 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 03:40:14 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 03:40:14 INFO - Downloading docopt-0.6.1.tar.gz 03:40:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:40:14 INFO - Installing collected packages: blobuploader, requests, docopt 03:40:14 INFO - Running setup.py install for blobuploader 03:40:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:40:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:40:14 INFO - Running setup.py install for requests 03:40:15 INFO - Running setup.py install for docopt 03:40:15 INFO - Successfully installed blobuploader requests docopt 03:40:15 INFO - Cleaning up... 03:40:15 INFO - Return code: 0 03:40:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:40:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:15 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 0x10be5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be654c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9476cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd02d30>, '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 0x10bb66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9b20>, '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.vjNI71fmam/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.gc16BxJFau/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:40:15 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 03:40:15 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 03:40:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vjNI71fmam/Render', 03:40:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:40:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:40:15 INFO - 'HOME': '/Users/cltbld', 03:40:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:40:15 INFO - 'LOGNAME': 'cltbld', 03:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:15 INFO - 'MOZ_NO_REMOTE': '1', 03:40:15 INFO - 'NO_EM_RESTART': '1', 03:40:15 INFO - 'PAGER': '/bin/cat', 03:40:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:15 INFO - 'PWD': '/builds/slave/test', 03:40:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:40:15 INFO - 'SHELL': '/bin/bash', 03:40:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gc16BxJFau/Listeners', 03:40:15 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:40:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:40:15 INFO - 'USER': 'cltbld', 03:40:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:40:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:15 INFO - 'XPC_FLAGS': '0x0', 03:40:15 INFO - 'XPC_SERVICE_NAME': '0', 03:40:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:40:15 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-ECcHuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:40:15 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Y5u2qZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:40:16 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-XUU7s0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:40:16 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-cknrPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:40:16 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-wlAEoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:40:16 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-gtrDqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:40:16 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-wIAgyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:40:16 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-HJieDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:40:16 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-PZ0tHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:40:16 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-5Vnz24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:40:16 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-EhjSdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:40:16 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-wZEyh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:40:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-xrLVic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:40:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-y1bmDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-4ecjyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:40:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-AzFsnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:40:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:40:17 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-GtVZxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:40:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:40:17 INFO - Running setup.py install for manifestparser 03:40:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:40:17 INFO - Running setup.py install for mozcrash 03:40:17 INFO - Running setup.py install for mozdebug 03:40:17 INFO - Running setup.py install for mozdevice 03:40:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:40:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:40:18 INFO - Running setup.py install for mozfile 03:40:18 INFO - Running setup.py install for mozhttpd 03:40:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:40:18 INFO - Running setup.py install for mozinfo 03:40:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:40:18 INFO - Running setup.py install for mozInstall 03:40:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:40:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:40:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:40:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:40:18 INFO - Running setup.py install for mozleak 03:40:18 INFO - Running setup.py install for mozlog 03:40:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:40:19 INFO - Running setup.py install for moznetwork 03:40:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:40:19 INFO - Running setup.py install for mozprocess 03:40:19 INFO - Running setup.py install for mozprofile 03:40:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:40:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:40:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:40:19 INFO - Running setup.py install for mozrunner 03:40:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:40:19 INFO - Running setup.py install for mozscreenshot 03:40:19 INFO - Running setup.py install for moztest 03:40:19 INFO - Running setup.py install for mozversion 03:40:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:40:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:40:20 INFO - Cleaning up... 03:40:20 INFO - Return code: 0 03:40:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:20 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 0x10be5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be654c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9476cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd02d30>, '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 0x10bb66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9b20>, '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.vjNI71fmam/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.gc16BxJFau/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:40:20 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 03:40:20 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 03:40:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vjNI71fmam/Render', 03:40:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:40:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:40:20 INFO - 'HOME': '/Users/cltbld', 03:40:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:40:20 INFO - 'LOGNAME': 'cltbld', 03:40:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:20 INFO - 'MOZ_NO_REMOTE': '1', 03:40:20 INFO - 'NO_EM_RESTART': '1', 03:40:20 INFO - 'PAGER': '/bin/cat', 03:40:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:40:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:20 INFO - 'PWD': '/builds/slave/test', 03:40:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:40:20 INFO - 'SHELL': '/bin/bash', 03:40:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gc16BxJFau/Listeners', 03:40:20 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:40:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:40:20 INFO - 'USER': 'cltbld', 03:40:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:40:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:40:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:20 INFO - 'XPC_FLAGS': '0x0', 03:40:20 INFO - 'XPC_SERVICE_NAME': '0', 03:40:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:40:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Lp4QpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:40:20 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)) 03:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-EGUY90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:40:20 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)) 03:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-l1hbw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:40:20 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)) 03:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-ql4etP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:40:20 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)) 03:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-ifVPIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:40:20 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)) 03:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:40:20 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-jRLnF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:40:21 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)) 03:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:40:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Cd1SYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:40:21 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)) 03:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:40:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-sbjnks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:40:21 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)) 03:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:40:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-TbdsfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:40:21 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)) 03:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:40:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-gnMsrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:40:21 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)) 03:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:40:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-fIyPum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:40:21 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)) 03:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:40:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-6R_oih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:40:21 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)) 03:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:40:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-qjacIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:40:21 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)) 03:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:40:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Uf5LbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:40:21 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)) 03:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-7qLk6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:21 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)) 03:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:40:21 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-JmTFIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:40:22 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)) 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:40:22 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-T8bGaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:40:22 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)) 03:40:22 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)) 03:40:22 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)) 03:40:22 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)) 03:40:22 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)) 03:40:22 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)) 03:40:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:40: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 03:40: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 03:40: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 03:40: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 03:40: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 03:40: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 03:40:24 INFO - Downloading blessings-1.6.tar.gz 03:40:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:40:24 INFO - Installing collected packages: blessings 03:40:24 INFO - Running setup.py install for blessings 03:40:24 INFO - Successfully installed blessings 03:40:24 INFO - Cleaning up... 03:40:24 INFO - Return code: 0 03:40:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:40:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:40:25 INFO - Reading from file tmpfile_stdout 03:40:25 INFO - Current package versions: 03:40:25 INFO - blessings == 1.6 03:40:25 INFO - blobuploader == 1.2.4 03:40:25 INFO - docopt == 0.6.1 03:40:25 INFO - manifestparser == 1.1 03:40:25 INFO - mozInstall == 1.12 03:40:25 INFO - mozcrash == 0.17 03:40:25 INFO - mozdebug == 0.1 03:40:25 INFO - mozdevice == 0.48 03:40:25 INFO - mozfile == 1.2 03:40:25 INFO - mozhttpd == 0.7 03:40:25 INFO - mozinfo == 0.9 03:40:25 INFO - mozleak == 0.1 03:40:25 INFO - mozlog == 3.1 03:40:25 INFO - moznetwork == 0.27 03:40:25 INFO - mozprocess == 0.22 03:40:25 INFO - mozprofile == 0.28 03:40:25 INFO - mozrunner == 6.11 03:40:25 INFO - mozscreenshot == 0.1 03:40:25 INFO - mozsystemmonitor == 0.0 03:40:25 INFO - moztest == 0.7 03:40:25 INFO - mozversion == 1.4 03:40:25 INFO - psutil == 3.1.1 03:40:25 INFO - requests == 1.2.3 03:40:25 INFO - wsgiref == 0.1.2 03:40:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:40:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:25 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 0x10be5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be654c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9476cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd02d30>, '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 0x10bb66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9b20>, '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.vjNI71fmam/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.gc16BxJFau/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:40:25 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 03:40:25 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 03:40:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vjNI71fmam/Render', 03:40:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:40:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:40:25 INFO - 'HOME': '/Users/cltbld', 03:40:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:40:25 INFO - 'LOGNAME': 'cltbld', 03:40:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:25 INFO - 'MOZ_NO_REMOTE': '1', 03:40:25 INFO - 'NO_EM_RESTART': '1', 03:40:25 INFO - 'PAGER': '/bin/cat', 03:40:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:40:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:25 INFO - 'PWD': '/builds/slave/test', 03:40:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:40:25 INFO - 'SHELL': '/bin/bash', 03:40:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gc16BxJFau/Listeners', 03:40:25 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:40:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:40:25 INFO - 'USER': 'cltbld', 03:40:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:40:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:40:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:25 INFO - 'XPC_FLAGS': '0x0', 03:40:25 INFO - 'XPC_SERVICE_NAME': '0', 03:40:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:40:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:25 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)) 03:40:25 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)) 03:40:25 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)) 03:40:25 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)) 03:40:25 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)) 03:40:25 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)) 03:40:25 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)) 03:40:25 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)) 03:40:25 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)) 03:40:25 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)) 03:40:25 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)) 03:40:25 INFO - Cleaning up... 03:40:25 INFO - Return code: 0 03:40:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:40:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be654c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9476cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd02d30>, '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 0x10bb66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9b20>, '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.vjNI71fmam/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.gc16BxJFau/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:40:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:40:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:40:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vjNI71fmam/Render', 03:40:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:40:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:40:25 INFO - 'HOME': '/Users/cltbld', 03:40:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:40:25 INFO - 'LOGNAME': 'cltbld', 03:40:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:25 INFO - 'MOZ_NO_REMOTE': '1', 03:40:25 INFO - 'NO_EM_RESTART': '1', 03:40:25 INFO - 'PAGER': '/bin/cat', 03:40:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:40:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:25 INFO - 'PWD': '/builds/slave/test', 03:40:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:40:25 INFO - 'SHELL': '/bin/bash', 03:40:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gc16BxJFau/Listeners', 03:40:25 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:40:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:40:25 INFO - 'USER': 'cltbld', 03:40:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:40:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:40:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:25 INFO - 'XPC_FLAGS': '0x0', 03:40:25 INFO - 'XPC_SERVICE_NAME': '0', 03:40:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:40:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:25 INFO - Downloading/unpacking jsonschema 03:40: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 03:40: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 03:40: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 03:40: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 03:40: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 03:40: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 03:40:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:40:28 INFO - Downloading/unpacking functools32 (from jsonschema) 03:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:28 INFO - Downloading functools32-3.2.3-2.tar.gz 03:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:40:28 INFO - warning: no files found matching '*.txt' 03:40:28 INFO - no previously-included directories found matching 'build' 03:40:28 INFO - no previously-included directories found matching 'dist' 03:40:28 INFO - no previously-included directories found matching '.git*' 03:40:28 INFO - Installing collected packages: jsonschema, functools32 03:40:28 INFO - Running setup.py install for functools32 03:40:28 INFO - warning: no files found matching '*.txt' 03:40:28 INFO - no previously-included directories found matching 'build' 03:40:28 INFO - no previously-included directories found matching 'dist' 03:40:28 INFO - no previously-included directories found matching '.git*' 03:40:28 INFO - Successfully installed jsonschema functools32 03:40:28 INFO - Cleaning up... 03:40:28 INFO - Return code: 0 03:40:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:29 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')]} 03:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:29 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 0x10be5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be654c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9476cb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd02d30>, '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 0x10bb66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9b20>, '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.vjNI71fmam/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.gc16BxJFau/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 03:40:29 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 03:40:29 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 03:40:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vjNI71fmam/Render', 03:40:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:40:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:40:29 INFO - 'HOME': '/Users/cltbld', 03:40:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:40:29 INFO - 'LOGNAME': 'cltbld', 03:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:29 INFO - 'MOZ_NO_REMOTE': '1', 03:40:29 INFO - 'NO_EM_RESTART': '1', 03:40:29 INFO - 'PAGER': '/bin/cat', 03:40:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:29 INFO - 'PWD': '/builds/slave/test', 03:40:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:40:29 INFO - 'SHELL': '/bin/bash', 03:40:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gc16BxJFau/Listeners', 03:40:29 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:40:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:40:29 INFO - 'USER': 'cltbld', 03:40:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:40:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:29 INFO - 'XPC_FLAGS': '0x0', 03:40:29 INFO - 'XPC_SERVICE_NAME': '0', 03:40:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:40:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:29 INFO - Downloading/unpacking flake8 03:40:29 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 03:40:29 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 03:40:29 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 03:40:29 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 03:40:29 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 03:40:29 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 03:40:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:40:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:40:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:40:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:40:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:40:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:40:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:40:33 INFO - no previously-included directories found matching 'docs/_build' 03:40:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:40:33 INFO - Running setup.py install for pep8 03:40:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:40:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:40:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:40:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:40:33 INFO - no previously-included directories found matching 'docs/_build' 03:40:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:40:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:40:33 INFO - Cleaning up... 03:40:34 INFO - Return code: 0 03:40:34 INFO - Running post-action listener: _resource_record_post_action 03:40:34 INFO - Running post-action listener: _start_resource_monitoring 03:40:34 INFO - Starting resource monitoring. 03:40:34 INFO - ##### 03:40:34 INFO - ##### Running install step. 03:40:34 INFO - ##### 03:40:34 INFO - Running pre-action listener: _resource_record_pre_action 03:40:34 INFO - Running main action method: install 03:40:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:40:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:40:34 INFO - Reading from file tmpfile_stdout 03:40:34 INFO - Detecting whether we're running mozinstall >=1.0... 03:40:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:40:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:40:34 INFO - Reading from file tmpfile_stdout 03:40:34 INFO - Output received: 03:40:34 INFO - Usage: mozinstall [options] installer 03:40:34 INFO - Options: 03:40:34 INFO - -h, --help show this help message and exit 03:40:34 INFO - -d DEST, --destination=DEST 03:40:34 INFO - Directory to install application into. [default: 03:40:34 INFO - "/builds/slave/test"] 03:40:34 INFO - --app=APP Application being installed. [default: firefox] 03:40:34 INFO - mkdir: /builds/slave/test/build/application 03:40:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:40:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:41:06 INFO - Reading from file tmpfile_stdout 03:41:06 INFO - Output received: 03:41:06 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:41:06 INFO - Running post-action listener: _resource_record_post_action 03:41:06 INFO - ##### 03:41:06 INFO - ##### Running run-tests step. 03:41:06 INFO - ##### 03:41:06 INFO - Running pre-action listener: _resource_record_pre_action 03:41:06 INFO - Running main action method: run_tests 03:41:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:41:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:41:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:41:06 INFO - 2016-04-27 03:41:06.886 screenresolution[1642:7613] starting screenresolution argv=screenresolution get 03:41:06 INFO - 2016-04-27 03:41:06.888 screenresolution[1642:7613] Display 0: 1600x1200x32@60 03:41:06 INFO - 2016-04-27 03:41:06.894 screenresolution[1643:7615] starting screenresolution argv=screenresolution list 03:41:06 INFO - Available Modes on Display 0 03:41:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:41:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:41:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:41:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:41:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:41:07 INFO - Intel Iris: 03:41:07 INFO - Chipset Model: Intel Iris 03:41:07 INFO - Type: GPU 03:41:07 INFO - Bus: Built-In 03:41:07 INFO - VRAM (Dynamic, Max): 1536 MB 03:41:07 INFO - Vendor: Intel (0x8086) 03:41:07 INFO - Device ID: 0x0a2e 03:41:07 INFO - Revision ID: 0x0009 03:41:07 INFO - Displays: 03:41:07 INFO - Display: 03:41:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:41:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:41:07 INFO - Display Serial Number: 1600x1200 60 03:41:07 INFO - Main Display: Yes 03:41:07 INFO - Mirror: Off 03:41:07 INFO - Online: Yes 03:41:07 INFO - Rotation: Supported 03:41:07 INFO - Return code: 0 03:41:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:41:07 INFO - Python 2.7.3 03:41:07 INFO - Return code: 0 03:41:07 INFO - grabbing minidump binary from tooltool 03:41:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:41:07 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 0x10bd02d30>, '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 0x10bb66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:41:07 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 03:41:07 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 03:41:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:41:07 INFO - Return code: 0 03:41:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:41:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:41:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:41:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:41:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:41:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:41:08 INFO - Return code: 0 03:41:08 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/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--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 03:41:08 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/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0081 --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 03:41:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vjNI71fmam/Render', 03:41:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:08 INFO - 'HOME': '/Users/cltbld', 03:41:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:08 INFO - 'LOGNAME': 'cltbld', 03:41:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:41:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:41:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:08 INFO - 'MOZ_NO_REMOTE': '1', 03:41:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:41:08 INFO - 'NO_EM_RESTART': '1', 03:41:08 INFO - 'PAGER': '/bin/cat', 03:41:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:08 INFO - 'PWD': '/builds/slave/test', 03:41:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:41:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:08 INFO - 'SHELL': '/bin/bash', 03:41:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gc16BxJFau/Listeners', 03:41:08 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 03:41:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:08 INFO - 'USER': 'cltbld', 03:41:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:08 INFO - 'XPC_FLAGS': '0x0', 03:41:08 INFO - 'XPC_SERVICE_NAME': '0', 03:41:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:08 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/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--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 03:41:08 INFO - mozversion application_buildid: 20160425062348 03:41:08 INFO - mozversion application_changeset: 8551b253f4061db31e4be7829c2f70c2610caf42 03:41:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:41:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:41:08 INFO - mozversion application_name: Firefox 03:41:08 INFO - mozversion application_remotingname: firefox-dev 03:41:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:41:08 INFO - mozversion application_vendor: Mozilla 03:41:08 INFO - mozversion application_version: 47.0a2 03:41:08 INFO - mozversion platform_buildid: 20160425062348 03:41:08 INFO - mozversion platform_changeset: 8551b253f4061db31e4be7829c2f70c2610caf42 03:41:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:41:08 INFO - mozversion platform_version: 47.0a2 03:41:08 INFO - using testdate: 1461753668 03:41:08 INFO - actual date: 1461753668 03:41:08 INFO - starting webserver on 'localhost:49277' 03:41:08 INFO - SUITE-START | Running 2 tests 03:41:08 INFO - TEST-START | dromaeo_css 03:41:08 INFO - operating with platform_type : mac_ 03:41:08 INFO - Initialising browser for dromaeo_css test... 03:41:08 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmplmJJAt/profile http://localhost:49277/getInfo.html) 03:41:11 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 03:41:11 INFO - PROCESS | 1661 | colorDepth:24 03:41:11 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 03:41:11 INFO - PROCESS | 1661 | __metrics 03:41:11 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:41:11 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:41:11 INFO - TEST-INFO | 1661: exit 0 03:41:11 INFO - Browser initialized. 03:41:11 INFO - Running cycle 1/1 for dromaeo_css test... 03:41:11 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmplmJJAt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:23 INFO - PROCESS | 1663 | RSS: Main: 207757312 03:41:23 INFO - PROCESS | 1663 | 03:42:14 INFO - PROCESS | 1663 | 1461753734749 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] 03:44:24 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 03:44:25 INFO - PROCESS | 1663 | RSS: Main: 310755328 03:44:25 INFO - PROCESS | 1663 | 03:47:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 03:47:28 INFO - PROCESS | 1663 | RSS: Main: 340013056 03:47:28 INFO - PROCESS | 1663 | 03:50:30 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 03:50:31 INFO - PROCESS | 1663 | RSS: Main: 349011968 03:50:31 INFO - PROCESS | 1663 | 03:53:33 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 03:53:34 INFO - PROCESS | 1663 | RSS: Main: 304631808 03:53:34 INFO - PROCESS | 1663 | 03:56:37 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 03:56:37 INFO - PROCESS | 1663 | RSS: Main: 307978240 03:56:37 INFO - PROCESS | 1663 | 03:59:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 03:59:40 INFO - PROCESS | 1663 | RSS: Main: 371179520 03:59:40 INFO - PROCESS | 1663 | 03:59:40 INFO - PROCESS | 1663 | __start_tp_report 03:59:40 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 03:59:40 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 03:59:40 INFO - PROCESS | 1663 | |i|pagename|runs| 03:59:40 INFO - PROCESS | 1663 | |0;dojo.html;3436.9140771480716;3510.999365180924;3516.788992660439;3536.3457760314336;3589.9641003589973;5763.769449222036;5777.480026797587;5789.623674461162;5800.187973683687;5828.504577110945;5776.335721392041;5809.7385617647205;5810.98307796135;5815.360310365867;5826.883462330751;4486.394336764536;5103.260027296058;5163.561097306715;5216.713080780574;5272.393674727398;5272.868178295555;5282.683039017652;5289.86775330616;5290.285811415468;5292.232626269208;5226.24219488174;5256.8685782855355;5258.789648414078;5280.260763493114;5282.2076688496645;5241.606879484035;5284.682919024833;5299.284596579451;5312.123499622577;5328.680279183222;5067.949320506785;5221.765020574083;5233.738313084368;5240.109181439165;5242.842714718565;5621.494065534122;5672;5688.516476099495;5697.344805347397;5697.658140511541;6185.319614842364;6266.216722909637;6273.529485288576;6283;6283.403076707706;6171.9691401542705;6204.379562043805;6211.875762484725;6245.46913512352;6266.342034086447;5851.531877449794;5881.735321910524;5935.376785437553;5940.851478712981;5941.494972927263;6187.102870083812;6202.100695399142;6276.780312689034;6278.529110316745;6288.874222515524;6188.412100850412;6235.906461403082;6239.095331176954;6240.594361366497;6245.063240513869;6231.875362492725;6236.719347629368;6238.968805156036;6243.906341404882;6268.49852011838;6040.879182416415;6145.617236121837;6191.133241346215;6258.249010118724;6262.060690896402;6094.634321940698;6233.594816336924;6235.968820155961;6238.1266622672865;6278.183836101369;4356.629686476622;4925.039617274669;4925.679830810986;4935.358403407534;4957.652964292465;3768.2840260350445;4419.071994881111;4443.977780111079;4476.992676647728;4479.0370070434365;5733.799317023968;5736.913946290809;5746.367899531048;5756.654600723886;5795.420457954171;6210.72051757672;6212.658303793244;6215.409536094064;6216.689165541795;6240.251169859555;4774.856754297377;5667.659940403589;5728.885422291531;5742.253507044057;5752.769889204469;5332.893342133136;5381.408045115034;5413.079776438015;5414.377346605112;5414.458554144554;6271.655058971709;6297.4017468340435;6300.180976473029;6307.085472606446;6326.715297811517;6193;6194.938050619436;6225.097360882737;6242.687865606611;6252.343503932022;6309.589876658002;6317.178766760292;6323.810285691437;6324.272708638381;6330.272018717907;6241.032639940817;6248.218972628421;6264.373562643699;6278.497720182283;6284.183056202664;6164.630122192682;6180.072989051679;6234.875302493834;6278.811635650938;6284.057391391328;5322.0154271459905;6109.526129009804;6147.385261473817;6150.261968563801;6172.629642221481;6090.390960903874;6098.725557349841;6132.6627035513475;6161.784337548164;6163.630182189083;5870.295564532284;5927.436893495111;5995.070764031582;5998.430149135826;6008.909866352095;5961.403859614177;6138.0078185458515;6205.441510264098;6206.162168107247;6220.129181914445;5974.492168165581;6044.758209671564;6055.364186760503;6072.696365181811;6100.725467354068 03:59:40 INFO - PROCESS | 1663 | |1;ext.html;501.12803721524557;598.0729868703511;618.2395653346384;655.2955572759284;666.8899631560791;31730;39973.60026399735;40337.39493907594;40351.79824100883;40463;42834.57165428345;43548.91127721809;43551.78224108875;43616;43626.563734362644;9619.374740641853;9860.457674827881;9867.90132098677;9876.061774131482;9888.901110988905;8647.827043459127;9607.615695372177;9771.169450596708;9785.217182625409;9792;9865.013498650149;9934.205263578933;9942.154916832042;9944.6519371822;9962.850557241647;9836.163926066292;9913;9931.15585175261;9936.95031524845;9940.5526751296;9630;9762.853557196648;9770.853437198448;9784.412935223909;9799.608015679436;40834.59165408338;41377.96555086117;41380.79309603433;41428.79285603553;41451.1709765803;3229.7739158258933;3420.264643101745;3861.710371722104;3901.3367727486398;3919.333713268751;28096;28100.859495702596;28160.85919570389;28312;28486.572701409495;2761.7446483714402;2794.832310061403;2827.7030911754177;2867.985660071707;2890.7253810887933;499436;499468;500679;503635;505250;65948;67501;67590;67728.32271677318;67736;71458.64270678717;71658;71697;71754;71829.64085179644;18376.724349134773;18578.628427431377;18588.44234672962;18607.255709771456;18620.34828781013;14217.573472795833;14286.714265714836;14326.713465730836;14349.713005739826;14377.496787612383;19407.514812129528;20573.485662858548;20911.26810561623;20929.372118836458;20964.475888102614;6366.840828979312;8495.830083398298;8662.393632445794;8764.474131552128;9047.366684332164;1005.2962925951863;1035.5805898611075;1138.362516990488;1152.1186292486254;1185.2355230876037;29036;30361.24096897551;30465.238369040508;30573.235669108006;31301.686983129875;1184.135581025856;1193.6896406934259;1228.8586812516496;1275.101053757099;1346.7104572516955;12220.349067830297;15195.696086078438;15858.92070539663;16148.919255403647;16534.503964881074;532023;534961;537396;538233;539931.3003434958;62292.68853655703;62571.374286257465;62673.68663156746;62673.74652506924;62940.68529657414;1435.2106341512126;1539.699758547095;1549.5816129644932;1578.27399396279;1581.2014932459035;25145.24564263075;28215.15354539367;29129.56305655459;29585;30215.848920755256;49852;50154;50289.74855125701;50293.74853125711;50299.74850125726;369280.1535992303;370937;371484;372208;372478.788878722;29535.85232073826;34202.31595368029;34314.48528272128;35055;35069.77255796035;42450;42848.92877678083;44672.32991505065;44752.10495790131;46128.769356153;33368.83315583504;35437;36558;36623.26753464863;37311.81344093262;35889.82055089708;36031.45952810658;36427.81786091052;36652.63347366492;36748.81625591855;35275.64724352724;35834;36740.867523111316;36933.63066369302;37170;31931.201719956258;32926.83536582302;32947.835260823544;33238.66761332453;33249.335013300086;21486.462838429165;22183.223587174372;24401.755982439947;24532.386690332885;25166.752689880166 03:59:40 INFO - PROCESS | 1663 | |2;jquery.html;382.05060424844265;383.4612369620682;385.5893473450775;391.3112921258584;391.90006548330206;16547.089910054972;17069.231884565193;19346.806531934675;22626.321210363676;22730.31809045729;20484.385468435932;20851.165953361884;21321.68017479739;21330;21403.67894481584;5271.4464981177;5798.130280457944;6066.575339726221;6067.02927531595;6070.757169713206;5749.56878234132;5908.113782932552;6002.789902353418;6005.159277701126;6011.30869949957;5929.88140237195;6002.339742628307;6027.126066720323;6042.154098426224;6044.879102417949;5701.458361455655;5997.7001149942325;6042.184305118819;6050.999999999978;6057.909131363033;5219.269302297644;5253.52718255355;5256.448072952323;5260.631755777097;5260.8684782880355;25916.481670366677;26483.47033059328;26902.865485672446;26949.595756063674;26957.191284261502;2340.180936672168;2575.8196926215173;2956.8965086222092;2970.2425881400104;2997.370552184022;14591.854081459156;14838.34519396591;15098.698026039527;15184.937054406197;15510.30203640839;1706.496583507868;1733.2633630706898;1760.3662681434673;1763.8941663500102;1835;77458.61270693611;100358;103690.96309036888;107068.92931070668;107450;9731.416115032978;10651.89348106509;10924.836127458095;11021.063209627111;11171.218014739054;13676.658083547793;13695.794563081561;13710.040297179301;13719.931400342934;13743.244121573409;7636.579988100708;8773.210411063035;8913.598888050054;8951.507667078244;9076.727698169065;9242;9243.260539156985;9276.304277179239;9367.391119577342;9373.39072960269;6214.347493513206;6317.1156038154695;6329.430351268408;6340.936590634127;6345.302016778151;1843.3640394064028;1861.7393564900924;1864.9254029838926;1870.0462763990463;1896.1183049881872;430.8513562820822;440.7135362014652;974.6345120579782;1055.8205105132147;1096.6490722968733;11505.482253298622;11834.289942603471;11893.22694024903;11948.820767688492;12051.879481205076;462.625273528443;476.2142464932819;477.29122253453596;478.9473157952622;483.20512756515336;6049.8487537811025;6091;6126.509879209652;6218.378162183745;6237.937620623827;585150;617651;636247;644610;648806;112901;116963;121882;124030.37984810019;125824.37087814503;996.3075662414698;1033.3283365812283;1037.180627304421;1038.6416686243233;1056.6196169379018;18409.98745069034;18804.341848035252;19114.235430583176;19119.75721578093;19397.70903436478;52033.47966520286;52695.68260793511;52912.73543632411;52968.73515632397;53238.46761532335;70334;75103.62448187724;75994.86007709778;76109.61945190238;76110.23889761254;8080.111187769223;8090.555019473985;8201.343892488705;8319.916800831914;8360.54017029045;11842.881571184178;13138.080334376502;13269.800952985519;13520.72958540804;13542.255175965416;11385.03227225695;11525.942370288094;11592.95663390299;11679.824802628138;11822.40887955616;8020.556299865847;11375;11426.2001659883;11449.255798373079;11748.530058797553;9556.761080972754;11800.940995294968;12033.037357011242;12038;12040;7280.199178090512;7967.964164658557;8150.7147249845975;8285.668573257244;8303.792405189919;10186.796264074825;10376.584936602452;10696.946515267375;11955.462004210005;11956.222845515014 03:59:40 INFO - PROCESS | 1663 | |3;mootools.html;1508.5700575336032;1625.260506469556;1658.2537857963919;1997.4107638246717;2070.306447340141;8369.121242269566;9003.954980225099;9203.17171454569;9216.861747073799;9234.67678631248;8841.248493878029;9080.591373388197;9096.545172741353;9104.317176211778;9141.45151290923;2261.8869056547187;2505.386180385808;2551.8086143539263;2565.0509311554656;2589.3138318345655;2583.470388570348;2589.029114082219;2590.2747230775412;2590.870456477169;2591.0931174089014;2487.2413913756295;2588.961165582518;2593.8703064846777;2594.662693849797;2600.1419531554543;2187.9124835006583;2555.782758465533;2590.365360486674;2590.456004239112;2591.7278685737915;2521.9747802522106;2562.1929092335854;2567.281161274846;2568.781653559431;2577.149540651599;8010.439269251155;9097.727068187965;9158.313126515539;9160.129787670161;9168.816623667555;5624.184493248456;5733.369329373765;5749.482546570829;5768.221290125821;5785.47930686236;3580.445031020196;3682.226732386202;3729.8321575529167;3735.477033215352;3740.738148329618;1066.9573217071309;1076.354187487509;1081.9080378167864;1103.4648195625116;1125.5610311978335;12450.564230251897;12469.625911222678;12499.250044997238;12500.874991250153;12532.185407948455;5032.47159048302;5060.1903695408555;5093.312402825572;5137.717425541557;5154.9484505155215;10518.474076296154;10535.367877927349;10549.789004219872;10554.788904221872;10568.260221784401;5768.74040668171;5794.79718209869;5805.506531944832;5814.418558144236;5820.126980952891;4587.357769912215;4758.547937945889;4979.651424400257;5177.715225662625;5186.299849520342;4045.296270357165;4109.671226301889;4111.403846442309;4139.544650088488;4140.213359461693;1341.4164838295374;1362.1350442469104;1362.9863701363058;1369.2674419185687;1370.020435388682;1145.1297014269214;1161.2509931094396;1167.3929556630674;1369.5069774881035;1447.8696917277284;8524.914750852537;8596.183362580545;8637.827243455096;8695.043545210021;8751.824963500821;1234.6481252843075;1235.9567415140427;1242.5961562492225;1243.7325974915436;1257.4215860704178;5737.196792449061;6441.774537891151;6535.509836762262;6540.640264785482;6545.967270163619;10838.241323107306;11338.43307834605;11343.376114313627;11347.54609815598;11358.09135269193;9986.650467233612;10919.454027298603;11166.274192177483;11310.773784524263;11341;8552.657893684183;8674.739757807276;8753.868691969625;8787.297016238686;8810.86783698245;8425.368097392597;8427.241548260687;8431.831363372821;8494.57527123629;8540;9861.211103111877;9993.350432221881;9997;10003.399796012263;10194.847077293995;10041.899581004389;10394.844077338998;10471.3193642413;10651.840222396826;10653.467326633792;7153.070100887059;7363.263673632594;7592.126905405949;7612.96193519051;7942.841143177219;8526.104759000464;8547.487150770725;8574.3997920145;8594.570271486526;8598.441101328393;7496.662650180864;7549.169591345058;7620.237976202336;7694.769156925301;7756.108047574601;7321.938318943617;7413.073365829271;7440.255974402517;7549.849003019799;7580.886286705593;7947.5231486111015;8087.069986951341;8100.797480063045;8119.634616442156;8121.106678265268;7014.719411223494;7279.4540409468445;7283.417326613752;7291.781246562611;7361.631918404166;7366.447516436181;7379.298966598165;7416.7404140854815;7431.70273189088;7439.032925719622 03:59:40 INFO - PROCESS | 1663 | |4;prototype.html;339.74519110667;347.2222222222221;349.5525727069351;350.0862748227128;355.2929669956787;6187.78342758003;6188.721507532161;6699.966500167498;6757.966210168955;6790.796276111724;6695.062691223233;6708.96645516773;6719.764808231713;6734.945841518335;6737.258901520829;4380.824767009316;4515.977420112895;4744.312074749159;4924.187509061018;4970.726610036447;4944.876378090558;4959.107360675077;4964.131277026506;4964.329815474901;5008.6744361616375;4162.396452514384;4911.140550403683;4915.803367865299;4924.778384972667;4933.45731969483;4708.364370809948;4773.618110551148;4907.288443175756;4910.877228069291;4926.704397736147;4244.045089854789;4251.128518653669;4255.340422234559;4258.190943720683;4280.6361459275995;6426.453751431134;6430.042180596931;6441.357453849285;6458.967705161491;6473.482121430275;5889.941100589025;5900.734466948998;5904.704764761787;5906.23218981534;5909.8227053188475;6967.885665410198;7149.034880291146;7186.281371862824;7188.640567971633;7193.604351760651;4735.1713450146435;4805.063012712522;4809;4811.422629284473;4832.492588278215;7502.212267711866;7536.886946695804;7555.886661700024;7577.659005344718;7596.65815038319;4076.796160192038;4884.511548845087;4888.266759986031;4893.681910675795;4904.117258893362;4959.429665588431;4960.528749768766;4967.130752118364;4968.254761785761;4972.179590367621;5868.589198756046;5870.178175055496;5874.794382196688;5879.588428809942;5889.0283103287475;6870.656467176721;6931.757388491378;7140.714371425189;7176.677049532784;7283.308085731953;1434.7489189391815;1663.6839000589848;1670.114839135236;1673.7991441027154;1674.4474323473225;1018.9911987132833;1046.576136664652;1055.9577616895237;1066.973325666849;1073.9409332486632;5793.536517078624;6113;6178.845528861814;6462.289148193695;6517.055027021151;6220.284667263232;7524.849503010018;7540.396768258526;7545.057427603239;7554.357879580188;6686.899696504556;6690.494638706349;6693.665316734144;6695.397414232742;6706.497012724065;3223.8871639492972;3447.4311738562856;3965.7422267552515;3966.4050392440786;3977.3039718049226;7533.284337987882;7847.293743562993;7876.724314649075;7883.093444253869;7884.369250459949;7714.4599878008;7759.961200193963;7761.534307941542;7767.417443691633;7774.844503109906;7832.138464768985;7837.7648670539975;7845.215478452109;7847.6468558914985;7856;6054.243219597551;7269;7353.926460735324;7527.88708169367;7545.358644515278;7326.926730732837;7332.670029848563;7339.559626422219;7352.0442342495135;7360.484766066324;7744.845103097794;7802.687892484237;7825.139234684067;7826.295633392794;7849.136594974662;4685.390899183084;4737.099951009297;4739.933514959107;4742.383490146259;4743.715377077387;7106.147262328554;7455.7017719290625;7468.663910123949;7472.252774722484;7475.36459400957;7089.929100708927;7155.499115062104;7155.713771449292;7161.355478007004;7176.102987126609;7137.10786151731;7150.499465037397;7150.606716630636;7162.283771623004;7174.246704096204;5799.884002320015;6999.405050570758;7002.719891204295;7011.894821577578;7014.649267536704;6929.072675618698;7146.9285307146265;7149.106361704787;7171.928280717126;7181.892271615826;6206.379362063757;6586.769463068961;6741.2921643226755;6873.140857392826;6886.48351373639 03:59:40 INFO - PROCESS | 1663 | |5;yui.html;726.6257877193245;765.9770206893795;788.9092754333253;790.5809920742005;809.4010432280114;15458.690826183469;15840.762388564151;15841.841581584195;15850.603734906634;15941.84058159418;14550.054246473972;14637.414503419877;14643.560693179195;14678.779818302733;14689.192094434802;783.0759703549824;800.4996876951905;971.0920289529278;995.0795514149419;998.9300748947577;993.9254555908336;1003.8293490106663;1007.914327282182;1008.9344192627456;1010.6361709784471;857.6012154348227;1055.6199768083484;1060.01948197917;1062.4156713807413;1062.7980683670116;859.6346552715103;861.0528418739399;861.4056301152185;1017.9643712470103;1047.2041248651003;788.7357735158729;789.8143936174994;790.9248621380959;792.2196636313226;792.5324058805274;15391.61520961985;15464.613384665361;15476.07143571378;15491;15531.3010914508;13163.736725265537;13184.208947463183;13222.622747047855;13394.933025334909;13460.394282257323;10397.272190946636;10422.374657520497;10428.687139385831;10849.349039057683;10884.183686223565;3612.3317186320614;3615.6022837487853;3658.3414985302634;3715.498407714951;3883.126296583246;85212;87867;89305.55347223222;90100;90355;9965.750856228507;10026.749331266778;10090.69727908164;10147.34042287249;10360.689179324632;23917.7608223919;23934.80325983694;23945.042198311872;23971.880140599533;24018.39954001164;7642.656080476392;7655.578943158069;7706.460547761715;7728;7938.841223175504;4082.22437736835;4185.916281674349;4221.662267018631;4281.785910704452;4428.114377124588;3781.7352785305316;3844.1927195288745;3846.9038274043373;3847.03824043989;3862.4013277941963;669.3206395508563;674.6356967237734;679.1748026148254;692.757534862804;703.8134894253027;533.5598131541494;587.166223961975;594.4976494861921;608.2184393054903;612.2834395220966;8463.873041904377;8555.572221388906;8602.268807151462;8635.438696484835;8647.351448641377;539.3285359727087;550.6668465578267;576.6597707352682;606.2028432611103;609.9904657791362;3846.6345697158727;3919.8236079376497;3964.3944202597995;4059.289624315733;4064.9593504065174;95875;96434;96737.51631241798;97521.512392439;98216.50891745495;82581.17418825855;83524;84187;84819.5759021201;86269.5686521576;73297.16757081116;74146;74821;75060;75568.62215688777;7798.610069496616;7914.24814642608;8106.351491880753;8157.06193787722;8239.134890836376;51951;52304.73847630737;53270.73364633152;53403;53477.465225347245;53974.73012635069;69954;74601.2539874616;83888;84887.72668410103;9882.806101428843;11888.28670279786;11926.403679815801;12189.51241950312;12589.24464532131;12355;12362.629121126382;12387.132900696864;12392.62822115338;12746.298953557643;11762.470688818852;12005.459754311252;12163.331016794162;12246.203996740183;12407.565735199225;11993.82009269879;12012.279263243883;12163.331016794162;12452.875471245534;12520.186187897758;11513.827292590451;11559.537618495166;11702.180847340946;11869.643910682693;12188.756224875275;9951.850722239027;10003.499825008575;10082.344647597884;10106.646267380607;10377.636782712569;10006.299559030798;10149.34029288094;10168;10168.33905796121;10173.237007224636 03:59:40 INFO - PROCESS | 1663 | __end_tp_report 03:59:40 INFO - PROCESS | 1663 | __start_cc_report 03:59:40 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,966 03:59:40 INFO - PROCESS | 1663 | __end_cc_report 03:59:40 INFO - PROCESS | 1663 | __startTimestamp1461754780690__endTimestamp 03:59:40 INFO - PROCESS | 1663 | 03:59:40 INFO - PROCESS | 1663 | ------- Summary: start ------- 03:59:40 INFO - PROCESS | 1663 | Number of tests: 6 03:59:40 INFO - PROCESS | 1663 | 03:59:40 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5758.28 Median:6008.91 stddev:647.01 (10.8%) stddev-sans-first:622.84 03:59:40 INFO - PROCESS | 1663 | Values: 3436.9 3511.0 3516.8 3536.3 3590.0 5763.8 5777.5 5789.6 5800.2 5828.5 5776.3 5809.7 5811.0 5815.4 5826.9 4486.4 5103.3 5163.6 5216.7 5272.4 5272.9 5282.7 5289.9 5290.3 5292.2 5226.2 5256.9 5258.8 5280.3 5282.2 5241.6 5284.7 5299.3 5312.1 5328.7 5067.9 5221.8 5233.7 5240.1 5242.8 5621.5 5672.0 5688.5 5697.3 5697.7 6185.3 6266.2 6273.5 6283.0 6283.4 6172.0 6204.4 6211.9 6245.5 6266.3 5851.5 5881.7 5935.4 5940.9 5941.5 6187.1 6202.1 6276.8 6278.5 6288.9 6188.4 6235.9 6239.1 6240.6 6245.1 6231.9 6236.7 6239.0 6243.9 6268.5 6040.9 6145.6 6191.1 6258.2 6262.1 6094.6 6233.6 6236.0 6238.1 6278.2 4356.6 4925.0 4925.7 4935.4 4957.7 3768.3 4419.1 4444.0 4477.0 4479.0 5733.8 5736.9 5746.4 5756.7 5795.4 6210.7 6212.7 6215.4 6216.7 6240.3 4774.9 5667.7 5728.9 5742.3 5752.8 5332.9 5381.4 5413.1 5414.4 5414.5 6271.7 6297.4 6300.2 6307.1 6326.7 6193.0 6194.9 6225.1 6242.7 6252.3 6309.6 6317.2 6323.8 6324.3 6330.3 6241.0 6248.2 6264.4 6278.5 6284.2 6164.6 6180.1 6234.9 6278.8 6284.1 5322.0 6109.5 6147.4 6150.3 6172.6 6090.4 6098.7 6132.7 6161.8 6163.6 5870.3 5927.4 5995.1 5998.4 6008.9 5961.4 6138.0 6205.4 6206.2 6220.1 5974.5 6044.8 6055.4 6072.7 6100.7 03:59:40 INFO - PROCESS | 1663 | 03:59:40 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:62534.21 Median:372343.39 stddev:126269.23 (33.9%) stddev-sans-first:126537.55 03:59:40 INFO - PROCESS | 1663 | Values: 501.1 598.1 618.2 655.3 666.9 31730.0 39973.6 40337.4 40351.8 40463.0 42834.6 43548.9 43551.8 43616.0 43626.6 9619.4 9860.5 9867.9 9876.1 9888.9 8647.8 9607.6 9771.2 9785.2 9792.0 9865.0 9934.2 9942.2 9944.7 9962.9 9836.2 9913.0 9931.2 9937.0 9940.6 9630.0 9762.9 9770.9 9784.4 9799.6 40834.6 41378.0 41380.8 41428.8 41451.2 3229.8 3420.3 3861.7 3901.3 3919.3 28096.0 28100.9 28160.9 28312.0 28486.6 2761.7 2794.8 2827.7 2868.0 2890.7 499436.0 499468.0 500679.0 503635.0 505250.0 65948.0 67501.0 67590.0 67728.3 67736.0 71458.6 71658.0 71697.0 71754.0 71829.6 18376.7 18578.6 18588.4 18607.3 18620.3 14217.6 14286.7 14326.7 14349.7 14377.5 19407.5 20573.5 20911.3 20929.4 20964.5 6366.8 8495.8 8662.4 8764.5 9047.4 1005.3 1035.6 1138.4 1152.1 1185.2 29036.0 30361.2 30465.2 30573.2 31301.7 1184.1 1193.7 1228.9 1275.1 1346.7 12220.3 15195.7 15858.9 16148.9 16534.5 532023.0 534961.0 537396.0 538233.0 539931.3 62292.7 62571.4 62673.7 62673.7 62940.7 1435.2 1539.7 1549.6 1578.3 1581.2 25145.2 28215.2 29129.6 29585.0 30215.8 49852.0 50154.0 50289.7 50293.7 50299.7 369280.2 370937.0 371484.0 372208.0 372478.8 29535.9 34202.3 34314.5 35055.0 35069.8 42450.0 42848.9 44672.3 44752.1 46128.8 33368.8 35437.0 36558.0 36623.3 37311.8 35889.8 36031.5 36427.8 36652.6 36748.8 35275.6 35834.0 36740.9 36933.6 37170.0 31931.2 32926.8 32947.8 33238.7 33249.3 21486.5 22183.2 24401.8 24532.4 25166.8 03:59:40 INFO - PROCESS | 1663 | 03:59:40 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:34830.87 Median:2983.81 stddev:103838.92 (3480.1%) stddev-sans-first:104098.00 03:59:40 INFO - PROCESS | 1663 | Values: 382.1 383.5 385.6 391.3 391.9 16547.1 17069.2 19346.8 22626.3 22730.3 20484.4 20851.2 21321.7 21330.0 21403.7 5271.4 5798.1 6066.6 6067.0 6070.8 5749.6 5908.1 6002.8 6005.2 6011.3 5929.9 6002.3 6027.1 6042.2 6044.9 5701.5 5997.7 6042.2 6051.0 6057.9 5219.3 5253.5 5256.4 5260.6 5260.9 25916.5 26483.5 26902.9 26949.6 26957.2 2340.2 2575.8 2956.9 2970.2 2997.4 14591.9 14838.3 15098.7 15184.9 15510.3 1706.5 1733.3 1760.4 1763.9 1835.0 77458.6 100358.0 103691.0 107068.9 107450.0 9731.4 10651.9 10924.8 11021.1 11171.2 13676.7 13695.8 13710.0 13719.9 13743.2 7636.6 8773.2 8913.6 8951.5 9076.7 9242.0 9243.3 9276.3 9367.4 9373.4 6214.3 6317.1 6329.4 6340.9 6345.3 1843.4 1861.7 1864.9 1870.0 1896.1 430.9 440.7 974.6 1055.8 1096.6 11505.5 11834.3 11893.2 11948.8 12051.9 462.6 476.2 477.3 478.9 483.2 6049.8 6091.0 6126.5 6218.4 6237.9 585150.0 617651.0 636247.0 644610.0 648806.0 112901.0 116963.0 121882.0 124030.4 125824.4 996.3 1033.3 1037.2 1038.6 1056.6 18410.0 18804.3 19114.2 19119.8 19397.7 52033.5 52695.7 52912.7 52968.7 53238.5 70334.0 75103.6 75994.9 76109.6 76110.2 8080.1 8090.6 8201.3 8319.9 8360.5 11842.9 13138.1 13269.8 13520.7 13542.3 11385.0 11525.9 11593.0 11679.8 11822.4 8020.6 11375.0 11426.2 11449.3 11748.5 9556.8 11800.9 12033.0 12038.0 12040.0 7280.2 7968.0 8150.7 8285.7 8303.8 10186.8 10376.6 10696.9 11955.5 11956.2 03:59:40 INFO - PROCESS | 1663 | 03:59:40 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:6321.24 Median:5076.75 stddev:3326.73 (65.5%) stddev-sans-first:3316.39 03:59:40 INFO - PROCESS | 1663 | Values: 1508.6 1625.3 1658.3 1997.4 2070.3 8369.1 9004.0 9203.2 9216.9 9234.7 8841.2 9080.6 9096.5 9104.3 9141.5 2261.9 2505.4 2551.8 2565.1 2589.3 2583.5 2589.0 2590.3 2590.9 2591.1 2487.2 2589.0 2593.9 2594.7 2600.1 2187.9 2555.8 2590.4 2590.5 2591.7 2522.0 2562.2 2567.3 2568.8 2577.1 8010.4 9097.7 9158.3 9160.1 9168.8 5624.2 5733.4 5749.5 5768.2 5785.5 3580.4 3682.2 3729.8 3735.5 3740.7 1067.0 1076.4 1081.9 1103.5 1125.6 12450.6 12469.6 12499.3 12500.9 12532.2 5032.5 5060.2 5093.3 5137.7 5154.9 10518.5 10535.4 10549.8 10554.8 10568.3 5768.7 5794.8 5805.5 5814.4 5820.1 4587.4 4758.5 4979.7 5177.7 5186.3 4045.3 4109.7 4111.4 4139.5 4140.2 1341.4 1362.1 1363.0 1369.3 1370.0 1145.1 1161.3 1167.4 1369.5 1447.9 8524.9 8596.2 8637.8 8695.0 8751.8 1234.6 1236.0 1242.6 1243.7 1257.4 5737.2 6441.8 6535.5 6540.6 6546.0 10838.2 11338.4 11343.4 11347.5 11358.1 9986.7 10919.5 11166.3 11310.8 11341.0 8552.7 8674.7 8753.9 8787.3 8810.9 8425.4 8427.2 8431.8 8494.6 8540.0 9861.2 9993.4 9997.0 10003.4 10194.8 10041.9 10394.8 10471.3 10651.8 10653.5 7153.1 7363.3 7592.1 7613.0 7942.8 8526.1 8547.5 8574.4 8594.6 8598.4 7496.7 7549.2 7620.2 7694.8 7756.1 7321.9 7413.1 7440.3 7549.8 7580.9 7947.5 8087.1 8100.8 8119.6 8121.1 7014.7 7279.5 7283.4 7291.8 7361.6 7366.4 7379.3 7416.7 7431.7 7439.0 03:59:40 INFO - PROCESS | 1663 | 03:59:40 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:5857.83 Median:6551.91 stddev:1883.20 (28.7%) stddev-sans-first:1842.38 03:59:40 INFO - PROCESS | 1663 | Values: 339.7 347.2 349.6 350.1 355.3 6187.8 6188.7 6700.0 6758.0 6790.8 6695.1 6709.0 6719.8 6734.9 6737.3 4380.8 4516.0 4744.3 4924.2 4970.7 4944.9 4959.1 4964.1 4964.3 5008.7 4162.4 4911.1 4915.8 4924.8 4933.5 4708.4 4773.6 4907.3 4910.9 4926.7 4244.0 4251.1 4255.3 4258.2 4280.6 6426.5 6430.0 6441.4 6459.0 6473.5 5889.9 5900.7 5904.7 5906.2 5909.8 6967.9 7149.0 7186.3 7188.6 7193.6 4735.2 4805.1 4809.0 4811.4 4832.5 7502.2 7536.9 7555.9 7577.7 7596.7 4076.8 4884.5 4888.3 4893.7 4904.1 4959.4 4960.5 4967.1 4968.3 4972.2 5868.6 5870.2 5874.8 5879.6 5889.0 6870.7 6931.8 7140.7 7176.7 7283.3 1434.7 1663.7 1670.1 1673.8 1674.4 1019.0 1046.6 1056.0 1067.0 1073.9 5793.5 6113.0 6178.8 6462.3 6517.1 6220.3 7524.8 7540.4 7545.1 7554.4 6686.9 6690.5 6693.7 6695.4 6706.5 3223.9 3447.4 3965.7 3966.4 3977.3 7533.3 7847.3 7876.7 7883.1 7884.4 7714.5 7760.0 7761.5 7767.4 7774.8 7832.1 7837.8 7845.2 7847.6 7856.0 6054.2 7269.0 7353.9 7527.9 7545.4 7326.9 7332.7 7339.6 7352.0 7360.5 7744.8 7802.7 7825.1 7826.3 7849.1 4685.4 4737.1 4739.9 4742.4 4743.7 7106.1 7455.7 7468.7 7472.3 7475.4 7089.9 7155.5 7155.7 7161.4 7176.1 7137.1 7150.5 7150.6 7162.3 7174.2 5799.9 6999.4 7002.7 7011.9 7014.6 6929.1 7146.9 7149.1 7171.9 7181.9 6206.4 6586.8 6741.3 6873.1 6886.5 03:59:40 INFO - PROCESS | 1663 | 03:59:40 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:19512.56 Median:4062.12 stddev:27663.22 (681.0%) stddev-sans-first:27704.86 03:59:40 INFO - PROCESS | 1663 | Values: 726.6 766.0 788.9 790.6 809.4 15458.7 15840.8 15841.8 15850.6 15941.8 14550.1 14637.4 14643.6 14678.8 14689.2 783.1 800.5 971.1 995.1 998.9 993.9 1003.8 1007.9 1008.9 1010.6 857.6 1055.6 1060.0 1062.4 1062.8 859.6 861.1 861.4 1018.0 1047.2 788.7 789.8 790.9 792.2 792.5 15391.6 15464.6 15476.1 15491.0 15531.3 13163.7 13184.2 13222.6 13394.9 13460.4 10397.3 10422.4 10428.7 10849.3 10884.2 3612.3 3615.6 3658.3 3715.5 3883.1 85212.0 87867.0 89305.6 90100.0 90355.0 9965.8 10026.7 10090.7 10147.3 10360.7 23917.8 23934.8 23945.0 23971.9 24018.4 7642.7 7655.6 7706.5 7728.0 7938.8 4082.2 4185.9 4221.7 4281.8 4428.1 3781.7 3844.2 3846.9 3847.0 3862.4 669.3 674.6 679.2 692.8 703.8 533.6 587.2 594.5 608.2 612.3 8463.9 8555.6 8602.3 8635.4 8647.4 539.3 550.7 576.7 606.2 610.0 3846.6 3919.8 3964.4 4059.3 4065.0 95875.0 96434.0 96737.5 97521.5 98216.5 82581.2 83524.0 84187.0 84819.6 86269.6 73297.2 74146.0 74821.0 75060.0 75568.6 7798.6 7914.2 8106.4 8157.1 8239.1 51951.0 52304.7 53270.7 53403.0 53477.5 53974.7 69954.0 74601.3 83888.0 84887.7 9882.8 11888.3 11926.4 12189.5 12589.2 12355.0 12362.6 12387.1 12392.6 12746.3 11762.5 12005.5 12163.3 12246.2 12407.6 11993.8 12012.3 12163.3 12452.9 12520.2 11513.8 11559.5 11702.2 11869.6 12188.8 9951.9 10003.5 10082.3 10106.6 10377.6 10006.3 10149.3 10168.0 10168.3 10173.2 03:59:40 INFO - PROCESS | 1663 | -------- Summary: end -------- 03:59:40 INFO - PROCESS | 1663 | 03:59:41 INFO - TEST-INFO | 1663: exit 0 03:59:41 INFO - TEST-OK | dromaeo_css | took 1112568ms 03:59:41 INFO - TEST-START | kraken 03:59:41 INFO - operating with platform_type : mac_ 03:59:41 INFO - Initialising browser for kraken test... 03:59:41 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpELlMC2/profile http://localhost:49277/getInfo.html) 03:59:43 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 03:59:43 INFO - PROCESS | 1677 | colorDepth:24 03:59:43 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 03:59:43 INFO - PROCESS | 1677 | __metrics 03:59:43 INFO - PROCESS | 1677 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:59:43 INFO - PROCESS | 1677 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:59:44 INFO - TEST-INFO | 1677: exit 0 03:59:44 INFO - Browser initialized. 03:59:44 INFO - Running cycle 1/1 for kraken test... 03:59:44 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpELlMC2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:59:56 INFO - PROCESS | 1679 | RSS: Main: 207765504 03:59:56 INFO - PROCESS | 1679 | 03:59:58 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 03:59:59 INFO - PROCESS | 1679 | RSS: Main: 262770688 03:59:59 INFO - PROCESS | 1679 | 04:00:01 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 04:00:02 INFO - PROCESS | 1679 | RSS: Main: 273317888 04:00:02 INFO - PROCESS | 1679 | 04:00:05 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 04:00:06 INFO - PROCESS | 1679 | RSS: Main: 263479296 04:00:06 INFO - PROCESS | 1679 | 04:00:08 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 04:00:08 INFO - PROCESS | 1679 | RSS: Main: 265592832 04:00:08 INFO - PROCESS | 1679 | 04:00:10 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:00:11 INFO - PROCESS | 1679 | RSS: Main: 265895936 04:00:11 INFO - PROCESS | 1679 | 04:00:14 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 04:00:14 INFO - PROCESS | 1679 | RSS: Main: 266358784 04:00:14 INFO - PROCESS | 1679 | 04:00:17 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 04:00:17 INFO - PROCESS | 1679 | RSS: Main: 265887744 04:00:17 INFO - PROCESS | 1679 | 04:00:20 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 04:00:21 INFO - PROCESS | 1679 | RSS: Main: 266170368 04:00:21 INFO - PROCESS | 1679 | 04:00:22 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:00:23 INFO - PROCESS | 1679 | RSS: Main: 299794432 04:00:23 INFO - PROCESS | 1679 | 04:00:25 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:00:25 INFO - PROCESS | 1679 | RSS: Main: 300662784 04:00:25 INFO - PROCESS | 1679 | 04:00:27 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:00:28 INFO - PROCESS | 1679 | RSS: Main: 305885184 04:00:28 INFO - PROCESS | 1679 | 04:00:31 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:00:31 INFO - PROCESS | 1679 | RSS: Main: 307519488 04:00:31 INFO - PROCESS | 1679 | 04:00:34 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:00:34 INFO - PROCESS | 1679 | RSS: Main: 307437568 04:00:34 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 04:00:36 INFO - PROCESS | 1679 | RSS: Main: 370204672 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | __start_tp_report 04:00:36 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 04:00:36 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 04:00:36 INFO - PROCESS | 1679 | |i|pagename|runs| 04:00:36 INFO - PROCESS | 1679 | |0;ai-astar;110;109;112;111;110;112;118;112;110;109 04:00:36 INFO - PROCESS | 1679 | |1;audio-beat-detection;153;121;143;154;102;137;138;178;152;117 04:00:36 INFO - PROCESS | 1679 | |2;audio-dft;187;186;185;186;185;185;185;185;185;186 04:00:36 INFO - PROCESS | 1679 | |3;audio-fft;71;72;75;115;55;58;58;57;114;55 04:00:36 INFO - PROCESS | 1679 | |4;audio-oscillator;90;98;135;78;78;81;118;76;79;121 04:00:36 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;87;84;86;85;87;83;88;84;88;83 04:00:36 INFO - PROCESS | 1679 | |6;imaging-darkroom;93;92;93;92;93;93;92;93;93;92 04:00:36 INFO - PROCESS | 1679 | |7;imaging-desaturate;85;84;86;84;85;86;101;137;132;84 04:00:36 INFO - PROCESS | 1679 | |8;json-parse-financial;55;60;59;57;57;58;56;66;55;53 04:00:36 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;64;67;67;71;69;66;64;65;66;65 04:00:36 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;80;79;81;81;81;81;80;81;82;86 04:00:36 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;132;132;134;122;132;123;123;125;130;117 04:00:36 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;134;124;136;133;133;134;133;133;134;134 04:00:36 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;67;64;64;65;66;66;65;70;85;92 04:00:36 INFO - PROCESS | 1679 | __end_tp_report 04:00:36 INFO - PROCESS | 1679 | __start_cc_report 04:00:36 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1212 04:00:36 INFO - PROCESS | 1679 | __end_cc_report 04:00:36 INFO - PROCESS | 1679 | __startTimestamp1461754836741__endTimestamp 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | ------- Summary: start ------- 04:00:36 INFO - PROCESS | 1679 | Number of tests: 14 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.50 stddev:2.63 (2.4%) stddev-sans-first:2.74 04:00:36 INFO - PROCESS | 1679 | Values: 110.0 109.0 112.0 111.0 110.0 112.0 118.0 112.0 110.0 109.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:147.50 stddev:21.87 (14.8%) stddev-sans-first:22.65 04:00:36 INFO - PROCESS | 1679 | Values: 153.0 121.0 143.0 154.0 102.0 137.0 138.0 178.0 152.0 117.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:185.50 Median:185.50 stddev:0.71 (0.4%) stddev-sans-first:0.50 04:00:36 INFO - PROCESS | 1679 | Values: 187.0 186.0 185.0 186.0 185.0 185.0 185.0 185.0 185.0 186.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:73.00 Median:58.00 stddev:23.11 (39.9%) stddev-sans-first:24.50 04:00:36 INFO - PROCESS | 1679 | Values: 71.0 72.0 75.0 115.0 55.0 58.0 58.0 57.0 114.0 55.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:78.50 stddev:21.68 (27.6%) stddev-sans-first:22.90 04:00:36 INFO - PROCESS | 1679 | Values: 90.0 98.0 135.0 78.0 78.0 81.0 118.0 76.0 79.0 121.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.50 stddev:1.96 (2.3%) stddev-sans-first:2.00 04:00:36 INFO - PROCESS | 1679 | Values: 87.0 84.0 86.0 85.0 87.0 83.0 88.0 84.0 88.0 83.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:93.00 stddev:0.52 (0.6%) stddev-sans-first:0.53 04:00:36 INFO - PROCESS | 1679 | Values: 93.0 92.0 93.0 92.0 93.0 93.0 92.0 93.0 93.0 92.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:84.50 stddev:20.75 (24.6%) stddev-sans-first:21.59 04:00:36 INFO - PROCESS | 1679 | Values: 85.0 84.0 86.0 84.0 85.0 86.0 101.0 137.0 132.0 84.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:57.50 stddev:3.60 (6.3%) stddev-sans-first:3.69 04:00:36 INFO - PROCESS | 1679 | Values: 55.0 60.0 59.0 57.0 57.0 58.0 56.0 66.0 55.0 53.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.50 stddev:2.22 (3.3%) stddev-sans-first:2.18 04:00:36 INFO - PROCESS | 1679 | Values: 64.0 67.0 67.0 71.0 69.0 66.0 64.0 65.0 66.0 65.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.00 stddev:1.87 (2.3%) stddev-sans-first:1.94 04:00:36 INFO - PROCESS | 1679 | Values: 80.0 79.0 81.0 81.0 81.0 81.0 80.0 81.0 82.0 86.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:131.00 stddev:5.72 (4.4%) stddev-sans-first:5.77 04:00:36 INFO - PROCESS | 1679 | Values: 132.0 132.0 134.0 122.0 132.0 123.0 123.0 125.0 130.0 117.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:132.80 Median:134.00 stddev:3.22 (2.4%) stddev-sans-first:3.39 04:00:36 INFO - PROCESS | 1679 | Values: 134.0 124.0 136.0 133.0 133.0 134.0 133.0 133.0 134.0 134.0 04:00:36 INFO - PROCESS | 1679 | 04:00:36 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:66.50 stddev:9.83 (14.8%) stddev-sans-first:10.35 04:00:36 INFO - PROCESS | 1679 | Values: 67.0 64.0 64.0 65.0 66.0 66.0 65.0 70.0 85.0 92.0 04:00:36 INFO - PROCESS | 1679 | -------- Summary: end -------- 04:00:36 INFO - PROCESS | 1679 | 04:00:37 INFO - TEST-INFO | 1679: exit 0 04:00:37 INFO - TEST-OK | kraken | took 56303ms 04:00:37 INFO - SUITE-END | took 1168s 04:00:37 INFO - Completed test suite (00:19:29) 04:00:37 INFO - javascript benchmark 04:00:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3436.9140771480716, 3510.999365180924, 3516.788992660439, 3536.3457760314336, 3589.9641003589973, 5763.769449222036, 5777.480026797587, 5789.623674461162, 5800.187973683687, 5828.504577110945, 5776.335721392041, 5809.7385617647205, 5810.98307796135, 5815.360310365867, 5826.883462330751, 4486.394336764536, 5103.260027296058, 5163.561097306715, 5216.713080780574, 5272.393674727398, 5272.868178295555, 5282.683039017652, 5289.86775330616, 5290.285811415468, 5292.232626269208, 5226.24219488174, 5256.8685782855355, 5258.789648414078, 5280.260763493114, 5282.2076688496645, 5241.606879484035, 5284.682919024833, 5299.284596579451, 5312.123499622577, 5328.680279183222, 5067.949320506785, 5221.765020574083, 5233.738313084368, 5240.109181439165, 5242.842714718565, 5621.494065534122, 5672.0, 5688.516476099495, 5697.344805347397, 5697.658140511541, 6185.319614842364, 6266.216722909637, 6273.529485288576, 6283.0, 6283.403076707706, 6171.9691401542705, 6204.379562043805, 6211.875762484725, 6245.46913512352, 6266.342034086447, 5851.531877449794, 5881.735321910524, 5935.376785437553, 5940.851478712981, 5941.494972927263, 6187.102870083812, 6202.100695399142, 6276.780312689034, 6278.529110316745, 6288.874222515524, 6188.412100850412, 6235.906461403082, 6239.095331176954, 6240.594361366497, 6245.063240513869, 6231.875362492725, 6236.719347629368, 6238.968805156036, 6243.906341404882, 6268.49852011838, 6040.879182416415, 6145.617236121837, 6191.133241346215, 6258.249010118724, 6262.060690896402, 6094.634321940698, 6233.594816336924, 6235.968820155961, 6238.1266622672865, 6278.183836101369, 4356.629686476622, 4925.039617274669, 4925.679830810986, 4935.358403407534, 4957.652964292465, 3768.2840260350445, 4419.071994881111, 4443.977780111079, 4476.992676647728, 4479.0370070434365, 5733.799317023968, 5736.913946290809, 5746.367899531048, 5756.654600723886, 5795.420457954171, 6210.72051757672, 6212.658303793244, 6215.409536094064, 6216.689165541795, 6240.251169859555, 4774.856754297377, 5667.659940403589, 5728.885422291531, 5742.253507044057, 5752.769889204469, 5332.893342133136, 5381.408045115034, 5413.079776438015, 5414.377346605112, 5414.458554144554, 6271.655058971709, 6297.4017468340435, 6300.180976473029, 6307.085472606446, 6326.715297811517, 6193.0, 6194.938050619436, 6225.097360882737, 6242.687865606611, 6252.343503932022, 6309.589876658002, 6317.178766760292, 6323.810285691437, 6324.272708638381, 6330.272018717907, 6241.032639940817, 6248.218972628421, 6264.373562643699, 6278.497720182283, 6284.183056202664, 6164.630122192682, 6180.072989051679, 6234.875302493834, 6278.811635650938, 6284.057391391328, 5322.0154271459905, 6109.526129009804, 6147.385261473817, 6150.261968563801, 6172.629642221481, 6090.390960903874, 6098.725557349841, 6132.6627035513475, 6161.784337548164, 6163.630182189083, 5870.295564532284, 5927.436893495111, 5995.070764031582, 5998.430149135826, 6008.909866352095, 5961.403859614177, 6138.0078185458515, 6205.441510264098, 6206.162168107247, 6220.129181914445, 5974.492168165581, 6044.758209671564, 6055.364186760503, 6072.696365181811, 6100.725467354068], "unit": "score", "name": "dojo.html", "value": 5717.525325578107}, {"lowerIsBetter": false, "replicates": [501.12803721524557, 598.0729868703511, 618.2395653346384, 655.2955572759284, 666.8899631560791, 31730.0, 39973.60026399735, 40337.39493907594, 40351.79824100883, 40463.0, 42834.57165428345, 43548.91127721809, 43551.78224108875, 43616.0, 43626.563734362644, 9619.374740641853, 9860.457674827881, 9867.90132098677, 9876.061774131482, 9888.901110988905, 8647.827043459127, 9607.615695372177, 9771.169450596708, 9785.217182625409, 9792.0, 9865.013498650149, 9934.205263578933, 9942.154916832042, 9944.6519371822, 9962.850557241647, 9836.163926066292, 9913.0, 9931.15585175261, 9936.95031524845, 9940.5526751296, 9630.0, 9762.853557196648, 9770.853437198448, 9784.412935223909, 9799.608015679436, 40834.59165408338, 41377.96555086117, 41380.79309603433, 41428.79285603553, 41451.1709765803, 3229.7739158258933, 3420.264643101745, 3861.710371722104, 3901.3367727486398, 3919.333713268751, 28096.0, 28100.859495702596, 28160.85919570389, 28312.0, 28486.572701409495, 2761.7446483714402, 2794.832310061403, 2827.7030911754177, 2867.985660071707, 2890.7253810887933, 499436.0, 499468.0, 500679.0, 503635.0, 505250.0, 65948.0, 67501.0, 67590.0, 67728.32271677318, 67736.0, 71458.64270678717, 71658.0, 71697.0, 71754.0, 71829.64085179644, 18376.724349134773, 18578.628427431377, 18588.44234672962, 18607.255709771456, 18620.34828781013, 14217.573472795833, 14286.714265714836, 14326.713465730836, 14349.713005739826, 14377.496787612383, 19407.514812129528, 20573.485662858548, 20911.26810561623, 20929.372118836458, 20964.475888102614, 6366.840828979312, 8495.830083398298, 8662.393632445794, 8764.474131552128, 9047.366684332164, 1005.2962925951863, 1035.5805898611075, 1138.362516990488, 1152.1186292486254, 1185.2355230876037, 29036.0, 30361.24096897551, 30465.238369040508, 30573.235669108006, 31301.686983129875, 1184.135581025856, 1193.6896406934259, 1228.8586812516496, 1275.101053757099, 1346.7104572516955, 12220.349067830297, 15195.696086078438, 15858.92070539663, 16148.919255403647, 16534.503964881074, 532023.0, 534961.0, 537396.0, 538233.0, 539931.3003434958, 62292.68853655703, 62571.374286257465, 62673.68663156746, 62673.74652506924, 62940.68529657414, 1435.2106341512126, 1539.699758547095, 1549.5816129644932, 1578.27399396279, 1581.2014932459035, 25145.24564263075, 28215.15354539367, 29129.56305655459, 29585.0, 30215.848920755256, 49852.0, 50154.0, 50289.74855125701, 50293.74853125711, 50299.74850125726, 369280.1535992303, 370937.0, 371484.0, 372208.0, 372478.788878722, 29535.85232073826, 34202.31595368029, 34314.48528272128, 35055.0, 35069.77255796035, 42450.0, 42848.92877678083, 44672.32991505065, 44752.10495790131, 46128.769356153, 33368.83315583504, 35437.0, 36558.0, 36623.26753464863, 37311.81344093262, 35889.82055089708, 36031.45952810658, 36427.81786091052, 36652.63347366492, 36748.81625591855, 35275.64724352724, 35834.0, 36740.867523111316, 36933.63066369302, 37170.0, 31931.201719956258, 32926.83536582302, 32947.835260823544, 33238.66761332453, 33249.335013300086, 21486.462838429165, 22183.223587174372, 24401.755982439947, 24532.386690332885, 25166.752689880166], "unit": "score", "name": "ext.html", "value": 20286.54356866899}, {"lowerIsBetter": false, "replicates": [382.05060424844265, 383.4612369620682, 385.5893473450775, 391.3112921258584, 391.90006548330206, 16547.089910054972, 17069.231884565193, 19346.806531934675, 22626.321210363676, 22730.31809045729, 20484.385468435932, 20851.165953361884, 21321.68017479739, 21330.0, 21403.67894481584, 5271.4464981177, 5798.130280457944, 6066.575339726221, 6067.02927531595, 6070.757169713206, 5749.56878234132, 5908.113782932552, 6002.789902353418, 6005.159277701126, 6011.30869949957, 5929.88140237195, 6002.339742628307, 6027.126066720323, 6042.154098426224, 6044.879102417949, 5701.458361455655, 5997.7001149942325, 6042.184305118819, 6050.999999999978, 6057.909131363033, 5219.269302297644, 5253.52718255355, 5256.448072952323, 5260.631755777097, 5260.8684782880355, 25916.481670366677, 26483.47033059328, 26902.865485672446, 26949.595756063674, 26957.191284261502, 2340.180936672168, 2575.8196926215173, 2956.8965086222092, 2970.2425881400104, 2997.370552184022, 14591.854081459156, 14838.34519396591, 15098.698026039527, 15184.937054406197, 15510.30203640839, 1706.496583507868, 1733.2633630706898, 1760.3662681434673, 1763.8941663500102, 1835.0, 77458.61270693611, 100358.0, 103690.96309036888, 107068.92931070668, 107450.0, 9731.416115032978, 10651.89348106509, 10924.836127458095, 11021.063209627111, 11171.218014739054, 13676.658083547793, 13695.794563081561, 13710.040297179301, 13719.931400342934, 13743.244121573409, 7636.579988100708, 8773.210411063035, 8913.598888050054, 8951.507667078244, 9076.727698169065, 9242.0, 9243.260539156985, 9276.304277179239, 9367.391119577342, 9373.39072960269, 6214.347493513206, 6317.1156038154695, 6329.430351268408, 6340.936590634127, 6345.302016778151, 1843.3640394064028, 1861.7393564900924, 1864.9254029838926, 1870.0462763990463, 1896.1183049881872, 430.8513562820822, 440.7135362014652, 974.6345120579782, 1055.8205105132147, 1096.6490722968733, 11505.482253298622, 11834.289942603471, 11893.22694024903, 11948.820767688492, 12051.879481205076, 462.625273528443, 476.2142464932819, 477.29122253453596, 478.9473157952622, 483.20512756515336, 6049.8487537811025, 6091.0, 6126.509879209652, 6218.378162183745, 6237.937620623827, 585150.0, 617651.0, 636247.0, 644610.0, 648806.0, 112901.0, 116963.0, 121882.0, 124030.37984810019, 125824.37087814503, 996.3075662414698, 1033.3283365812283, 1037.180627304421, 1038.6416686243233, 1056.6196169379018, 18409.98745069034, 18804.341848035252, 19114.235430583176, 19119.75721578093, 19397.70903436478, 52033.47966520286, 52695.68260793511, 52912.73543632411, 52968.73515632397, 53238.46761532335, 70334.0, 75103.62448187724, 75994.86007709778, 76109.61945190238, 76110.23889761254, 8080.111187769223, 8090.555019473985, 8201.343892488705, 8319.916800831914, 8360.54017029045, 11842.881571184178, 13138.080334376502, 13269.800952985519, 13520.72958540804, 13542.255175965416, 11385.03227225695, 11525.942370288094, 11592.95663390299, 11679.824802628138, 11822.40887955616, 8020.556299865847, 11375.0, 11426.2001659883, 11449.255798373079, 11748.530058797553, 9556.761080972754, 11800.940995294968, 12033.037357011242, 12038.0, 12040.0, 7280.199178090512, 7967.964164658557, 8150.7147249845975, 8285.668573257244, 8303.792405189919, 10186.796264074825, 10376.584936602452, 10696.946515267375, 11955.462004210005, 11956.222845515014], "unit": "score", "name": "jquery.html", "value": 9259.642693713202}, {"lowerIsBetter": false, "replicates": [1508.5700575336032, 1625.260506469556, 1658.2537857963919, 1997.4107638246717, 2070.306447340141, 8369.121242269566, 9003.954980225099, 9203.17171454569, 9216.861747073799, 9234.67678631248, 8841.248493878029, 9080.591373388197, 9096.545172741353, 9104.317176211778, 9141.45151290923, 2261.8869056547187, 2505.386180385808, 2551.8086143539263, 2565.0509311554656, 2589.3138318345655, 2583.470388570348, 2589.029114082219, 2590.2747230775412, 2590.870456477169, 2591.0931174089014, 2487.2413913756295, 2588.961165582518, 2593.8703064846777, 2594.662693849797, 2600.1419531554543, 2187.9124835006583, 2555.782758465533, 2590.365360486674, 2590.456004239112, 2591.7278685737915, 2521.9747802522106, 2562.1929092335854, 2567.281161274846, 2568.781653559431, 2577.149540651599, 8010.439269251155, 9097.727068187965, 9158.313126515539, 9160.129787670161, 9168.816623667555, 5624.184493248456, 5733.369329373765, 5749.482546570829, 5768.221290125821, 5785.47930686236, 3580.445031020196, 3682.226732386202, 3729.8321575529167, 3735.477033215352, 3740.738148329618, 1066.9573217071309, 1076.354187487509, 1081.9080378167864, 1103.4648195625116, 1125.5610311978335, 12450.564230251897, 12469.625911222678, 12499.250044997238, 12500.874991250153, 12532.185407948455, 5032.47159048302, 5060.1903695408555, 5093.312402825572, 5137.717425541557, 5154.9484505155215, 10518.474076296154, 10535.367877927349, 10549.789004219872, 10554.788904221872, 10568.260221784401, 5768.74040668171, 5794.79718209869, 5805.506531944832, 5814.418558144236, 5820.126980952891, 4587.357769912215, 4758.547937945889, 4979.651424400257, 5177.715225662625, 5186.299849520342, 4045.296270357165, 4109.671226301889, 4111.403846442309, 4139.544650088488, 4140.213359461693, 1341.4164838295374, 1362.1350442469104, 1362.9863701363058, 1369.2674419185687, 1370.020435388682, 1145.1297014269214, 1161.2509931094396, 1167.3929556630674, 1369.5069774881035, 1447.8696917277284, 8524.914750852537, 8596.183362580545, 8637.827243455096, 8695.043545210021, 8751.824963500821, 1234.6481252843075, 1235.9567415140427, 1242.5961562492225, 1243.7325974915436, 1257.4215860704178, 5737.196792449061, 6441.774537891151, 6535.509836762262, 6540.640264785482, 6545.967270163619, 10838.241323107306, 11338.43307834605, 11343.376114313627, 11347.54609815598, 11358.09135269193, 9986.650467233612, 10919.454027298603, 11166.274192177483, 11310.773784524263, 11341.0, 8552.657893684183, 8674.739757807276, 8753.868691969625, 8787.297016238686, 8810.86783698245, 8425.368097392597, 8427.241548260687, 8431.831363372821, 8494.57527123629, 8540.0, 9861.211103111877, 9993.350432221881, 9997.0, 10003.399796012263, 10194.847077293995, 10041.899581004389, 10394.844077338998, 10471.3193642413, 10651.840222396826, 10653.467326633792, 7153.070100887059, 7363.263673632594, 7592.126905405949, 7612.96193519051, 7942.841143177219, 8526.104759000464, 8547.487150770725, 8574.3997920145, 8594.570271486526, 8598.441101328393, 7496.662650180864, 7549.169591345058, 7620.237976202336, 7694.769156925301, 7756.108047574601, 7321.938318943617, 7413.073365829271, 7440.255974402517, 7549.849003019799, 7580.886286705593, 7947.5231486111015, 8087.069986951341, 8100.797480063045, 8119.634616442156, 8121.106678265268, 7014.719411223494, 7279.4540409468445, 7283.417326613752, 7291.781246562611, 7361.631918404166, 7366.447516436181, 7379.298966598165, 7416.7404140854815, 7431.70273189088, 7439.032925719622], "unit": "score", "name": "mootools.html", "value": 5157.1112378075795}, {"lowerIsBetter": false, "replicates": [339.74519110667, 347.2222222222221, 349.5525727069351, 350.0862748227128, 355.2929669956787, 6187.78342758003, 6188.721507532161, 6699.966500167498, 6757.966210168955, 6790.796276111724, 6695.062691223233, 6708.96645516773, 6719.764808231713, 6734.945841518335, 6737.258901520829, 4380.824767009316, 4515.977420112895, 4744.312074749159, 4924.187509061018, 4970.726610036447, 4944.876378090558, 4959.107360675077, 4964.131277026506, 4964.329815474901, 5008.6744361616375, 4162.396452514384, 4911.140550403683, 4915.803367865299, 4924.778384972667, 4933.45731969483, 4708.364370809948, 4773.618110551148, 4907.288443175756, 4910.877228069291, 4926.704397736147, 4244.045089854789, 4251.128518653669, 4255.340422234559, 4258.190943720683, 4280.6361459275995, 6426.453751431134, 6430.042180596931, 6441.357453849285, 6458.967705161491, 6473.482121430275, 5889.941100589025, 5900.734466948998, 5904.704764761787, 5906.23218981534, 5909.8227053188475, 6967.885665410198, 7149.034880291146, 7186.281371862824, 7188.640567971633, 7193.604351760651, 4735.1713450146435, 4805.063012712522, 4809.0, 4811.422629284473, 4832.492588278215, 7502.212267711866, 7536.886946695804, 7555.886661700024, 7577.659005344718, 7596.65815038319, 4076.796160192038, 4884.511548845087, 4888.266759986031, 4893.681910675795, 4904.117258893362, 4959.429665588431, 4960.528749768766, 4967.130752118364, 4968.254761785761, 4972.179590367621, 5868.589198756046, 5870.178175055496, 5874.794382196688, 5879.588428809942, 5889.0283103287475, 6870.656467176721, 6931.757388491378, 7140.714371425189, 7176.677049532784, 7283.308085731953, 1434.7489189391815, 1663.6839000589848, 1670.114839135236, 1673.7991441027154, 1674.4474323473225, 1018.9911987132833, 1046.576136664652, 1055.9577616895237, 1066.973325666849, 1073.9409332486632, 5793.536517078624, 6113.0, 6178.845528861814, 6462.289148193695, 6517.055027021151, 6220.284667263232, 7524.849503010018, 7540.396768258526, 7545.057427603239, 7554.357879580188, 6686.899696504556, 6690.494638706349, 6693.665316734144, 6695.397414232742, 6706.497012724065, 3223.8871639492972, 3447.4311738562856, 3965.7422267552515, 3966.4050392440786, 3977.3039718049226, 7533.284337987882, 7847.293743562993, 7876.724314649075, 7883.093444253869, 7884.369250459949, 7714.4599878008, 7759.961200193963, 7761.534307941542, 7767.417443691633, 7774.844503109906, 7832.138464768985, 7837.7648670539975, 7845.215478452109, 7847.6468558914985, 7856.0, 6054.243219597551, 7269.0, 7353.926460735324, 7527.88708169367, 7545.358644515278, 7326.926730732837, 7332.670029848563, 7339.559626422219, 7352.0442342495135, 7360.484766066324, 7744.845103097794, 7802.687892484237, 7825.139234684067, 7826.295633392794, 7849.136594974662, 4685.390899183084, 4737.099951009297, 4739.933514959107, 4742.383490146259, 4743.715377077387, 7106.147262328554, 7455.7017719290625, 7468.663910123949, 7472.252774722484, 7475.36459400957, 7089.929100708927, 7155.499115062104, 7155.713771449292, 7161.355478007004, 7176.102987126609, 7137.10786151731, 7150.499465037397, 7150.606716630636, 7162.283771623004, 7174.246704096204, 5799.884002320015, 6999.405050570758, 7002.719891204295, 7011.894821577578, 7014.649267536704, 6929.072675618698, 7146.9285307146265, 7149.106361704787, 7171.928280717126, 7181.892271615826, 6206.379362063757, 6586.769463068961, 6741.2921643226755, 6873.140857392826, 6886.48351373639], "unit": "score", "name": "prototype.html", "value": 5228.577134910393}, {"lowerIsBetter": false, "replicates": [726.6257877193245, 765.9770206893795, 788.9092754333253, 790.5809920742005, 809.4010432280114, 15458.690826183469, 15840.762388564151, 15841.841581584195, 15850.603734906634, 15941.84058159418, 14550.054246473972, 14637.414503419877, 14643.560693179195, 14678.779818302733, 14689.192094434802, 783.0759703549824, 800.4996876951905, 971.0920289529278, 995.0795514149419, 998.9300748947577, 993.9254555908336, 1003.8293490106663, 1007.914327282182, 1008.9344192627456, 1010.6361709784471, 857.6012154348227, 1055.6199768083484, 1060.01948197917, 1062.4156713807413, 1062.7980683670116, 859.6346552715103, 861.0528418739399, 861.4056301152185, 1017.9643712470103, 1047.2041248651003, 788.7357735158729, 789.8143936174994, 790.9248621380959, 792.2196636313226, 792.5324058805274, 15391.61520961985, 15464.613384665361, 15476.07143571378, 15491.0, 15531.3010914508, 13163.736725265537, 13184.208947463183, 13222.622747047855, 13394.933025334909, 13460.394282257323, 10397.272190946636, 10422.374657520497, 10428.687139385831, 10849.349039057683, 10884.183686223565, 3612.3317186320614, 3615.6022837487853, 3658.3414985302634, 3715.498407714951, 3883.126296583246, 85212.0, 87867.0, 89305.55347223222, 90100.0, 90355.0, 9965.750856228507, 10026.749331266778, 10090.69727908164, 10147.34042287249, 10360.689179324632, 23917.7608223919, 23934.80325983694, 23945.042198311872, 23971.880140599533, 24018.39954001164, 7642.656080476392, 7655.578943158069, 7706.460547761715, 7728.0, 7938.841223175504, 4082.22437736835, 4185.916281674349, 4221.662267018631, 4281.785910704452, 4428.114377124588, 3781.7352785305316, 3844.1927195288745, 3846.9038274043373, 3847.03824043989, 3862.4013277941963, 669.3206395508563, 674.6356967237734, 679.1748026148254, 692.757534862804, 703.8134894253027, 533.5598131541494, 587.166223961975, 594.4976494861921, 608.2184393054903, 612.2834395220966, 8463.873041904377, 8555.572221388906, 8602.268807151462, 8635.438696484835, 8647.351448641377, 539.3285359727087, 550.6668465578267, 576.6597707352682, 606.2028432611103, 609.9904657791362, 3846.6345697158727, 3919.8236079376497, 3964.3944202597995, 4059.289624315733, 4064.9593504065174, 95875.0, 96434.0, 96737.51631241798, 97521.512392439, 98216.50891745495, 82581.17418825855, 83524.0, 84187.0, 84819.5759021201, 86269.5686521576, 73297.16757081116, 74146.0, 74821.0, 75060.0, 75568.62215688777, 7798.610069496616, 7914.24814642608, 8106.351491880753, 8157.06193787722, 8239.134890836376, 51951.0, 52304.73847630737, 53270.73364633152, 53403.0, 53477.465225347245, 53974.73012635069, 69954.0, 74601.2539874616, 83888.0, 84887.72668410103, 9882.806101428843, 11888.28670279786, 11926.403679815801, 12189.51241950312, 12589.24464532131, 12355.0, 12362.629121126382, 12387.132900696864, 12392.62822115338, 12746.298953557643, 11762.470688818852, 12005.459754311252, 12163.331016794162, 12246.203996740183, 12407.565735199225, 11993.82009269879, 12012.279263243883, 12163.331016794162, 12452.875471245534, 12520.186187897758, 11513.827292590451, 11559.537618495166, 11702.180847340946, 11869.643910682693, 12188.756224875275, 9951.850722239027, 10003.499825008575, 10082.344647597884, 10106.646267380607, 10377.636782712569, 10006.299559030798, 10149.34029288094, 10168.0, 10168.33905796121, 10173.237007224636], "unit": "score", "name": "yui.html", "value": 7273.480818070806}], "name": "dromaeo_css", "value": 7713.699016368164}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 109.0, 112.0, 111.0, 110.0, 112.0, 118.0, 112.0, 110.0, 109.0], "unit": "score", "name": "ai-astar", "value": 111.3}, {"lowerIsBetter": true, "replicates": [153.0, 121.0, 143.0, 154.0, 102.0, 137.0, 138.0, 178.0, 152.0, 117.0], "unit": "score", "name": "audio-beat-detection", "value": 139.5}, {"lowerIsBetter": true, "replicates": [187.0, 186.0, 185.0, 186.0, 185.0, 185.0, 185.0, 185.0, 185.0, 186.0], "unit": "score", "name": "audio-dft", "value": 185.5}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 75.0, 115.0, 55.0, 58.0, 58.0, 57.0, 114.0, 55.0], "unit": "score", "name": "audio-fft", "value": 73.0}, {"lowerIsBetter": true, "replicates": [90.0, 98.0, 135.0, 78.0, 78.0, 81.0, 118.0, 76.0, 79.0, 121.0], "unit": "score", "name": "audio-oscillator", "value": 95.4}, {"lowerIsBetter": true, "replicates": [87.0, 84.0, 86.0, 85.0, 87.0, 83.0, 88.0, 84.0, 88.0, 83.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 85.5}, {"lowerIsBetter": true, "replicates": [93.0, 92.0, 93.0, 92.0, 93.0, 93.0, 92.0, 93.0, 93.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 92.6}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 86.0, 84.0, 85.0, 86.0, 101.0, 137.0, 132.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 96.4}, {"lowerIsBetter": true, "replicates": [55.0, 60.0, 59.0, 57.0, 57.0, 58.0, 56.0, 66.0, 55.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 57.6}, {"lowerIsBetter": true, "replicates": [64.0, 67.0, 67.0, 71.0, 69.0, 66.0, 64.0, 65.0, 66.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.4}, {"lowerIsBetter": true, "replicates": [80.0, 79.0, 81.0, 81.0, 81.0, 81.0, 80.0, 81.0, 82.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 81.2}, {"lowerIsBetter": true, "replicates": [132.0, 132.0, 134.0, 122.0, 132.0, 123.0, 123.0, 125.0, 130.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 127.0}, {"lowerIsBetter": true, "replicates": [134.0, 124.0, 136.0, 133.0, 133.0, 134.0, 133.0, 133.0, 134.0, 134.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 132.8}, {"lowerIsBetter": true, "replicates": [67.0, 64.0, 64.0, 65.0, 66.0, 66.0, 65.0, 70.0, 85.0, 92.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.4}], "name": "kraken", "value": 1414.6000000000001}]} 04:00:37 INFO - Return code: 0 04:00:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:00:37 INFO - # TBPL SUCCESS # 04:00:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:00:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:00:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:00:38 INFO - 2016-04-27 04:00:38.014 screenresolution[1683:9872] starting screenresolution argv=screenresolution get 04:00:38 INFO - 2016-04-27 04:00:38.016 screenresolution[1683:9872] Display 0: 1600x1200x32@60 04:00:38 INFO - 2016-04-27 04:00:38.022 screenresolution[1684:9875] starting screenresolution argv=screenresolution list 04:00:38 INFO - Available Modes on Display 0 04:00:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:00:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:00:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:00:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:00:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:00:38 INFO - Intel Iris: 04:00:38 INFO - Chipset Model: Intel Iris 04:00:38 INFO - Type: GPU 04:00:38 INFO - Bus: Built-In 04:00:38 INFO - VRAM (Dynamic, Max): 1536 MB 04:00:38 INFO - Vendor: Intel (0x8086) 04:00:38 INFO - Device ID: 0x0a2e 04:00:38 INFO - Revision ID: 0x0009 04:00:38 INFO - Displays: 04:00:38 INFO - Display: 04:00:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:00:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:00:38 INFO - Display Serial Number: 1600x1200 60 04:00:38 INFO - Main Display: Yes 04:00:38 INFO - Mirror: Off 04:00:38 INFO - Online: Yes 04:00:38 INFO - Rotation: Supported 04:00:38 INFO - Return code: 0 04:00:38 INFO - Running post-action listener: _resource_record_post_action 04:00:38 INFO - Running post-run listener: _resource_record_post_run 04:00:39 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 37946880; Write bytes: 665735680; Read time: 404; Write time: 2055 04:00:39 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 354054656; Write bytes: 354392064; Read time: 27342; Write time: 862 04:00:39 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 36988416; Write bytes: 301001216; Read time: 388; Write time: 1165 04:00:39 INFO - Running post-run listener: _upload_blobber_files 04:00:39 INFO - Blob upload gear active. 04:00:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:00:39 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 04:00:39 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'] 04:00:39 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 04:00:39 INFO - (blobuploader) - INFO - Open directory for files ... 04:00:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:00:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:00:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:40 INFO - (blobuploader) - INFO - Done attempting. 04:00:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:00:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:00:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:42 INFO - (blobuploader) - INFO - Done attempting. 04:00:42 INFO - (blobuploader) - INFO - Iteration through files over. 04:00:42 INFO - Return code: 0 04:00:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:00:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:00:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee621c6fb09cb7904ce77ccc736d73072a2ac6ee8fd6510285c7d67cbfb4403d1ed7070c2c8f51ec63052db146ed538f20bdd70ea95ca910f91e27245d314a11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfd4518b02d00020e902cb6f42471a0c0245c301c1d061b4a85d12b2ff6242a7a4313fe379558416255ae12e9f7b3770e14358ed7342653780db68c0d12f45b5"} 04:00:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:00:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:00:42 INFO - Contents: 04:00:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee621c6fb09cb7904ce77ccc736d73072a2ac6ee8fd6510285c7d67cbfb4403d1ed7070c2c8f51ec63052db146ed538f20bdd70ea95ca910f91e27245d314a11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfd4518b02d00020e902cb6f42471a0c0245c301c1d061b4a85d12b2ff6242a7a4313fe379558416255ae12e9f7b3770e14358ed7342653780db68c0d12f45b5"} 04:00:42 INFO - Running post-run listener: copy_logs_to_upload_dir 04:00:42 INFO - Copying logs to upload dir... 04:00:42 INFO - mkdir: /builds/slave/test/build/upload/logs 04:00:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.761825 ========= master_lag: 3.25 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-04-27 04:00:45.292434) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 04:00:45.295545) ========= 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.vjNI71fmam/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.gc16BxJFau/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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/ee621c6fb09cb7904ce77ccc736d73072a2ac6ee8fd6510285c7d67cbfb4403d1ed7070c2c8f51ec63052db146ed538f20bdd70ea95ca910f91e27245d314a11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfd4518b02d00020e902cb6f42471a0c0245c301c1d061b4a85d12b2ff6242a7a4313fe379558416255ae12e9f7b3770e14358ed7342653780db68c0d12f45b5"} build_url:https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011218 build_url: 'https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee621c6fb09cb7904ce77ccc736d73072a2ac6ee8fd6510285c7d67cbfb4403d1ed7070c2c8f51ec63052db146ed538f20bdd70ea95ca910f91e27245d314a11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfd4518b02d00020e902cb6f42471a0c0245c301c1d061b4a85d12b2ff6242a7a4313fe379558416255ae12e9f7b3770e14358ed7342653780db68c0d12f45b5"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 04:00:45.386362) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:00:45.386691) ========= 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.vjNI71fmam/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.gc16BxJFau/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.005034 ========= master_lag: 0.30 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:00:45.688040) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 04:00:45.691377) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 04:00:45.697295) ========= ========= Total master_lag: 3.71 =========