builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0142 starttime: 1462833658.87 results: success (0) buildid: 20160509134609 builduid: 09aef832063f45eb8080f23855736164 revision: 2b710898eaf609aa9dc45fd75e03bc134d20f6b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 15:40:58.875317) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 15:40:58.875816) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 15:40:58.876143) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 15:40:59.087473) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:40:59.088290) ========= 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.geRm1RGeLM/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.RHzt92oizE/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-09 15:40:59-- 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% 161M=0s 2016-05-09 15:40:59 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090270 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:40:59.201720) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:40:59.202256) ========= 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.geRm1RGeLM/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.RHzt92oizE/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.027918 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:40:59.255519) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 15:40:59.255877) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2b710898eaf609aa9dc45fd75e03bc134d20f6b8 --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-beta --rev 2b710898eaf609aa9dc45fd75e03bc134d20f6b8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.geRm1RGeLM/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.RHzt92oizE/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-09 15:40:59,327 truncating revision to first 12 chars 2016-05-09 15:40:59,327 Setting DEBUG logging. 2016-05-09 15:40:59,327 attempt 1/10 2016-05-09 15:40:59,327 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2b710898eaf6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 15:41:00,285 unpacking tar archive at: mozilla-beta-2b710898eaf6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.209853 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 15:41:00.483774) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:00.484183) ========= script_repo_revision: 2b710898eaf609aa9dc45fd75e03bc134d20f6b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:00.484650) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:00.484963) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:00.499886) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-09 15:41:00.500369) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.geRm1RGeLM/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.RHzt92oizE/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 15:41:00 INFO - MultiFileLogger online at 20160509 15:41:00 in /builds/slave/test 15:41:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:41:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:41:00 INFO - {'append_to_log': False, 15:41:00 INFO - 'base_work_dir': '/builds/slave/test', 15:41:00 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:41:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:41:00 INFO - 'branch': 'Mozilla-Beta', 15:41:00 INFO - 'buildbot_json_path': 'buildprops.json', 15:41:00 INFO - 'config_files': ('talos/mac_config.py',), 15:41:00 INFO - 'default_actions': ('clobber', 15:41:00 INFO - 'read-buildbot-config', 15:41:00 INFO - 'download-and-extract', 15:41:00 INFO - 'populate-webroot', 15:41:00 INFO - 'create-virtualenv', 15:41:00 INFO - 'install', 15:41:00 INFO - 'run-tests'), 15:41:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:41:00 INFO - 'download_minidump_stackwalk': True, 15:41:00 INFO - 'download_symbols': 'ondemand', 15:41:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:41:00 INFO - 'tooltool.py': '/tools/tooltool.py', 15:41:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:41:00 INFO - '/tools/misc-python/virtualenv.py')}, 15:41:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:41:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:41:00 INFO - 'installer_path': 'installer.exe', 15:41:00 INFO - 'log_level': 'info', 15:41:00 INFO - 'log_name': 'talos', 15:41:00 INFO - 'log_to_console': True, 15:41:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:41:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:41:00 INFO - 'opt_config_files': (), 15:41:00 INFO - 'pip_index': False, 15:41:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:41:00 INFO - 'cmd': ('bash', 15:41:00 INFO - '-c', 15:41:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:41:00 INFO - 'enabled': True, 15:41:00 INFO - 'halt_on_failure': False, 15:41:00 INFO - 'name': 'check_screen_resolution'},), 15:41:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:41:00 INFO - 'cmd': ('bash', 15:41:00 INFO - '-c', 15:41:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:41:00 INFO - 'enabled': True, 15:41:00 INFO - 'halt_on_failure': False, 15:41:00 INFO - 'name': 'check_screen_resolution'},), 15:41:00 INFO - 'run_cmd_checks_enabled': True, 15:41:00 INFO - 'sps_profile': False, 15:41:00 INFO - 'sps_profile_interval': 0, 15:41:00 INFO - 'suite': 'dromaeojs', 15:41:00 INFO - 'system_bits': '32', 15:41:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:41:00 INFO - 'title': 't-yosemite-r7-0142', 15:41:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:41:00 INFO - 'use_talos_json': True, 15:41:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:41:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:41:00 INFO - 'work_dir': 'build'} 15:41:00 INFO - ##### 15:41:00 INFO - ##### Running clobber step. 15:41:00 INFO - ##### 15:41:00 INFO - Running pre-action listener: _resource_record_pre_action 15:41:00 INFO - Running main action method: clobber 15:41:00 INFO - rmtree: /builds/slave/test/build 15:41:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:41:02 INFO - Running post-action listener: _resource_record_post_action 15:41:02 INFO - ##### 15:41:02 INFO - ##### Running read-buildbot-config step. 15:41:02 INFO - ##### 15:41:02 INFO - Running pre-action listener: _resource_record_pre_action 15:41:02 INFO - Running main action method: read_buildbot_config 15:41:02 INFO - Using buildbot properties: 15:41:02 INFO - { 15:41:02 INFO - "project": "", 15:41:02 INFO - "product": "firefox", 15:41:02 INFO - "script_repo_revision": "production", 15:41:02 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 15:41:02 INFO - "repository": "", 15:41:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 15:41:02 INFO - "buildid": "20160509134609", 15:41:02 INFO - "pgo_build": "False", 15:41:02 INFO - "basedir": "/builds/slave/test", 15:41:02 INFO - "buildnumber": 62, 15:41:02 INFO - "slavename": "t-yosemite-r7-0142", 15:41:02 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:41:02 INFO - "platform": "yosemite_r7", 15:41:02 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:41:02 INFO - "branch": "mozilla-beta", 15:41:02 INFO - "repo_path": "releases/mozilla-beta", 15:41:02 INFO - "revision": "2b710898eaf609aa9dc45fd75e03bc134d20f6b8", 15:41:02 INFO - "stage_platform": "macosx64", 15:41:02 INFO - "builduid": "09aef832063f45eb8080f23855736164", 15:41:02 INFO - "slavebuilddir": "test" 15:41:02 INFO - } 15:41:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 15:41:02 INFO - Running post-action listener: _resource_record_post_action 15:41:02 INFO - ##### 15:41:02 INFO - ##### Running download-and-extract step. 15:41:02 INFO - ##### 15:41:02 INFO - Running pre-action listener: _resource_record_pre_action 15:41:02 INFO - Running main action method: download_and_extract 15:41:02 INFO - mkdir: /builds/slave/test/build/tests 15:41: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')]} 15:41:02 INFO - https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:41:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 15:41:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 15:41:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 15:41:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 15:41:04 INFO - Downloaded 1241 bytes. 15:41:04 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 15:41:04 INFO - Using the following test package requirements: 15:41:04 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 15:41:04 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:04 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 15:41:04 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:04 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 15:41:04 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:41:04 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:04 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 15:41:04 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 15:41:04 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:04 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 15:41:04 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:04 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 15:41:04 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:04 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 15:41:04 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 15:41:04 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:04 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 15:41:04 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 15:41:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:04 INFO - https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:41:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 15:41:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 15:41:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 15:41:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 15:41:06 INFO - Downloaded 17747473 bytes. 15:41:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:41:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:41:09 INFO - Return code: 0 15:41:09 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 15:41:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:09 INFO - https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:41:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 15:41:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 15:41:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 15:41:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 15:41:10 INFO - Downloaded 17747473 bytes. 15:41:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:41:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:41:13 INFO - Return code: 0 15:41:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:13 INFO - https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:41:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 15:41:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 15:41:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 15:41:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 15:41:15 INFO - Downloaded 10845194 bytes. 15:41:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:41:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:41:15 INFO - Return code: 0 15:41: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')]} 15:41:15 INFO - https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 15:41:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 15:41:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 15:41:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 15:41:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 15:41:17 INFO - Downloaded 88333205 bytes. 15:41:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 15:41:17 INFO - mkdir: /builds/slave/test/properties 15:41:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:41:17 INFO - Writing to file /builds/slave/test/properties/build_url 15:41:17 INFO - Contents: 15:41:17 INFO - build_url:https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 15:41:18 INFO - Running post-action listener: _resource_record_post_action 15:41:18 INFO - Running post-action listener: set_extra_try_arguments 15:41:18 INFO - ##### 15:41:18 INFO - ##### Running populate-webroot step. 15:41:18 INFO - ##### 15:41:18 INFO - Running pre-action listener: _resource_record_pre_action 15:41:18 INFO - Running main action method: populate_webroot 15:41:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:41:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:41:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:41:18 INFO - '--tppagecycles', 15:41:18 INFO - '7'], 15:41:18 INFO - 'tests': ['tsvgm']}}, 15:41:18 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:41:18 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:41:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:41:18 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 15:41:18 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:41:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:41:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:41:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:18 INFO - 'talos_options': ['--e10s'], 15:41:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:41:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:18 INFO - 'tests': ['damp', 'tps']}, 15:41:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:18 INFO - 'talos_options': ['--e10s'], 15:41:18 INFO - 'tests': ['damp', 'tps']}, 15:41:18 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:41:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:41:18 INFO - 'other': {'tests': ['a11yr', 15:41:18 INFO - 'ts_paint', 15:41:18 INFO - 'tpaint', 15:41:18 INFO - 'sessionrestore', 15:41:18 INFO - 'sessionrestore_no_auto_restore', 15:41:18 INFO - 'tabpaint']}, 15:41:18 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:41:18 INFO - 'tests': ['a11yr', 15:41:18 INFO - 'ts_paint', 15:41:18 INFO - 'tpaint', 15:41:18 INFO - 'sessionrestore', 15:41:18 INFO - 'sessionrestore_no_auto_restore', 15:41:18 INFO - 'tabpaint']}, 15:41:18 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:41:18 INFO - 'tests': ['a11yr', 15:41:18 INFO - 'ts_paint', 15:41:18 INFO - 'tpaint', 15:41:18 INFO - 'sessionrestore', 15:41:18 INFO - 'sessionrestore_no_auto_restore', 15:41:18 INFO - 'tabpaint']}, 15:41:18 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:41:18 INFO - 'tests': ['a11yr', 15:41:18 INFO - 'ts_paint', 15:41:18 INFO - 'tpaint', 15:41:18 INFO - 'sessionrestore', 15:41:18 INFO - 'sessionrestore_no_auto_restore', 15:41:18 INFO - 'tabpaint']}, 15:41:18 INFO - 'other_l64': {'tests': ['a11yr', 15:41:18 INFO - 'ts_paint', 15:41:18 INFO - 'tpaint', 15:41:18 INFO - 'sessionrestore', 15:41:18 INFO - 'sessionrestore_no_auto_restore', 15:41:18 INFO - 'tabpaint']}, 15:41:18 INFO - 'other_nol64': {'tests': ['a11yr', 15:41:18 INFO - 'ts_paint', 15:41:18 INFO - 'tpaint', 15:41:18 INFO - 'sessionrestore', 15:41:18 INFO - 'sessionrestore_no_auto_restore', 15:41:18 INFO - 'tabpaint']}, 15:41:18 INFO - 'svgr': {'tests': ['tsvgx', 15:41:18 INFO - 'tsvgr_opacity', 15:41:18 INFO - 'tart', 15:41:18 INFO - 'tscrollx', 15:41:18 INFO - 'cart']}, 15:41:18 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:41:18 INFO - 'tests': ['tsvgx', 15:41:18 INFO - 'tsvgr_opacity', 15:41:18 INFO - 'tart', 15:41:18 INFO - 'tscrollx', 15:41:18 INFO - 'cart']}, 15:41:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:18 INFO - 'tests': ['tp5o']}, 15:41:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:18 INFO - 'talos_options': ['--e10s'], 15:41:18 INFO - 'tests': ['tp5o']}, 15:41:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:18 INFO - 'talos_options': ['--xperf_path', 15:41:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:41:18 INFO - 'tests': ['tp5n']}, 15:41:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:18 INFO - 'talos_options': ['--e10s', 15:41:18 INFO - '--xperf_path', 15:41:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:41:18 INFO - 'tests': ['tp5n']}}, 15:41:18 INFO - 'talos.zip': {'path': '', 15:41:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:41:18 INFO - Running post-action listener: _resource_record_post_action 15:41:18 INFO - ##### 15:41:18 INFO - ##### Running create-virtualenv step. 15:41:18 INFO - ##### 15:41:18 INFO - Running pre-action listener: _resource_record_pre_action 15:41:18 INFO - Running main action method: create_virtualenv 15:41:18 INFO - Creating virtualenv /builds/slave/test/build/venv 15:41:18 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 15:41:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:41:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:41:18 INFO - Using real prefix '/tools/python27' 15:41:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:41:19 INFO - Installing distribute.............................................................................................................................................................................................done. 15:41:22 INFO - Installing pip.................done. 15:41:22 INFO - Return code: 0 15:41:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb12ad937a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be3d30>, '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 0x104a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bdab20>, '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.geRm1RGeLM/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.RHzt92oizE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 15:41:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:41:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.geRm1RGeLM/Render', 15:41:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:22 INFO - 'HOME': '/Users/cltbld', 15:41:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:22 INFO - 'LOGNAME': 'cltbld', 15:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:22 INFO - 'MOZ_NO_REMOTE': '1', 15:41:22 INFO - 'NO_EM_RESTART': '1', 15:41:22 INFO - 'PAGER': '/bin/cat', 15:41:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:22 INFO - 'PWD': '/builds/slave/test', 15:41:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:22 INFO - 'SHELL': '/bin/bash', 15:41:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RHzt92oizE/Listeners', 15:41:22 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 15:41:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:22 INFO - 'USER': 'cltbld', 15:41:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:22 INFO - 'XPC_FLAGS': '0x0', 15:41:22 INFO - 'XPC_SERVICE_NAME': '0', 15:41:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:22 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 15:41:22 INFO - Cleaning up... 15:41:22 INFO - Return code: 0 15:41:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb12ad937a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be3d30>, '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 0x104a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bdab20>, '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.geRm1RGeLM/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.RHzt92oizE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 15:41:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 15:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:41:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.geRm1RGeLM/Render', 15:41:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:22 INFO - 'HOME': '/Users/cltbld', 15:41:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:22 INFO - 'LOGNAME': 'cltbld', 15:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:22 INFO - 'MOZ_NO_REMOTE': '1', 15:41:22 INFO - 'NO_EM_RESTART': '1', 15:41:22 INFO - 'PAGER': '/bin/cat', 15:41:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:22 INFO - 'PWD': '/builds/slave/test', 15:41:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:22 INFO - 'SHELL': '/bin/bash', 15:41:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RHzt92oizE/Listeners', 15:41:22 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 15:41:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:22 INFO - 'USER': 'cltbld', 15:41:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:22 INFO - 'XPC_FLAGS': '0x0', 15:41:22 INFO - 'XPC_SERVICE_NAME': '0', 15:41:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:23 INFO - Downloading/unpacking psutil>=0.7.1 15:41:23 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 15:41:23 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 15:41:23 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 15:41:23 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 15:41:23 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 15:41:23 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 15:41:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:41:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:41:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:41:26 INFO - Installing collected packages: psutil 15:41:26 INFO - Running setup.py install for psutil 15:41:26 INFO - building 'psutil._psutil_osx' extension 15:41:26 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 15:41:26 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 15:41:26 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 15:41:27 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 15:41:27 INFO - building 'psutil._psutil_posix' extension 15:41:27 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 15:41:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:41:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:41:27 INFO - ^ 15:41:27 INFO - 1 warning generated. 15:41:27 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 15:41:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:41:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:41:27 INFO - Successfully installed psutil 15:41:27 INFO - Cleaning up... 15:41:27 INFO - Return code: 0 15:41:27 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:41:27 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')]} 15:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:27 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')]} 15:41:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:27 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 0x104d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb12ad937a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be3d30>, '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 0x104a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bdab20>, '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.geRm1RGeLM/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.RHzt92oizE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 15:41:27 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 15:41:27 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 15:41:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.geRm1RGeLM/Render', 15:41:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:27 INFO - 'HOME': '/Users/cltbld', 15:41:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:27 INFO - 'LOGNAME': 'cltbld', 15:41:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:27 INFO - 'MOZ_NO_REMOTE': '1', 15:41:27 INFO - 'NO_EM_RESTART': '1', 15:41:27 INFO - 'PAGER': '/bin/cat', 15:41:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:27 INFO - 'PWD': '/builds/slave/test', 15:41:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:27 INFO - 'SHELL': '/bin/bash', 15:41:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RHzt92oizE/Listeners', 15:41:27 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 15:41:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:27 INFO - 'USER': 'cltbld', 15:41:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:27 INFO - 'XPC_FLAGS': '0x0', 15:41:27 INFO - 'XPC_SERVICE_NAME': '0', 15:41:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:27 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:41:27 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 15:41:27 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 15:41:27 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 15:41:27 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 15:41:27 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 15:41:27 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 15:41:30 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:41:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:41:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:41:30 INFO - Installing collected packages: mozsystemmonitor 15:41:30 INFO - Running setup.py install for mozsystemmonitor 15:41:30 INFO - Successfully installed mozsystemmonitor 15:41:30 INFO - Cleaning up... 15:41:30 INFO - Return code: 0 15:41:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb12ad937a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be3d30>, '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 0x104a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bdab20>, '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.geRm1RGeLM/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.RHzt92oizE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 15:41:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:41:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.geRm1RGeLM/Render', 15:41:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:30 INFO - 'HOME': '/Users/cltbld', 15:41:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:30 INFO - 'LOGNAME': 'cltbld', 15:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:30 INFO - 'MOZ_NO_REMOTE': '1', 15:41:30 INFO - 'NO_EM_RESTART': '1', 15:41:30 INFO - 'PAGER': '/bin/cat', 15:41:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:30 INFO - 'PWD': '/builds/slave/test', 15:41:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:30 INFO - 'SHELL': '/bin/bash', 15:41:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RHzt92oizE/Listeners', 15:41:30 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 15:41:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:30 INFO - 'USER': 'cltbld', 15:41:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:30 INFO - 'XPC_FLAGS': '0x0', 15:41:30 INFO - 'XPC_SERVICE_NAME': '0', 15:41:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:30 INFO - Downloading/unpacking blobuploader==1.2.4 15:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:32 INFO - Downloading blobuploader-1.2.4.tar.gz 15:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:41:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:41:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41:34 INFO - Downloading docopt-0.6.1.tar.gz 15:41:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:41:34 INFO - Installing collected packages: blobuploader, requests, docopt 15:41:34 INFO - Running setup.py install for blobuploader 15:41:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:41:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:41:34 INFO - Running setup.py install for requests 15:41:34 INFO - Running setup.py install for docopt 15:41:35 INFO - Successfully installed blobuploader requests docopt 15:41:35 INFO - Cleaning up... 15:41:35 INFO - Return code: 0 15:41:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:41:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:35 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 0x104d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb12ad937a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be3d30>, '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 0x104a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bdab20>, '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.geRm1RGeLM/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.RHzt92oizE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 15:41:35 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 15:41:35 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 15:41:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.geRm1RGeLM/Render', 15:41:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:35 INFO - 'HOME': '/Users/cltbld', 15:41:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:35 INFO - 'LOGNAME': 'cltbld', 15:41:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:35 INFO - 'MOZ_NO_REMOTE': '1', 15:41:35 INFO - 'NO_EM_RESTART': '1', 15:41:35 INFO - 'PAGER': '/bin/cat', 15:41:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:35 INFO - 'PWD': '/builds/slave/test', 15:41:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:35 INFO - 'SHELL': '/bin/bash', 15:41:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RHzt92oizE/Listeners', 15:41:35 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 15:41:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:35 INFO - 'USER': 'cltbld', 15:41:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:35 INFO - 'XPC_FLAGS': '0x0', 15:41:35 INFO - 'XPC_SERVICE_NAME': '0', 15:41:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:41:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-xmx5uX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:41:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-t3qccq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:41:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-pJ1nDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:41:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-DmLA6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:41:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-VKMMOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:41:35 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-lXvrDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:41:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-va6ySV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:41:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-NBSWNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:41:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-gnIwyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:41:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-rNJyUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:41:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-JSNsbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:41:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-DcOCWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:41:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-tXVS23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:41:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Vx9wHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:41:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-PBHxv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:41:36 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-OCbhyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:41:37 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-J7lLnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:41:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:41:37 INFO - Running setup.py install for manifestparser 15:41:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:41:37 INFO - Running setup.py install for mozcrash 15:41:37 INFO - Running setup.py install for mozdebug 15:41:37 INFO - Running setup.py install for mozdevice 15:41:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:41:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:41:37 INFO - Running setup.py install for mozfile 15:41:37 INFO - Running setup.py install for mozhttpd 15:41:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:41:38 INFO - Running setup.py install for mozinfo 15:41:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:41:38 INFO - Running setup.py install for mozInstall 15:41:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:41:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:41:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:41:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:41:38 INFO - Running setup.py install for mozleak 15:41:38 INFO - Running setup.py install for mozlog 15:41:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:41:38 INFO - Running setup.py install for moznetwork 15:41:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:41:38 INFO - Running setup.py install for mozprocess 15:41:38 INFO - Running setup.py install for mozprofile 15:41:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:41:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:41:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:41:39 INFO - Running setup.py install for mozrunner 15:41:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:41:39 INFO - Running setup.py install for mozscreenshot 15:41:39 INFO - Running setup.py install for moztest 15:41:39 INFO - Running setup.py install for mozversion 15:41:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:41:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:41:39 INFO - Cleaning up... 15:41:39 INFO - Return code: 0 15:41:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:41:39 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')]} 15:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:39 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')]} 15:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:39 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 0x104d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb12ad937a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be3d30>, '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 0x104a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bdab20>, '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.geRm1RGeLM/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.RHzt92oizE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 15:41:39 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 15:41:39 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 15:41:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.geRm1RGeLM/Render', 15:41:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:39 INFO - 'HOME': '/Users/cltbld', 15:41:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:39 INFO - 'LOGNAME': 'cltbld', 15:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:39 INFO - 'MOZ_NO_REMOTE': '1', 15:41:39 INFO - 'NO_EM_RESTART': '1', 15:41:39 INFO - 'PAGER': '/bin/cat', 15:41:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:39 INFO - 'PWD': '/builds/slave/test', 15:41:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:39 INFO - 'SHELL': '/bin/bash', 15:41:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RHzt92oizE/Listeners', 15:41:39 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 15:41:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:39 INFO - 'USER': 'cltbld', 15:41:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:39 INFO - 'XPC_FLAGS': '0x0', 15:41:39 INFO - 'XPC_SERVICE_NAME': '0', 15:41:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:41:39 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-buxgE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:41:40 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)) 15:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:41:40 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-wi86ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:41:40 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)) 15:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:41:40 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-bjH78Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:41:40 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)) 15:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:41:40 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Hv3VsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:41:40 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)) 15:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:41:40 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-AlJ9MA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:41:40 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)) 15:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:41:40 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-G5YMWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:41:40 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)) 15:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:41:40 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-BNnAOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:41:40 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)) 15:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:41:40 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Q3TRbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:41:40 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)) 15:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:41:40 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-cFyqhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:41:40 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)) 15:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:41:40 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-2WktL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:41:41 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)) 15:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:41:41 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-YTIIA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:41:41 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)) 15:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:41:41 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-9Feofm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:41:41 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)) 15:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:41:41 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-79paIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:41:41 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)) 15:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:41:41 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Dz9TYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:41:41 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)) 15:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:41:41 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-cN24Um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:41:41 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)) 15:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:41:41 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-FXDrKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:41:41 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)) 15:41:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:41:41 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-prgNeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:41:41 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)) 15:41:41 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)) 15:41:41 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)) 15:41:41 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)) 15:41:41 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)) 15:41:41 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)) 15:41:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:41:41 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 15:41:41 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 15:41:41 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 15:41:41 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 15:41:41 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 15:41:41 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 15:41:44 INFO - Downloading blessings-1.6.tar.gz 15:41:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:41:44 INFO - Installing collected packages: blessings 15:41:44 INFO - Running setup.py install for blessings 15:41:44 INFO - Successfully installed blessings 15:41:44 INFO - Cleaning up... 15:41:44 INFO - Return code: 0 15:41:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:41:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:41:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:41:44 INFO - Reading from file tmpfile_stdout 15:41:44 INFO - Current package versions: 15:41:44 INFO - blessings == 1.6 15:41:44 INFO - blobuploader == 1.2.4 15:41:44 INFO - docopt == 0.6.1 15:41:44 INFO - manifestparser == 1.1 15:41:44 INFO - mozInstall == 1.12 15:41:44 INFO - mozcrash == 0.17 15:41:44 INFO - mozdebug == 0.1 15:41:44 INFO - mozdevice == 0.48 15:41:44 INFO - mozfile == 1.2 15:41:44 INFO - mozhttpd == 0.7 15:41:44 INFO - mozinfo == 0.9 15:41:44 INFO - mozleak == 0.1 15:41:44 INFO - mozlog == 3.1 15:41:44 INFO - moznetwork == 0.27 15:41:44 INFO - mozprocess == 0.22 15:41:44 INFO - mozprofile == 0.28 15:41:44 INFO - mozrunner == 6.11 15:41:44 INFO - mozscreenshot == 0.1 15:41:44 INFO - mozsystemmonitor == 0.0 15:41:44 INFO - moztest == 0.7 15:41:44 INFO - mozversion == 1.4 15:41:44 INFO - psutil == 3.1.1 15:41:44 INFO - requests == 1.2.3 15:41:44 INFO - wsgiref == 0.1.2 15:41:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:41:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:44 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 0x104d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb12ad937a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be3d30>, '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 0x104a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bdab20>, '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.geRm1RGeLM/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.RHzt92oizE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 15:41:44 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 15:41:44 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 15:41:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.geRm1RGeLM/Render', 15:41:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:44 INFO - 'HOME': '/Users/cltbld', 15:41:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:44 INFO - 'LOGNAME': 'cltbld', 15:41:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:44 INFO - 'MOZ_NO_REMOTE': '1', 15:41:44 INFO - 'NO_EM_RESTART': '1', 15:41:44 INFO - 'PAGER': '/bin/cat', 15:41:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:44 INFO - 'PWD': '/builds/slave/test', 15:41:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:44 INFO - 'SHELL': '/bin/bash', 15:41:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RHzt92oizE/Listeners', 15:41:44 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 15:41:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:44 INFO - 'USER': 'cltbld', 15:41:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:44 INFO - 'XPC_FLAGS': '0x0', 15:41:44 INFO - 'XPC_SERVICE_NAME': '0', 15:41:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:45 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)) 15:41:45 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)) 15:41:45 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)) 15:41:45 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)) 15:41:45 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)) 15:41:45 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)) 15:41:45 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)) 15:41:45 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)) 15:41:45 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)) 15:41:45 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)) 15:41:45 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)) 15:41:45 INFO - Cleaning up... 15:41:45 INFO - Return code: 0 15:41:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:45 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 0x104d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb12ad937a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be3d30>, '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 0x104a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bdab20>, '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.geRm1RGeLM/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.RHzt92oizE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 15:41:45 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 15:41:45 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 15:41:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.geRm1RGeLM/Render', 15:41:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:45 INFO - 'HOME': '/Users/cltbld', 15:41:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:45 INFO - 'LOGNAME': 'cltbld', 15:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:45 INFO - 'MOZ_NO_REMOTE': '1', 15:41:45 INFO - 'NO_EM_RESTART': '1', 15:41:45 INFO - 'PAGER': '/bin/cat', 15:41:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:45 INFO - 'PWD': '/builds/slave/test', 15:41:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:45 INFO - 'SHELL': '/bin/bash', 15:41:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RHzt92oizE/Listeners', 15:41:45 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 15:41:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:45 INFO - 'USER': 'cltbld', 15:41:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:45 INFO - 'XPC_FLAGS': '0x0', 15:41:45 INFO - 'XPC_SERVICE_NAME': '0', 15:41:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:45 INFO - Downloading/unpacking jsonschema 15:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:41:47 INFO - Downloading/unpacking functools32 (from jsonschema) 15:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:48 INFO - Downloading functools32-3.2.3-2.tar.gz 15:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:41:48 INFO - warning: no files found matching '*.txt' 15:41:48 INFO - no previously-included directories found matching 'build' 15:41:48 INFO - no previously-included directories found matching 'dist' 15:41:48 INFO - no previously-included directories found matching '.git*' 15:41:48 INFO - Installing collected packages: jsonschema, functools32 15:41:48 INFO - Running setup.py install for functools32 15:41:48 INFO - warning: no files found matching '*.txt' 15:41:48 INFO - no previously-included directories found matching 'build' 15:41:48 INFO - no previously-included directories found matching 'dist' 15:41:48 INFO - no previously-included directories found matching '.git*' 15:41:48 INFO - Successfully installed jsonschema functools32 15:41:48 INFO - Cleaning up... 15:41:48 INFO - Return code: 0 15:41:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d464c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb12ad937a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be3d30>, '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 0x104a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bdab20>, '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.geRm1RGeLM/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.RHzt92oizE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 15:41:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:41:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.geRm1RGeLM/Render', 15:41:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:48 INFO - 'HOME': '/Users/cltbld', 15:41:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:48 INFO - 'LOGNAME': 'cltbld', 15:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:48 INFO - 'MOZ_NO_REMOTE': '1', 15:41:48 INFO - 'NO_EM_RESTART': '1', 15:41:48 INFO - 'PAGER': '/bin/cat', 15:41:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:48 INFO - 'PWD': '/builds/slave/test', 15:41:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:48 INFO - 'SHELL': '/bin/bash', 15:41:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RHzt92oizE/Listeners', 15:41:48 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 15:41:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:48 INFO - 'USER': 'cltbld', 15:41:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:48 INFO - 'XPC_FLAGS': '0x0', 15:41:48 INFO - 'XPC_SERVICE_NAME': '0', 15:41:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:48 INFO - Downloading/unpacking flake8 15:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:41:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:41:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:41:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:41:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:41:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:41:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:41:52 INFO - no previously-included directories found matching 'docs/_build' 15:41:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:41:52 INFO - Running setup.py install for pep8 15:41:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:41:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:41:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:41:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:41:52 INFO - no previously-included directories found matching 'docs/_build' 15:41:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:41:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:41:52 INFO - Cleaning up... 15:41:52 INFO - Return code: 0 15:41:52 INFO - Running post-action listener: _resource_record_post_action 15:41:52 INFO - Running post-action listener: _start_resource_monitoring 15:41:52 INFO - Starting resource monitoring. 15:41:52 INFO - ##### 15:41:52 INFO - ##### Running install step. 15:41:52 INFO - ##### 15:41:52 INFO - Running pre-action listener: _resource_record_pre_action 15:41:52 INFO - Running main action method: install 15:41:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:41:53 INFO - Reading from file tmpfile_stdout 15:41:53 INFO - Detecting whether we're running mozinstall >=1.0... 15:41:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:41:53 INFO - Reading from file tmpfile_stdout 15:41:53 INFO - Output received: 15:41:53 INFO - Usage: mozinstall [options] installer 15:41:53 INFO - Options: 15:41:53 INFO - -h, --help show this help message and exit 15:41:53 INFO - -d DEST, --destination=DEST 15:41:53 INFO - Directory to install application into. [default: 15:41:53 INFO - "/builds/slave/test"] 15:41:53 INFO - --app=APP Application being installed. [default: firefox] 15:41:53 INFO - mkdir: /builds/slave/test/build/application 15:41:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:42:15 INFO - Reading from file tmpfile_stdout 15:42:15 INFO - Output received: 15:42:15 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:42:15 INFO - Running post-action listener: _resource_record_post_action 15:42:15 INFO - ##### 15:42:15 INFO - ##### Running run-tests step. 15:42:15 INFO - ##### 15:42:15 INFO - Running pre-action listener: _resource_record_pre_action 15:42:15 INFO - Running main action method: run_tests 15:42:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:42:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:42:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:42:15 INFO - 2016-05-09 15:42:15.370 screenresolution[1638:7348] starting screenresolution argv=screenresolution get 15:42:15 INFO - 2016-05-09 15:42:15.373 screenresolution[1638:7348] Display 0: 1600x1200x32@60 15:42:15 INFO - 2016-05-09 15:42:15.378 screenresolution[1639:7350] starting screenresolution argv=screenresolution list 15:42:15 INFO - Available Modes on Display 0 15:42:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:42:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:42:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:42:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:42:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:42:15 INFO - Intel Iris: 15:42:15 INFO - Chipset Model: Intel Iris 15:42:15 INFO - Type: GPU 15:42:15 INFO - Bus: Built-In 15:42:15 INFO - VRAM (Dynamic, Max): 1536 MB 15:42:15 INFO - Vendor: Intel (0x8086) 15:42:15 INFO - Device ID: 0x0a2e 15:42:15 INFO - Revision ID: 0x0009 15:42:15 INFO - Displays: 15:42:15 INFO - Display: 15:42:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:42:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:42:15 INFO - Display Serial Number: 1600x1200 60 15:42:15 INFO - Main Display: Yes 15:42:15 INFO - Mirror: Off 15:42:15 INFO - Online: Yes 15:42:15 INFO - Rotation: Supported 15:42:15 INFO - Return code: 0 15:42:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:42:15 INFO - Python 2.7.3 15:42:15 INFO - Return code: 0 15:42:15 INFO - grabbing minidump binary from tooltool 15:42: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')]} 15:42:15 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 0x104be3d30>, '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 0x104a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104bdab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:42:15 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 15:42:15 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 15:42:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:42:15 INFO - Return code: 0 15:42:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:42:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:42:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:42:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:42:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:42:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:42:16 INFO - Return code: 0 15:42:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 15:42:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0142 --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 15:42:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.geRm1RGeLM/Render', 15:42:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:16 INFO - 'HOME': '/Users/cltbld', 15:42:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:16 INFO - 'LOGNAME': 'cltbld', 15:42:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:42:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:42:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:16 INFO - 'MOZ_NO_REMOTE': '1', 15:42:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:42:16 INFO - 'NO_EM_RESTART': '1', 15:42:16 INFO - 'PAGER': '/bin/cat', 15:42:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:16 INFO - 'PWD': '/builds/slave/test', 15:42:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:42:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:16 INFO - 'SHELL': '/bin/bash', 15:42:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RHzt92oizE/Listeners', 15:42:16 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 15:42:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:16 INFO - 'USER': 'cltbld', 15:42:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:16 INFO - 'XPC_FLAGS': '0x0', 15:42:16 INFO - 'XPC_SERVICE_NAME': '0', 15:42:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 15:42:17 INFO - mozversion application_buildid: 20160509134609 15:42:17 INFO - mozversion application_changeset: 2b710898eaf609aa9dc45fd75e03bc134d20f6b8 15:42:17 INFO - mozversion application_display_name: Firefox 15:42:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:42:17 INFO - mozversion application_name: Firefox 15:42:17 INFO - mozversion application_remotingname: firefox 15:42:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:42:17 INFO - mozversion application_vendor: Mozilla 15:42:17 INFO - mozversion application_version: 47.0 15:42:17 INFO - mozversion platform_buildid: 20160509134609 15:42:17 INFO - mozversion platform_changeset: 2b710898eaf609aa9dc45fd75e03bc134d20f6b8 15:42:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:42:17 INFO - mozversion platform_version: 47.0 15:42:17 INFO - using testdate: 1462833737 15:42:17 INFO - actual date: 1462833737 15:42:17 INFO - starting webserver on 'localhost:49270' 15:42:17 INFO - SUITE-START | Running 2 tests 15:42:17 INFO - TEST-START | dromaeo_css 15:42:17 INFO - operating with platform_type : mac_ 15:42:17 INFO - Initialising browser for dromaeo_css test... 15:42:17 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpmlSpaK/profile http://localhost:49270/getInfo.html) 15:42:19 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 15:42:19 INFO - PROCESS | 1657 | colorDepth:24 15:42:19 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 15:42:19 INFO - PROCESS | 1657 | __metrics 15:42:19 INFO - PROCESS | 1657 | 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" 15:42:19 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:42:20 INFO - TEST-INFO | 1657: exit 0 15:42:20 INFO - Browser initialized. 15:42:20 INFO - Running cycle 1/1 for dromaeo_css test... 15:42:20 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpmlSpaK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:42:32 INFO - PROCESS | 1660 | RSS: Main: 157020160 15:42:32 INFO - PROCESS | 1660 | 15:43:23 INFO - PROCESS | 1660 | 1462833803887 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] 15:45:32 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 15:45:33 INFO - PROCESS | 1660 | RSS: Main: 274046976 15:45:33 INFO - PROCESS | 1660 | 15:48:36 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 15:48:36 INFO - PROCESS | 1660 | RSS: Main: 289509376 15:48:36 INFO - PROCESS | 1660 | 15:51:38 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 15:51:39 INFO - PROCESS | 1660 | RSS: Main: 285761536 15:51:39 INFO - PROCESS | 1660 | 15:54:41 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 15:54:41 INFO - PROCESS | 1660 | RSS: Main: 250667008 15:54:41 INFO - PROCESS | 1660 | 15:57:44 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 15:57:45 INFO - PROCESS | 1660 | RSS: Main: 253997056 15:57:45 INFO - PROCESS | 1660 | 16:00:47 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 16:00:47 INFO - PROCESS | 1660 | RSS: Main: 302702592 16:00:47 INFO - PROCESS | 1660 | 16:00:47 INFO - PROCESS | 1660 | __start_tp_report 16:00:47 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 16:00:47 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 16:00:47 INFO - PROCESS | 1660 | |i|pagename|runs| 16:00:47 INFO - PROCESS | 1660 | |0;dojo.html;3584.3906535888896;3650.981745091274;3663.523741913551;3673.1735359544095;3731.4216296474037;4729.976350118253;5478.205660179272;5796.30444346679;5837.824865254044;5881.588288819784;5794.362620111784;5809.593328467009;5825;5831.591788574801;5843.64938103714;5205.557527610158;5239.5546378557865;5256.658317209388;5285.127953887604;5329.094054010808;5355.410904800468;5362.3029006229135;5371.9524692684745;5376.032314183445;5376.892462150755;5281.762320695578;5352.304200453936;5363.436839131893;5366.597505187107;5371.731413429333;5347.278117454152;5396.703181325045;5401.675899446007;5402.459754024605;5413.810516631899;5138.820141295074;5309.522143007148;5321.733913304358;5324.760385782649;5330;5768.8846223075725;5797.101449275369;5812.3025236971835;5824;5840;6220.066989951498;6352.110704501373;6374.553781235315;6381.202349706286;6390.55266131371;5947.1971283876555;6123.469132716821;6293.685315734195;6298.4646305064125;6306.211723534559;4967.478414766434;6059;6078.787242446493;6080.209572755513;6082.756689732405;6306.621602703853;6370.299267080617;6456.709448074754;6471.320511346335;6475.870482590321;6302.590331628429;6355.841103972438;6356.713947872357;6359.427651511386;6362.045693145972;6328.968355158287;6336.873262534724;6340.492760579143;6346.841328966812;6356.904646430307;6197;6262.467690246289;6280.874382512324;6366.6180029198395;6407.13503677007;6315.242170939425;6324.3991820776955;6336.651484168415;6356.2054743157105;6382.36176382358;5068.82259120929;5077.543021128116;5083.796648134107;5091.567216786615;5095.210242412432;4426.26966550522;4497.617702495325;4559.407277054028;4566.566176213254;4613.215753321943;6055.788047418319;6057.121717350959;6070.089486577049;6081.513478921675;6103.1455596216565;6270.9372906271265;6286.057091436322;6299.716512756937;6304.085907543381;6307.211598550181;5828.038373668382;5999.400059994053;6027.095935609694;6043.395660433922;6104.755809767559;5517.724113794295;5531.944680553223;5534.944650553523;5539;5558.972205138948;6478.708458119396;6481.09264702942;6487.708053137714;6494.805155845238;6500.739970401226;6240.625562466266;6271.717772700331;6284.717187726472;6294.842628934313;6344.841378965378;6510.0885875976455;6530.510211734044;6540.2151741789385;6545.37818907203;6551.0173473979285;6298.275698294755;6310.337414571404;6314.210723659543;6329.398707122816;6343.52423568225;6144.279601711528;6238.532110091671;6267.091271765477;6287.528435367457;6321.830461364662;6257.43683068526;6296.842578935563;6299.433051025429;6301.842453938688;6304.779332723333;6118.375534461997;6286.15136956505;6293.339199383999;6307.148534947723;6310.337414571404;6106.399996025858;6137.959273351923;6170.167027451236;6185.84535386619;6202.038684003986;6291.276503202191;6293.96853015732;6300.810975670737;6308.526860485574;6309.684515774285;4629.4676112247525;5993.430624090704;6099.817005489665;6167.537434692326;6210.689465526615 16:00:47 INFO - PROCESS | 1660 | |1;ext.html;467.18709445564707;619.7149311316794;621.4965877639115;664.7939138866951;671.0571646836195;32145.678543214563;32600.67399326009;32783.01650950472;41156.38265426014;41554.75335739925;44001.77999110008;44435.77782111093;44619.33071003937;44638.776806116;44639.330410043796;9776.853347199798;10015.19878409729;10031.498425078753;10034.197264218845;10036.247281453883;9847.359921605108;9989.650362237324;9994.300398972075;10009.149222316111;10022.248331375176;9906.157976572;10017.39895606266;10029.097381235688;10054.648087316946;10076.445795481282;10011.399316041025;10046.547905344278;10093.243006774448;10094.545745441435;10095.091441770275;9976.152027077707;10115.039071288289;10123.088921996981;10125.848112278321;10128;41821.53624623123;42198.57801421977;42673.35989960153;42713.35929961053;42718.35922461165;3397.048826328632;3462.8787992420143;3528.576570811493;3626.9093272668392;3910.667593254577;29328;30022;30111.247218819484;30181.849090754407;30751.384972300428;2833.5891295762203;2833.659960804696;2859.5281778506524;2869.0818937939866;2917.7811664125275;495104;501048;502063.99999999633;504027;508497;48392.27411588829;73049.6347518259;74230;74301.25698743052;74358.25641743513;47781;47913.04173916572;47987.76006119947;48060.7596962013;48170.759146204044;13434.865651343556;15853.603659908364;17244.913775431043;17340.566485837702;17529.29882804699;12007.339596322287;13956.790648140286;13975.88192944562;13976.161430314214;13981.930090349482;15293.541193764206;15363.385464581292;15483.5354939352;15548.989315694444;15624.98437601575;6461.8061458156335;6549.672516374162;6590.934090659032;6596.736130554724;6599.307072757388;1012.9949350253349;1076.9246152769388;1126.442411006557;1183.1303991566192;1224.706070543081;30395.616132075123;30598.388032239676;30667.38665226683;31459.84270078681;31529.21176970594;1121.994390028061;1196.8384268123866;1202.6151631477844;1233.5189276182296;1243.322389297835;15648.843511564966;15678.9216053919;15755.763663545056;15956.76064859028;16102.355905763872;529286.3535682374;533302;534089;535019;541617;61738.69130654318;62502.687486562274;62553;62554;62590.687046564475;1432.9713405731827;1552.2626752292797;1566.9529914102595;1590.498992817282;1601.2874270949317;30190.396192076474;30389.848050759603;30609.846950765103;30892.845535772176;30893.227669307547;48929;49109.75445122751;49474.50525494699;49577.75211123921;49612.25581616206;355480;356730;357895;358876;359014;30113.849430752707;34272.48591271083;34676;34724;35310.823445882605;42104.3684344729;44456;48161.51838481711;49937;50506;35465.468017979234;36114.27771444504;37529.061773455884;37947;40102;36961.44557831688;37699;37728.81135594304;38247;39039.41440878446;38335.42496862605;38680.6131938677;38896.61103389043;39159.41260881146;39439;33603.66396336005;33919.491207631414;34407.31185376328;34647.65352346444;34648.30703385868;23322.33113952272;25849.870750646125;26054.827532760693;26124.91135548691;26281.60577591376 16:00:47 INFO - PROCESS | 1660 | |2;jquery.html;373.7719990805609;374.6871362412387;379.3948651900219;384.1012031845481;384.87491565241305;20471.692924606123;21206.89396553017;21768.782312176874;22066.88966555165;22102.447438814037;10641.946790266058;12179.51281948721;12757.553485627981;12759.915407190376;12763.23420594765;4879.097366987101;6126.019836826109;6217.658028808413;6217.813465596039;6229.345918678541;6034.8793024139495;6074.787382441616;6179.104029915659;6189.133521307021;6189.566730328879;5416.295881535415;5561.555075593943;5566.025945459549;5571.9442805572035;5593.52455041322;5544.3346798384255;5565.109582466826;5567.721613919288;5581.832545023656;5584.46947539983;5644.181593668895;5902.97048514759;5914.112883067532;5920.378360272152;5929.021711417611;12124.636260912186;13934.581962541039;14148.292585370691;14242.643933901736;14568.198749068797;2260.6495993121093;2280.851744636593;2293.3005433342823;2368.6683864259016;2694.488861521361;15407.845921540753;16378.426755063634;16838.83161168397;17150.82849171505;17532.29870805166;1904.6952487601918;1978.82190602845;2018.0817727933754;2032.1058734156923;2044.2845004248395;116118;127539.3623031879;129552.70447295594;131607;132100;17023.48929532116;19905.303314384215;20411.591768164555;20554.69167962482;20629;31308;31376.215594610316;31948.680513195035;32134.678653213636;32245.51631725526;11332.546698132148;11738.295702257892;11772.88227117735;11785.88214117848;11899.583514576949;8195.426320157532;9870.555824987892;9901.207903367713;9912.900870991341;10059.597616095274;7524.811879703051;8892.555372231493;9107.81784364309;9143.177114059767;9150.313726470407;3010.6086208793154;3453.240287136826;3591.6947059500235;3608.1340478285024;3624.2389098289655;888.7778055486128;926.9304802139867;964.7154089543601;972.3825370889537;990.3612170150212;15728;20583;20711.8964405177;20783;20817.58364832695;948.5968463403026;993.1558175550724;1040.625374865048;1048.7849990751877;1059.5020340440135;11058.778824423467;11322.471466351937;11349;11536.884631153749;11578.826317605242;523346;662859;671878;672308;683498;119579.40210299067;120291.62451599386;123825;124082;125466.37266813607;892.8035832116924;1066.7759770448138;1089.8801131875487;1092.1699508373692;1113.5545781687229;18817.90591047091;18899;19687.70468443003;19727.704084439032;19914.70127948053;54522.727386362814;55528.44471555233;55598;56728.432715672316;56775;78692;79493.20506794858;81610;82429;82955.58522207351;8241.464304820407;8427.049936970818;8491.108433614381;8549;8584;12328.691782705504;13124.14693044949;13465.882964473438;13678.110922789987;13758.724825503634;10330.793384132125;10465.738356540844;10497.370157790558;11781.469833857669;11881.881181188077;8329.250367466715;9794;10299.485025748532;10623.256372053882;11037.172212083966;11881.5841445549;11939.223950443193;12022;12044.277343359427;12151.270923744603;8159.551224682699;8345.749627511184;8355.37334699912;8623;8670.78323041929;8809.295256379346;11313.660590182308;11685;11789.646310610695;11978 16:00:47 INFO - PROCESS | 1660 | |3;mootools.html;1512.0398546922702;1651.0836485750408;1669.9833001669983;1715.382462313567;2097.664373700208;8387.412881098318;9203.401778884368;9370.297227707915;9397.389169703965;9399.765005874857;8966;9212.677556285531;9226.98503164653;9227.215686666625;9246.306527010467;2581.8321809082445;2597.636330913664;2603.609458581209;2605.1663467690346;2606.9869650651817;2102.6530622447353;2605.3356394119473;2607.0484273240227;2609.2041927212194;2622.7639512443875;2160.794724501178;2573.768360847523;2602.3233959170657;2614.4901744159897;2615.4246065865477;2579.793616510675;2591.7926565874686;2602.830815996964;2606.2962999990086;2609.856457894815;2583.599542070982;2590.611408288753;2606.100895191156;2629.368951451657;2632.4471860909234;9375.437473751597;9417.670381536613;9436.480993545352;9436.905630943671;9448.716538503855;5810.82567522975;5977.565384307778;5979.372165922602;5984.431479009487;6004.159417681528;3733.8133093345496;3837.1558257183374;3863.5943225961432;3891;3920.6471417572557;1070.5396679427843;1088.6679562733364;1089.5042755546208;1098.028245003178;1128.1313388690662;12675.429605667674;12683.556075537404;12703.618891433272;12704.3012634306;12747.808782868171;5098.107831129538;5122.487751224922;5182.300389447451;5196.714180720097;5236.816711415158;10880.61917832888;10888.183386246063;10902.836457452986;10904.50929708165;10910.23628346008;5940.34656187819;5946.513371657085;5954.910676339859;5955.255593050869;5955.374685657944;4690;4722.716637001791;4807.495213002654;4847.684900481458;5339.492748188916;4158.854440094643;4194.3498757692605;4197.496300443905;4222.746635201897;4247.532771395144;1388.0769288423287;1391.5268808604922;1391.9373628186756;1396.3367400484688;1405.3816320818808;1401.8948578856628;1425.7932599772976;1438.6691061056015;1451.2743628185908;1471.3305446022034;8828.602712877935;8842.46945183291;8881.644734210688;8891.244244239182;8920.375573709907;536.9194620806832;547.4416095582484;549.0035585412463;554.7143221241064;555.2920026965651;6498;6526.543141980016;6544.116544266558;6549.770758023446;6550.148480697479;11274.492647830868;11696.590619328283;11698.06415486759;11704.648860534198;11707;11274;11277.266977646426;11302;11361.659150225507;11539.826902596467;8717.91282087171;8822.2060014599;8904.287656987426;8926.152015558511;8938.10618938114;8378.162183781573;8445.873311900203;8630.654773808978;8641.438306510308;8659;9712.077352651488;10041.447720375449;10056.84914726265;10126.79746405053;10142;10085.747856303651;10128.544215510172;10370.325928814604;10561.683149505527;10573.2598718089;7238.0952380952385;7750.961245193738;7877.684892604231;7970.322522585645;7976.202379761978;8791.868121978303;8833.911660883308;8858.911410886067;8881.911180888108;8889.155530224618;7655.808604784925;7744.380449564134;7830.4518683691595;7855.921440785519;7868.095169055403;7554.395648348229;7601.315881570686;7676.357787950019;7717.652705628372;7778.88331675014;7980.84038319242;8279.83440331178;8315.00219973607;8345.499270043574;8345.624446900025;7315.63421828917;7409.110906691231;7411.518251313863;7411.777646670608;7416.184219735716;6267.592606480564;7409.740659076906;7518.097828260644;7533.058367704037;7547.358474529727 16:00:47 INFO - PROCESS | 1660 | |4;prototype.html;334.7991205276833;342.5683638615345;347.60373174580974;349.9580050393952;351.48682922932517;6746.089277947475;6771.1874575051;6776.05135281061;6804.149481314836;6817.3182681731805;6748.460123190145;6761.459083273338;6776.254611992689;6776.390124888759;6788.72845086196;4766.594839438651;4859.076775412678;4895.510448955111;5014.548690617844;5021.246812978046;4978.5270399312185;5015.799368025275;5023.296738456619;5024;5029.767706911812;4771.117343291485;4983.750812459362;4986.825461108844;4995.475475075119;4999.425066117388;4829.3480380148585;4963.379577552806;4978.87552811179;4985.900281994376;4986.725730084844;4289.270823959934;4289.528151903288;4301.548337424588;4302.096559722462;4304.139172165579;6513.478921686254;6544.640044797534;6551;6559.868802623969;6570.671466426707;6001.039833626599;6005.159277701126;6013.75944962201;6019.849503762397;6029.427204415573;5845.532357411439;7240.746573869942;7253.528520646194;7255.455840811907;7272.0546328977425;4682;4728.503507131736;4730.053989202173;4741.075490279397;4744.7153170809515;7554.206808285161;7633.236676332378;7638.274363935473;7656.808579785495;7665.271799179125;4793.281007848851;4795.760211989456;4806.303086052572;4808.903821923542;4809.951900480951;4877.097736918681;4884.706917584886;4885;4885.2427873679635;4895.559399654048;5579.609427340048;5775.913361299584;5847.912281315784;5851.063829787214;5853.648781073064;5676.602637815396;7177.748778792821;7180.102487189101;7187.712491500386;7239.529430586995;1672.2976349933058;1702.421176799894;1704.2160606121074;1705.6844483770542;1709.7948246210535;1029.7013865978931;1053.2995557953875;1069.3102948598112;1075.2526993739275;1099.8625171853519;6655;6666.899996500057;6677.198736151596;6745.9662701686175;6785.219699734495;7437.405007599379;7465.440091993014;7470.589117598584;7470.887936680954;7478.214787447239;5856.824295271149;6352.396522330371;6733.831654208684;6738.663066846638;6743.1908171019795;3860.1507668312925;3890.132736286598;3929.960700393017;3932.05630648648;3938.7045971552247;6279.780207692709;7908.01149856268;7909.5649739263745;7947.483413578099;7948.523088614586;7825.452218344662;7835.216478352119;7837.6081195941115;7850.646720897574;7853.371730261565;6293.874122517524;7908.130105688369;7929.048514178336;7929.563873986986;8008.799780005546;5625.936250632382;7086.574805511686;7463.514871533333;7570.507916985378;7591.62041897914;5912.526997840248;7412.11054673421;7420.703171873065;7433.331000210007;7446.478746487911;7887.566183859713;7890.881636775568;7914.0503139623615;7914.643841027052;7924.6433910473015;4773.236282194832;4820.867096232453;4828.782704778223;4834.709917404967;4838.903221935471;7333.376662983706;7538.472306938462;7538.811529711801;7545.622718863925;7554.357879580078;7210.242924492853;7267.345938865527;7271.200167981412;7293.243979350533;7309.159446663702;5964.582479226586;7129.536580122275;7256.96371518139;7261.782146535613;7275.927240727525;7107.680154393168;7121.643917804193;7127.85744285101;7130.2869713028285;7132.679029443584;7051.932409401927;7246.927530724625;7259.20148783644;7263.782086537412;7268;5892.410758924073;6963.895541566779;7075.044868942627;7100.680469378994;7112.644367781693 16:00:47 INFO - PROCESS | 1660 | |5;yui.html;726.164910353094;758.6548120605125;773.5397438524076;802.8755542890854;806.3146325623221;15566;15968.281427335769;15977.520674379759;15980.760288595651;15992.440264590741;13791.31043447826;13884.722305553883;13942.16347019177;13944.163350198995;13959.162450252967;807.1323327423014;827.6523859978823;979.2753362511761;1004.8392257238843;1005.3615953869272;992.93545919515;1007.5063219022662;1008.495752123938;1008.9596416143382;1009.0212493880924;855.3670283989834;1064.1646307648498;1065.2170654568886;1065.2915810985685;1068.1294744783015;871.9825603487926;872.0799556467924;1017.9694609161774;1038.0449986012886;1040.4589613401065;783.0955246690082;783.9647215875299;784.9058113026473;786.0606575142707;787.2757063501535;15620.687586248325;15697.136657483834;15699.68600627981;15705.528834134995;15722.842771572252;13317.600471985856;13319.134256273408;13320.08746268359;13530.458781648722;13622.250776207306;10330.690079297634;10399.272050956437;10481.685549433529;10795.514201860857;10904.50929708165;3605.3330133925306;3644.2711457708565;3668.706503479742;3687.0966613179944;3698.500702405168;85661.57169214176;86935.56532217361;88101;89386;89707;10022.849657255147;10064.698059058093;10099.091081802673;10273.640422585173;10368.066873981377;23923.043078276674;23934;24101;24108.276751697478;24173.51652966931;7724.729634462767;7839.333656639135;7949.403794715419;8177.9182208178345;8318.209770071833;4244.575542445731;4248.91502169961;4298.527162012176;4397.142557201315;4595.954040459619;3729.6270372962485;3788.583255841855;3789.9431508527396;3794.848206071726;3802.429635554689;655.1155939481757;662.9834254143685;671.6943790575276;678.375894177358;701.6456689371937;554.3431034224457;592.380961894821;604.7611193578512;622.1321256846674;630.6310808177176;8462.238398544037;8527.616257268437;8570.100139485388;8653.134686531297;8800.207981281716;512.5489569179099;527.741406710709;557.4926816596877;569.3574038897809;617.1883972576048;3883.8446462141765;3963.960360396417;4065.1463192729266;4078.146151886439;4088.8160032798596;91187;95467.52266238764;95499;95760.52119739357;96287;80131;80845;80853.59573202096;80861.52370389216;81811.7728234077;68413.97379039318;68444.28889277807;68928.62142757117;69113.30886691068;70185.64907175432;7703.383729301754;7884.84230315402;7995.560244186626;8114.026316842017;8324.042735085543;51666.22500662568;51874;52292;53015.67460813511;53922.730386347816;63187;67897.6605116991;70716.23209419806;70896;72072.27927720656;10192.133668637953;10457.424841633783;10911.890881091087;10958.56165753361;11067.944660276647;11009.559617615205;11159.888401115884;11316.434178290889;11449.255798373079;11877.22798018126;10223.7444063899;10434.478276086316;10474.21443391764;10713.142948564253;11319.943400282946;10709.57161713554;10852.186086043735;10897.182711296526;10900.618478353219;10926.945365273123;10394.376337419779;10471.895281047093;10673.733156671145;10675;10890.836637450286;9107.499087550248;9173.770655733393;9247.815043699224;9266.44401335922;9533.142017218484;9125.361224714206;9135.588606498499;9175.724728258163;9207.677731279373;9298.860517092115 16:00:47 INFO - PROCESS | 1660 | __end_tp_report 16:00:47 INFO - PROCESS | 1660 | __start_cc_report 16:00:47 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1041 16:00:47 INFO - PROCESS | 1660 | __end_cc_report 16:00:47 INFO - PROCESS | 1660 | __startTimestamp1462834847979__endTimestamp 16:00:47 INFO - PROCESS | 1660 | 16:00:47 INFO - PROCESS | 1660 | ------- Summary: start ------- 16:00:47 INFO - PROCESS | 1660 | Number of tests: 6 16:00:47 INFO - PROCESS | 1660 | 16:00:47 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5874.93 Median:6137.96 stddev:640.82 (10.4%) stddev-sans-first:617.07 16:00:47 INFO - PROCESS | 1660 | Values: 3584.4 3651.0 3663.5 3673.2 3731.4 4730.0 5478.2 5796.3 5837.8 5881.6 5794.4 5809.6 5825.0 5831.6 5843.6 5205.6 5239.6 5256.7 5285.1 5329.1 5355.4 5362.3 5372.0 5376.0 5376.9 5281.8 5352.3 5363.4 5366.6 5371.7 5347.3 5396.7 5401.7 5402.5 5413.8 5138.8 5309.5 5321.7 5324.8 5330.0 5768.9 5797.1 5812.3 5824.0 5840.0 6220.1 6352.1 6374.6 6381.2 6390.6 5947.2 6123.5 6293.7 6298.5 6306.2 4967.5 6059.0 6078.8 6080.2 6082.8 6306.6 6370.3 6456.7 6471.3 6475.9 6302.6 6355.8 6356.7 6359.4 6362.0 6329.0 6336.9 6340.5 6346.8 6356.9 6197.0 6262.5 6280.9 6366.6 6407.1 6315.2 6324.4 6336.7 6356.2 6382.4 5068.8 5077.5 5083.8 5091.6 5095.2 4426.3 4497.6 4559.4 4566.6 4613.2 6055.8 6057.1 6070.1 6081.5 6103.1 6270.9 6286.1 6299.7 6304.1 6307.2 5828.0 5999.4 6027.1 6043.4 6104.8 5517.7 5531.9 5534.9 5539.0 5559.0 6478.7 6481.1 6487.7 6494.8 6500.7 6240.6 6271.7 6284.7 6294.8 6344.8 6510.1 6530.5 6540.2 6545.4 6551.0 6298.3 6310.3 6314.2 6329.4 6343.5 6144.3 6238.5 6267.1 6287.5 6321.8 6257.4 6296.8 6299.4 6301.8 6304.8 6118.4 6286.2 6293.3 6307.1 6310.3 6106.4 6138.0 6170.2 6185.8 6202.0 6291.3 6294.0 6300.8 6308.5 6309.7 4629.5 5993.4 6099.8 6167.5 6210.7 16:00:47 INFO - PROCESS | 1660 | 16:00:47 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:61699.89 Median:34662.15 stddev:125217.17 (361.3%) stddev-sans-first:125484.04 16:00:47 INFO - PROCESS | 1660 | Values: 467.2 619.7 621.5 664.8 671.1 32145.7 32600.7 32783.0 41156.4 41554.8 44001.8 44435.8 44619.3 44638.8 44639.3 9776.9 10015.2 10031.5 10034.2 10036.2 9847.4 9989.7 9994.3 10009.1 10022.2 9906.2 10017.4 10029.1 10054.6 10076.4 10011.4 10046.5 10093.2 10094.5 10095.1 9976.2 10115.0 10123.1 10125.8 10128.0 41821.5 42198.6 42673.4 42713.4 42718.4 3397.0 3462.9 3528.6 3626.9 3910.7 29328.0 30022.0 30111.2 30181.8 30751.4 2833.6 2833.7 2859.5 2869.1 2917.8 495104.0 501048.0 502064.0 504027.0 508497.0 48392.3 73049.6 74230.0 74301.3 74358.3 47781.0 47913.0 47987.8 48060.8 48170.8 13434.9 15853.6 17244.9 17340.6 17529.3 12007.3 13956.8 13975.9 13976.2 13981.9 15293.5 15363.4 15483.5 15549.0 15625.0 6461.8 6549.7 6590.9 6596.7 6599.3 1013.0 1076.9 1126.4 1183.1 1224.7 30395.6 30598.4 30667.4 31459.8 31529.2 1122.0 1196.8 1202.6 1233.5 1243.3 15648.8 15678.9 15755.8 15956.8 16102.4 529286.4 533302.0 534089.0 535019.0 541617.0 61738.7 62502.7 62553.0 62554.0 62590.7 1433.0 1552.3 1567.0 1590.5 1601.3 30190.4 30389.8 30609.8 30892.8 30893.2 48929.0 49109.8 49474.5 49577.8 49612.3 355480.0 356730.0 357895.0 358876.0 359014.0 30113.8 34272.5 34676.0 34724.0 35310.8 42104.4 44456.0 48161.5 49937.0 50506.0 35465.5 36114.3 37529.1 37947.0 40102.0 36961.4 37699.0 37728.8 38247.0 39039.4 38335.4 38680.6 38896.6 39159.4 39439.0 33603.7 33919.5 34407.3 34647.7 34648.3 23322.3 25849.9 26054.8 26124.9 26281.6 16:00:47 INFO - PROCESS | 1660 | 16:00:47 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:37236.54 Median:2270.75 stddev:107348.41 (4727.4%) stddev-sans-first:107613.86 16:00:47 INFO - PROCESS | 1660 | Values: 373.8 374.7 379.4 384.1 384.9 20471.7 21206.9 21768.8 22066.9 22102.4 10641.9 12179.5 12757.6 12759.9 12763.2 4879.1 6126.0 6217.7 6217.8 6229.3 6034.9 6074.8 6179.1 6189.1 6189.6 5416.3 5561.6 5566.0 5571.9 5593.5 5544.3 5565.1 5567.7 5581.8 5584.5 5644.2 5903.0 5914.1 5920.4 5929.0 12124.6 13934.6 14148.3 14242.6 14568.2 2260.6 2280.9 2293.3 2368.7 2694.5 15407.8 16378.4 16838.8 17150.8 17532.3 1904.7 1978.8 2018.1 2032.1 2044.3 116118.0 127539.4 129552.7 131607.0 132100.0 17023.5 19905.3 20411.6 20554.7 20629.0 31308.0 31376.2 31948.7 32134.7 32245.5 11332.5 11738.3 11772.9 11785.9 11899.6 8195.4 9870.6 9901.2 9912.9 10059.6 7524.8 8892.6 9107.8 9143.2 9150.3 3010.6 3453.2 3591.7 3608.1 3624.2 888.8 926.9 964.7 972.4 990.4 15728.0 20583.0 20711.9 20783.0 20817.6 948.6 993.2 1040.6 1048.8 1059.5 11058.8 11322.5 11349.0 11536.9 11578.8 523346.0 662859.0 671878.0 672308.0 683498.0 119579.4 120291.6 123825.0 124082.0 125466.4 892.8 1066.8 1089.9 1092.2 1113.6 18817.9 18899.0 19687.7 19727.7 19914.7 54522.7 55528.4 55598.0 56728.4 56775.0 78692.0 79493.2 81610.0 82429.0 82955.6 8241.5 8427.0 8491.1 8549.0 8584.0 12328.7 13124.1 13465.9 13678.1 13758.7 10330.8 10465.7 10497.4 11781.5 11881.9 8329.3 9794.0 10299.5 10623.3 11037.2 11881.6 11939.2 12022.0 12044.3 12151.3 8159.6 8345.7 8355.4 8623.0 8670.8 8809.3 11313.7 11685.0 11789.6 11978.0 16:00:47 INFO - PROCESS | 1660 | 16:00:47 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:6430.73 Median:5189.51 stddev:3432.66 (66.1%) stddev-sans-first:3422.38 16:00:47 INFO - PROCESS | 1660 | Values: 1512.0 1651.1 1670.0 1715.4 2097.7 8387.4 9203.4 9370.3 9397.4 9399.8 8966.0 9212.7 9227.0 9227.2 9246.3 2581.8 2597.6 2603.6 2605.2 2607.0 2102.7 2605.3 2607.0 2609.2 2622.8 2160.8 2573.8 2602.3 2614.5 2615.4 2579.8 2591.8 2602.8 2606.3 2609.9 2583.6 2590.6 2606.1 2629.4 2632.4 9375.4 9417.7 9436.5 9436.9 9448.7 5810.8 5977.6 5979.4 5984.4 6004.2 3733.8 3837.2 3863.6 3891.0 3920.6 1070.5 1088.7 1089.5 1098.0 1128.1 12675.4 12683.6 12703.6 12704.3 12747.8 5098.1 5122.5 5182.3 5196.7 5236.8 10880.6 10888.2 10902.8 10904.5 10910.2 5940.3 5946.5 5954.9 5955.3 5955.4 4690.0 4722.7 4807.5 4847.7 5339.5 4158.9 4194.3 4197.5 4222.7 4247.5 1388.1 1391.5 1391.9 1396.3 1405.4 1401.9 1425.8 1438.7 1451.3 1471.3 8828.6 8842.5 8881.6 8891.2 8920.4 536.9 547.4 549.0 554.7 555.3 6498.0 6526.5 6544.1 6549.8 6550.1 11274.5 11696.6 11698.1 11704.6 11707.0 11274.0 11277.3 11302.0 11361.7 11539.8 8717.9 8822.2 8904.3 8926.2 8938.1 8378.2 8445.9 8630.7 8641.4 8659.0 9712.1 10041.4 10056.8 10126.8 10142.0 10085.7 10128.5 10370.3 10561.7 10573.3 7238.1 7751.0 7877.7 7970.3 7976.2 8791.9 8833.9 8858.9 8881.9 8889.2 7655.8 7744.4 7830.5 7855.9 7868.1 7554.4 7601.3 7676.4 7717.7 7778.9 7980.8 8279.8 8315.0 8345.5 8345.6 7315.6 7409.1 7411.5 7411.8 7416.2 6267.6 7409.7 7518.1 7533.1 7547.4 16:00:47 INFO - PROCESS | 1660 | 16:00:48 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:5902.57 Median:6660.95 stddev:1880.75 (28.2%) stddev-sans-first:1839.01 16:00:48 INFO - PROCESS | 1660 | Values: 334.8 342.6 347.6 350.0 351.5 6746.1 6771.2 6776.1 6804.1 6817.3 6748.5 6761.5 6776.3 6776.4 6788.7 4766.6 4859.1 4895.5 5014.5 5021.2 4978.5 5015.8 5023.3 5024.0 5029.8 4771.1 4983.8 4986.8 4995.5 4999.4 4829.3 4963.4 4978.9 4985.9 4986.7 4289.3 4289.5 4301.5 4302.1 4304.1 6513.5 6544.6 6551.0 6559.9 6570.7 6001.0 6005.2 6013.8 6019.8 6029.4 5845.5 7240.7 7253.5 7255.5 7272.1 4682.0 4728.5 4730.1 4741.1 4744.7 7554.2 7633.2 7638.3 7656.8 7665.3 4793.3 4795.8 4806.3 4808.9 4810.0 4877.1 4884.7 4885.0 4885.2 4895.6 5579.6 5775.9 5847.9 5851.1 5853.6 5676.6 7177.7 7180.1 7187.7 7239.5 1672.3 1702.4 1704.2 1705.7 1709.8 1029.7 1053.3 1069.3 1075.3 1099.9 6655.0 6666.9 6677.2 6746.0 6785.2 7437.4 7465.4 7470.6 7470.9 7478.2 5856.8 6352.4 6733.8 6738.7 6743.2 3860.2 3890.1 3930.0 3932.1 3938.7 6279.8 7908.0 7909.6 7947.5 7948.5 7825.5 7835.2 7837.6 7850.6 7853.4 6293.9 7908.1 7929.0 7929.6 8008.8 5625.9 7086.6 7463.5 7570.5 7591.6 5912.5 7412.1 7420.7 7433.3 7446.5 7887.6 7890.9 7914.1 7914.6 7924.6 4773.2 4820.9 4828.8 4834.7 4838.9 7333.4 7538.5 7538.8 7545.6 7554.4 7210.2 7267.3 7271.2 7293.2 7309.2 5964.6 7129.5 7257.0 7261.8 7275.9 7107.7 7121.6 7127.9 7130.3 7132.7 7051.9 7246.9 7259.2 7263.8 7268.0 5892.4 6963.9 7075.0 7100.7 7112.6 16:00:48 INFO - PROCESS | 1660 | 16:00:48 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:18842.07 Median:4347.83 stddev:26682.68 (613.7%) stddev-sans-first:26722.86 16:00:48 INFO - PROCESS | 1660 | Values: 726.2 758.7 773.5 802.9 806.3 15566.0 15968.3 15977.5 15980.8 15992.4 13791.3 13884.7 13942.2 13944.2 13959.2 807.1 827.7 979.3 1004.8 1005.4 992.9 1007.5 1008.5 1009.0 1009.0 855.4 1064.2 1065.2 1065.3 1068.1 872.0 872.1 1018.0 1038.0 1040.5 783.1 784.0 784.9 786.1 787.3 15620.7 15697.1 15699.7 15705.5 15722.8 13317.6 13319.1 13320.1 13530.5 13622.3 10330.7 10399.3 10481.7 10795.5 10904.5 3605.3 3644.3 3668.7 3687.1 3698.5 85661.6 86935.6 88101.0 89386.0 89707.0 10022.8 10064.7 10099.1 10273.6 10368.1 23923.0 23934.0 24101.0 24108.3 24173.5 7724.7 7839.3 7949.4 8177.9 8318.2 4244.6 4248.9 4298.5 4397.1 4596.0 3729.6 3788.6 3789.9 3794.8 3802.4 655.1 663.0 671.7 678.4 701.6 554.3 592.4 604.8 622.1 630.6 8462.2 8527.6 8570.1 8653.1 8800.2 512.5 527.7 557.5 569.4 617.2 3883.8 3964.0 4065.1 4078.1 4088.8 91187.0 95467.5 95499.0 95760.5 96287.0 80131.0 80845.0 80853.6 80861.5 81811.8 68414.0 68444.3 68928.6 69113.3 70185.6 7703.4 7884.8 7995.6 8114.0 8324.0 51666.2 51874.0 52292.0 53015.7 53922.7 63187.0 67897.7 70716.2 70896.0 72072.3 10192.1 10457.4 10911.9 10958.6 11067.9 11009.6 11159.9 11316.4 11449.3 11877.2 10223.7 10434.5 10474.2 10713.1 11319.9 10709.6 10852.2 10897.2 10900.6 10926.9 10394.4 10471.9 10673.7 10675.0 10890.8 9107.5 9173.8 9247.8 9266.4 9533.1 9125.4 9135.6 9175.7 9207.7 9298.9 16:00:48 INFO - PROCESS | 1660 | -------- Summary: end -------- 16:00:48 INFO - PROCESS | 1660 | 16:00:48 INFO - TEST-INFO | 1660: exit 0 16:00:48 INFO - TEST-OK | dromaeo_css | took 1111493ms 16:00:48 INFO - TEST-START | kraken 16:00:48 INFO - operating with platform_type : mac_ 16:00:48 INFO - Initialising browser for kraken test... 16:00:48 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpAggPcv/profile http://localhost:49270/getInfo.html) 16:00:50 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 16:00:50 INFO - PROCESS | 1670 | colorDepth:24 16:00:50 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 16:00:50 INFO - PROCESS | 1670 | __metrics 16:00:50 INFO - PROCESS | 1670 | 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" 16:00:50 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:00:51 INFO - TEST-INFO | 1670: exit 0 16:00:51 INFO - Browser initialized. 16:00:51 INFO - Running cycle 1/1 for kraken test... 16:00:51 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpAggPcv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:01:03 INFO - PROCESS | 1673 | RSS: Main: 156540928 16:01:03 INFO - PROCESS | 1673 | 16:01:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 16:01:06 INFO - PROCESS | 1673 | RSS: Main: 213356544 16:01:06 INFO - PROCESS | 1673 | 16:01:08 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 16:01:09 INFO - PROCESS | 1673 | RSS: Main: 223416320 16:01:09 INFO - PROCESS | 1673 | 16:01:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 16:01:13 INFO - PROCESS | 1673 | RSS: Main: 212545536 16:01:13 INFO - PROCESS | 1673 | 16:01:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 16:01:15 INFO - PROCESS | 1673 | RSS: Main: 214609920 16:01:15 INFO - PROCESS | 1673 | 16:01:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:01:18 INFO - PROCESS | 1673 | RSS: Main: 215048192 16:01:18 INFO - PROCESS | 1673 | 16:01:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 16:01:21 INFO - PROCESS | 1673 | RSS: Main: 214962176 16:01:21 INFO - PROCESS | 1673 | 16:01:24 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 16:01:25 INFO - PROCESS | 1673 | RSS: Main: 215031808 16:01:25 INFO - PROCESS | 1673 | 16:01:27 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 16:01:28 INFO - PROCESS | 1673 | RSS: Main: 215162880 16:01:28 INFO - PROCESS | 1673 | 16:01:30 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:01:30 INFO - PROCESS | 1673 | RSS: Main: 248823808 16:01:30 INFO - PROCESS | 1673 | 16:01:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:01:33 INFO - PROCESS | 1673 | RSS: Main: 249851904 16:01:33 INFO - PROCESS | 1673 | 16:01:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:01:35 INFO - PROCESS | 1673 | RSS: Main: 254885888 16:01:35 INFO - PROCESS | 1673 | 16:01:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:01:38 INFO - PROCESS | 1673 | RSS: Main: 256233472 16:01:38 INFO - PROCESS | 1673 | 16:01:41 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:01:41 INFO - PROCESS | 1673 | RSS: Main: 256364544 16:01:41 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 16:01:43 INFO - PROCESS | 1673 | RSS: Main: 319639552 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | __start_tp_report 16:01:43 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 16:01:43 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 16:01:43 INFO - PROCESS | 1673 | |i|pagename|runs| 16:01:43 INFO - PROCESS | 1673 | |0;ai-astar;110;108;110;126;112;111;121;111;109;109 16:01:43 INFO - PROCESS | 1673 | |1;audio-beat-detection;173;147;153;148;116;134;118;149;147;118 16:01:43 INFO - PROCESS | 1673 | |2;audio-dft;191;257;206;184;183;183;180;183;182;181 16:01:43 INFO - PROCESS | 1673 | |3;audio-fft;74;78;72;114;58;75;84;85;139;55 16:01:43 INFO - PROCESS | 1673 | |4;audio-oscillator;88;92;126;78;80;83;117;74;79;115 16:01:43 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;92;129;115;83;87;84;90;84;85;83 16:01:43 INFO - PROCESS | 1673 | |6;imaging-darkroom;92;91;91;92;92;92;93;93;92;92 16:01:43 INFO - PROCESS | 1673 | |7;imaging-desaturate;83;83;84;84;85;86;83;86;84;85 16:01:43 INFO - PROCESS | 1673 | |8;json-parse-financial;55;57;57;58;59;59;58;67;52;51 16:01:43 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;62;61;65;73;72;73;66;66;66;66 16:01:43 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;80;82;82;80;79;79;80;79;80;82 16:01:43 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;117;121;132;128;117;115;119;126;134;141 16:01:43 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;134;121;133;133;137;159;168;138;133;130 16:01:43 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;67;66;65;65;66;70;65;93;65;65 16:01:43 INFO - PROCESS | 1673 | __end_tp_report 16:01:43 INFO - PROCESS | 1673 | __start_cc_report 16:01:43 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1184 16:01:43 INFO - PROCESS | 1673 | __end_cc_report 16:01:43 INFO - PROCESS | 1673 | __startTimestamp1462834903823__endTimestamp 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | ------- Summary: start ------- 16:01:43 INFO - PROCESS | 1673 | Number of tests: 14 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:112.70 Median:111.00 stddev:5.93 (5.3%) stddev-sans-first:6.20 16:01:43 INFO - PROCESS | 1673 | Values: 110.0 108.0 110.0 126.0 112.0 111.0 121.0 111.0 109.0 109.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:147.50 stddev:18.50 (12.5%) stddev-sans-first:15.38 16:01:43 INFO - PROCESS | 1673 | Values: 173.0 147.0 153.0 148.0 116.0 134.0 118.0 149.0 147.0 118.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:193.00 Median:183.50 stddev:23.77 (13.0%) stddev-sans-first:25.20 16:01:43 INFO - PROCESS | 1673 | Values: 191.0 257.0 206.0 184.0 183.0 183.0 180.0 183.0 182.0 181.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:83.40 Median:74.50 stddev:25.39 (34.1%) stddev-sans-first:26.70 16:01:43 INFO - PROCESS | 1673 | Values: 74.0 78.0 72.0 114.0 58.0 75.0 84.0 85.0 139.0 55.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:93.20 Median:79.50 stddev:18.93 (23.8%) stddev-sans-first:19.99 16:01:43 INFO - PROCESS | 1673 | Values: 88.0 92.0 126.0 78.0 80.0 83.0 117.0 74.0 79.0 115.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:84.50 stddev:15.82 (18.7%) stddev-sans-first:16.77 16:01:43 INFO - PROCESS | 1673 | Values: 92.0 129.0 115.0 83.0 87.0 84.0 90.0 84.0 85.0 83.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:92.00 Median:92.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 16:01:43 INFO - PROCESS | 1673 | Values: 92.0 91.0 91.0 92.0 92.0 92.0 93.0 93.0 92.0 92.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.50 stddev:1.16 (1.4%) stddev-sans-first:1.13 16:01:43 INFO - PROCESS | 1673 | Values: 83.0 83.0 84.0 84.0 85.0 86.0 83.0 86.0 84.0 85.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:58.00 stddev:4.40 (7.6%) stddev-sans-first:4.59 16:01:43 INFO - PROCESS | 1673 | Values: 55.0 57.0 57.0 58.0 59.0 59.0 58.0 67.0 52.0 51.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:66.00 stddev:4.29 (6.5%) stddev-sans-first:4.16 16:01:43 INFO - PROCESS | 1673 | Values: 62.0 61.0 65.0 73.0 72.0 73.0 66.0 66.0 66.0 66.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:80.00 stddev:1.25 (1.6%) stddev-sans-first:1.32 16:01:43 INFO - PROCESS | 1673 | Values: 80.0 82.0 82.0 80.0 79.0 79.0 80.0 79.0 80.0 82.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:125.00 Median:127.00 stddev:8.67 (6.8%) stddev-sans-first:8.70 16:01:43 INFO - PROCESS | 1673 | Values: 117.0 121.0 132.0 128.0 117.0 115.0 119.0 126.0 134.0 141.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.60 Median:135.50 stddev:14.07 (10.4%) stddev-sans-first:14.83 16:01:43 INFO - PROCESS | 1673 | Values: 134.0 121.0 133.0 133.0 137.0 159.0 168.0 138.0 133.0 130.0 16:01:43 INFO - PROCESS | 1673 | 16:01:43 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:66.00 stddev:8.68 (13.2%) stddev-sans-first:9.18 16:01:43 INFO - PROCESS | 1673 | Values: 67.0 66.0 65.0 65.0 66.0 70.0 65.0 93.0 65.0 65.0 16:01:43 INFO - PROCESS | 1673 | -------- Summary: end -------- 16:01:43 INFO - PROCESS | 1673 | 16:01:44 INFO - TEST-INFO | 1673: exit 0 16:01:44 INFO - TEST-OK | kraken | took 55939ms 16:01:44 INFO - SUITE-END | took 1167s 16:01:44 INFO - Completed test suite (00:19:27) 16:01:44 INFO - javascript benchmark 16:01:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3584.3906535888896, 3650.981745091274, 3663.523741913551, 3673.1735359544095, 3731.4216296474037, 4729.976350118253, 5478.205660179272, 5796.30444346679, 5837.824865254044, 5881.588288819784, 5794.362620111784, 5809.593328467009, 5825.0, 5831.591788574801, 5843.64938103714, 5205.557527610158, 5239.5546378557865, 5256.658317209388, 5285.127953887604, 5329.094054010808, 5355.410904800468, 5362.3029006229135, 5371.9524692684745, 5376.032314183445, 5376.892462150755, 5281.762320695578, 5352.304200453936, 5363.436839131893, 5366.597505187107, 5371.731413429333, 5347.278117454152, 5396.703181325045, 5401.675899446007, 5402.459754024605, 5413.810516631899, 5138.820141295074, 5309.522143007148, 5321.733913304358, 5324.760385782649, 5330.0, 5768.8846223075725, 5797.101449275369, 5812.3025236971835, 5824.0, 5840.0, 6220.066989951498, 6352.110704501373, 6374.553781235315, 6381.202349706286, 6390.55266131371, 5947.1971283876555, 6123.469132716821, 6293.685315734195, 6298.4646305064125, 6306.211723534559, 4967.478414766434, 6059.0, 6078.787242446493, 6080.209572755513, 6082.756689732405, 6306.621602703853, 6370.299267080617, 6456.709448074754, 6471.320511346335, 6475.870482590321, 6302.590331628429, 6355.841103972438, 6356.713947872357, 6359.427651511386, 6362.045693145972, 6328.968355158287, 6336.873262534724, 6340.492760579143, 6346.841328966812, 6356.904646430307, 6197.0, 6262.467690246289, 6280.874382512324, 6366.6180029198395, 6407.13503677007, 6315.242170939425, 6324.3991820776955, 6336.651484168415, 6356.2054743157105, 6382.36176382358, 5068.82259120929, 5077.543021128116, 5083.796648134107, 5091.567216786615, 5095.210242412432, 4426.26966550522, 4497.617702495325, 4559.407277054028, 4566.566176213254, 4613.215753321943, 6055.788047418319, 6057.121717350959, 6070.089486577049, 6081.513478921675, 6103.1455596216565, 6270.9372906271265, 6286.057091436322, 6299.716512756937, 6304.085907543381, 6307.211598550181, 5828.038373668382, 5999.400059994053, 6027.095935609694, 6043.395660433922, 6104.755809767559, 5517.724113794295, 5531.944680553223, 5534.944650553523, 5539.0, 5558.972205138948, 6478.708458119396, 6481.09264702942, 6487.708053137714, 6494.805155845238, 6500.739970401226, 6240.625562466266, 6271.717772700331, 6284.717187726472, 6294.842628934313, 6344.841378965378, 6510.0885875976455, 6530.510211734044, 6540.2151741789385, 6545.37818907203, 6551.0173473979285, 6298.275698294755, 6310.337414571404, 6314.210723659543, 6329.398707122816, 6343.52423568225, 6144.279601711528, 6238.532110091671, 6267.091271765477, 6287.528435367457, 6321.830461364662, 6257.43683068526, 6296.842578935563, 6299.433051025429, 6301.842453938688, 6304.779332723333, 6118.375534461997, 6286.15136956505, 6293.339199383999, 6307.148534947723, 6310.337414571404, 6106.399996025858, 6137.959273351923, 6170.167027451236, 6185.84535386619, 6202.038684003986, 6291.276503202191, 6293.96853015732, 6300.810975670737, 6308.526860485574, 6309.684515774285, 4629.4676112247525, 5993.430624090704, 6099.817005489665, 6167.537434692326, 6210.689465526615], "unit": "score", "name": "dojo.html", "value": 5837.128332780236}, {"lowerIsBetter": false, "replicates": [467.18709445564707, 619.7149311316794, 621.4965877639115, 664.7939138866951, 671.0571646836195, 32145.678543214563, 32600.67399326009, 32783.01650950472, 41156.38265426014, 41554.75335739925, 44001.77999110008, 44435.77782111093, 44619.33071003937, 44638.776806116, 44639.330410043796, 9776.853347199798, 10015.19878409729, 10031.498425078753, 10034.197264218845, 10036.247281453883, 9847.359921605108, 9989.650362237324, 9994.300398972075, 10009.149222316111, 10022.248331375176, 9906.157976572, 10017.39895606266, 10029.097381235688, 10054.648087316946, 10076.445795481282, 10011.399316041025, 10046.547905344278, 10093.243006774448, 10094.545745441435, 10095.091441770275, 9976.152027077707, 10115.039071288289, 10123.088921996981, 10125.848112278321, 10128.0, 41821.53624623123, 42198.57801421977, 42673.35989960153, 42713.35929961053, 42718.35922461165, 3397.048826328632, 3462.8787992420143, 3528.576570811493, 3626.9093272668392, 3910.667593254577, 29328.0, 30022.0, 30111.247218819484, 30181.849090754407, 30751.384972300428, 2833.5891295762203, 2833.659960804696, 2859.5281778506524, 2869.0818937939866, 2917.7811664125275, 495104.0, 501048.0, 502063.99999999633, 504027.0, 508497.0, 48392.27411588829, 73049.6347518259, 74230.0, 74301.25698743052, 74358.25641743513, 47781.0, 47913.04173916572, 47987.76006119947, 48060.7596962013, 48170.759146204044, 13434.865651343556, 15853.603659908364, 17244.913775431043, 17340.566485837702, 17529.29882804699, 12007.339596322287, 13956.790648140286, 13975.88192944562, 13976.161430314214, 13981.930090349482, 15293.541193764206, 15363.385464581292, 15483.5354939352, 15548.989315694444, 15624.98437601575, 6461.8061458156335, 6549.672516374162, 6590.934090659032, 6596.736130554724, 6599.307072757388, 1012.9949350253349, 1076.9246152769388, 1126.442411006557, 1183.1303991566192, 1224.706070543081, 30395.616132075123, 30598.388032239676, 30667.38665226683, 31459.84270078681, 31529.21176970594, 1121.994390028061, 1196.8384268123866, 1202.6151631477844, 1233.5189276182296, 1243.322389297835, 15648.843511564966, 15678.9216053919, 15755.763663545056, 15956.76064859028, 16102.355905763872, 529286.3535682374, 533302.0, 534089.0, 535019.0, 541617.0, 61738.69130654318, 62502.687486562274, 62553.0, 62554.0, 62590.687046564475, 1432.9713405731827, 1552.2626752292797, 1566.9529914102595, 1590.498992817282, 1601.2874270949317, 30190.396192076474, 30389.848050759603, 30609.846950765103, 30892.845535772176, 30893.227669307547, 48929.0, 49109.75445122751, 49474.50525494699, 49577.75211123921, 49612.25581616206, 355480.0, 356730.0, 357895.0, 358876.0, 359014.0, 30113.849430752707, 34272.48591271083, 34676.0, 34724.0, 35310.823445882605, 42104.3684344729, 44456.0, 48161.51838481711, 49937.0, 50506.0, 35465.468017979234, 36114.27771444504, 37529.061773455884, 37947.0, 40102.0, 36961.44557831688, 37699.0, 37728.81135594304, 38247.0, 39039.41440878446, 38335.42496862605, 38680.6131938677, 38896.61103389043, 39159.41260881146, 39439.0, 33603.66396336005, 33919.491207631414, 34407.31185376328, 34647.65352346444, 34648.30703385868, 23322.33113952272, 25849.870750646125, 26054.827532760693, 26124.91135548691, 26281.60577591376], "unit": "score", "name": "ext.html", "value": 19997.997868789742}, {"lowerIsBetter": false, "replicates": [373.7719990805609, 374.6871362412387, 379.3948651900219, 384.1012031845481, 384.87491565241305, 20471.692924606123, 21206.89396553017, 21768.782312176874, 22066.88966555165, 22102.447438814037, 10641.946790266058, 12179.51281948721, 12757.553485627981, 12759.915407190376, 12763.23420594765, 4879.097366987101, 6126.019836826109, 6217.658028808413, 6217.813465596039, 6229.345918678541, 6034.8793024139495, 6074.787382441616, 6179.104029915659, 6189.133521307021, 6189.566730328879, 5416.295881535415, 5561.555075593943, 5566.025945459549, 5571.9442805572035, 5593.52455041322, 5544.3346798384255, 5565.109582466826, 5567.721613919288, 5581.832545023656, 5584.46947539983, 5644.181593668895, 5902.97048514759, 5914.112883067532, 5920.378360272152, 5929.021711417611, 12124.636260912186, 13934.581962541039, 14148.292585370691, 14242.643933901736, 14568.198749068797, 2260.6495993121093, 2280.851744636593, 2293.3005433342823, 2368.6683864259016, 2694.488861521361, 15407.845921540753, 16378.426755063634, 16838.83161168397, 17150.82849171505, 17532.29870805166, 1904.6952487601918, 1978.82190602845, 2018.0817727933754, 2032.1058734156923, 2044.2845004248395, 116118.0, 127539.3623031879, 129552.70447295594, 131607.0, 132100.0, 17023.48929532116, 19905.303314384215, 20411.591768164555, 20554.69167962482, 20629.0, 31308.0, 31376.215594610316, 31948.680513195035, 32134.678653213636, 32245.51631725526, 11332.546698132148, 11738.295702257892, 11772.88227117735, 11785.88214117848, 11899.583514576949, 8195.426320157532, 9870.555824987892, 9901.207903367713, 9912.900870991341, 10059.597616095274, 7524.811879703051, 8892.555372231493, 9107.81784364309, 9143.177114059767, 9150.313726470407, 3010.6086208793154, 3453.240287136826, 3591.6947059500235, 3608.1340478285024, 3624.2389098289655, 888.7778055486128, 926.9304802139867, 964.7154089543601, 972.3825370889537, 990.3612170150212, 15728.0, 20583.0, 20711.8964405177, 20783.0, 20817.58364832695, 948.5968463403026, 993.1558175550724, 1040.625374865048, 1048.7849990751877, 1059.5020340440135, 11058.778824423467, 11322.471466351937, 11349.0, 11536.884631153749, 11578.826317605242, 523346.0, 662859.0, 671878.0, 672308.0, 683498.0, 119579.40210299067, 120291.62451599386, 123825.0, 124082.0, 125466.37266813607, 892.8035832116924, 1066.7759770448138, 1089.8801131875487, 1092.1699508373692, 1113.5545781687229, 18817.90591047091, 18899.0, 19687.70468443003, 19727.704084439032, 19914.70127948053, 54522.727386362814, 55528.44471555233, 55598.0, 56728.432715672316, 56775.0, 78692.0, 79493.20506794858, 81610.0, 82429.0, 82955.58522207351, 8241.464304820407, 8427.049936970818, 8491.108433614381, 8549.0, 8584.0, 12328.691782705504, 13124.14693044949, 13465.882964473438, 13678.110922789987, 13758.724825503634, 10330.793384132125, 10465.738356540844, 10497.370157790558, 11781.469833857669, 11881.881181188077, 8329.250367466715, 9794.0, 10299.485025748532, 10623.256372053882, 11037.172212083966, 11881.5841445549, 11939.223950443193, 12022.0, 12044.277343359427, 12151.270923744603, 8159.551224682699, 8345.749627511184, 8355.37334699912, 8623.0, 8670.78323041929, 8809.295256379346, 11313.660590182308, 11685.0, 11789.646310610695, 11978.0], "unit": "score", "name": "jquery.html", "value": 10413.105124287506}, {"lowerIsBetter": false, "replicates": [1512.0398546922702, 1651.0836485750408, 1669.9833001669983, 1715.382462313567, 2097.664373700208, 8387.412881098318, 9203.401778884368, 9370.297227707915, 9397.389169703965, 9399.765005874857, 8966.0, 9212.677556285531, 9226.98503164653, 9227.215686666625, 9246.306527010467, 2581.8321809082445, 2597.636330913664, 2603.609458581209, 2605.1663467690346, 2606.9869650651817, 2102.6530622447353, 2605.3356394119473, 2607.0484273240227, 2609.2041927212194, 2622.7639512443875, 2160.794724501178, 2573.768360847523, 2602.3233959170657, 2614.4901744159897, 2615.4246065865477, 2579.793616510675, 2591.7926565874686, 2602.830815996964, 2606.2962999990086, 2609.856457894815, 2583.599542070982, 2590.611408288753, 2606.100895191156, 2629.368951451657, 2632.4471860909234, 9375.437473751597, 9417.670381536613, 9436.480993545352, 9436.905630943671, 9448.716538503855, 5810.82567522975, 5977.565384307778, 5979.372165922602, 5984.431479009487, 6004.159417681528, 3733.8133093345496, 3837.1558257183374, 3863.5943225961432, 3891.0, 3920.6471417572557, 1070.5396679427843, 1088.6679562733364, 1089.5042755546208, 1098.028245003178, 1128.1313388690662, 12675.429605667674, 12683.556075537404, 12703.618891433272, 12704.3012634306, 12747.808782868171, 5098.107831129538, 5122.487751224922, 5182.300389447451, 5196.714180720097, 5236.816711415158, 10880.61917832888, 10888.183386246063, 10902.836457452986, 10904.50929708165, 10910.23628346008, 5940.34656187819, 5946.513371657085, 5954.910676339859, 5955.255593050869, 5955.374685657944, 4690.0, 4722.716637001791, 4807.495213002654, 4847.684900481458, 5339.492748188916, 4158.854440094643, 4194.3498757692605, 4197.496300443905, 4222.746635201897, 4247.532771395144, 1388.0769288423287, 1391.5268808604922, 1391.9373628186756, 1396.3367400484688, 1405.3816320818808, 1401.8948578856628, 1425.7932599772976, 1438.6691061056015, 1451.2743628185908, 1471.3305446022034, 8828.602712877935, 8842.46945183291, 8881.644734210688, 8891.244244239182, 8920.375573709907, 536.9194620806832, 547.4416095582484, 549.0035585412463, 554.7143221241064, 555.2920026965651, 6498.0, 6526.543141980016, 6544.116544266558, 6549.770758023446, 6550.148480697479, 11274.492647830868, 11696.590619328283, 11698.06415486759, 11704.648860534198, 11707.0, 11274.0, 11277.266977646426, 11302.0, 11361.659150225507, 11539.826902596467, 8717.91282087171, 8822.2060014599, 8904.287656987426, 8926.152015558511, 8938.10618938114, 8378.162183781573, 8445.873311900203, 8630.654773808978, 8641.438306510308, 8659.0, 9712.077352651488, 10041.447720375449, 10056.84914726265, 10126.79746405053, 10142.0, 10085.747856303651, 10128.544215510172, 10370.325928814604, 10561.683149505527, 10573.2598718089, 7238.0952380952385, 7750.961245193738, 7877.684892604231, 7970.322522585645, 7976.202379761978, 8791.868121978303, 8833.911660883308, 8858.911410886067, 8881.911180888108, 8889.155530224618, 7655.808604784925, 7744.380449564134, 7830.4518683691595, 7855.921440785519, 7868.095169055403, 7554.395648348229, 7601.315881570686, 7676.357787950019, 7717.652705628372, 7778.88331675014, 7980.84038319242, 8279.83440331178, 8315.00219973607, 8345.499270043574, 8345.624446900025, 7315.63421828917, 7409.110906691231, 7411.518251313863, 7411.777646670608, 7416.184219735716, 6267.592606480564, 7409.740659076906, 7518.097828260644, 7533.058367704037, 7547.358474529727], "unit": "score", "name": "mootools.html", "value": 5142.687343981518}, {"lowerIsBetter": false, "replicates": [334.7991205276833, 342.5683638615345, 347.60373174580974, 349.9580050393952, 351.48682922932517, 6746.089277947475, 6771.1874575051, 6776.05135281061, 6804.149481314836, 6817.3182681731805, 6748.460123190145, 6761.459083273338, 6776.254611992689, 6776.390124888759, 6788.72845086196, 4766.594839438651, 4859.076775412678, 4895.510448955111, 5014.548690617844, 5021.246812978046, 4978.5270399312185, 5015.799368025275, 5023.296738456619, 5024.0, 5029.767706911812, 4771.117343291485, 4983.750812459362, 4986.825461108844, 4995.475475075119, 4999.425066117388, 4829.3480380148585, 4963.379577552806, 4978.87552811179, 4985.900281994376, 4986.725730084844, 4289.270823959934, 4289.528151903288, 4301.548337424588, 4302.096559722462, 4304.139172165579, 6513.478921686254, 6544.640044797534, 6551.0, 6559.868802623969, 6570.671466426707, 6001.039833626599, 6005.159277701126, 6013.75944962201, 6019.849503762397, 6029.427204415573, 5845.532357411439, 7240.746573869942, 7253.528520646194, 7255.455840811907, 7272.0546328977425, 4682.0, 4728.503507131736, 4730.053989202173, 4741.075490279397, 4744.7153170809515, 7554.206808285161, 7633.236676332378, 7638.274363935473, 7656.808579785495, 7665.271799179125, 4793.281007848851, 4795.760211989456, 4806.303086052572, 4808.903821923542, 4809.951900480951, 4877.097736918681, 4884.706917584886, 4885.0, 4885.2427873679635, 4895.559399654048, 5579.609427340048, 5775.913361299584, 5847.912281315784, 5851.063829787214, 5853.648781073064, 5676.602637815396, 7177.748778792821, 7180.102487189101, 7187.712491500386, 7239.529430586995, 1672.2976349933058, 1702.421176799894, 1704.2160606121074, 1705.6844483770542, 1709.7948246210535, 1029.7013865978931, 1053.2995557953875, 1069.3102948598112, 1075.2526993739275, 1099.8625171853519, 6655.0, 6666.899996500057, 6677.198736151596, 6745.9662701686175, 6785.219699734495, 7437.405007599379, 7465.440091993014, 7470.589117598584, 7470.887936680954, 7478.214787447239, 5856.824295271149, 6352.396522330371, 6733.831654208684, 6738.663066846638, 6743.1908171019795, 3860.1507668312925, 3890.132736286598, 3929.960700393017, 3932.05630648648, 3938.7045971552247, 6279.780207692709, 7908.01149856268, 7909.5649739263745, 7947.483413578099, 7948.523088614586, 7825.452218344662, 7835.216478352119, 7837.6081195941115, 7850.646720897574, 7853.371730261565, 6293.874122517524, 7908.130105688369, 7929.048514178336, 7929.563873986986, 8008.799780005546, 5625.936250632382, 7086.574805511686, 7463.514871533333, 7570.507916985378, 7591.62041897914, 5912.526997840248, 7412.11054673421, 7420.703171873065, 7433.331000210007, 7446.478746487911, 7887.566183859713, 7890.881636775568, 7914.0503139623615, 7914.643841027052, 7924.6433910473015, 4773.236282194832, 4820.867096232453, 4828.782704778223, 4834.709917404967, 4838.903221935471, 7333.376662983706, 7538.472306938462, 7538.811529711801, 7545.622718863925, 7554.357879580078, 7210.242924492853, 7267.345938865527, 7271.200167981412, 7293.243979350533, 7309.159446663702, 5964.582479226586, 7129.536580122275, 7256.96371518139, 7261.782146535613, 7275.927240727525, 7107.680154393168, 7121.643917804193, 7127.85744285101, 7130.2869713028285, 7132.679029443584, 7051.932409401927, 7246.927530724625, 7259.20148783644, 7263.782086537412, 7268.0, 5892.410758924073, 6963.895541566779, 7075.044868942627, 7100.680469378994, 7112.644367781693], "unit": "score", "name": "prototype.html", "value": 5277.826501649589}, {"lowerIsBetter": false, "replicates": [726.164910353094, 758.6548120605125, 773.5397438524076, 802.8755542890854, 806.3146325623221, 15566.0, 15968.281427335769, 15977.520674379759, 15980.760288595651, 15992.440264590741, 13791.31043447826, 13884.722305553883, 13942.16347019177, 13944.163350198995, 13959.162450252967, 807.1323327423014, 827.6523859978823, 979.2753362511761, 1004.8392257238843, 1005.3615953869272, 992.93545919515, 1007.5063219022662, 1008.495752123938, 1008.9596416143382, 1009.0212493880924, 855.3670283989834, 1064.1646307648498, 1065.2170654568886, 1065.2915810985685, 1068.1294744783015, 871.9825603487926, 872.0799556467924, 1017.9694609161774, 1038.0449986012886, 1040.4589613401065, 783.0955246690082, 783.9647215875299, 784.9058113026473, 786.0606575142707, 787.2757063501535, 15620.687586248325, 15697.136657483834, 15699.68600627981, 15705.528834134995, 15722.842771572252, 13317.600471985856, 13319.134256273408, 13320.08746268359, 13530.458781648722, 13622.250776207306, 10330.690079297634, 10399.272050956437, 10481.685549433529, 10795.514201860857, 10904.50929708165, 3605.3330133925306, 3644.2711457708565, 3668.706503479742, 3687.0966613179944, 3698.500702405168, 85661.57169214176, 86935.56532217361, 88101.0, 89386.0, 89707.0, 10022.849657255147, 10064.698059058093, 10099.091081802673, 10273.640422585173, 10368.066873981377, 23923.043078276674, 23934.0, 24101.0, 24108.276751697478, 24173.51652966931, 7724.729634462767, 7839.333656639135, 7949.403794715419, 8177.9182208178345, 8318.209770071833, 4244.575542445731, 4248.91502169961, 4298.527162012176, 4397.142557201315, 4595.954040459619, 3729.6270372962485, 3788.583255841855, 3789.9431508527396, 3794.848206071726, 3802.429635554689, 655.1155939481757, 662.9834254143685, 671.6943790575276, 678.375894177358, 701.6456689371937, 554.3431034224457, 592.380961894821, 604.7611193578512, 622.1321256846674, 630.6310808177176, 8462.238398544037, 8527.616257268437, 8570.100139485388, 8653.134686531297, 8800.207981281716, 512.5489569179099, 527.741406710709, 557.4926816596877, 569.3574038897809, 617.1883972576048, 3883.8446462141765, 3963.960360396417, 4065.1463192729266, 4078.146151886439, 4088.8160032798596, 91187.0, 95467.52266238764, 95499.0, 95760.52119739357, 96287.0, 80131.0, 80845.0, 80853.59573202096, 80861.52370389216, 81811.7728234077, 68413.97379039318, 68444.28889277807, 68928.62142757117, 69113.30886691068, 70185.64907175432, 7703.383729301754, 7884.84230315402, 7995.560244186626, 8114.026316842017, 8324.042735085543, 51666.22500662568, 51874.0, 52292.0, 53015.67460813511, 53922.730386347816, 63187.0, 67897.6605116991, 70716.23209419806, 70896.0, 72072.27927720656, 10192.133668637953, 10457.424841633783, 10911.890881091087, 10958.56165753361, 11067.944660276647, 11009.559617615205, 11159.888401115884, 11316.434178290889, 11449.255798373079, 11877.22798018126, 10223.7444063899, 10434.478276086316, 10474.21443391764, 10713.142948564253, 11319.943400282946, 10709.57161713554, 10852.186086043735, 10897.182711296526, 10900.618478353219, 10926.945365273123, 10394.376337419779, 10471.895281047093, 10673.733156671145, 10675.0, 10890.836637450286, 9107.499087550248, 9173.770655733393, 9247.815043699224, 9266.44401335922, 9533.142017218484, 9125.361224714206, 9135.588606498499, 9175.724728258163, 9207.677731279373, 9298.860517092115], "unit": "score", "name": "yui.html", "value": 7098.762129662351}], "name": "dromaeo_css", "value": 7851.234536852979}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 108.0, 110.0, 126.0, 112.0, 111.0, 121.0, 111.0, 109.0, 109.0], "unit": "score", "name": "ai-astar", "value": 112.7}, {"lowerIsBetter": true, "replicates": [173.0, 147.0, 153.0, 148.0, 116.0, 134.0, 118.0, 149.0, 147.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 140.3}, {"lowerIsBetter": true, "replicates": [191.0, 257.0, 206.0, 184.0, 183.0, 183.0, 180.0, 183.0, 182.0, 181.0], "unit": "score", "name": "audio-dft", "value": 193.0}, {"lowerIsBetter": true, "replicates": [74.0, 78.0, 72.0, 114.0, 58.0, 75.0, 84.0, 85.0, 139.0, 55.0], "unit": "score", "name": "audio-fft", "value": 83.4}, {"lowerIsBetter": true, "replicates": [88.0, 92.0, 126.0, 78.0, 80.0, 83.0, 117.0, 74.0, 79.0, 115.0], "unit": "score", "name": "audio-oscillator", "value": 93.2}, {"lowerIsBetter": true, "replicates": [92.0, 129.0, 115.0, 83.0, 87.0, 84.0, 90.0, 84.0, 85.0, 83.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 93.2}, {"lowerIsBetter": true, "replicates": [92.0, 91.0, 91.0, 92.0, 92.0, 92.0, 93.0, 93.0, 92.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 92.0}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 84.0, 84.0, 85.0, 86.0, 83.0, 86.0, 84.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 84.3}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 57.0, 58.0, 59.0, 59.0, 58.0, 67.0, 52.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 57.3}, {"lowerIsBetter": true, "replicates": [62.0, 61.0, 65.0, 73.0, 72.0, 73.0, 66.0, 66.0, 66.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.0}, {"lowerIsBetter": true, "replicates": [80.0, 82.0, 82.0, 80.0, 79.0, 79.0, 80.0, 79.0, 80.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.3}, {"lowerIsBetter": true, "replicates": [117.0, 121.0, 132.0, 128.0, 117.0, 115.0, 119.0, 126.0, 134.0, 141.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.0}, {"lowerIsBetter": true, "replicates": [134.0, 121.0, 133.0, 133.0, 137.0, 159.0, 168.0, 138.0, 133.0, 130.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 138.6}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 65.0, 65.0, 66.0, 70.0, 65.0, 93.0, 65.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.7}], "name": "kraken", "value": 1429.0}]} 16:01:45 INFO - Return code: 0 16:01:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:01:45 INFO - # TBPL SUCCESS # 16:01:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:01:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:01:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:01:45 INFO - 2016-05-09 16:01:45.141 screenresolution[1677:9669] starting screenresolution argv=screenresolution get 16:01:45 INFO - 2016-05-09 16:01:45.144 screenresolution[1677:9669] Display 0: 1600x1200x32@60 16:01:45 INFO - 2016-05-09 16:01:45.150 screenresolution[1678:9672] starting screenresolution argv=screenresolution list 16:01:45 INFO - Available Modes on Display 0 16:01:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:01:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:01:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:01:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:01:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:01:45 INFO - Intel Iris: 16:01:45 INFO - Chipset Model: Intel Iris 16:01:45 INFO - Type: GPU 16:01:45 INFO - Bus: Built-In 16:01:45 INFO - VRAM (Dynamic, Max): 1536 MB 16:01:45 INFO - Vendor: Intel (0x8086) 16:01:45 INFO - Device ID: 0x0a2e 16:01:45 INFO - Revision ID: 0x0009 16:01:45 INFO - Displays: 16:01:45 INFO - Display: 16:01:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:01:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:01:45 INFO - Display Serial Number: 1600x1200 60 16:01:45 INFO - Main Display: Yes 16:01:45 INFO - Mirror: Off 16:01:45 INFO - Online: Yes 16:01:45 INFO - Rotation: Supported 16:01:45 INFO - Return code: 0 16:01:45 INFO - Running post-action listener: _resource_record_post_action 16:01:45 INFO - Running post-run listener: _resource_record_post_run 16:01:46 INFO - Total resource usage - Wall time: 1192s; CPU: 52.0%; Read bytes: 37709312; Write bytes: 507304448; Read time: 455; Write time: 1732 16:01:46 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 200665600; Write bytes: 196177920; Read time: 18013; Write time: 356 16:01:46 INFO - run-tests - Wall time: 1170s; CPU: 52.0%; Read bytes: 35943936; Write bytes: 298488832; Read time: 438; Write time: 1327 16:01:46 INFO - Running post-run listener: _upload_blobber_files 16:01:46 INFO - Blob upload gear active. 16:01:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:01:46 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 16:01:46 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:01:46 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:01:46 INFO - (blobuploader) - INFO - Open directory for files ... 16:01:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:01:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:01:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:01:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:01:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:01:47 INFO - (blobuploader) - INFO - Done attempting. 16:01:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:01:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:01:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:01:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:01:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:01:48 INFO - (blobuploader) - INFO - Done attempting. 16:01:48 INFO - (blobuploader) - INFO - Iteration through files over. 16:01:48 INFO - Return code: 0 16:01:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:01:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:01:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7fb4393707ab78fa49d33abb399de8eb08c3f323d700109007ec51b3aee40eb0ed766255ac10e23679f1e74223eb18d2af47ec3c08bfa5ba55afcdd09ef7f181", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/67608fe6fb45ac1dd5e08f799c7fb4495c54c276a54a8836b0fade4e50d60d3795912c1fb9979fc91fed6a72c10bef5a70870de98ac92eed7f87e9be6c260924"} 16:01:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:01:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:01:48 INFO - Contents: 16:01:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7fb4393707ab78fa49d33abb399de8eb08c3f323d700109007ec51b3aee40eb0ed766255ac10e23679f1e74223eb18d2af47ec3c08bfa5ba55afcdd09ef7f181", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/67608fe6fb45ac1dd5e08f799c7fb4495c54c276a54a8836b0fade4e50d60d3795912c1fb9979fc91fed6a72c10bef5a70870de98ac92eed7f87e9be6c260924"} 16:01:48 INFO - Running post-run listener: copy_logs_to_upload_dir 16:01:48 INFO - Copying logs to upload dir... 16:01:48 INFO - mkdir: /builds/slave/test/build/upload/logs 16:01:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.410436 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-09 16:01:48.943948) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 16:01:48.947905) ========= 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.geRm1RGeLM/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.RHzt92oizE/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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-Beta/sha512/7fb4393707ab78fa49d33abb399de8eb08c3f323d700109007ec51b3aee40eb0ed766255ac10e23679f1e74223eb18d2af47ec3c08bfa5ba55afcdd09ef7f181", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/67608fe6fb45ac1dd5e08f799c7fb4495c54c276a54a8836b0fade4e50d60d3795912c1fb9979fc91fed6a72c10bef5a70870de98ac92eed7f87e9be6c260924"} build_url:https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010984 build_url: 'https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7fb4393707ab78fa49d33abb399de8eb08c3f323d700109007ec51b3aee40eb0ed766255ac10e23679f1e74223eb18d2af47ec3c08bfa5ba55afcdd09ef7f181", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/67608fe6fb45ac1dd5e08f799c7fb4495c54c276a54a8836b0fade4e50d60d3795912c1fb9979fc91fed6a72c10bef5a70870de98ac92eed7f87e9be6c260924"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 16:01:48.975731) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:01:48.976073) ========= 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.geRm1RGeLM/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.RHzt92oizE/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.005005 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:01:49.024239) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 16:01:49.024574) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 16:01:49.024903) ========= ========= Total master_lag: 0.16 =========