builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0117 starttime: 1461153394.96 results: success (0) buildid: 20160420025138 builduid: 3bb49e3aa94a4d0a96badb2f0439b956 revision: f05a1242fb29023bd7ebc492897ed3d6907733c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 04:56:34.956015) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 04:56:34.956417) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 04:56:34.956706) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 04:56:35.420111) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 04:56:35.420568) ========= 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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-20 04:56:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-04-20 04:56:35 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124659 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 04:56:35.580579) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 04:56:35.583927) ========= 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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.026743 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 04:56:35.657675) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-20 04:56:35.658008) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f05a1242fb29023bd7ebc492897ed3d6907733c7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f05a1242fb29023bd7ebc492897ed3d6907733c7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.a1RnAlqdse/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.hX0Pkk0Lev/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-20 04:56:35,739 truncating revision to first 12 chars 2016-04-20 04:56:35,739 Setting DEBUG logging. 2016-04-20 04:56:35,739 attempt 1/10 2016-04-20 04:56:35,739 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f05a1242fb29?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 04:56:36,325 unpacking tar archive at: mozilla-central-f05a1242fb29/testing/mozharness/ program finished with exit code 0 elapsedTime=0.836293 ========= master_lag: 5.94 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-20 04:56:42.435587) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 04:56:42.435886) ========= script_repo_revision: f05a1242fb29023bd7ebc492897ed3d6907733c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 04:56:42.436272) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 17 secs) (at 2016-04-20 04:56:42.436554) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 17 secs) (at 2016-04-20 04:56:59.861779) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-04-20 04:56:59.862040) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.a1RnAlqdse/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.hX0Pkk0Lev/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:57:00 INFO - MultiFileLogger online at 20160420 04:57:00 in /builds/slave/test 04:57:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:57:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:57:00 INFO - {'append_to_log': False, 04:57:00 INFO - 'base_work_dir': '/builds/slave/test', 04:57:00 INFO - 'blob_upload_branch': 'Firefox', 04:57:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:57:00 INFO - 'branch': 'Firefox', 04:57:00 INFO - 'buildbot_json_path': 'buildprops.json', 04:57:00 INFO - 'config_files': ('talos/mac_config.py',), 04:57:00 INFO - 'default_actions': ('clobber', 04:57:00 INFO - 'read-buildbot-config', 04:57:00 INFO - 'download-and-extract', 04:57:00 INFO - 'populate-webroot', 04:57:00 INFO - 'create-virtualenv', 04:57:00 INFO - 'install', 04:57:00 INFO - 'run-tests'), 04:57:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:57:00 INFO - 'download_minidump_stackwalk': True, 04:57:00 INFO - 'download_symbols': 'ondemand', 04:57:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:57:00 INFO - 'tooltool.py': '/tools/tooltool.py', 04:57:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:57:00 INFO - '/tools/misc-python/virtualenv.py')}, 04:57:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:57:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:57:00 INFO - 'installer_path': 'installer.exe', 04:57:00 INFO - 'log_level': 'info', 04:57:00 INFO - 'log_name': 'talos', 04:57:00 INFO - 'log_to_console': True, 04:57:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:57:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:57:00 INFO - 'opt_config_files': (), 04:57:00 INFO - 'pip_index': False, 04:57:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:57:00 INFO - 'cmd': ('bash', 04:57:00 INFO - '-c', 04:57:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:57:00 INFO - 'enabled': True, 04:57:00 INFO - 'halt_on_failure': False, 04:57:00 INFO - 'name': 'check_screen_resolution'},), 04:57:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:57:00 INFO - 'cmd': ('bash', 04:57:00 INFO - '-c', 04:57:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:57:00 INFO - 'enabled': True, 04:57:00 INFO - 'halt_on_failure': False, 04:57:00 INFO - 'name': 'check_screen_resolution'},), 04:57:00 INFO - 'run_cmd_checks_enabled': True, 04:57:00 INFO - 'sps_profile': False, 04:57:00 INFO - 'sps_profile_interval': 0, 04:57:00 INFO - 'suite': 'dromaeojs', 04:57:00 INFO - 'system_bits': '32', 04:57:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:57:00 INFO - 'title': 't-yosemite-r7-0117', 04:57:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:57:00 INFO - 'use_talos_json': True, 04:57:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:57:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:57:00 INFO - 'work_dir': 'build'} 04:57:00 INFO - ##### 04:57:00 INFO - ##### Running clobber step. 04:57:00 INFO - ##### 04:57:00 INFO - Running pre-action listener: _resource_record_pre_action 04:57:00 INFO - Running main action method: clobber 04:57:00 INFO - rmtree: /builds/slave/test/build 04:57:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:57:01 INFO - Running post-action listener: _resource_record_post_action 04:57:01 INFO - ##### 04:57:01 INFO - ##### Running read-buildbot-config step. 04:57:01 INFO - ##### 04:57:01 INFO - Running pre-action listener: _resource_record_pre_action 04:57:01 INFO - Running main action method: read_buildbot_config 04:57:01 INFO - Using buildbot properties: 04:57:01 INFO - { 04:57:01 INFO - "project": "", 04:57:01 INFO - "product": "firefox", 04:57:01 INFO - "script_repo_revision": "production", 04:57:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:57:01 INFO - "repository": "", 04:57:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:57:01 INFO - "buildid": "20160420025138", 04:57:01 INFO - "pgo_build": "False", 04:57:01 INFO - "basedir": "/builds/slave/test", 04:57:01 INFO - "buildnumber": 219, 04:57:01 INFO - "slavename": "t-yosemite-r7-0117", 04:57:01 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:57:01 INFO - "platform": "yosemite_r7", 04:57:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:57:01 INFO - "branch": "mozilla-central", 04:57:01 INFO - "repo_path": "mozilla-central", 04:57:01 INFO - "revision": "f05a1242fb29023bd7ebc492897ed3d6907733c7", 04:57:01 INFO - "stage_platform": "macosx64", 04:57:01 INFO - "builduid": "3bb49e3aa94a4d0a96badb2f0439b956", 04:57:01 INFO - "slavebuilddir": "test" 04:57:01 INFO - } 04:57:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 04:57:01 INFO - Running post-action listener: _resource_record_post_action 04:57:01 INFO - ##### 04:57:01 INFO - ##### Running download-and-extract step. 04:57:01 INFO - ##### 04:57:01 INFO - Running pre-action listener: _resource_record_pre_action 04:57:01 INFO - Running main action method: download_and_extract 04:57:01 INFO - mkdir: /builds/slave/test/build/tests 04:57:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:01 INFO - https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:57:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:57:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:57:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 04:57:05 INFO - Downloaded 1199 bytes. 04:57:05 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:57:05 INFO - Using the following test package requirements: 04:57:05 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:57:05 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:57:05 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 04:57:05 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:57:05 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 04:57:05 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:57:05 INFO - u'jsshell-mac.zip'], 04:57:05 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:57:05 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 04:57:05 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:57:05 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:57:05 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 04:57:05 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:57:05 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 04:57:05 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:57:05 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 04:57:05 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:57:05 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 04:57:05 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:57:05 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')]} 04:57:05 INFO - https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:57:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:57:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:57:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:57:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:57:08 INFO - Downloaded 18096123 bytes. 04:57:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:57:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:57:12 INFO - Return code: 0 04:57:12 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:57:12 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')]} 04:57:12 INFO - https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:57:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:57:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:57:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:57:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:57:20 INFO - Downloaded 18096123 bytes. 04:57:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:57:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:57:23 INFO - Return code: 0 04:57:23 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')]} 04:57:23 INFO - https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:57:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:57:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:57:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:57:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:57:25 INFO - Downloaded 10845952 bytes. 04:57:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:57:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:57:25 INFO - Return code: 0 04:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:25 INFO - https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:57:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:57:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:57:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 04:57:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 04:57:29 INFO - Downloaded 110048181 bytes. 04:57:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:57:29 INFO - mkdir: /builds/slave/test/properties 04:57:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:57:29 INFO - Writing to file /builds/slave/test/properties/build_url 04:57:29 INFO - Contents: 04:57:29 INFO - build_url:https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:57:30 INFO - Running post-action listener: _resource_record_post_action 04:57:30 INFO - Running post-action listener: set_extra_try_arguments 04:57:30 INFO - ##### 04:57:30 INFO - ##### Running populate-webroot step. 04:57:30 INFO - ##### 04:57:30 INFO - Running pre-action listener: _resource_record_pre_action 04:57:30 INFO - Running main action method: populate_webroot 04:57:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:57:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:57:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:57:30 INFO - '--tppagecycles', 04:57:30 INFO - '7'], 04:57:30 INFO - 'tests': ['tsvgm']}}, 04:57:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:57:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:57:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:57:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:57:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:57:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:57:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:57:30 INFO - 'talos_options': ['--disable-e10s'], 04:57:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:57:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:57:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:57:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:57:30 INFO - 'talos_options': ['--disable-e10s'], 04:57:30 INFO - 'tests': ['damp', 'tps']}, 04:57:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:57:30 INFO - 'tests': ['damp', 'tps']}, 04:57:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:57:30 INFO - 'tests': ['dromaeo_dom']}, 04:57:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:57:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:57:30 INFO - 'tests': ['a11yr', 04:57:30 INFO - 'ts_paint', 04:57:30 INFO - 'tpaint', 04:57:30 INFO - 'sessionrestore', 04:57:30 INFO - 'sessionrestore_no_auto_restore', 04:57:30 INFO - 'tabpaint']}, 04:57:30 INFO - 'other-e10s': {'tests': ['a11yr', 04:57:30 INFO - 'ts_paint', 04:57:30 INFO - 'tpaint', 04:57:30 INFO - 'sessionrestore', 04:57:30 INFO - 'sessionrestore_no_auto_restore', 04:57:30 INFO - 'tabpaint']}, 04:57:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:57:30 INFO - 'ts_paint', 04:57:30 INFO - 'tpaint', 04:57:30 INFO - 'sessionrestore', 04:57:30 INFO - 'sessionrestore_no_auto_restore', 04:57:30 INFO - 'tabpaint']}, 04:57:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:57:30 INFO - 'ts_paint', 04:57:30 INFO - 'tpaint', 04:57:30 INFO - 'sessionrestore', 04:57:30 INFO - 'sessionrestore_no_auto_restore', 04:57:30 INFO - 'tabpaint']}, 04:57:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:57:30 INFO - 'tests': ['a11yr', 04:57:30 INFO - 'ts_paint', 04:57:30 INFO - 'tpaint', 04:57:30 INFO - 'sessionrestore', 04:57:30 INFO - 'sessionrestore_no_auto_restore', 04:57:30 INFO - 'tabpaint']}, 04:57:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:57:30 INFO - 'tests': ['a11yr', 04:57:30 INFO - 'ts_paint', 04:57:30 INFO - 'tpaint', 04:57:30 INFO - 'sessionrestore', 04:57:30 INFO - 'sessionrestore_no_auto_restore', 04:57:30 INFO - 'tabpaint']}, 04:57:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:57:30 INFO - 'tests': ['tsvgx', 04:57:30 INFO - 'tsvgr_opacity', 04:57:30 INFO - 'tart', 04:57:30 INFO - 'tscrollx', 04:57:30 INFO - 'cart']}, 04:57:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:57:30 INFO - 'tsvgr_opacity', 04:57:30 INFO - 'tart', 04:57:30 INFO - 'tscrollx', 04:57:30 INFO - 'cart']}, 04:57:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:57:30 INFO - 'talos_options': ['--disable-e10s'], 04:57:30 INFO - 'tests': ['tp5o']}, 04:57:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:57:30 INFO - 'tests': ['tp5o']}, 04:57:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:57:30 INFO - 'talos_options': ['--disable-e10s', 04:57:30 INFO - '--xperf_path', 04:57:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:57:30 INFO - 'tests': ['tp5n']}, 04:57:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:57:30 INFO - 'talos_options': ['--xperf_path', 04:57:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:57:30 INFO - 'tests': ['tp5n']}}, 04:57:30 INFO - 'talos.zip': {'path': '', 04:57:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:57:30 INFO - Running post-action listener: _resource_record_post_action 04:57:30 INFO - ##### 04:57:30 INFO - ##### Running create-virtualenv step. 04:57:30 INFO - ##### 04:57:30 INFO - Running pre-action listener: _resource_record_pre_action 04:57:30 INFO - Running main action method: create_virtualenv 04:57:30 INFO - Creating virtualenv /builds/slave/test/build/venv 04:57:30 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 04:57:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:57:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:57:30 INFO - Using real prefix '/tools/python27' 04:57:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:57:31 INFO - Installing distribute.............................................................................................................................................................................................done. 04:57:33 INFO - Installing pip.................done. 04:57:33 INFO - Return code: 0 04:57:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:57:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1012a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9591de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101142e00>, '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 0x100f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10113db20>, '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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:57:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:57:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a1RnAlqdse/Render', 04:57:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:33 INFO - 'HOME': '/Users/cltbld', 04:57:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:33 INFO - 'LOGNAME': 'cltbld', 04:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:33 INFO - 'MOZ_NO_REMOTE': '1', 04:57:33 INFO - 'NO_EM_RESTART': '1', 04:57:33 INFO - 'PAGER': '/bin/cat', 04:57:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:33 INFO - 'PWD': '/builds/slave/test', 04:57:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:33 INFO - 'SHELL': '/bin/bash', 04:57:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hX0Pkk0Lev/Listeners', 04:57:33 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:57:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:33 INFO - 'USER': 'cltbld', 04:57:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:33 INFO - 'XPC_FLAGS': '0x0', 04:57:33 INFO - 'XPC_SERVICE_NAME': '0', 04:57:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:34 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 04:57:34 INFO - Cleaning up... 04:57:34 INFO - Return code: 0 04:57:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:57:34 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')]} 04:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:34 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')]} 04:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:34 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 0x1012a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9591de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101142e00>, '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 0x100f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10113db20>, '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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:57:34 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 04:57:34 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 04:57:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a1RnAlqdse/Render', 04:57:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:34 INFO - 'HOME': '/Users/cltbld', 04:57:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:34 INFO - 'LOGNAME': 'cltbld', 04:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:34 INFO - 'MOZ_NO_REMOTE': '1', 04:57:34 INFO - 'NO_EM_RESTART': '1', 04:57:34 INFO - 'PAGER': '/bin/cat', 04:57:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:34 INFO - 'PWD': '/builds/slave/test', 04:57:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:34 INFO - 'SHELL': '/bin/bash', 04:57:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hX0Pkk0Lev/Listeners', 04:57:34 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:57:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:34 INFO - 'USER': 'cltbld', 04:57:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:34 INFO - 'XPC_FLAGS': '0x0', 04:57:34 INFO - 'XPC_SERVICE_NAME': '0', 04:57:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:34 INFO - Downloading/unpacking psutil>=0.7.1 04:57:34 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 04:57:34 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 04:57:34 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 04:57:34 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 04:57:34 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 04:57:34 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 04:57:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:57:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:57:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:57:36 INFO - Installing collected packages: psutil 04:57:36 INFO - Running setup.py install for psutil 04:57:37 INFO - building 'psutil._psutil_osx' extension 04:57:37 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 04:57:37 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 04:57:37 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 04:57:37 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 04:57:37 INFO - building 'psutil._psutil_posix' extension 04:57:37 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 04:57:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:57:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:57:37 INFO - ^ 04:57:37 INFO - 1 warning generated. 04:57:37 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 04:57:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:57:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:57:38 INFO - Successfully installed psutil 04:57:38 INFO - Cleaning up... 04:57:38 INFO - Return code: 0 04:57:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:57:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:38 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 0x1012a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9591de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101142e00>, '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 0x100f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10113db20>, '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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:57:38 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 04:57:38 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 04:57:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a1RnAlqdse/Render', 04:57:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:38 INFO - 'HOME': '/Users/cltbld', 04:57:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:38 INFO - 'LOGNAME': 'cltbld', 04:57:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:38 INFO - 'MOZ_NO_REMOTE': '1', 04:57:38 INFO - 'NO_EM_RESTART': '1', 04:57:38 INFO - 'PAGER': '/bin/cat', 04:57:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:38 INFO - 'PWD': '/builds/slave/test', 04:57:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:38 INFO - 'SHELL': '/bin/bash', 04:57:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hX0Pkk0Lev/Listeners', 04:57:38 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:57:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:38 INFO - 'USER': 'cltbld', 04:57:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:38 INFO - 'XPC_FLAGS': '0x0', 04:57:38 INFO - 'XPC_SERVICE_NAME': '0', 04:57:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:38 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:57:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:57:41 INFO - Installing collected packages: mozsystemmonitor 04:57:41 INFO - Running setup.py install for mozsystemmonitor 04:57:41 INFO - Successfully installed mozsystemmonitor 04:57:41 INFO - Cleaning up... 04:57:41 INFO - Return code: 0 04:57:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:41 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 0x1012a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9591de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101142e00>, '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 0x100f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10113db20>, '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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:57:41 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 04:57:41 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 04:57:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a1RnAlqdse/Render', 04:57:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:41 INFO - 'HOME': '/Users/cltbld', 04:57:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:41 INFO - 'LOGNAME': 'cltbld', 04:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:41 INFO - 'MOZ_NO_REMOTE': '1', 04:57:41 INFO - 'NO_EM_RESTART': '1', 04:57:41 INFO - 'PAGER': '/bin/cat', 04:57:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:41 INFO - 'PWD': '/builds/slave/test', 04:57:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:41 INFO - 'SHELL': '/bin/bash', 04:57:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hX0Pkk0Lev/Listeners', 04:57:41 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:57:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:41 INFO - 'USER': 'cltbld', 04:57:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:41 INFO - 'XPC_FLAGS': '0x0', 04:57:41 INFO - 'XPC_SERVICE_NAME': '0', 04:57:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:41 INFO - Downloading/unpacking blobuploader==1.2.4 04:57: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 04:57: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 04:57: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 04:57: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 04:57: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 04:57: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 04:57:43 INFO - Downloading blobuploader-1.2.4.tar.gz 04:57:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:57:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:57:44 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 04:57:44 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 04:57:44 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 04:57:44 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 04:57:44 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 04:57:44 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 04:57:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:57:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:57:44 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 04:57:44 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 04:57:44 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 04:57:44 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 04:57:44 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 04:57:44 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 04:57:45 INFO - Downloading docopt-0.6.1.tar.gz 04:57:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:57:45 INFO - Installing collected packages: blobuploader, requests, docopt 04:57:45 INFO - Running setup.py install for blobuploader 04:57:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:57:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:57:45 INFO - Running setup.py install for requests 04:57:45 INFO - Running setup.py install for docopt 04:57:46 INFO - Successfully installed blobuploader requests docopt 04:57:46 INFO - Cleaning up... 04:57:46 INFO - Return code: 0 04:57:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:46 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 0x1012a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9591de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101142e00>, '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 0x100f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10113db20>, '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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:57:46 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 04:57:46 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 04:57:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a1RnAlqdse/Render', 04:57:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:46 INFO - 'HOME': '/Users/cltbld', 04:57:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:46 INFO - 'LOGNAME': 'cltbld', 04:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:46 INFO - 'MOZ_NO_REMOTE': '1', 04:57:46 INFO - 'NO_EM_RESTART': '1', 04:57:46 INFO - 'PAGER': '/bin/cat', 04:57:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:46 INFO - 'PWD': '/builds/slave/test', 04:57:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:46 INFO - 'SHELL': '/bin/bash', 04:57:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hX0Pkk0Lev/Listeners', 04:57:46 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:57:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:46 INFO - 'USER': 'cltbld', 04:57:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:46 INFO - 'XPC_FLAGS': '0x0', 04:57:46 INFO - 'XPC_SERVICE_NAME': '0', 04:57:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:57:46 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Ge832T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:57:46 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-grajUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:57:46 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-L8A7rE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:57:46 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-EumM5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:57:46 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-UzyqIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:57:46 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-GEhKyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:57:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-O8HRcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:57:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-af6VB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:57:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-12gBjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:57:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-K1T2SJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:57:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ts5fut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:57:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-tiKMGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:57:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ZF41u1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:57:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-WrlSpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:57:47 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-J5kX0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:57:48 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-DojwXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:57:48 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-54tzWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:57:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:57:48 INFO - Running setup.py install for manifestparser 04:57:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:57:48 INFO - Running setup.py install for mozcrash 04:57:48 INFO - Running setup.py install for mozdebug 04:57:48 INFO - Running setup.py install for mozdevice 04:57:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:57:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:57:48 INFO - Running setup.py install for mozfile 04:57:48 INFO - Running setup.py install for mozhttpd 04:57:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:57:49 INFO - Running setup.py install for mozinfo 04:57:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:57:49 INFO - Running setup.py install for mozInstall 04:57:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:57:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:57:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:57:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:57:49 INFO - Running setup.py install for mozleak 04:57:49 INFO - Running setup.py install for mozlog 04:57:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:57:49 INFO - Running setup.py install for moznetwork 04:57:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:57:49 INFO - Running setup.py install for mozprocess 04:57:49 INFO - Running setup.py install for mozprofile 04:57:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:57:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:57:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:57:50 INFO - Running setup.py install for mozrunner 04:57:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:57:50 INFO - Running setup.py install for mozscreenshot 04:57:50 INFO - Running setup.py install for moztest 04:57:50 INFO - Running setup.py install for mozversion 04:57:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:57:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:57:50 INFO - Cleaning up... 04:57:50 INFO - Return code: 0 04:57:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:50 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 0x1012a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9591de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101142e00>, '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 0x100f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10113db20>, '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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:57:50 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 04:57:50 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 04:57:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a1RnAlqdse/Render', 04:57:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:50 INFO - 'HOME': '/Users/cltbld', 04:57:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:50 INFO - 'LOGNAME': 'cltbld', 04:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:50 INFO - 'MOZ_NO_REMOTE': '1', 04:57:50 INFO - 'NO_EM_RESTART': '1', 04:57:50 INFO - 'PAGER': '/bin/cat', 04:57:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:50 INFO - 'PWD': '/builds/slave/test', 04:57:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:50 INFO - 'SHELL': '/bin/bash', 04:57:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hX0Pkk0Lev/Listeners', 04:57:50 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:57:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:50 INFO - 'USER': 'cltbld', 04:57:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:50 INFO - 'XPC_FLAGS': '0x0', 04:57:50 INFO - 'XPC_SERVICE_NAME': '0', 04:57:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:57:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-HlKRet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:57:51 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)) 04:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:57:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-CAAYQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:57:51 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)) 04:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:57:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-a3tuzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:57:51 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)) 04:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:57:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-AZwSZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:57:51 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)) 04:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:57:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-L3GtSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:57:51 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)) 04:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:57:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-I2qHNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:57:51 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)) 04:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:57:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-5efUXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:57:51 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)) 04:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:57:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-t4_7Ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:57:51 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)) 04:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:57:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-UKrvhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:57:51 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)) 04:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:57:51 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Ej6hqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:57:52 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)) 04:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:57:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-LxXeAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:57:52 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)) 04:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:57:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-qgGf6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:57:52 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)) 04:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:57:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-keketh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:57:52 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)) 04:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:57:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-2qee6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:57:52 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)) 04:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:57:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-HpGmOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:57:52 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)) 04:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:57:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-BE9a6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:57:52 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)) 04:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:57:52 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-fb5agR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:57:52 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)) 04:57:52 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)) 04:57:52 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)) 04:57:52 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)) 04:57:52 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)) 04:57:52 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)) 04:57:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:55 INFO - Downloading blessings-1.6.tar.gz 04:57:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:57:55 INFO - Installing collected packages: blessings 04:57:55 INFO - Running setup.py install for blessings 04:57:55 INFO - Successfully installed blessings 04:57:55 INFO - Cleaning up... 04:57:55 INFO - Return code: 0 04:57:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:57:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:57:55 INFO - Reading from file tmpfile_stdout 04:57:55 INFO - Current package versions: 04:57:55 INFO - blessings == 1.6 04:57:55 INFO - blobuploader == 1.2.4 04:57:55 INFO - docopt == 0.6.1 04:57:55 INFO - manifestparser == 1.1 04:57:55 INFO - mozInstall == 1.12 04:57:55 INFO - mozcrash == 0.17 04:57:55 INFO - mozdebug == 0.1 04:57:55 INFO - mozdevice == 0.48 04:57:55 INFO - mozfile == 1.2 04:57:55 INFO - mozhttpd == 0.7 04:57:55 INFO - mozinfo == 0.9 04:57:55 INFO - mozleak == 0.1 04:57:55 INFO - mozlog == 3.1 04:57:55 INFO - moznetwork == 0.27 04:57:55 INFO - mozprocess == 0.22 04:57:55 INFO - mozprofile == 0.28 04:57:55 INFO - mozrunner == 6.11 04:57:55 INFO - mozscreenshot == 0.1 04:57:55 INFO - mozsystemmonitor == 0.0 04:57:55 INFO - moztest == 0.7 04:57:55 INFO - mozversion == 1.4 04:57:55 INFO - psutil == 3.1.1 04:57:55 INFO - requests == 1.2.3 04:57:55 INFO - wsgiref == 0.1.2 04:57:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:55 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 0x1012a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9591de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101142e00>, '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 0x100f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10113db20>, '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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:57:55 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 04:57:55 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 04:57:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a1RnAlqdse/Render', 04:57:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:55 INFO - 'HOME': '/Users/cltbld', 04:57:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:55 INFO - 'LOGNAME': 'cltbld', 04:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:55 INFO - 'MOZ_NO_REMOTE': '1', 04:57:55 INFO - 'NO_EM_RESTART': '1', 04:57:55 INFO - 'PAGER': '/bin/cat', 04:57:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:55 INFO - 'PWD': '/builds/slave/test', 04:57:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:55 INFO - 'SHELL': '/bin/bash', 04:57:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hX0Pkk0Lev/Listeners', 04:57:55 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:57:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:55 INFO - 'USER': 'cltbld', 04:57:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:55 INFO - 'XPC_FLAGS': '0x0', 04:57:55 INFO - 'XPC_SERVICE_NAME': '0', 04:57:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:56 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)) 04:57:56 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)) 04:57:56 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)) 04:57:56 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)) 04:57:56 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)) 04:57:56 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)) 04:57:56 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)) 04:57:56 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)) 04:57:56 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)) 04:57:56 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)) 04:57:56 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)) 04:57:56 INFO - Cleaning up... 04:57:56 INFO - Return code: 0 04:57:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:57:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1012a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9591de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101142e00>, '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 0x100f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10113db20>, '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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:57:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:57:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a1RnAlqdse/Render', 04:57:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:56 INFO - 'HOME': '/Users/cltbld', 04:57:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:56 INFO - 'LOGNAME': 'cltbld', 04:57:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:56 INFO - 'MOZ_NO_REMOTE': '1', 04:57:56 INFO - 'NO_EM_RESTART': '1', 04:57:56 INFO - 'PAGER': '/bin/cat', 04:57:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:56 INFO - 'PWD': '/builds/slave/test', 04:57:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:56 INFO - 'SHELL': '/bin/bash', 04:57:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hX0Pkk0Lev/Listeners', 04:57:56 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:57:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:56 INFO - 'USER': 'cltbld', 04:57:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:56 INFO - 'XPC_FLAGS': '0x0', 04:57:56 INFO - 'XPC_SERVICE_NAME': '0', 04:57:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:56 INFO - Downloading/unpacking jsonschema 04:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:57:58 INFO - Downloading/unpacking functools32 (from jsonschema) 04:57:58 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 04:57:58 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 04:57:58 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 04:57:58 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 04:57:58 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 04:57:58 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 04:57:59 INFO - Downloading functools32-3.2.3-2.tar.gz 04:57:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:57:59 INFO - warning: no files found matching '*.txt' 04:57:59 INFO - no previously-included directories found matching 'build' 04:57:59 INFO - no previously-included directories found matching 'dist' 04:57:59 INFO - no previously-included directories found matching '.git*' 04:57:59 INFO - Installing collected packages: jsonschema, functools32 04:57:59 INFO - Running setup.py install for functools32 04:57:59 INFO - warning: no files found matching '*.txt' 04:57:59 INFO - no previously-included directories found matching 'build' 04:57:59 INFO - no previously-included directories found matching 'dist' 04:57:59 INFO - no previously-included directories found matching '.git*' 04:57:59 INFO - Successfully installed jsonschema functools32 04:57:59 INFO - Cleaning up... 04:57:59 INFO - Return code: 0 04:57:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:57:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:59 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 0x1012a3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012b3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9591de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101142e00>, '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 0x100f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10113db20>, '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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:57:59 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 04:57:59 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 04:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a1RnAlqdse/Render', 04:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:59 INFO - 'HOME': '/Users/cltbld', 04:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:59 INFO - 'LOGNAME': 'cltbld', 04:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:59 INFO - 'MOZ_NO_REMOTE': '1', 04:57:59 INFO - 'NO_EM_RESTART': '1', 04:57:59 INFO - 'PAGER': '/bin/cat', 04:57:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:59 INFO - 'PWD': '/builds/slave/test', 04:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:59 INFO - 'SHELL': '/bin/bash', 04:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hX0Pkk0Lev/Listeners', 04:57:59 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:59 INFO - 'USER': 'cltbld', 04:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:59 INFO - 'XPC_FLAGS': '0x0', 04:57:59 INFO - 'XPC_SERVICE_NAME': '0', 04:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:59 INFO - Downloading/unpacking flake8 04:57:59 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 04:57:59 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 04:57:59 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 04:57:59 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 04:57:59 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 04:57:59 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 04:58:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:58:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:58:02 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 04:58:02 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 04:58:02 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 04:58:02 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 04:58:02 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 04:58:02 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 04:58:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:58:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:58:02 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 04:58:02 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 04:58:02 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 04:58:02 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 04:58:02 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 04:58:02 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 04:58:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:58:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:58:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:58:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:58:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:58:03 INFO - no previously-included directories found matching 'docs/_build' 04:58:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:58:03 INFO - Running setup.py install for pep8 04:58:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:58:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:58:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:58:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:58:03 INFO - no previously-included directories found matching 'docs/_build' 04:58:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:58:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:58:03 INFO - Cleaning up... 04:58:03 INFO - Return code: 0 04:58:03 INFO - Running post-action listener: _resource_record_post_action 04:58:03 INFO - Running post-action listener: _start_resource_monitoring 04:58:03 INFO - Starting resource monitoring. 04:58:03 INFO - ##### 04:58:03 INFO - ##### Running install step. 04:58:03 INFO - ##### 04:58:03 INFO - Running pre-action listener: _resource_record_pre_action 04:58:03 INFO - Running main action method: install 04:58:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:58:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:58:04 INFO - Reading from file tmpfile_stdout 04:58:04 INFO - Detecting whether we're running mozinstall >=1.0... 04:58:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:58:04 INFO - Reading from file tmpfile_stdout 04:58:04 INFO - Output received: 04:58:04 INFO - Usage: mozinstall [options] installer 04:58:04 INFO - Options: 04:58:04 INFO - -h, --help show this help message and exit 04:58:04 INFO - -d DEST, --destination=DEST 04:58:04 INFO - Directory to install application into. [default: 04:58:04 INFO - "/builds/slave/test"] 04:58:04 INFO - --app=APP Application being installed. [default: firefox] 04:58:04 INFO - mkdir: /builds/slave/test/build/application 04:58:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:58:38 INFO - Reading from file tmpfile_stdout 04:58:38 INFO - Output received: 04:58:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:58:38 INFO - Running post-action listener: _resource_record_post_action 04:58:38 INFO - ##### 04:58:38 INFO - ##### Running run-tests step. 04:58:38 INFO - ##### 04:58:38 INFO - Running pre-action listener: _resource_record_pre_action 04:58:38 INFO - Running main action method: run_tests 04:58:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:58:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:58:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:58:38 INFO - 2016-04-20 04:58:38.091 screenresolution[1640:7716] starting screenresolution argv=screenresolution get 04:58:38 INFO - 2016-04-20 04:58:38.094 screenresolution[1640:7716] Display 0: 1600x1200x32@60 04:58:38 INFO - 2016-04-20 04:58:38.100 screenresolution[1641:7718] starting screenresolution argv=screenresolution list 04:58:38 INFO - Available Modes on Display 0 04:58:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:58:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:58:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:58:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:58:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:58:38 INFO - Intel Iris: 04:58:38 INFO - Chipset Model: Intel Iris 04:58:38 INFO - Type: GPU 04:58:38 INFO - Bus: Built-In 04:58:38 INFO - VRAM (Dynamic, Max): 1536 MB 04:58:38 INFO - Vendor: Intel (0x8086) 04:58:38 INFO - Device ID: 0x0a2e 04:58:38 INFO - Revision ID: 0x0009 04:58:38 INFO - Displays: 04:58:38 INFO - Display: 04:58:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:58:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:58:38 INFO - Display Serial Number: 1600x1200 60 04:58:38 INFO - Main Display: Yes 04:58:38 INFO - Mirror: Off 04:58:38 INFO - Online: Yes 04:58:38 INFO - Rotation: Supported 04:58:38 INFO - Return code: 0 04:58:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:58:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:58:38 INFO - Python 2.7.3 04:58:38 INFO - Return code: 0 04:58:38 INFO - grabbing minidump binary from tooltool 04:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:58:38 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 0x101142e00>, '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 0x100f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10113db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:58:38 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 04:58:38 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 04:58:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:58:38 INFO - Return code: 0 04:58:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:58:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:58:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:58:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:58:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:58:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:58:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:58:39 INFO - Return code: 0 04:58:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 04:58:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0117 --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 04:58:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a1RnAlqdse/Render', 04:58:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:39 INFO - 'HOME': '/Users/cltbld', 04:58:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:39 INFO - 'LOGNAME': 'cltbld', 04:58:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:58:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:58:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:39 INFO - 'MOZ_NO_REMOTE': '1', 04:58:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:58:39 INFO - 'NO_EM_RESTART': '1', 04:58:39 INFO - 'PAGER': '/bin/cat', 04:58:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:39 INFO - 'PWD': '/builds/slave/test', 04:58:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:58:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:39 INFO - 'SHELL': '/bin/bash', 04:58:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hX0Pkk0Lev/Listeners', 04:58:39 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:58:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:39 INFO - 'USER': 'cltbld', 04:58:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:39 INFO - 'XPC_FLAGS': '0x0', 04:58:39 INFO - 'XPC_SERVICE_NAME': '0', 04:58:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 04:58:39 INFO - mozversion application_buildid: 20160420025138 04:58:39 INFO - mozversion application_changeset: f05a1242fb29023bd7ebc492897ed3d6907733c7 04:58:39 INFO - mozversion application_display_name: Nightly 04:58:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:58:39 INFO - mozversion application_name: Firefox 04:58:39 INFO - mozversion application_remotingname: firefox 04:58:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:58:39 INFO - mozversion application_vendor: Mozilla 04:58:39 INFO - mozversion application_version: 48.0a1 04:58:39 INFO - mozversion platform_buildid: 20160420025138 04:58:39 INFO - mozversion platform_changeset: f05a1242fb29023bd7ebc492897ed3d6907733c7 04:58:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:58:39 INFO - mozversion platform_version: 48.0a1 04:58:39 INFO - using testdate: 1461153519 04:58:39 INFO - actual date: 1461153519 04:58:39 INFO - starting webserver on 'localhost:49270' 04:58:39 INFO - SUITE-START | Running 2 tests 04:58:39 INFO - TEST-START | dromaeo_css 04:58:39 INFO - operating with platform_type : mac_ 04:58:39 INFO - Initialising browser for dromaeo_css test... 04:58:39 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpsfPohC/profile http://localhost:49270/getInfo.html) 04:58:42 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 04:58:42 INFO - PROCESS | 1659 | colorDepth:24 04:58:42 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 04:58:42 INFO - PROCESS | 1659 | __metrics 04:58:42 INFO - PROCESS | 1659 | 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" 04:58:42 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:58:42 INFO - TEST-INFO | 1659: exit 0 04:58:42 INFO - Browser initialized. 04:58:42 INFO - Running cycle 1/1 for dromaeo_css test... 04:58:42 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpsfPohC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:58:55 INFO - PROCESS | 1661 | RSS: Main: 237903872 04:58:55 INFO - PROCESS | 1661 | 04:59:46 INFO - PROCESS | 1661 | 1461153586838 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] 05:01:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 05:01:58 INFO - PROCESS | 1661 | RSS: Main: 365301760 05:01:58 INFO - PROCESS | 1661 | 05:05:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 05:05:02 INFO - PROCESS | 1661 | RSS: Main: 379977728 05:05:02 INFO - PROCESS | 1661 | 05:08:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 05:08:04 INFO - PROCESS | 1661 | RSS: Main: 379777024 05:08:04 INFO - PROCESS | 1661 | 05:11:07 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 05:11:07 INFO - PROCESS | 1661 | RSS: Main: 377442304 05:11:07 INFO - PROCESS | 1661 | 05:14:11 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 05:14:11 INFO - PROCESS | 1661 | RSS: Main: 341110784 05:14:11 INFO - PROCESS | 1661 | 05:17:14 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 05:17:15 INFO - PROCESS | 1661 | RSS: Main: 454123520 05:17:15 INFO - PROCESS | 1661 | 05:17:15 INFO - PROCESS | 1661 | __start_tp_report 05:17:15 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 05:17:15 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 05:17:15 INFO - PROCESS | 1661 | |i|pagename|runs| 05:17:15 INFO - PROCESS | 1661 | |0;dojo.html;2792.3019245188707;3423.263998240378;3449.3101379724044;3451.30973805239;3475.322312149132;5539.778408863641;5631.1271752878265;5676.971615141923;5687.260656114704;5693.117566777151;5628.887422251552;5685.1756495308155;5696.316442026952;5712.371639119704;5717.942180696574;5158.43257241704;5187.584993200554;5204.115300398941;5213.765380557865;5282.709450980195;5271.288376069239;5295.655782374152;5297.17893726473;5304.893902121955;5311.362636483627;5218.739063046833;5287.048331300364;5296.2055691646365;5298.993191293661;5341.786328546854;5267.130923397634;5286.97356513215;5296.629235953485;5306.548943339821;5325.627206095575;4216.873493795191;4696.178168820443;4824.975875120602;5188.377394712658;5241.606879484016;5518.586106042063;5528.336599608033;5543.279373681436;5555.055640541077;5635.5773317001385;6197.721102550396;6203.844903877394;6213.720382582795;6227.968860155715;6231.252249730016;6136.631802091889;6182.629042257479;6198.659073750942;6248.1564988726395;6306.905396419057;4826.1795494765975;5785.913211301834;5810.912836307459;5824.242848429677;5843.4448727371255;6230.190075290183;6237.688115594202;6248.9062664060075;6254.405831446005;6257.155284036597;5979.940200597938;6011.669358185254;6061.818145455643;6093.021863430864;6170.938290617126;6267.059941008885;6274.403931626488;6279.7488100476385;6292.150559674477;6296.992481203077;6215.906761398583;6241.594296370721;6242.65665388408;6249.500039996789;6269.937300626935;5142.794288228429;6102.701334035014;6205.47253483459;6208.751649934043;6270.216222972129;4866.635002374836;4882.7558622068755;4891.437484689235;4899.4365647950945;4901.681390709664;4370.934435983463;4402.669799765031;4413.889652758642;4481.35020422037;4490.191765482245;5594.104943209149;5630.183623374588;5642.351129620064;5657.264555607745;5664.318581776628;5604.215409842625;6146.7706458708435;6275.623462592259;6290.056491526216;6295.118683384329;5353.1167357386375;5496.285482887199;5610.07433773431;5631.211630371751;5644.181593668895;4619.422572178478;5337.86655333612;5348.598855085884;5354.303940487712;5415.53967912724;6118.741008805671;6233.657148856856;6244;6259.96870015647;6276.340984196594;6293.716782744696;6297.960836461933;6312.4634406074165;6325.177726895658;6332.366763323815;5966.925953328442;6228.532860035606;6293.465055470337;6294.8111556653375;6298.779542715983;6061.969690151521;6116.327204007466;6136.028952951454;6169.475594574512;6206.906896396467;6005.321926835975;6182.629042257479;6199.1011303361665;6209.161763161916;6217.595856269328;6007.939920600913;6017.217761690952;6020.698965051817;6027.6986150693165;6070.696465176812;5922.1412895130825;5964.224650795369;6043.697815109315;6125.234345706786;6134.35589263121;5946.256717910262;5946.613470124428;5960.403959604005;5971.492423143907;5975.940240597713;6129.448349648553;6131.981316619475;6133.294671112879;6149.600275982047;6155.199824022849;5810.6513609183585;5949.672767997802;5952.5833191676165;6085.604435711664;6099.9390006099375 05:17:15 INFO - PROCESS | 1661 | |1;ext.html;498.9073928097466;504.7324917793571;516.2926790297291;580.477570186832;585.4584509328871;29565.55651665224;30480.23799405013;37508.812455937754;38748;38852;40855.38716919249;41410.37884431728;41435.585644143546;41448.37827432591;41487.58512414875;9257.629694812216;9495.477748723817;9507.23942084635;9512.952435237814;9526.142647161754;8454.788630284262;8755.693550725695;11269.887301127006;11582.536698532049;11591.6522504325;11104.61133860315;11165;11226.101911847074;11247.268927519726;11308.886911130907;8466.66133354665;11141.610043648474;11227.101831853513;11244.606438774685;11261.493232804543;11079.612213572567;11258.155638327076;11268.887311126866;11268.985791278743;11274.830877536844;31238.37523249545;37433.62566374356;37971.8101409494;37992.050198744975;38095.61904380976;3189.2345836999184;3240.125166205135;3247.8538465769097;3279.3441311737747;3417.3507033663523;23596.528069438686;27674;28503.85748071267;28616;28668.713312866814;2662.4142688608476;2709.7425744554234;2710.7500774357236;2735.644366232397;2800.075974928269;336552;443717;443848;444743;444987;34357.312853743286;59178.40821591729;59475;59505.70247148823;59580.40419595748;35057.82471087628;35121.47317790235;35215;35277.82361088178;35287;12092;14749.557513274618;14749.631259218391;14756.262186890612;14775.778363324558;11474.254173478865;11834.467448964817;11847.763044739057;12245.448954796877;12546.937265313614;7589.772306830804;7704.036995375578;7955;8201.179882011751;8204.8359032819;3300.1914530940076;3374.122728090665;3375.493675948578;3405.438102713074;3406.7785593936314;981.3523074770617;997.5162046407487;1019.7297716105238;1046.4296958157822;1149.8788681035924;28464.573031404547;28742.856285718855;28993.75889631037;29366;29482.410351792845;1093.8195197792274;1117.446863802423;1144.3820337017917;1153.3253046967618;1155.763068570941;15349.539513814603;15409.383624655113;15546.300416481285;15779.763303550455;15813.68372632541;599815;716978;733920;735536;737276;59622.403775961684;60432.69783651141;61314;61341;61452.69273653603;1355.539116700307;1393.1501783911604;1503.5414198669512;1511.7883496310526;1526.1834918318868;25089.74910250874;28735.27588344706;29921;30300;31686.68313316837;56419.71790141023;57509.712451437474;57542.7122864383;57619.423805761406;57849.421505784405;572633;576701;576901.1154944198;577546;578013;23007.53984920259;27039.86480067587;27274.863625681744;27906.720932791224;27926.882924682785;32367.35265294728;38649;38989;44892;46199.76900115478;29532.409351812414;34398.65601343955;35290.82354588211;35443.291134177685;35943.74196903096;34832;35023.124421888635;35235.64764352426;35892.102697432776;37297;35925.46111808273;36138.8193059033;36252.456213157355;36647;36961.26077478382;31774.841125794224;32094.67905320917;32291;32531.794912813835;32662.34675306528;21793.56412871702;23582.764172358056;23908.163214287415;24129.793510324063;24533.87733061323 05:17:15 INFO - PROCESS | 1661 | |2;jquery.html;396.54793535369674;398.35267690003724;399.64232012348947;405.08046733914034;406.5568530301972;20278;20390.89804550975;21112.0499577519;21124;21510.784892151074;19802.405927822187;20035.298764543244;21032.474188145265;21332.4666883328;21345.466363340925;5235.685858848462;5709.914351284744;6001.309849367313;6037.6075555089;6041.365656606059;5694.6867922264255;5970.0746384310505;5977.402259774031;6030.125631783389;6040.335563088057;5869.148973398855;5905.114232865083;5908.468237858592;5957.553183511232;5959.731812068446;5582.888342233153;5907.202527658754;5915.556333275;5940.9108863366655;5943.04911214208;5173.379194496684;5200.271961925334;5201.8959620807755;5205.557527610158;5215.947840521584;23407.06371745128;26075.478490430276;26633;26638.600420993505;26754;1853.721941708741;2173.945651358713;2175.869447833135;2200.6919031335624;2349.048635302705;14855.960082794209;14890.404383824634;15048.774268385983;15121.773173402407;15405.460808871636;1544.2510382464495;1715.4596302164778;1781.26967943144;1803.3147403986532;1805.6117934643983;111703;115495;116033.4198329003;116841.6631667362;118688.21967670492;10591.682249532527;10832.241743078066;11084.61203857861;11175;11403.315801051964;14217.64455888611;14237;14256.215908124945;14265.001449898615;14277.42890284377;8384.413091083565;8536.018357888923;8549.615267312985;8649.135086491426;8662.78343041429;8443.704470343508;8460.280876125475;8480.406371554056;8581.098984606651;8583.914160858436;6536.901946470807;6636.900446493307;6658.4673226141795;6663.200415950117;6678.165229346332;1623.0910690013395;1702.2935481775173;1857.7120546315343;1882.4634979031184;1886.3114963038392;894.2667013049237;894.9910500895038;953.9236861051099;1001.5643195210107;1063.542676649033;19375.612487750164;20819.805194805387;21030.369088927357;21112.261070862754;21423.035963381684;988.186677446893;1002.3384566186282;1009.152312057875;1010.3937637417491;1014.1633152648947;10793.62222322215;11244.931731485496;11340.829887551694;11366.943165284287;11373.976342129248;532746;539409;547507;550640;551176.2205944803;105504.47247763711;109996.90003100026;110443.89556104496;112372;112847;1037.5175543372368;1037.6458721368535;1083.4203701019917;1084.4577711144427;1086.131095123882;15203.923980380026;18569;18618.906905465385;18735.71896421582;18798.3420580279;48341.75829120973;49099.509004909494;49390;49545.76135596639;50114.498855010985;47913;58923.70538147282;61837.07244391428;67376.98934516084;68444;7757.883631745415;7869.134395216407;7887.64505597238;7907.8813817793925;8043.19568043191;11443.198976071928;12229.510819567118;12345.604958636446;12512.29706718063;12600.306983116017;10736.785264294827;10762.40806755636;10767.676969690921;10888.237823352289;10936.830461688807;10492.422916739653;10538.578456861946;10624.609462871102;10823.567057317934;11125.998660120627;9643.27675424332;10331.690049298533;11301.43492825339;11329.490172942402;11756.294622322688;6824.317568243136;8089.23152300554;8218.589070546568;8221.136780638186;8233.83532329338;8325.375984721766;11068.28056176346;11123.666290011313;11208.607698730506;11361.772764544829 05:17:15 INFO - PROCESS | 1661 | |3;mootools.html;1437.8418373978855;1624.7887774589306;1656.1967445788794;1914.9617007659838;2031.451508092815;8060.113387527367;8759.21167094962;8898.866517002241;8902.777430564223;8922.464652120862;8530.104339044388;8760;8771.91228087719;8771.956140219307;8791.516466594334;2149.344449942767;2374.7269064057596;2422.491276831863;2453.950920981579;2459.692538432696;2468.06213638817;2496.1388321029217;2497.300755788382;2498.962515562268;2507.623856421533;2059.217497351012;2410.156445244168;2421.4309637235237;2423.1397853761923;2424.684790977179;2090.9268175613856;2112.9260475883348;2277.703898493202;2353.3998830298337;2354.693889794333;2453.362125847275;2457.8156638252203;2462.1382516119215;2466.962995555068;2478.8264821462503;8719.258862996652;8792.868106978336;8801.119888011212;8805.823883522293;8843.60203790831;5184.507471790174;5399.892002159974;5402.081646120169;5405.783768649249;5411.945880541183;2916.9270768230963;3381.3237352529345;3557.6620221078956;3586.820658967067;3589.9589119155676;1037.2894567221476;1039.4283144270696;1043.087298613713;1051.4742628685658;1053.525913338993;11660.941695291554;11701.70745731355;11723.76552468946;11731.354775487345;11735.4132293385;4040.3535434330365;4705.623550115983;4758.578581023324;4916.3362946001835;4935.432425271068;9841.50792460374;9939.701808945743;9948.104670579538;9955.55200016001;9957.701268961942;5410.513053825174;5421.430749771296;5422.7017514036315;5434.809781657623;5456.61803673739;4004.979975100106;4404.581564751344;4552.1806074906835;4906.58294045003;4946.233333833262;3522.9295414091575;3975.264576053439;4007.3788562772816;4118.155778065489;4128.339465685476;1162.7325715085578;1345.2668295778824;1361.3737680667004;1374.2853716067589;1375.3112664740408;824.8968878890139;875.6497401039508;891.9241864441595;923.3951761596165;929.5770424456856;5080.74596270185;5519.69641669712;5674.914876276859;5722;5732.684702341411;452.2447512653876;464.97907594158346;467.49000727649883;470.74344482257254;474.20570544338096;3286.3263031078573;3314.254292784104;3773.132179598708;3786.905327366783;3862.6716729078344;10392.37645741258;10782.838257426145;10795.136389089012;10921.071708904832;10923.672289831318;10004.399736015717;10779.946100269448;10822.404767737693;10876.238663293652;10881.455927203608;8040.959795200986;8053.436259461781;8083.959580202179;8118.634661440249;8191.631376587949;5593.580481463825;5721.628094173865;5805.187273781589;5832;5943.138244954544;9818.410895346302;9839.557219925264;9866.506674666382;9963.900360996297;9982.35114717541;9431.669891553762;9649.925266067828;10051.396916185053;10076.798464030824;10204.336718113298;5811;6867.07294515234;7114.288571142844;7357.77926662201;7524.435667324863;8230.547319897223;8244.258016778518;8319;8335.91633087719;8359.749207523784;7167.390771784457;7316.304951029643;7340.265973402617;7442.404607631268;7512.474126811071;7107.147142342952;7183.20984691694;7250.3112204342615;7278.454115941431;7296.744613938699;6867.416269616939;6947.4789390796905;6952.339527744856;6976.162860456778;6982;4510.548945105595;4679.4557795926585;4793.664443489062;4863.416390033077;5048.217526283432;4818.156822556109;4858.1012512685265;4860.659753817199;4866.853994380174;5076.806950366735 05:17:15 INFO - PROCESS | 1661 | |4;prototype.html;289.43271188470595;313.7505682982029;317.34626669061737;320.5992509363296;323.16461945869935;4865.0053478074;5797.884042319156;5880.705964701764;5892.616979896315;5906.763729450817;5754;5812.941870581293;5816.621919575225;5854.648721076738;5857.707114644272;4220.7256528325715;4268.231718290706;4402.3836662867225;4483.34991426243;4497.572730590605;4328.740275583459;4376.584224498668;4409.426774519324;4445.110977804436;4496.280595104784;4439.400680908068;4459.955400446002;4462.8884277892985;4464.928417179886;4497.977510112445;3652.926941461156;4142.772147531885;4159.126583417471;4453.755043472608;4492.3710680504755;3231.87072517099;3779.414190800403;3841.7118716096124;3842.865499707497;3849.788261645608;5606.579506537026;5623.738231825531;5625.409332020161;5656.971715141398;5688.203651488795;4614.307853821953;4918.901621967577;4987.5511203991455;5084.542391184811;5130.4356520782685;6087.421694938974;6107.633541987495;6141.692915354259;6148.723307451175;6152.661988444407;4092.897677558055;4223;4245.172191422674;4258.68059895509;4266.701330906838;5527.143292789624;6457.580257283262;6490.156279683611;6503.447206987412;6511.088447617338;3658.829174664082;3991.0887330415776;4101.938470922939;4302.612764851178;4329.675274354436;3885.592012838668;4049.331860243086;4359.346098085312;4368.257396242583;4380.123975204908;4681.127548980367;4684.945887175427;4788.688735232199;4987.526185012418;4998.825041123543;6271.811845644638;6296.811095667137;6306.779262725782;6337.261016000582;6337.524685648595;1255.9058070644737;1378.9034767566175;1462.1154201708025;1477.0399240493764;1478.6894752101964;792.3898598079446;895.2793001633747;925.1858364639074;952.1906379577304;990.7424069741775;4885.5847252983895;5667.3482549507335;5867.559933005042;5883.646981181143;5890.440408161304;5380.91928621071;5399.649022813504;6485.967570162214;6492.772752953719;6526.967365163238;5132.871678208;5647.519960803378;5813.302403711497;5873.676947767828;5882.735276912464;3380.8816691416173;3394.3381040696827;3395.592528896548;3428.3314753623304;3430.559165150643;5023.844515761395;5687.516561092353;6685.022411665052;6745.143496682381;6758.493113016543;5636.802711905146;6537.5750576212395;6737.056812046318;6759.797206083826;6777.1189745332795;5500.964643082085;5737.995850726106;6630.53586248958;6787.1176747023555;6807.863842723118;5435.016219080087;6392.840178995562;6546.639934803631;6558.573327868719;6560.376764207393;5136.768845402041;6334;6439.259485159079;6451.58065725353;6486.740530378732;5669.461401166883;6443.516736244894;6794.660266986532;6798.688445928408;6801.693923773343;3905.6484916357663;4336.002719374625;4381.890452738707;4412.602865742098;4424.305704736581;5395.3525576931015;5607.495325420732;6312.295680166421;6456.159289912423;6464.385883341075;5821.417858214145;5826.563007774349;6192.504599631929;6211.65835879031;6291.905621415591;6239.906401403891;6240.689455214456;6262.279837818709;6274.1843560336865;6302.369763023844;5152.510511501401;5822.94177058224;6037.486813620892;6149.938500615115;6167.352427994996;5560.360558535658;6151.200343955257;6262.401103542161;6306.369363063657;6325.7153428094925;5158;5370.006548788487;6156.692165391623;6173.753049877954;6202.41077097675 05:17:15 INFO - PROCESS | 1661 | |5;yui.html;721.8592374486977;733.4425836364363;735.5219107580076;738.915024772151;799.5043073294556;14611.123332600038;15023.624409389771;15029.473968411108;15033.398664053439;15060.623484412868;13879.722405551907;13966.231857247847;13974;13988.58034258974;14012.579622611338;716.5664772812452;736.0651971995562;866.51908190954;881.8060026794111;905.6830109461669;866.8439680857443;881.5327876225579;892.0232345581578;895.6506962284714;899.8470260055774;836.481381543444;938.0338251600862;965.420747551467;967.1295833749647;968.8934217236099;752.2966026764992;931.3340961212732;932.5197523275518;942.9575669094908;945.3099237556553;699.5348093517805;700.3976580141075;702.2731472925498;706.9540479868792;713.6752777486231;14854.405823767145;14870.405183792634;14952.551423457313;14963.551093467213;14984.925075374662;12798.040146989013;12833.614991550268;13004.804927926089;13056.738865222642;13077.149985250928;9314.487703176323;9487.857682134774;10140.391576505359;10526.157907367393;10686.51910664022;3693.3351996640595;4056.1482088761395;4152.709310348277;4211.473565804275;4340.13197360526;83859.16140838635;85137;85172.72240916391;85413.57293213494;85586.57206713989;8114.513129212266;9305.674301399418;9522.476263805418;9553.808923821485;9809.509524523744;20966.685499717518;21395.67906481404;21423.250186243407;21485.03317350723;21501;7349.191588925214;7414.6292685365515;7476.401887848959;7573.962130189424;7787.415943804237;3998.080441498472;4083.14254006656;4099.61053699898;4108.589141085928;4384.254676704904;2921.7662586992988;2983.895563655262;3594.8202589870402;3601.0637234319265;3617.2403795202777;652.4845372155944;659.8584448903329;661.9371159739817;663.1147418196762;675.9826461175899;474.7768548782136;483.98306059287756;553.2089112568984;592.1059200607115;592.6473748119862;7566.35685966699;7674.194209608022;7712.5758083305955;7925.603719813987;7976.880346794803;547.0720228757559;547.4744245524262;578.3002566894021;580.8228490310496;582.7727186397309;3727.794971276606;3737.6449237322413;3765.4916586260506;3771.151490914522;3797.7911214882893;75493.62253188809;75537.6223118892;76744.61627691826;78603;79056;65087;66026;66736.66526669536;66885.662286754;68020;57394.7130264346;58568.7071564648;58692.11961820576;58925;59381;7306.890396644161;7317.304856038668;7397.630118493946;7574.848503030019;7707.730229441942;39057;40053;40124.59563915249;40334.43045434752;41093.383599246634;52044.69888252824;52192.73903630609;52299.73850130725;54705.45294547004;55049.34851954287;9581.664641737505;10636.023251072666;10690.091342235686;10983.340999540053;11868;10494.632687855888;12231;12289.815652765394;12389.705517453589;12715.80926286125;10046.949765251127;11188.328700278009;11255.718607034889;11306.756256811908;11725.824112638487;11189;11294.435278235891;11391;11618.46854267442;12301;11009.009189173014;11167.38579378142;11188.66434006981;11460.829949489815;11487.712807179887;9224.308176886627;9436.103570160823;9462.858057129011;9505.477198754135;10553.102986246255;7325;8320.268312528538;8818.162274583905;9084.863727044232;9694.660686876208 05:17:15 INFO - PROCESS | 1661 | __end_tp_report 05:17:15 INFO - PROCESS | 1661 | __start_cc_report 05:17:15 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1164 05:17:15 INFO - PROCESS | 1661 | __end_cc_report 05:17:15 INFO - PROCESS | 1661 | __startTimestamp1461154635108__endTimestamp 05:17:15 INFO - PROCESS | 1661 | 05:17:15 INFO - PROCESS | 1661 | ------- Summary: start ------- 05:17:15 INFO - PROCESS | 1661 | Number of tests: 6 05:17:15 INFO - PROCESS | 1661 | 05:17:15 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5698.37 Median:5952.58 stddev:659.04 (11.1%) stddev-sans-first:620.38 05:17:15 INFO - PROCESS | 1661 | Values: 2792.3 3423.3 3449.3 3451.3 3475.3 5539.8 5631.1 5677.0 5687.3 5693.1 5628.9 5685.2 5696.3 5712.4 5717.9 5158.4 5187.6 5204.1 5213.8 5282.7 5271.3 5295.7 5297.2 5304.9 5311.4 5218.7 5287.0 5296.2 5299.0 5341.8 5267.1 5287.0 5296.6 5306.5 5325.6 4216.9 4696.2 4825.0 5188.4 5241.6 5518.6 5528.3 5543.3 5555.1 5635.6 6197.7 6203.8 6213.7 6228.0 6231.3 6136.6 6182.6 6198.7 6248.2 6306.9 4826.2 5785.9 5810.9 5824.2 5843.4 6230.2 6237.7 6248.9 6254.4 6257.2 5979.9 6011.7 6061.8 6093.0 6170.9 6267.1 6274.4 6279.7 6292.2 6297.0 6215.9 6241.6 6242.7 6249.5 6269.9 5142.8 6102.7 6205.5 6208.8 6270.2 4866.6 4882.8 4891.4 4899.4 4901.7 4370.9 4402.7 4413.9 4481.4 4490.2 5594.1 5630.2 5642.4 5657.3 5664.3 5604.2 6146.8 6275.6 6290.1 6295.1 5353.1 5496.3 5610.1 5631.2 5644.2 4619.4 5337.9 5348.6 5354.3 5415.5 6118.7 6233.7 6244.0 6260.0 6276.3 6293.7 6298.0 6312.5 6325.2 6332.4 5966.9 6228.5 6293.5 6294.8 6298.8 6062.0 6116.3 6136.0 6169.5 6206.9 6005.3 6182.6 6199.1 6209.2 6217.6 6007.9 6017.2 6020.7 6027.7 6070.7 5922.1 5964.2 6043.7 6125.2 6134.4 5946.3 5946.6 5960.4 5971.5 5975.9 6129.4 6132.0 6133.3 6149.6 6155.2 5810.7 5949.7 5952.6 6085.6 6099.9 05:17:15 INFO - PROCESS | 1661 | 05:17:15 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:68199.30 Median:169963.06 stddev:156048.54 (91.8%) stddev-sans-first:156403.51 05:17:15 INFO - PROCESS | 1661 | Values: 498.9 504.7 516.3 580.5 585.5 29565.6 30480.2 37508.8 38748.0 38852.0 40855.4 41410.4 41435.6 41448.4 41487.6 9257.6 9495.5 9507.2 9513.0 9526.1 8454.8 8755.7 11269.9 11582.5 11591.7 11104.6 11165.0 11226.1 11247.3 11308.9 8466.7 11141.6 11227.1 11244.6 11261.5 11079.6 11258.2 11268.9 11269.0 11274.8 31238.4 37433.6 37971.8 37992.1 38095.6 3189.2 3240.1 3247.9 3279.3 3417.4 23596.5 27674.0 28503.9 28616.0 28668.7 2662.4 2709.7 2710.8 2735.6 2800.1 336552.0 443717.0 443848.0 444743.0 444987.0 34357.3 59178.4 59475.0 59505.7 59580.4 35057.8 35121.5 35215.0 35277.8 35287.0 12092.0 14749.6 14749.6 14756.3 14775.8 11474.3 11834.5 11847.8 12245.4 12546.9 7589.8 7704.0 7955.0 8201.2 8204.8 3300.2 3374.1 3375.5 3405.4 3406.8 981.4 997.5 1019.7 1046.4 1149.9 28464.6 28742.9 28993.8 29366.0 29482.4 1093.8 1117.4 1144.4 1153.3 1155.8 15349.5 15409.4 15546.3 15779.8 15813.7 599815.0 716978.0 733920.0 735536.0 737276.0 59622.4 60432.7 61314.0 61341.0 61452.7 1355.5 1393.2 1503.5 1511.8 1526.2 25089.7 28735.3 29921.0 30300.0 31686.7 56419.7 57509.7 57542.7 57619.4 57849.4 572633.0 576701.0 576901.1 577546.0 578013.0 23007.5 27039.9 27274.9 27906.7 27926.9 32367.4 38649.0 38989.0 44892.0 46199.8 29532.4 34398.7 35290.8 35443.3 35943.7 34832.0 35023.1 35235.6 35892.1 37297.0 35925.5 36138.8 36252.5 36647.0 36961.3 31774.8 32094.7 32291.0 32531.8 32662.3 21793.6 23582.8 23908.2 24129.8 24533.9 05:17:15 INFO - PROCESS | 1661 | 05:17:15 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:32523.98 Median:21228.23 stddev:90631.68 (426.9%) stddev-sans-first:90853.82 05:17:15 INFO - PROCESS | 1661 | Values: 396.5 398.4 399.6 405.1 406.6 20278.0 20390.9 21112.0 21124.0 21510.8 19802.4 20035.3 21032.5 21332.5 21345.5 5235.7 5709.9 6001.3 6037.6 6041.4 5694.7 5970.1 5977.4 6030.1 6040.3 5869.1 5905.1 5908.5 5957.6 5959.7 5582.9 5907.2 5915.6 5940.9 5943.0 5173.4 5200.3 5201.9 5205.6 5215.9 23407.1 26075.5 26633.0 26638.6 26754.0 1853.7 2173.9 2175.9 2200.7 2349.0 14856.0 14890.4 15048.8 15121.8 15405.5 1544.3 1715.5 1781.3 1803.3 1805.6 111703.0 115495.0 116033.4 116841.7 118688.2 10591.7 10832.2 11084.6 11175.0 11403.3 14217.6 14237.0 14256.2 14265.0 14277.4 8384.4 8536.0 8549.6 8649.1 8662.8 8443.7 8460.3 8480.4 8581.1 8583.9 6536.9 6636.9 6658.5 6663.2 6678.2 1623.1 1702.3 1857.7 1882.5 1886.3 894.3 895.0 953.9 1001.6 1063.5 19375.6 20819.8 21030.4 21112.3 21423.0 988.2 1002.3 1009.2 1010.4 1014.2 10793.6 11244.9 11340.8 11366.9 11374.0 532746.0 539409.0 547507.0 550640.0 551176.2 105504.5 109996.9 110443.9 112372.0 112847.0 1037.5 1037.6 1083.4 1084.5 1086.1 15203.9 18569.0 18618.9 18735.7 18798.3 48341.8 49099.5 49390.0 49545.8 50114.5 47913.0 58923.7 61837.1 67377.0 68444.0 7757.9 7869.1 7887.6 7907.9 8043.2 11443.2 12229.5 12345.6 12512.3 12600.3 10736.8 10762.4 10767.7 10888.2 10936.8 10492.4 10538.6 10624.6 10823.6 11126.0 9643.3 10331.7 11301.4 11329.5 11756.3 6824.3 8089.2 8218.6 8221.1 8233.8 8325.4 11068.3 11123.7 11208.6 11361.8 05:17:15 INFO - PROCESS | 1661 | 05:17:15 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5646.97 Median:4865.14 stddev:3206.44 (65.9%) stddev-sans-first:3199.83 05:17:15 INFO - PROCESS | 1661 | Values: 1437.8 1624.8 1656.2 1915.0 2031.5 8060.1 8759.2 8898.9 8902.8 8922.5 8530.1 8760.0 8771.9 8772.0 8791.5 2149.3 2374.7 2422.5 2454.0 2459.7 2468.1 2496.1 2497.3 2499.0 2507.6 2059.2 2410.2 2421.4 2423.1 2424.7 2090.9 2112.9 2277.7 2353.4 2354.7 2453.4 2457.8 2462.1 2467.0 2478.8 8719.3 8792.9 8801.1 8805.8 8843.6 5184.5 5399.9 5402.1 5405.8 5411.9 2916.9 3381.3 3557.7 3586.8 3590.0 1037.3 1039.4 1043.1 1051.5 1053.5 11660.9 11701.7 11723.8 11731.4 11735.4 4040.4 4705.6 4758.6 4916.3 4935.4 9841.5 9939.7 9948.1 9955.6 9957.7 5410.5 5421.4 5422.7 5434.8 5456.6 4005.0 4404.6 4552.2 4906.6 4946.2 3522.9 3975.3 4007.4 4118.2 4128.3 1162.7 1345.3 1361.4 1374.3 1375.3 824.9 875.6 891.9 923.4 929.6 5080.7 5519.7 5674.9 5722.0 5732.7 452.2 465.0 467.5 470.7 474.2 3286.3 3314.3 3773.1 3786.9 3862.7 10392.4 10782.8 10795.1 10921.1 10923.7 10004.4 10779.9 10822.4 10876.2 10881.5 8041.0 8053.4 8084.0 8118.6 8191.6 5593.6 5721.6 5805.2 5832.0 5943.1 9818.4 9839.6 9866.5 9963.9 9982.4 9431.7 9649.9 10051.4 10076.8 10204.3 5811.0 6867.1 7114.3 7357.8 7524.4 8230.5 8244.3 8319.0 8335.9 8359.7 7167.4 7316.3 7340.3 7442.4 7512.5 7107.1 7183.2 7250.3 7278.5 7296.7 6867.4 6947.5 6952.3 6976.2 6982.0 4510.5 4679.5 4793.7 4863.4 5048.2 4818.2 4858.1 4860.7 4866.9 5076.8 05:17:15 INFO - PROCESS | 1661 | 05:17:15 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:5030.34 Median:5652.25 stddev:1575.64 (27.9%) stddev-sans-first:1539.36 05:17:15 INFO - PROCESS | 1661 | Values: 289.4 313.8 317.3 320.6 323.2 4865.0 5797.9 5880.7 5892.6 5906.8 5754.0 5812.9 5816.6 5854.6 5857.7 4220.7 4268.2 4402.4 4483.3 4497.6 4328.7 4376.6 4409.4 4445.1 4496.3 4439.4 4460.0 4462.9 4464.9 4498.0 3652.9 4142.8 4159.1 4453.8 4492.4 3231.9 3779.4 3841.7 3842.9 3849.8 5606.6 5623.7 5625.4 5657.0 5688.2 4614.3 4918.9 4987.6 5084.5 5130.4 6087.4 6107.6 6141.7 6148.7 6152.7 4092.9 4223.0 4245.2 4258.7 4266.7 5527.1 6457.6 6490.2 6503.4 6511.1 3658.8 3991.1 4101.9 4302.6 4329.7 3885.6 4049.3 4359.3 4368.3 4380.1 4681.1 4684.9 4788.7 4987.5 4998.8 6271.8 6296.8 6306.8 6337.3 6337.5 1255.9 1378.9 1462.1 1477.0 1478.7 792.4 895.3 925.2 952.2 990.7 4885.6 5667.3 5867.6 5883.6 5890.4 5380.9 5399.6 6486.0 6492.8 6527.0 5132.9 5647.5 5813.3 5873.7 5882.7 3380.9 3394.3 3395.6 3428.3 3430.6 5023.8 5687.5 6685.0 6745.1 6758.5 5636.8 6537.6 6737.1 6759.8 6777.1 5501.0 5738.0 6630.5 6787.1 6807.9 5435.0 6392.8 6546.6 6558.6 6560.4 5136.8 6334.0 6439.3 6451.6 6486.7 5669.5 6443.5 6794.7 6798.7 6801.7 3905.6 4336.0 4381.9 4412.6 4424.3 5395.4 5607.5 6312.3 6456.2 6464.4 5821.4 5826.6 6192.5 6211.7 6291.9 6239.9 6240.7 6262.3 6274.2 6302.4 5152.5 5822.9 6037.5 6149.9 6167.4 5560.4 6151.2 6262.4 6306.4 6325.7 5158.0 5370.0 6156.7 6173.8 6202.4 05:17:15 INFO - PROCESS | 1661 | 05:17:15 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:16609.16 Median:581.80 stddev:22461.05 (3860.6%) stddev-sans-first:22492.38 05:17:15 INFO - PROCESS | 1661 | Values: 721.9 733.4 735.5 738.9 799.5 14611.1 15023.6 15029.5 15033.4 15060.6 13879.7 13966.2 13974.0 13988.6 14012.6 716.6 736.1 866.5 881.8 905.7 866.8 881.5 892.0 895.7 899.8 836.5 938.0 965.4 967.1 968.9 752.3 931.3 932.5 943.0 945.3 699.5 700.4 702.3 707.0 713.7 14854.4 14870.4 14952.6 14963.6 14984.9 12798.0 12833.6 13004.8 13056.7 13077.1 9314.5 9487.9 10140.4 10526.2 10686.5 3693.3 4056.1 4152.7 4211.5 4340.1 83859.2 85137.0 85172.7 85413.6 85586.6 8114.5 9305.7 9522.5 9553.8 9809.5 20966.7 21395.7 21423.3 21485.0 21501.0 7349.2 7414.6 7476.4 7574.0 7787.4 3998.1 4083.1 4099.6 4108.6 4384.3 2921.8 2983.9 3594.8 3601.1 3617.2 652.5 659.9 661.9 663.1 676.0 474.8 484.0 553.2 592.1 592.6 7566.4 7674.2 7712.6 7925.6 7976.9 547.1 547.5 578.3 580.8 582.8 3727.8 3737.6 3765.5 3771.2 3797.8 75493.6 75537.6 76744.6 78603.0 79056.0 65087.0 66026.0 66736.7 66885.7 68020.0 57394.7 58568.7 58692.1 58925.0 59381.0 7306.9 7317.3 7397.6 7574.8 7707.7 39057.0 40053.0 40124.6 40334.4 41093.4 52044.7 52192.7 52299.7 54705.5 55049.3 9581.7 10636.0 10690.1 10983.3 11868.0 10494.6 12231.0 12289.8 12389.7 12715.8 10046.9 11188.3 11255.7 11306.8 11725.8 11189.0 11294.4 11391.0 11618.5 12301.0 11009.0 11167.4 11188.7 11460.8 11487.7 9224.3 9436.1 9462.9 9505.5 10553.1 7325.0 8320.3 8818.2 9084.9 9694.7 05:17:15 INFO - PROCESS | 1661 | -------- Summary: end -------- 05:17:15 INFO - PROCESS | 1661 | 05:17:15 INFO - TEST-INFO | 1661: exit 0 05:17:15 INFO - TEST-OK | dromaeo_css | took 1115855ms 05:17:15 INFO - TEST-START | kraken 05:17:15 INFO - operating with platform_type : mac_ 05:17:15 INFO - Initialising browser for kraken test... 05:17:15 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpmr31lL/profile http://localhost:49270/getInfo.html) 05:17:17 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 05:17:17 INFO - PROCESS | 1668 | colorDepth:24 05:17:17 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 05:17:17 INFO - PROCESS | 1668 | __metrics 05:17:17 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:17:17 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:17:18 INFO - TEST-INFO | 1668: exit 0 05:17:18 INFO - Browser initialized. 05:17:18 INFO - Running cycle 1/1 for kraken test... 05:17:18 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpmr31lL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:17:30 INFO - PROCESS | 1670 | RSS: Main: 237887488 05:17:30 INFO - PROCESS | 1670 | 05:17:33 INFO - PROCESS | 1670 | 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) 05:17:34 INFO - PROCESS | 1670 | RSS: Main: 285728768 05:17:34 INFO - PROCESS | 1670 | 05:17:36 INFO - PROCESS | 1670 | 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) 05:17:37 INFO - PROCESS | 1670 | RSS: Main: 299216896 05:17:37 INFO - PROCESS | 1670 | 05:17:40 INFO - PROCESS | 1670 | 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) 05:17:41 INFO - PROCESS | 1670 | RSS: Main: 384286720 05:17:41 INFO - PROCESS | 1670 | 05:17:43 INFO - PROCESS | 1670 | 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) 05:17:44 INFO - PROCESS | 1670 | RSS: Main: 285839360 05:17:44 INFO - PROCESS | 1670 | 05:17:46 INFO - PROCESS | 1670 | 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) 05:17:47 INFO - PROCESS | 1670 | RSS: Main: 290869248 05:17:47 INFO - PROCESS | 1670 | 05:17:50 INFO - PROCESS | 1670 | 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) 05:17:50 INFO - PROCESS | 1670 | RSS: Main: 329023488 05:17:50 INFO - PROCESS | 1670 | 05:17:53 INFO - PROCESS | 1670 | 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) 05:17:54 INFO - PROCESS | 1670 | RSS: Main: 330481664 05:17:54 INFO - PROCESS | 1670 | 05:17:56 INFO - PROCESS | 1670 | 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) 05:17:57 INFO - PROCESS | 1670 | RSS: Main: 326418432 05:17:57 INFO - PROCESS | 1670 | 05:17:58 INFO - PROCESS | 1670 | 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) 05:17:59 INFO - PROCESS | 1670 | RSS: Main: 319848448 05:17:59 INFO - PROCESS | 1670 | 05:18:01 INFO - PROCESS | 1670 | 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) 05:18:02 INFO - PROCESS | 1670 | RSS: Main: 326639616 05:18:02 INFO - PROCESS | 1670 | 05:18:04 INFO - PROCESS | 1670 | 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) 05:18:04 INFO - PROCESS | 1670 | RSS: Main: 329420800 05:18:04 INFO - PROCESS | 1670 | 05:18:07 INFO - PROCESS | 1670 | 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) 05:18:08 INFO - PROCESS | 1670 | RSS: Main: 334213120 05:18:08 INFO - PROCESS | 1670 | 05:18:11 INFO - PROCESS | 1670 | 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) 05:18:12 INFO - PROCESS | 1670 | RSS: Main: 331800576 05:18:12 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | 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) 05:18:13 INFO - PROCESS | 1670 | RSS: Main: 396013568 05:18:13 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | __start_tp_report 05:18:13 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 05:18:13 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 05:18:13 INFO - PROCESS | 1670 | |i|pagename|runs| 05:18:13 INFO - PROCESS | 1670 | |0;ai-astar;113;110;113;112;113;110;119;113;114;114 05:18:13 INFO - PROCESS | 1670 | |1;audio-beat-detection;155;121;144;171;118;142;140;125;206;148 05:18:13 INFO - PROCESS | 1670 | |2;audio-dft;183;188;186;184;186;181;181;183;185;185 05:18:13 INFO - PROCESS | 1670 | |3;audio-fft;89;73;86;90;57;77;82;111;86;55 05:18:13 INFO - PROCESS | 1670 | |4;audio-oscillator;88;103;137;88;113;114;123;71;109;159 05:18:13 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;89;87;88;84;135;86;89;92;133;101 05:18:13 INFO - PROCESS | 1670 | |6;imaging-darkroom;94;94;97;95;94;93;94;95;93;92 05:18:13 INFO - PROCESS | 1670 | |7;imaging-desaturate;84;83;83;85;86;84;84;86;84;84 05:18:13 INFO - PROCESS | 1670 | |8;json-parse-financial;59;61;60;58;57;58;59;75;59;58 05:18:13 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;63;61;76;80;71;70;66;65;90;96 05:18:13 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;82;82;81;82;81;81;81;82;84;83 05:18:13 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;153;152;149;146;147;137;137;138;173;164 05:18:13 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;172;168;215;206;170;170;168;169;166;160 05:18:13 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;68;67;69;68;67;68;67;70;68;66 05:18:13 INFO - PROCESS | 1670 | __end_tp_report 05:18:13 INFO - PROCESS | 1670 | __start_cc_report 05:18:13 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2003 05:18:13 INFO - PROCESS | 1670 | __end_cc_report 05:18:13 INFO - PROCESS | 1670 | __startTimestamp1461154693988__endTimestamp 05:18:13 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | ------- Summary: start ------- 05:18:13 INFO - PROCESS | 1670 | Number of tests: 14 05:18:13 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:113.10 Median:113.00 stddev:2.51 (2.2%) stddev-sans-first:2.67 05:18:13 INFO - PROCESS | 1670 | Values: 113.0 110.0 113.0 112.0 113.0 110.0 119.0 113.0 114.0 114.0 05:18:13 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:147.00 Median:146.00 stddev:26.26 (18.0%) stddev-sans-first:27.69 05:18:13 INFO - PROCESS | 1670 | Values: 155.0 121.0 144.0 171.0 118.0 142.0 140.0 125.0 206.0 148.0 05:18:13 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:2.25 (1.2%) stddev-sans-first:2.35 05:18:13 INFO - PROCESS | 1670 | Values: 183.0 188.0 186.0 184.0 186.0 181.0 181.0 183.0 185.0 185.0 05:18:13 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:80.60 Median:84.00 stddev:16.42 (19.5%) stddev-sans-first:17.13 05:18:13 INFO - PROCESS | 1670 | Values: 89.0 73.0 86.0 90.0 57.0 77.0 82.0 111.0 86.0 55.0 05:18:13 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:110.50 Median:148.00 stddev:25.52 (17.2%) stddev-sans-first:25.73 05:18:13 INFO - PROCESS | 1670 | Values: 88.0 103.0 137.0 88.0 113.0 114.0 123.0 71.0 109.0 159.0 05:18:13 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:87.50 stddev:19.32 (22.1%) stddev-sans-first:20.19 05:18:13 INFO - PROCESS | 1670 | Values: 89.0 87.0 88.0 84.0 135.0 86.0 89.0 92.0 133.0 101.0 05:18:13 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:1.37 (1.5%) stddev-sans-first:1.45 05:18:13 INFO - PROCESS | 1670 | Values: 94.0 94.0 97.0 95.0 94.0 93.0 94.0 95.0 93.0 92.0 05:18:13 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.00 stddev:1.06 (1.3%) stddev-sans-first:1.12 05:18:13 INFO - PROCESS | 1670 | Values: 84.0 83.0 83.0 85.0 86.0 84.0 84.0 86.0 84.0 84.0 05:18:13 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:59.00 stddev:5.25 (8.9%) stddev-sans-first:5.55 05:18:13 INFO - PROCESS | 1670 | Values: 59.0 61.0 60.0 58.0 57.0 58.0 59.0 75.0 59.0 58.0 05:18:13 INFO - PROCESS | 1670 | 05:18:13 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:73.50 stddev:11.74 (16.0%) stddev-sans-first:11.78 05:18:13 INFO - PROCESS | 1670 | Values: 63.0 61.0 76.0 80.0 71.0 70.0 66.0 65.0 90.0 96.0 05:18:14 INFO - PROCESS | 1670 | 05:18:14 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:82.00 stddev:0.99 (1.2%) stddev-sans-first:1.05 05:18:14 INFO - PROCESS | 1670 | Values: 82.0 82.0 81.0 82.0 81.0 81.0 81.0 82.0 84.0 83.0 05:18:14 INFO - PROCESS | 1670 | 05:18:14 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:150.50 stddev:11.76 (7.8%) stddev-sans-first:12.41 05:18:14 INFO - PROCESS | 1670 | Values: 153.0 152.0 149.0 146.0 147.0 137.0 137.0 138.0 173.0 164.0 05:18:14 INFO - PROCESS | 1670 | 05:18:14 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:170.00 stddev:18.38 (10.8%) stddev-sans-first:19.43 05:18:14 INFO - PROCESS | 1670 | Values: 172.0 168.0 215.0 206.0 170.0 170.0 168.0 169.0 166.0 160.0 05:18:14 INFO - PROCESS | 1670 | 05:18:14 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 05:18:14 INFO - PROCESS | 1670 | Values: 68.0 67.0 69.0 68.0 67.0 68.0 67.0 70.0 68.0 66.0 05:18:14 INFO - PROCESS | 1670 | -------- Summary: end -------- 05:18:14 INFO - PROCESS | 1670 | 05:18:14 INFO - TEST-INFO | 1670: exit 0 05:18:14 INFO - TEST-OK | kraken | took 59094ms 05:18:14 INFO - SUITE-END | took 1174s 05:18:15 INFO - Completed test suite (00:19:35) 05:18:15 INFO - javascript benchmark 05:18:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2792.3019245188707, 3423.263998240378, 3449.3101379724044, 3451.30973805239, 3475.322312149132, 5539.778408863641, 5631.1271752878265, 5676.971615141923, 5687.260656114704, 5693.117566777151, 5628.887422251552, 5685.1756495308155, 5696.316442026952, 5712.371639119704, 5717.942180696574, 5158.43257241704, 5187.584993200554, 5204.115300398941, 5213.765380557865, 5282.709450980195, 5271.288376069239, 5295.655782374152, 5297.17893726473, 5304.893902121955, 5311.362636483627, 5218.739063046833, 5287.048331300364, 5296.2055691646365, 5298.993191293661, 5341.786328546854, 5267.130923397634, 5286.97356513215, 5296.629235953485, 5306.548943339821, 5325.627206095575, 4216.873493795191, 4696.178168820443, 4824.975875120602, 5188.377394712658, 5241.606879484016, 5518.586106042063, 5528.336599608033, 5543.279373681436, 5555.055640541077, 5635.5773317001385, 6197.721102550396, 6203.844903877394, 6213.720382582795, 6227.968860155715, 6231.252249730016, 6136.631802091889, 6182.629042257479, 6198.659073750942, 6248.1564988726395, 6306.905396419057, 4826.1795494765975, 5785.913211301834, 5810.912836307459, 5824.242848429677, 5843.4448727371255, 6230.190075290183, 6237.688115594202, 6248.9062664060075, 6254.405831446005, 6257.155284036597, 5979.940200597938, 6011.669358185254, 6061.818145455643, 6093.021863430864, 6170.938290617126, 6267.059941008885, 6274.403931626488, 6279.7488100476385, 6292.150559674477, 6296.992481203077, 6215.906761398583, 6241.594296370721, 6242.65665388408, 6249.500039996789, 6269.937300626935, 5142.794288228429, 6102.701334035014, 6205.47253483459, 6208.751649934043, 6270.216222972129, 4866.635002374836, 4882.7558622068755, 4891.437484689235, 4899.4365647950945, 4901.681390709664, 4370.934435983463, 4402.669799765031, 4413.889652758642, 4481.35020422037, 4490.191765482245, 5594.104943209149, 5630.183623374588, 5642.351129620064, 5657.264555607745, 5664.318581776628, 5604.215409842625, 6146.7706458708435, 6275.623462592259, 6290.056491526216, 6295.118683384329, 5353.1167357386375, 5496.285482887199, 5610.07433773431, 5631.211630371751, 5644.181593668895, 4619.422572178478, 5337.86655333612, 5348.598855085884, 5354.303940487712, 5415.53967912724, 6118.741008805671, 6233.657148856856, 6244.0, 6259.96870015647, 6276.340984196594, 6293.716782744696, 6297.960836461933, 6312.4634406074165, 6325.177726895658, 6332.366763323815, 5966.925953328442, 6228.532860035606, 6293.465055470337, 6294.8111556653375, 6298.779542715983, 6061.969690151521, 6116.327204007466, 6136.028952951454, 6169.475594574512, 6206.906896396467, 6005.321926835975, 6182.629042257479, 6199.1011303361665, 6209.161763161916, 6217.595856269328, 6007.939920600913, 6017.217761690952, 6020.698965051817, 6027.6986150693165, 6070.696465176812, 5922.1412895130825, 5964.224650795369, 6043.697815109315, 6125.234345706786, 6134.35589263121, 5946.256717910262, 5946.613470124428, 5960.403959604005, 5971.492423143907, 5975.940240597713, 6129.448349648553, 6131.981316619475, 6133.294671112879, 6149.600275982047, 6155.199824022849, 5810.6513609183585, 5949.672767997802, 5952.5833191676165, 6085.604435711664, 6099.9390006099375], "value": 5655.788605624977, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.9073928097466, 504.7324917793571, 516.2926790297291, 580.477570186832, 585.4584509328871, 29565.55651665224, 30480.23799405013, 37508.812455937754, 38748.0, 38852.0, 40855.38716919249, 41410.37884431728, 41435.585644143546, 41448.37827432591, 41487.58512414875, 9257.629694812216, 9495.477748723817, 9507.23942084635, 9512.952435237814, 9526.142647161754, 8454.788630284262, 8755.693550725695, 11269.887301127006, 11582.536698532049, 11591.6522504325, 11104.61133860315, 11165.0, 11226.101911847074, 11247.268927519726, 11308.886911130907, 8466.66133354665, 11141.610043648474, 11227.101831853513, 11244.606438774685, 11261.493232804543, 11079.612213572567, 11258.155638327076, 11268.887311126866, 11268.985791278743, 11274.830877536844, 31238.37523249545, 37433.62566374356, 37971.8101409494, 37992.050198744975, 38095.61904380976, 3189.2345836999184, 3240.125166205135, 3247.8538465769097, 3279.3441311737747, 3417.3507033663523, 23596.528069438686, 27674.0, 28503.85748071267, 28616.0, 28668.713312866814, 2662.4142688608476, 2709.7425744554234, 2710.7500774357236, 2735.644366232397, 2800.075974928269, 336552.0, 443717.0, 443848.0, 444743.0, 444987.0, 34357.312853743286, 59178.40821591729, 59475.0, 59505.70247148823, 59580.40419595748, 35057.82471087628, 35121.47317790235, 35215.0, 35277.82361088178, 35287.0, 12092.0, 14749.557513274618, 14749.631259218391, 14756.262186890612, 14775.778363324558, 11474.254173478865, 11834.467448964817, 11847.763044739057, 12245.448954796877, 12546.937265313614, 7589.772306830804, 7704.036995375578, 7955.0, 8201.179882011751, 8204.8359032819, 3300.1914530940076, 3374.122728090665, 3375.493675948578, 3405.438102713074, 3406.7785593936314, 981.3523074770617, 997.5162046407487, 1019.7297716105238, 1046.4296958157822, 1149.8788681035924, 28464.573031404547, 28742.856285718855, 28993.75889631037, 29366.0, 29482.410351792845, 1093.8195197792274, 1117.446863802423, 1144.3820337017917, 1153.3253046967618, 1155.763068570941, 15349.539513814603, 15409.383624655113, 15546.300416481285, 15779.763303550455, 15813.68372632541, 599815.0, 716978.0, 733920.0, 735536.0, 737276.0, 59622.403775961684, 60432.69783651141, 61314.0, 61341.0, 61452.69273653603, 1355.539116700307, 1393.1501783911604, 1503.5414198669512, 1511.7883496310526, 1526.1834918318868, 25089.74910250874, 28735.27588344706, 29921.0, 30300.0, 31686.68313316837, 56419.71790141023, 57509.712451437474, 57542.7122864383, 57619.423805761406, 57849.421505784405, 572633.0, 576701.0, 576901.1154944198, 577546.0, 578013.0, 23007.53984920259, 27039.86480067587, 27274.863625681744, 27906.720932791224, 27926.882924682785, 32367.35265294728, 38649.0, 38989.0, 44892.0, 46199.76900115478, 29532.409351812414, 34398.65601343955, 35290.82354588211, 35443.291134177685, 35943.74196903096, 34832.0, 35023.124421888635, 35235.64764352426, 35892.102697432776, 37297.0, 35925.46111808273, 36138.8193059033, 36252.456213157355, 36647.0, 36961.26077478382, 31774.841125794224, 32094.67905320917, 32291.0, 32531.794912813835, 32662.34675306528, 21793.56412871702, 23582.764172358056, 23908.163214287415, 24129.793510324063, 24533.87733061323], "value": 18470.116720877722, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.54793535369674, 398.35267690003724, 399.64232012348947, 405.08046733914034, 406.5568530301972, 20278.0, 20390.89804550975, 21112.0499577519, 21124.0, 21510.784892151074, 19802.405927822187, 20035.298764543244, 21032.474188145265, 21332.4666883328, 21345.466363340925, 5235.685858848462, 5709.914351284744, 6001.309849367313, 6037.6075555089, 6041.365656606059, 5694.6867922264255, 5970.0746384310505, 5977.402259774031, 6030.125631783389, 6040.335563088057, 5869.148973398855, 5905.114232865083, 5908.468237858592, 5957.553183511232, 5959.731812068446, 5582.888342233153, 5907.202527658754, 5915.556333275, 5940.9108863366655, 5943.04911214208, 5173.379194496684, 5200.271961925334, 5201.8959620807755, 5205.557527610158, 5215.947840521584, 23407.06371745128, 26075.478490430276, 26633.0, 26638.600420993505, 26754.0, 1853.721941708741, 2173.945651358713, 2175.869447833135, 2200.6919031335624, 2349.048635302705, 14855.960082794209, 14890.404383824634, 15048.774268385983, 15121.773173402407, 15405.460808871636, 1544.2510382464495, 1715.4596302164778, 1781.26967943144, 1803.3147403986532, 1805.6117934643983, 111703.0, 115495.0, 116033.4198329003, 116841.6631667362, 118688.21967670492, 10591.682249532527, 10832.241743078066, 11084.61203857861, 11175.0, 11403.315801051964, 14217.64455888611, 14237.0, 14256.215908124945, 14265.001449898615, 14277.42890284377, 8384.413091083565, 8536.018357888923, 8549.615267312985, 8649.135086491426, 8662.78343041429, 8443.704470343508, 8460.280876125475, 8480.406371554056, 8581.098984606651, 8583.914160858436, 6536.901946470807, 6636.900446493307, 6658.4673226141795, 6663.200415950117, 6678.165229346332, 1623.0910690013395, 1702.2935481775173, 1857.7120546315343, 1882.4634979031184, 1886.3114963038392, 894.2667013049237, 894.9910500895038, 953.9236861051099, 1001.5643195210107, 1063.542676649033, 19375.612487750164, 20819.805194805387, 21030.369088927357, 21112.261070862754, 21423.035963381684, 988.186677446893, 1002.3384566186282, 1009.152312057875, 1010.3937637417491, 1014.1633152648947, 10793.62222322215, 11244.931731485496, 11340.829887551694, 11366.943165284287, 11373.976342129248, 532746.0, 539409.0, 547507.0, 550640.0, 551176.2205944803, 105504.47247763711, 109996.90003100026, 110443.89556104496, 112372.0, 112847.0, 1037.5175543372368, 1037.6458721368535, 1083.4203701019917, 1084.4577711144427, 1086.131095123882, 15203.923980380026, 18569.0, 18618.906905465385, 18735.71896421582, 18798.3420580279, 48341.75829120973, 49099.509004909494, 49390.0, 49545.76135596639, 50114.498855010985, 47913.0, 58923.70538147282, 61837.07244391428, 67376.98934516084, 68444.0, 7757.883631745415, 7869.134395216407, 7887.64505597238, 7907.8813817793925, 8043.19568043191, 11443.198976071928, 12229.510819567118, 12345.604958636446, 12512.29706718063, 12600.306983116017, 10736.785264294827, 10762.40806755636, 10767.676969690921, 10888.237823352289, 10936.830461688807, 10492.422916739653, 10538.578456861946, 10624.609462871102, 10823.567057317934, 11125.998660120627, 9643.27675424332, 10331.690049298533, 11301.43492825339, 11329.490172942402, 11756.294622322688, 6824.317568243136, 8089.23152300554, 8218.589070546568, 8221.136780638186, 8233.83532329338, 8325.375984721766, 11068.28056176346, 11123.666290011313, 11208.607698730506, 11361.772764544829], "value": 9558.752086482946, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.8418373978855, 1624.7887774589306, 1656.1967445788794, 1914.9617007659838, 2031.451508092815, 8060.113387527367, 8759.21167094962, 8898.866517002241, 8902.777430564223, 8922.464652120862, 8530.104339044388, 8760.0, 8771.91228087719, 8771.956140219307, 8791.516466594334, 2149.344449942767, 2374.7269064057596, 2422.491276831863, 2453.950920981579, 2459.692538432696, 2468.06213638817, 2496.1388321029217, 2497.300755788382, 2498.962515562268, 2507.623856421533, 2059.217497351012, 2410.156445244168, 2421.4309637235237, 2423.1397853761923, 2424.684790977179, 2090.9268175613856, 2112.9260475883348, 2277.703898493202, 2353.3998830298337, 2354.693889794333, 2453.362125847275, 2457.8156638252203, 2462.1382516119215, 2466.962995555068, 2478.8264821462503, 8719.258862996652, 8792.868106978336, 8801.119888011212, 8805.823883522293, 8843.60203790831, 5184.507471790174, 5399.892002159974, 5402.081646120169, 5405.783768649249, 5411.945880541183, 2916.9270768230963, 3381.3237352529345, 3557.6620221078956, 3586.820658967067, 3589.9589119155676, 1037.2894567221476, 1039.4283144270696, 1043.087298613713, 1051.4742628685658, 1053.525913338993, 11660.941695291554, 11701.70745731355, 11723.76552468946, 11731.354775487345, 11735.4132293385, 4040.3535434330365, 4705.623550115983, 4758.578581023324, 4916.3362946001835, 4935.432425271068, 9841.50792460374, 9939.701808945743, 9948.104670579538, 9955.55200016001, 9957.701268961942, 5410.513053825174, 5421.430749771296, 5422.7017514036315, 5434.809781657623, 5456.61803673739, 4004.979975100106, 4404.581564751344, 4552.1806074906835, 4906.58294045003, 4946.233333833262, 3522.9295414091575, 3975.264576053439, 4007.3788562772816, 4118.155778065489, 4128.339465685476, 1162.7325715085578, 1345.2668295778824, 1361.3737680667004, 1374.2853716067589, 1375.3112664740408, 824.8968878890139, 875.6497401039508, 891.9241864441595, 923.3951761596165, 929.5770424456856, 5080.74596270185, 5519.69641669712, 5674.914876276859, 5722.0, 5732.684702341411, 452.2447512653876, 464.97907594158346, 467.49000727649883, 470.74344482257254, 474.20570544338096, 3286.3263031078573, 3314.254292784104, 3773.132179598708, 3786.905327366783, 3862.6716729078344, 10392.37645741258, 10782.838257426145, 10795.136389089012, 10921.071708904832, 10923.672289831318, 10004.399736015717, 10779.946100269448, 10822.404767737693, 10876.238663293652, 10881.455927203608, 8040.959795200986, 8053.436259461781, 8083.959580202179, 8118.634661440249, 8191.631376587949, 5593.580481463825, 5721.628094173865, 5805.187273781589, 5832.0, 5943.138244954544, 9818.410895346302, 9839.557219925264, 9866.506674666382, 9963.900360996297, 9982.35114717541, 9431.669891553762, 9649.925266067828, 10051.396916185053, 10076.798464030824, 10204.336718113298, 5811.0, 6867.07294515234, 7114.288571142844, 7357.77926662201, 7524.435667324863, 8230.547319897223, 8244.258016778518, 8319.0, 8335.91633087719, 8359.749207523784, 7167.390771784457, 7316.304951029643, 7340.265973402617, 7442.404607631268, 7512.474126811071, 7107.147142342952, 7183.20984691694, 7250.3112204342615, 7278.454115941431, 7296.744613938699, 6867.416269616939, 6947.4789390796905, 6952.339527744856, 6976.162860456778, 6982.0, 4510.548945105595, 4679.4557795926585, 4793.664443489062, 4863.416390033077, 5048.217526283432, 4818.156822556109, 4858.1012512685265, 4860.659753817199, 4866.853994380174, 5076.806950366735], "value": 4470.367612827377, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.43271188470595, 313.7505682982029, 317.34626669061737, 320.5992509363296, 323.16461945869935, 4865.0053478074, 5797.884042319156, 5880.705964701764, 5892.616979896315, 5906.763729450817, 5754.0, 5812.941870581293, 5816.621919575225, 5854.648721076738, 5857.707114644272, 4220.7256528325715, 4268.231718290706, 4402.3836662867225, 4483.34991426243, 4497.572730590605, 4328.740275583459, 4376.584224498668, 4409.426774519324, 4445.110977804436, 4496.280595104784, 4439.400680908068, 4459.955400446002, 4462.8884277892985, 4464.928417179886, 4497.977510112445, 3652.926941461156, 4142.772147531885, 4159.126583417471, 4453.755043472608, 4492.3710680504755, 3231.87072517099, 3779.414190800403, 3841.7118716096124, 3842.865499707497, 3849.788261645608, 5606.579506537026, 5623.738231825531, 5625.409332020161, 5656.971715141398, 5688.203651488795, 4614.307853821953, 4918.901621967577, 4987.5511203991455, 5084.542391184811, 5130.4356520782685, 6087.421694938974, 6107.633541987495, 6141.692915354259, 6148.723307451175, 6152.661988444407, 4092.897677558055, 4223.0, 4245.172191422674, 4258.68059895509, 4266.701330906838, 5527.143292789624, 6457.580257283262, 6490.156279683611, 6503.447206987412, 6511.088447617338, 3658.829174664082, 3991.0887330415776, 4101.938470922939, 4302.612764851178, 4329.675274354436, 3885.592012838668, 4049.331860243086, 4359.346098085312, 4368.257396242583, 4380.123975204908, 4681.127548980367, 4684.945887175427, 4788.688735232199, 4987.526185012418, 4998.825041123543, 6271.811845644638, 6296.811095667137, 6306.779262725782, 6337.261016000582, 6337.524685648595, 1255.9058070644737, 1378.9034767566175, 1462.1154201708025, 1477.0399240493764, 1478.6894752101964, 792.3898598079446, 895.2793001633747, 925.1858364639074, 952.1906379577304, 990.7424069741775, 4885.5847252983895, 5667.3482549507335, 5867.559933005042, 5883.646981181143, 5890.440408161304, 5380.91928621071, 5399.649022813504, 6485.967570162214, 6492.772752953719, 6526.967365163238, 5132.871678208, 5647.519960803378, 5813.302403711497, 5873.676947767828, 5882.735276912464, 3380.8816691416173, 3394.3381040696827, 3395.592528896548, 3428.3314753623304, 3430.559165150643, 5023.844515761395, 5687.516561092353, 6685.022411665052, 6745.143496682381, 6758.493113016543, 5636.802711905146, 6537.5750576212395, 6737.056812046318, 6759.797206083826, 6777.1189745332795, 5500.964643082085, 5737.995850726106, 6630.53586248958, 6787.1176747023555, 6807.863842723118, 5435.016219080087, 6392.840178995562, 6546.639934803631, 6558.573327868719, 6560.376764207393, 5136.768845402041, 6334.0, 6439.259485159079, 6451.58065725353, 6486.740530378732, 5669.461401166883, 6443.516736244894, 6794.660266986532, 6798.688445928408, 6801.693923773343, 3905.6484916357663, 4336.002719374625, 4381.890452738707, 4412.602865742098, 4424.305704736581, 5395.3525576931015, 5607.495325420732, 6312.295680166421, 6456.159289912423, 6464.385883341075, 5821.417858214145, 5826.563007774349, 6192.504599631929, 6211.65835879031, 6291.905621415591, 6239.906401403891, 6240.689455214456, 6262.279837818709, 6274.1843560336865, 6302.369763023844, 5152.510511501401, 5822.94177058224, 6037.486813620892, 6149.938500615115, 6167.352427994996, 5560.360558535658, 6151.200343955257, 6262.401103542161, 6306.369363063657, 6325.7153428094925, 5158.0, 5370.006548788487, 6156.692165391623, 6173.753049877954, 6202.41077097675], "value": 4513.620526519063, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.8592374486977, 733.4425836364363, 735.5219107580076, 738.915024772151, 799.5043073294556, 14611.123332600038, 15023.624409389771, 15029.473968411108, 15033.398664053439, 15060.623484412868, 13879.722405551907, 13966.231857247847, 13974.0, 13988.58034258974, 14012.579622611338, 716.5664772812452, 736.0651971995562, 866.51908190954, 881.8060026794111, 905.6830109461669, 866.8439680857443, 881.5327876225579, 892.0232345581578, 895.6506962284714, 899.8470260055774, 836.481381543444, 938.0338251600862, 965.420747551467, 967.1295833749647, 968.8934217236099, 752.2966026764992, 931.3340961212732, 932.5197523275518, 942.9575669094908, 945.3099237556553, 699.5348093517805, 700.3976580141075, 702.2731472925498, 706.9540479868792, 713.6752777486231, 14854.405823767145, 14870.405183792634, 14952.551423457313, 14963.551093467213, 14984.925075374662, 12798.040146989013, 12833.614991550268, 13004.804927926089, 13056.738865222642, 13077.149985250928, 9314.487703176323, 9487.857682134774, 10140.391576505359, 10526.157907367393, 10686.51910664022, 3693.3351996640595, 4056.1482088761395, 4152.709310348277, 4211.473565804275, 4340.13197360526, 83859.16140838635, 85137.0, 85172.72240916391, 85413.57293213494, 85586.57206713989, 8114.513129212266, 9305.674301399418, 9522.476263805418, 9553.808923821485, 9809.509524523744, 20966.685499717518, 21395.67906481404, 21423.250186243407, 21485.03317350723, 21501.0, 7349.191588925214, 7414.6292685365515, 7476.401887848959, 7573.962130189424, 7787.415943804237, 3998.080441498472, 4083.14254006656, 4099.61053699898, 4108.589141085928, 4384.254676704904, 2921.7662586992988, 2983.895563655262, 3594.8202589870402, 3601.0637234319265, 3617.2403795202777, 652.4845372155944, 659.8584448903329, 661.9371159739817, 663.1147418196762, 675.9826461175899, 474.7768548782136, 483.98306059287756, 553.2089112568984, 592.1059200607115, 592.6473748119862, 7566.35685966699, 7674.194209608022, 7712.5758083305955, 7925.603719813987, 7976.880346794803, 547.0720228757559, 547.4744245524262, 578.3002566894021, 580.8228490310496, 582.7727186397309, 3727.794971276606, 3737.6449237322413, 3765.4916586260506, 3771.151490914522, 3797.7911214882893, 75493.62253188809, 75537.6223118892, 76744.61627691826, 78603.0, 79056.0, 65087.0, 66026.0, 66736.66526669536, 66885.662286754, 68020.0, 57394.7130264346, 58568.7071564648, 58692.11961820576, 58925.0, 59381.0, 7306.890396644161, 7317.304856038668, 7397.630118493946, 7574.848503030019, 7707.730229441942, 39057.0, 40053.0, 40124.59563915249, 40334.43045434752, 41093.383599246634, 52044.69888252824, 52192.73903630609, 52299.73850130725, 54705.45294547004, 55049.34851954287, 9581.664641737505, 10636.023251072666, 10690.091342235686, 10983.340999540053, 11868.0, 10494.632687855888, 12231.0, 12289.815652765394, 12389.705517453589, 12715.80926286125, 10046.949765251127, 11188.328700278009, 11255.718607034889, 11306.756256811908, 11725.824112638487, 11189.0, 11294.435278235891, 11391.0, 11618.46854267442, 12301.0, 11009.009189173014, 11167.38579378142, 11188.66434006981, 11460.829949489815, 11487.712807179887, 9224.308176886627, 9436.103570160823, 9462.858057129011, 9505.477198754135, 10553.102986246255, 7325.0, 8320.268312528538, 8818.162274583905, 9084.863727044232, 9694.660686876208], "value": 6649.538517562309, "unit": "score"}], "name": "dromaeo_css", "value": 7153.369433856619, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 110.0, 113.0, 112.0, 113.0, 110.0, 119.0, 113.0, 114.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 121.0, 144.0, 171.0, 118.0, 142.0, 140.0, 125.0, 206.0, 148.0], "value": 147.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 188.0, 186.0, 184.0, 186.0, 181.0, 181.0, 183.0, 185.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 73.0, 86.0, 90.0, 57.0, 77.0, 82.0, 111.0, 86.0, 55.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 103.0, 137.0, 88.0, 113.0, 114.0, 123.0, 71.0, 109.0, 159.0], "value": 110.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 84.0, 135.0, 86.0, 89.0, 92.0, 133.0, 101.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 97.0, 95.0, 94.0, 93.0, 94.0, 95.0, 93.0, 92.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 85.0, 86.0, 84.0, 84.0, 86.0, 84.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 60.0, 58.0, 57.0, 58.0, 59.0, 75.0, 59.0, 58.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 76.0, 80.0, 71.0, 70.0, 66.0, 65.0, 90.0, 96.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 82.0, 81.0, 81.0, 81.0, 82.0, 84.0, 83.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 149.0, 146.0, 147.0, 137.0, 137.0, 138.0, 173.0, 164.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 168.0, 215.0, 206.0, 170.0, 170.0, 168.0, 169.0, 166.0, 160.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 68.0, 67.0, 68.0, 67.0, 70.0, 68.0, 66.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1522.1, "alertThreshold": 2.0}]} 05:18:15 INFO - Return code: 0 05:18:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:18:15 ERROR - # TBPL SUCCESS # 05:18:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:18:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:18:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:18:15 INFO - 2016-04-20 05:18:15.308 screenresolution[1674:9811] starting screenresolution argv=screenresolution get 05:18:15 INFO - 2016-04-20 05:18:15.310 screenresolution[1674:9811] Display 0: 1600x1200x32@60 05:18:15 INFO - 2016-04-20 05:18:15.316 screenresolution[1675:9814] starting screenresolution argv=screenresolution list 05:18:15 INFO - Available Modes on Display 0 05:18:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:18:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:18:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:18:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:18:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:18:15 INFO - Intel Iris: 05:18:15 INFO - Chipset Model: Intel Iris 05:18:15 INFO - Type: GPU 05:18:15 INFO - Bus: Built-In 05:18:15 INFO - VRAM (Dynamic, Max): 1536 MB 05:18:15 INFO - Vendor: Intel (0x8086) 05:18:15 INFO - Device ID: 0x0a2e 05:18:15 INFO - Revision ID: 0x0009 05:18:15 INFO - Displays: 05:18:15 INFO - Display: 05:18:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:18:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:18:15 INFO - Display Serial Number: 1600x1200 60 05:18:15 INFO - Main Display: Yes 05:18:15 INFO - Mirror: Off 05:18:15 INFO - Online: Yes 05:18:15 INFO - Rotation: Supported 05:18:15 INFO - Return code: 0 05:18:15 INFO - Running post-action listener: _resource_record_post_action 05:18:15 INFO - Running post-run listener: _resource_record_post_run 05:18:16 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37750272; Write bytes: 695351296; Read time: 392; Write time: 2533 05:18:16 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400876032; Write bytes: 398194688; Read time: 29585; Write time: 764 05:18:16 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30641664; Write bytes: 296259584; Read time: 303; Write time: 1765 05:18:16 INFO - Running post-run listener: _upload_blobber_files 05:18:16 INFO - Blob upload gear active. 05:18:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:18:16 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 05:18:16 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:18:16 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:18:17 INFO - (blobuploader) - INFO - Open directory for files ... 05:18:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:18:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:18:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:18:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:18:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:18:17 INFO - (blobuploader) - INFO - Done attempting. 05:18:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:18:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:18:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:18:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:18:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:18:19 INFO - (blobuploader) - INFO - Done attempting. 05:18:19 INFO - (blobuploader) - INFO - Iteration through files over. 05:18:19 INFO - Return code: 0 05:18:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:18:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:18:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82ad0a5f1c811fefa26610795cdf29daea50a0910d15961d2394548e48236daa41934f05b2d0b7f4ca54f4d3e72a4a7adc1a0c03a0db570d71e9d0032aec1c56", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a510e5a8de0ecb466398aad1a18d608014404f78232c22b72b980055ff445b4dd4de9589a7582433b8a9cdf47c403ada0d37c530ec0ff6c7a9c1372e02e2b5e5"} 05:18:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:18:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:18:19 INFO - Contents: 05:18:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82ad0a5f1c811fefa26610795cdf29daea50a0910d15961d2394548e48236daa41934f05b2d0b7f4ca54f4d3e72a4a7adc1a0c03a0db570d71e9d0032aec1c56", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a510e5a8de0ecb466398aad1a18d608014404f78232c22b72b980055ff445b4dd4de9589a7582433b8a9cdf47c403ada0d37c530ec0ff6c7a9c1372e02e2b5e5"} 05:18:19 INFO - Running post-run listener: copy_logs_to_upload_dir 05:18:19 INFO - Copying logs to upload dir... 05:18:19 INFO - mkdir: /builds/slave/test/build/upload/logs 05:18:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.144563 ========= master_lag: 0.90 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-04-20 05:18:19.907432) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-20 05:18:19.912232) ========= 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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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/Firefox/sha512/82ad0a5f1c811fefa26610795cdf29daea50a0910d15961d2394548e48236daa41934f05b2d0b7f4ca54f4d3e72a4a7adc1a0c03a0db570d71e9d0032aec1c56", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a510e5a8de0ecb466398aad1a18d608014404f78232c22b72b980055ff445b4dd4de9589a7582433b8a9cdf47c403ada0d37c530ec0ff6c7a9c1372e02e2b5e5"} build_url:https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011317 build_url: 'https://queue.taskcluster.net/v1/task/N93ceuS9S-26CElRUwFzow/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82ad0a5f1c811fefa26610795cdf29daea50a0910d15961d2394548e48236daa41934f05b2d0b7f4ca54f4d3e72a4a7adc1a0c03a0db570d71e9d0032aec1c56", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a510e5a8de0ecb466398aad1a18d608014404f78232c22b72b980055ff445b4dd4de9589a7582433b8a9cdf47c403ada0d37c530ec0ff6c7a9c1372e02e2b5e5"}' ========= master_lag: 0.50 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-20 05:18:20.421314) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 05:18:20.424739) ========= 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.a1RnAlqdse/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.hX0Pkk0Lev/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.005062 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 05:18:20.479220) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 05:18:20.479726) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 05:18:20.491415) ========= ========= Total master_lag: 7.47 =========