builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0215 starttime: 1467417182.77 results: success (0) buildid: 20160701145923 builduid: fb3d8de2a63d4f57b660eb50addd8227 revision: 3dcecf06e37b403d3c8a518b6848fbcf5e3c8c13 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:02.772703) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:02.773135) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:02.773402) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:02.874357) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:02.874604) ========= 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.yknRzcgS4U/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.MqSZHeQlUM/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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-07-01 16:53:02-- 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% 17.9M=0.001s 2016-07-01 16:53:03 (17.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139790 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:03.047344) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:03.047626) ========= 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.yknRzcgS4U/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.MqSZHeQlUM/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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.026032 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:03.096014) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:03.096320) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 3dcecf06e37b403d3c8a518b6848fbcf5e3c8c13 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 3dcecf06e37b403d3c8a518b6848fbcf5e3c8c13 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yknRzcgS4U/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.MqSZHeQlUM/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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-07-01 16:53:03,177 truncating revision to first 12 chars 2016-07-01 16:53:03,177 Setting DEBUG logging. 2016-07-01 16:53:03,178 attempt 1/10 2016-07-01 16:53:03,178 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/3dcecf06e37b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-01 16:53:03,879 unpacking tar archive at: autoland-3dcecf06e37b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.977347 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:04.095391) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:04.095703) ========= script_repo_revision: 3dcecf06e37b403d3c8a518b6848fbcf5e3c8c13 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:04.096096) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:04.096360) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-01 16:53:04.114557) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2016-07-01 16:53:04.114894) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yknRzcgS4U/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.MqSZHeQlUM/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:53:04 INFO - MultiFileLogger online at 20160701 16:53:04 in /builds/slave/test 16:53:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 16:53:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:53:04 INFO - {'append_to_log': False, 16:53:04 INFO - 'base_work_dir': '/builds/slave/test', 16:53:04 INFO - 'blob_upload_branch': 'Autoland', 16:53:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:53:04 INFO - 'branch': 'Autoland', 16:53:04 INFO - 'buildbot_json_path': 'buildprops.json', 16:53:04 INFO - 'config_files': ('talos/mac_config.py',), 16:53:04 INFO - 'default_actions': ('clobber', 16:53:04 INFO - 'read-buildbot-config', 16:53:04 INFO - 'download-and-extract', 16:53:04 INFO - 'populate-webroot', 16:53:04 INFO - 'create-virtualenv', 16:53:04 INFO - 'install', 16:53:04 INFO - 'run-tests'), 16:53:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:53:04 INFO - 'download_minidump_stackwalk': True, 16:53:04 INFO - 'download_symbols': 'ondemand', 16:53:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:53:04 INFO - 'tooltool.py': '/tools/tooltool.py', 16:53:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:53:04 INFO - '/tools/misc-python/virtualenv.py')}, 16:53:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:53:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:53:04 INFO - 'installer_path': 'installer.exe', 16:53:04 INFO - 'log_level': 'info', 16:53:04 INFO - 'log_name': 'talos', 16:53:04 INFO - 'log_to_console': True, 16:53:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:53:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:53:04 INFO - 'opt_config_files': (), 16:53:04 INFO - 'pip_index': False, 16:53:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:53:04 INFO - 'cmd': ('bash', 16:53:04 INFO - '-c', 16:53:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:53:04 INFO - 'enabled': True, 16:53:04 INFO - 'halt_on_failure': False, 16:53:04 INFO - 'name': 'check_screen_resolution'},), 16:53:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:53:04 INFO - 'cmd': ('bash', 16:53:04 INFO - '-c', 16:53:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:53:04 INFO - 'enabled': True, 16:53:04 INFO - 'halt_on_failure': False, 16:53:04 INFO - 'name': 'check_screen_resolution'},), 16:53:04 INFO - 'run_cmd_checks_enabled': True, 16:53:04 INFO - 'sps_profile': False, 16:53:04 INFO - 'sps_profile_interval': 0, 16:53:04 INFO - 'suite': 'dromaeojs', 16:53:04 INFO - 'system_bits': '32', 16:53:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:53:04 INFO - 'title': 't-yosemite-r7-0215', 16:53:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:53:04 INFO - 'use_talos_json': True, 16:53:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:53:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:53:04 INFO - 'work_dir': 'build'} 16:53:04 INFO - ##### 16:53:04 INFO - ##### Running clobber step. 16:53:04 INFO - ##### 16:53:04 INFO - Running pre-action listener: _resource_record_pre_action 16:53:04 INFO - Running main action method: clobber 16:53:04 INFO - rmtree: /builds/slave/test/build 16:53:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:53:06 INFO - Running post-action listener: _resource_record_post_action 16:53:06 INFO - ##### 16:53:06 INFO - ##### Finished clobber step (success) 16:53:06 INFO - ##### 16:53:06 INFO - ##### 16:53:06 INFO - ##### Running read-buildbot-config step. 16:53:06 INFO - ##### 16:53:06 INFO - Running pre-action listener: _resource_record_pre_action 16:53:06 INFO - Running main action method: read_buildbot_config 16:53:06 INFO - Using buildbot properties: 16:53:06 INFO - { 16:53:06 INFO - "project": "", 16:53:06 INFO - "product": "firefox", 16:53:06 INFO - "script_repo_revision": "production", 16:53:06 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 16:53:06 INFO - "repository": "", 16:53:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 16:53:06 INFO - "buildid": "20160701145923", 16:53:06 INFO - "pgo_build": "False", 16:53:06 INFO - "basedir": "/builds/slave/test", 16:53:06 INFO - "buildnumber": 23, 16:53:06 INFO - "slavename": "t-yosemite-r7-0215", 16:53:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:53:06 INFO - "platform": "yosemite_r7", 16:53:06 INFO - "scheduler": "tests-autoland-macosx64-talos", 16:53:06 INFO - "branch": "autoland", 16:53:06 INFO - "repo_path": "integration/autoland", 16:53:06 INFO - "revision": "3dcecf06e37b403d3c8a518b6848fbcf5e3c8c13", 16:53:06 INFO - "stage_platform": "macosx64", 16:53:06 INFO - "builduid": "fb3d8de2a63d4f57b660eb50addd8227", 16:53:06 INFO - "slavebuilddir": "test" 16:53:06 INFO - } 16:53:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 16:53:06 INFO - Running post-action listener: _resource_record_post_action 16:53:06 INFO - ##### 16:53:06 INFO - ##### Finished read-buildbot-config step (success) 16:53:06 INFO - ##### 16:53:06 INFO - ##### 16:53:06 INFO - ##### Running download-and-extract step. 16:53:06 INFO - ##### 16:53:06 INFO - Running pre-action listener: _resource_record_pre_action 16:53:06 INFO - Running main action method: download_and_extract 16:53:06 INFO - mkdir: /builds/slave/test/build/tests 16:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:53:06 INFO - https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:53:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:53:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:53:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:53:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 16:53:33 INFO - Downloaded 1199 bytes. 16:53:33 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:53:33 INFO - Using the following test package requirements: 16:53:33 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:53:33 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:53:33 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 16:53:33 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:53:33 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 16:53:33 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:53:33 INFO - u'jsshell-mac.zip'], 16:53:33 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:53:33 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 16:53:33 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:53:33 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:53:33 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 16:53:33 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:53:33 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 16:53:33 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:53:33 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 16:53:33 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:53:33 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 16:53:33 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:53: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')]} 16:53:33 INFO - https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:53:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:53:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:53:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:53:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:53:39 INFO - Downloaded 18789528 bytes. 16:53:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:53:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:53:42 INFO - Return code: 0 16:53:42 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:53:42 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')]} 16:53:42 INFO - https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:53:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:53:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:53:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:53:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:53:43 INFO - Downloaded 18789528 bytes. 16:53:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:53:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:53:46 INFO - Return code: 0 16:53: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')]} 16:53:46 INFO - https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:53:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:53:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:53:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:53:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:53:48 INFO - Downloaded 14405242 bytes. 16:53:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:53:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:53:48 INFO - Return code: 0 16:53:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:53:48 INFO - https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:53:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:53:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:53:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 16:53:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 16:53:54 INFO - Downloaded 112395969 bytes. 16:53:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:53:54 INFO - mkdir: /builds/slave/test/properties 16:53:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:53:54 INFO - Writing to file /builds/slave/test/properties/build_url 16:53:54 INFO - Contents: 16:53:54 INFO - build_url:https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:53:55 INFO - Running post-action listener: _resource_record_post_action 16:53:55 INFO - Running post-action listener: set_extra_try_arguments 16:53:55 INFO - ##### 16:53:55 INFO - ##### Finished download-and-extract step (success) 16:53:55 INFO - ##### 16:53:55 INFO - ##### 16:53:55 INFO - ##### Running populate-webroot step. 16:53:55 INFO - ##### 16:53:55 INFO - Running pre-action listener: _resource_record_pre_action 16:53:55 INFO - Running main action method: populate_webroot 16:53:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:53:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:53:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:53:55 INFO - '--tppagecycles', 16:53:55 INFO - '7'], 16:53:55 INFO - 'tests': ['tsvgm']}}, 16:53:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:53:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:53:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:53:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:53:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:53:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:53:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:53:55 INFO - 'talos_options': ['--disable-e10s'], 16:53:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:53:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:53:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:53:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:53:55 INFO - 'talos_options': ['--disable-e10s'], 16:53:55 INFO - 'tests': ['damp', 'tps']}, 16:53:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:53:55 INFO - 'tests': ['damp', 'tps']}, 16:53:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:53:55 INFO - 'tests': ['dromaeo_dom']}, 16:53:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:53:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:53:55 INFO - 'tests': ['basic_compositor_video']}, 16:53:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:53:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:53:55 INFO - 'tests': ['a11yr', 16:53:55 INFO - 'ts_paint', 16:53:55 INFO - 'tpaint', 16:53:55 INFO - 'sessionrestore', 16:53:55 INFO - 'sessionrestore_no_auto_restore', 16:53:55 INFO - 'tabpaint']}, 16:53:55 INFO - 'other-e10s': {'tests': ['a11yr', 16:53:55 INFO - 'ts_paint', 16:53:55 INFO - 'tpaint', 16:53:55 INFO - 'sessionrestore', 16:53:55 INFO - 'sessionrestore_no_auto_restore', 16:53:55 INFO - 'tabpaint']}, 16:53:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:53:55 INFO - 'ts_paint', 16:53:55 INFO - 'tpaint', 16:53:55 INFO - 'sessionrestore', 16:53:55 INFO - 'sessionrestore_no_auto_restore', 16:53:55 INFO - 'tabpaint']}, 16:53:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:53:55 INFO - 'ts_paint', 16:53:55 INFO - 'tpaint', 16:53:55 INFO - 'sessionrestore', 16:53:55 INFO - 'sessionrestore_no_auto_restore', 16:53:55 INFO - 'tabpaint']}, 16:53:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:53:55 INFO - 'tests': ['a11yr', 16:53:55 INFO - 'ts_paint', 16:53:55 INFO - 'tpaint', 16:53:55 INFO - 'sessionrestore', 16:53:55 INFO - 'sessionrestore_no_auto_restore', 16:53:55 INFO - 'tabpaint']}, 16:53:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:53:55 INFO - 'tests': ['a11yr', 16:53:55 INFO - 'ts_paint', 16:53:55 INFO - 'tpaint', 16:53:55 INFO - 'sessionrestore', 16:53:55 INFO - 'sessionrestore_no_auto_restore', 16:53:55 INFO - 'tabpaint']}, 16:53:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:53:55 INFO - 'tests': ['tsvgx', 16:53:55 INFO - 'tsvgr_opacity', 16:53:55 INFO - 'tart', 16:53:55 INFO - 'tscrollx', 16:53:55 INFO - 'cart']}, 16:53:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:53:55 INFO - 'tsvgr_opacity', 16:53:55 INFO - 'tart', 16:53:55 INFO - 'tscrollx', 16:53:55 INFO - 'cart']}, 16:53:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:53:55 INFO - 'talos_options': ['--disable-e10s'], 16:53:55 INFO - 'tests': ['tp5o']}, 16:53:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:53:55 INFO - 'tests': ['tp5o']}, 16:53:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:53:55 INFO - 'talos_options': ['--disable-e10s', 16:53:55 INFO - '--xperf_path', 16:53:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:53:55 INFO - 'tests': ['tp5n']}, 16:53:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:53:55 INFO - 'talos_options': ['--xperf_path', 16:53:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:53:55 INFO - 'tests': ['tp5n']}}, 16:53:55 INFO - 'talos.zip': {'path': '', 16:53:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:53:55 INFO - Running post-action listener: _resource_record_post_action 16:53:55 INFO - ##### 16:53:55 INFO - ##### Finished populate-webroot step (success) 16:53:55 INFO - ##### 16:53:55 INFO - ##### 16:53:55 INFO - ##### Running create-virtualenv step. 16:53:55 INFO - ##### 16:53:55 INFO - Running pre-action listener: _resource_record_pre_action 16:53:55 INFO - Running main action method: create_virtualenv 16:53:55 INFO - Creating virtualenv /builds/slave/test/build/venv 16:53:55 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 16:53:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:53:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:53:55 INFO - Using real prefix '/tools/python27' 16:53:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:53:56 INFO - Installing distribute.............................................................................................................................................................................................done. 16:53:58 INFO - Installing pip.................done. 16:53:58 INFO - Return code: 0 16:53:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:53:58 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')]} 16:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:58 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')]} 16:53:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:58 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 0x10e86ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb67159bfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d2d30>, '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 0x10e537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.yknRzcgS4U/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.MqSZHeQlUM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:53:58 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 16:53:58 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 16:53:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yknRzcgS4U/Render', 16:53:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:58 INFO - 'HOME': '/Users/cltbld', 16:53:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:58 INFO - 'LOGNAME': 'cltbld', 16:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:58 INFO - 'MOZ_NO_REMOTE': '1', 16:53:58 INFO - 'NO_EM_RESTART': '1', 16:53:58 INFO - 'PAGER': '/bin/cat', 16:53:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:58 INFO - 'PWD': '/builds/slave/test', 16:53:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:58 INFO - 'SHELL': '/bin/bash', 16:53:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MqSZHeQlUM/Listeners', 16:53:58 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:53:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:58 INFO - 'USER': 'cltbld', 16:53:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:58 INFO - 'XPC_FLAGS': '0x0', 16:53:58 INFO - 'XPC_SERVICE_NAME': '0', 16:53:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:59 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 16:53:59 INFO - Cleaning up... 16:53:59 INFO - Return code: 0 16:53:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:53: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')]} 16:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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')]} 16:53:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53: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', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e86ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb67159bfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d2d30>, '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 0x10e537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.yknRzcgS4U/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.MqSZHeQlUM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:53: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:53: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 psutil>=3.1.1 16:53:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yknRzcgS4U/Render', 16:53:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:59 INFO - 'HOME': '/Users/cltbld', 16:53:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:59 INFO - 'LOGNAME': 'cltbld', 16:53:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:59 INFO - 'MOZ_NO_REMOTE': '1', 16:53:59 INFO - 'NO_EM_RESTART': '1', 16:53:59 INFO - 'PAGER': '/bin/cat', 16:53:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:59 INFO - 'PWD': '/builds/slave/test', 16:53:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:59 INFO - 'SHELL': '/bin/bash', 16:53:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MqSZHeQlUM/Listeners', 16:53:59 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:53:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:59 INFO - 'USER': 'cltbld', 16:53:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:59 INFO - 'XPC_FLAGS': '0x0', 16:53:59 INFO - 'XPC_SERVICE_NAME': '0', 16:53:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:59 INFO - Downloading/unpacking psutil>=3.1.1 16:53: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 16:53: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 16:53: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 16:53: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 16:53: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 16:53: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 16:54:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:54:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:54:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:54:02 INFO - Installing collected packages: psutil 16:54:02 INFO - Running setup.py install for psutil 16:54:02 INFO - building 'psutil._psutil_osx' extension 16:54:02 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 16:54:03 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 16:54:03 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 16:54:03 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 16:54:03 INFO - building 'psutil._psutil_posix' extension 16:54:03 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 16:54:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:54:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:54:03 INFO - ^ 16:54:03 INFO - 1 warning generated. 16:54:03 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 16:54:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:54:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:54:03 INFO - Successfully installed psutil 16:54:03 INFO - Cleaning up... 16:54:04 INFO - Return code: 0 16:54:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e86ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb67159bfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d2d30>, '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 0x10e537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.yknRzcgS4U/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.MqSZHeQlUM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:54:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yknRzcgS4U/Render', 16:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:04 INFO - 'HOME': '/Users/cltbld', 16:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:04 INFO - 'LOGNAME': 'cltbld', 16:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:04 INFO - 'MOZ_NO_REMOTE': '1', 16:54:04 INFO - 'NO_EM_RESTART': '1', 16:54:04 INFO - 'PAGER': '/bin/cat', 16:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:04 INFO - 'PWD': '/builds/slave/test', 16:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:04 INFO - 'SHELL': '/bin/bash', 16:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MqSZHeQlUM/Listeners', 16:54:04 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:04 INFO - 'USER': 'cltbld', 16:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:04 INFO - 'XPC_FLAGS': '0x0', 16:54:04 INFO - 'XPC_SERVICE_NAME': '0', 16:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:54:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:54:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:54:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:54:06 INFO - Installing collected packages: mozsystemmonitor 16:54:06 INFO - Running setup.py install for mozsystemmonitor 16:54:06 INFO - Successfully installed mozsystemmonitor 16:54:06 INFO - Cleaning up... 16:54:07 INFO - Return code: 0 16:54:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e86ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb67159bfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d2d30>, '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 0x10e537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.yknRzcgS4U/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.MqSZHeQlUM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:54:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:54:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yknRzcgS4U/Render', 16:54:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:07 INFO - 'HOME': '/Users/cltbld', 16:54:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:07 INFO - 'LOGNAME': 'cltbld', 16:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:07 INFO - 'MOZ_NO_REMOTE': '1', 16:54:07 INFO - 'NO_EM_RESTART': '1', 16:54:07 INFO - 'PAGER': '/bin/cat', 16:54:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:07 INFO - 'PWD': '/builds/slave/test', 16:54:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:07 INFO - 'SHELL': '/bin/bash', 16:54:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MqSZHeQlUM/Listeners', 16:54:07 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:54:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:07 INFO - 'USER': 'cltbld', 16:54:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:07 INFO - 'XPC_FLAGS': '0x0', 16:54:07 INFO - 'XPC_SERVICE_NAME': '0', 16:54:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:07 INFO - Downloading/unpacking jsonschema==2.5.1 16:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:54:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:54:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:54:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:10 INFO - Downloading functools32-3.2.3-2.tar.gz 16:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:54:10 INFO - warning: no files found matching '*.txt' 16:54:10 INFO - no previously-included directories found matching 'build' 16:54:10 INFO - no previously-included directories found matching 'dist' 16:54:10 INFO - no previously-included directories found matching '.git*' 16:54:10 INFO - Installing collected packages: jsonschema, functools32 16:54:10 INFO - Running setup.py install for functools32 16:54:10 INFO - warning: no files found matching '*.txt' 16:54:10 INFO - no previously-included directories found matching 'build' 16:54:10 INFO - no previously-included directories found matching 'dist' 16:54:10 INFO - no previously-included directories found matching '.git*' 16:54:10 INFO - Successfully installed jsonschema functools32 16:54:10 INFO - Cleaning up... 16:54:10 INFO - Return code: 0 16:54:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:54:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e86ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb67159bfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d2d30>, '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 0x10e537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.yknRzcgS4U/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.MqSZHeQlUM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:54:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:54:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:54:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yknRzcgS4U/Render', 16:54:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:10 INFO - 'HOME': '/Users/cltbld', 16:54:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:10 INFO - 'LOGNAME': 'cltbld', 16:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:10 INFO - 'MOZ_NO_REMOTE': '1', 16:54:10 INFO - 'NO_EM_RESTART': '1', 16:54:10 INFO - 'PAGER': '/bin/cat', 16:54:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:10 INFO - 'PWD': '/builds/slave/test', 16:54:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:10 INFO - 'SHELL': '/bin/bash', 16:54:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MqSZHeQlUM/Listeners', 16:54:10 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:54:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:10 INFO - 'USER': 'cltbld', 16:54:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:10 INFO - 'XPC_FLAGS': '0x0', 16:54:10 INFO - 'XPC_SERVICE_NAME': '0', 16:54:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:11 INFO - Downloading/unpacking blobuploader==1.2.4 16:54:11 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 16:54:11 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 16:54:11 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 16:54:11 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 16:54:11 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 16:54:11 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 16:54:13 INFO - Downloading blobuploader-1.2.4.tar.gz 16:54:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:54:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:54:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:54:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:54:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:14 INFO - Downloading docopt-0.6.1.tar.gz 16:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:54:14 INFO - Installing collected packages: blobuploader, requests, docopt 16:54:14 INFO - Running setup.py install for blobuploader 16:54:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:54:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:54:15 INFO - Running setup.py install for requests 16:54:15 INFO - Running setup.py install for docopt 16:54:15 INFO - Successfully installed blobuploader requests docopt 16:54:15 INFO - Cleaning up... 16:54:15 INFO - Return code: 0 16:54:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:54:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e86ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb67159bfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d2d30>, '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 0x10e537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.yknRzcgS4U/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.MqSZHeQlUM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:54:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:54:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yknRzcgS4U/Render', 16:54:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:15 INFO - 'HOME': '/Users/cltbld', 16:54:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:15 INFO - 'LOGNAME': 'cltbld', 16:54:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:15 INFO - 'MOZ_NO_REMOTE': '1', 16:54:15 INFO - 'NO_EM_RESTART': '1', 16:54:15 INFO - 'PAGER': '/bin/cat', 16:54:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:15 INFO - 'PWD': '/builds/slave/test', 16:54:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:15 INFO - 'SHELL': '/bin/bash', 16:54:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MqSZHeQlUM/Listeners', 16:54:15 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:54:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:15 INFO - 'USER': 'cltbld', 16:54:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:15 INFO - 'XPC_FLAGS': '0x0', 16:54:15 INFO - 'XPC_SERVICE_NAME': '0', 16:54:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:54:15 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-2R5UKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:54:16 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-QSp14K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:54:16 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-M6_9lY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:54:16 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-prp9Qd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:54:16 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-WhrvFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:54:16 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-Pr_8sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:54:16 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-6EE6NO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:54:16 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-Y_kpV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:54:16 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-dOK_Nt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:54:16 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-leDCwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:54:17 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-rLEI5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:54:17 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-LTcxfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:54:17 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-hQoJq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:54:17 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-yxpQ5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:54:17 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-FNlsV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:54:17 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-7uczoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:54:17 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-RCOK92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:54:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:54:17 INFO - Running setup.py install for manifestparser 16:54:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:54:17 INFO - Running setup.py install for mozcrash 16:54:18 INFO - Running setup.py install for mozdebug 16:54:18 INFO - Running setup.py install for mozdevice 16:54:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:54:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:54:18 INFO - Running setup.py install for mozfile 16:54:18 INFO - Running setup.py install for mozhttpd 16:54:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:54:18 INFO - Running setup.py install for mozinfo 16:54:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:54:18 INFO - Running setup.py install for mozInstall 16:54:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:54:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:54:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:54:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:54:18 INFO - Running setup.py install for mozleak 16:54:19 INFO - Running setup.py install for mozlog 16:54:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:54:19 INFO - Running setup.py install for moznetwork 16:54:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:54:19 INFO - Running setup.py install for mozprocess 16:54:19 INFO - Running setup.py install for mozprofile 16:54:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:54:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:54:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:54:19 INFO - Running setup.py install for mozrunner 16:54:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:54:19 INFO - Running setup.py install for mozscreenshot 16:54:19 INFO - Running setup.py install for moztest 16:54:20 INFO - Running setup.py install for mozversion 16:54:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:54:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:54:20 INFO - Cleaning up... 16:54:20 INFO - Return code: 0 16:54:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:54:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:54:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:54:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e86ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb67159bfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d2d30>, '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 0x10e537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.yknRzcgS4U/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.MqSZHeQlUM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:54:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:54:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:54:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yknRzcgS4U/Render', 16:54:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:20 INFO - 'HOME': '/Users/cltbld', 16:54:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:20 INFO - 'LOGNAME': 'cltbld', 16:54:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:20 INFO - 'MOZ_NO_REMOTE': '1', 16:54:20 INFO - 'NO_EM_RESTART': '1', 16:54:20 INFO - 'PAGER': '/bin/cat', 16:54:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:20 INFO - 'PWD': '/builds/slave/test', 16:54:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:20 INFO - 'SHELL': '/bin/bash', 16:54:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MqSZHeQlUM/Listeners', 16:54:20 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:54:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:20 INFO - 'USER': 'cltbld', 16:54:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:20 INFO - 'XPC_FLAGS': '0x0', 16:54:20 INFO - 'XPC_SERVICE_NAME': '0', 16:54:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:54:20 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-oYvE8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:54:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:54:20 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-z_i3Ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:54:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:54:20 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-_MzPU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:54:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:54:20 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-JD9g_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:54:21 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)) 16:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:54:21 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-b7pCj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:54:21 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)) 16:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:54:21 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-BqZ6T3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:54:21 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-ItFKZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:54:21 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-FpTL27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:54:21 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-XJ_bJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:54:21 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-oXmGjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 16:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:54:21 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-0kzvl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:54:21 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-jMMYy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:54:21 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-pEmC08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:54:21 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-3FdLC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:54:22 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)) 16:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:54:22 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-jhhbXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:54:22 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)) 16:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:54:22 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-Mgmjjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:54:22 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-44pxTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:54:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:54:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:54:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:54:24 INFO - Downloading blessings-1.6.tar.gz 16:54:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:54:24 INFO - Installing collected packages: blessings 16:54:24 INFO - Running setup.py install for blessings 16:54:25 INFO - Successfully installed blessings 16:54:25 INFO - Cleaning up... 16:54:25 INFO - Return code: 0 16:54:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:54:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:54:25 INFO - Reading from file tmpfile_stdout 16:54:25 INFO - Current package versions: 16:54:25 INFO - blessings == 1.6 16:54:25 INFO - blobuploader == 1.2.4 16:54:25 INFO - docopt == 0.6.1 16:54:25 INFO - functools32 == 3.2.3-2 16:54:25 INFO - jsonschema == 2.5.1 16:54:25 INFO - manifestparser == 1.1 16:54:25 INFO - mozInstall == 1.12 16:54:25 INFO - mozcrash == 0.17 16:54:25 INFO - mozdebug == 0.1 16:54:25 INFO - mozdevice == 0.48 16:54:25 INFO - mozfile == 1.2 16:54:25 INFO - mozhttpd == 0.7 16:54:25 INFO - mozinfo == 0.9 16:54:25 INFO - mozleak == 0.1 16:54:25 INFO - mozlog == 3.2 16:54:25 INFO - moznetwork == 0.27 16:54:25 INFO - mozprocess == 0.22 16:54:25 INFO - mozprofile == 0.28 16:54:25 INFO - mozrunner == 6.11 16:54:25 INFO - mozscreenshot == 0.1 16:54:25 INFO - mozsystemmonitor == 0.3 16:54:25 INFO - moztest == 0.7 16:54:25 INFO - mozversion == 1.4 16:54:25 INFO - psutil == 3.1.1 16:54:25 INFO - requests == 1.2.3 16:54:25 INFO - wsgiref == 0.1.2 16:54:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:54: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')]} 16:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54: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')]} 16:54:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e86ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb67159bfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d2d30>, '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 0x10e537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.yknRzcgS4U/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.MqSZHeQlUM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:54:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:54:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yknRzcgS4U/Render', 16:54:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:25 INFO - 'HOME': '/Users/cltbld', 16:54:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:25 INFO - 'LOGNAME': 'cltbld', 16:54:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:25 INFO - 'MOZ_NO_REMOTE': '1', 16:54:25 INFO - 'NO_EM_RESTART': '1', 16:54:25 INFO - 'PAGER': '/bin/cat', 16:54:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:25 INFO - 'PWD': '/builds/slave/test', 16:54:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:25 INFO - 'SHELL': '/bin/bash', 16:54:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MqSZHeQlUM/Listeners', 16:54:25 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:54:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:25 INFO - 'USER': 'cltbld', 16:54:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:25 INFO - 'XPC_FLAGS': '0x0', 16:54:25 INFO - 'XPC_SERVICE_NAME': '0', 16:54:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:54:25 INFO - Cleaning up... 16:54:25 INFO - Return code: 0 16:54:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:54: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')]} 16:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:54:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54: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')]} 16:54:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:54:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:54:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e86ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e903618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb67159bfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d2d30>, '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 0x10e537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.yknRzcgS4U/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.MqSZHeQlUM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 16:54:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:54:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yknRzcgS4U/Render', 16:54:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:54:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:54:25 INFO - 'HOME': '/Users/cltbld', 16:54:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:54:25 INFO - 'LOGNAME': 'cltbld', 16:54:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:54:25 INFO - 'MOZ_NO_REMOTE': '1', 16:54:25 INFO - 'NO_EM_RESTART': '1', 16:54:25 INFO - 'PAGER': '/bin/cat', 16:54:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:54:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:54:25 INFO - 'PWD': '/builds/slave/test', 16:54:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:54:25 INFO - 'SHELL': '/bin/bash', 16:54:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MqSZHeQlUM/Listeners', 16:54:25 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:54:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:54:25 INFO - 'USER': 'cltbld', 16:54:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:54:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:54:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:54:25 INFO - 'XPC_FLAGS': '0x0', 16:54:25 INFO - 'XPC_SERVICE_NAME': '0', 16:54:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:54:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:54:25 INFO - Cleaning up... 16:54:25 INFO - Return code: 0 16:54:25 INFO - Running post-action listener: _resource_record_post_action 16:54:25 INFO - Running post-action listener: _start_resource_monitoring 16:54:26 INFO - Starting resource monitoring. 16:54:26 INFO - ##### 16:54:26 INFO - ##### Finished create-virtualenv step (success) 16:54:26 INFO - ##### 16:54:26 INFO - ##### 16:54:26 INFO - ##### Running install step. 16:54:26 INFO - ##### 16:54:26 INFO - Running pre-action listener: _resource_record_pre_action 16:54:26 INFO - Running main action method: install 16:54:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:54:26 INFO - Reading from file tmpfile_stdout 16:54:26 INFO - Detecting whether we're running mozinstall >=1.0... 16:54:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:54:26 INFO - Reading from file tmpfile_stdout 16:54:26 INFO - Output received: 16:54:26 INFO - Usage: mozinstall [options] installer 16:54:26 INFO - Options: 16:54:26 INFO - -h, --help show this help message and exit 16:54:26 INFO - -d DEST, --destination=DEST 16:54:26 INFO - Directory to install application into. [default: 16:54:26 INFO - "/builds/slave/test"] 16:54:26 INFO - --app=APP Application being installed. [default: firefox] 16:54:26 INFO - mkdir: /builds/slave/test/build/application 16:54:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:55:00 INFO - Reading from file tmpfile_stdout 16:55:00 INFO - Output received: 16:55:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:55:00 INFO - Running post-action listener: _resource_record_post_action 16:55:00 INFO - ##### 16:55:00 INFO - ##### Finished install step (success) 16:55:00 INFO - ##### 16:55:00 INFO - ##### 16:55:00 INFO - ##### Running run-tests step. 16:55:00 INFO - ##### 16:55:00 INFO - Running pre-action listener: _resource_record_pre_action 16:55:00 INFO - Running main action method: run_tests 16:55:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:55:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:55:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:55:00 INFO - 2016-07-01 16:55:00.810 screenresolution[1642:7852] starting screenresolution argv=screenresolution get 16:55:00 INFO - 2016-07-01 16:55:00.813 screenresolution[1642:7852] Display 0: 1600x1200x32@60 16:55:00 INFO - 2016-07-01 16:55:00.818 screenresolution[1643:7854] starting screenresolution argv=screenresolution list 16:55:00 INFO - Available Modes on Display 0 16:55:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:55:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:55:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:55:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:55:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:55:01 INFO - Intel Iris: 16:55:01 INFO - Chipset Model: Intel Iris 16:55:01 INFO - Type: GPU 16:55:01 INFO - Bus: Built-In 16:55:01 INFO - VRAM (Dynamic, Max): 1536 MB 16:55:01 INFO - Vendor: Intel (0x8086) 16:55:01 INFO - Device ID: 0x0a2e 16:55:01 INFO - Revision ID: 0x0009 16:55:01 INFO - Displays: 16:55:01 INFO - Display: 16:55:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:55:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:55:01 INFO - Display Serial Number: 1600x1200 60 16:55:01 INFO - Main Display: Yes 16:55:01 INFO - Mirror: Off 16:55:01 INFO - Online: Yes 16:55:01 INFO - Rotation: Supported 16:55:01 INFO - Return code: 0 16:55:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:55:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:55:01 INFO - Python 2.7.3 16:55:01 INFO - Return code: 0 16:55:01 INFO - grabbing minidump binary from tooltool 16:55: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')]} 16:55:01 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d2d30>, '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 0x10e537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:55:01 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 16:55:01 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 16:55:01 INFO - Calling ['/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'] with output_timeout 600 16:55:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:55:01 INFO - Return code: 0 16:55:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:55:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:55:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:55:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:55:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:55:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0215', '--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 16:55:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0215 --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 16:55:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yknRzcgS4U/Render', 16:55:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:55:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:55:01 INFO - 'HOME': '/Users/cltbld', 16:55:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:55:01 INFO - 'LOGNAME': 'cltbld', 16:55:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:55:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:55:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:01 INFO - 'MOZ_NO_REMOTE': '1', 16:55:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:55:01 INFO - 'NO_EM_RESTART': '1', 16:55:01 INFO - 'PAGER': '/bin/cat', 16:55:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:55:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:01 INFO - 'PWD': '/builds/slave/test', 16:55:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:55:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:55:01 INFO - 'SHELL': '/bin/bash', 16:55:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MqSZHeQlUM/Listeners', 16:55:01 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 16:55:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:55:01 INFO - 'USER': 'cltbld', 16:55:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:55:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:55:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:01 INFO - 'XPC_FLAGS': '0x0', 16:55:01 INFO - 'XPC_SERVICE_NAME': '0', 16:55:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:55:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0215', '--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 16:55:01 INFO - mozversion application_buildid: 20160701145923 16:55:01 INFO - mozversion application_changeset: 3dcecf06e37b403d3c8a518b6848fbcf5e3c8c13 16:55:01 INFO - mozversion application_display_name: Nightly 16:55:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:55:01 INFO - mozversion application_name: Firefox 16:55:01 INFO - mozversion application_remotingname: firefox 16:55:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:55:01 INFO - mozversion application_vendor: Mozilla 16:55:01 INFO - mozversion application_version: 50.0a1 16:55:01 INFO - mozversion platform_buildid: 20160701145923 16:55:01 INFO - mozversion platform_changeset: 3dcecf06e37b403d3c8a518b6848fbcf5e3c8c13 16:55:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:55:01 INFO - mozversion platform_version: 50.0a1 16:55:01 INFO - using testdate: 1467417301 16:55:01 INFO - actual date: 1467417301 16:55:01 INFO - starting webserver on 'localhost:49267' 16:55:01 INFO - SUITE-START | Running 2 tests 16:55:01 INFO - TEST-START | dromaeo_css 16:55:01 INFO - operating with platform_type : mac_ 16:55:01 INFO - Initialising browser for dromaeo_css test... 16:55:01 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpasM93w/profile http://localhost:49267/getInfo.html) 16:55:03 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 16:55:03 INFO - PROCESS | 1653 | colorDepth:24 16:55:03 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 16:55:03 INFO - PROCESS | 1653 | __metrics 16:55:03 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:55:03 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:55:04 INFO - TEST-INFO | 1653: exit 0 16:55:04 INFO - Browser initialized. 16:55:04 INFO - Running cycle 1/1 for dromaeo_css test... 16:55:04 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpasM93w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:55:16 INFO - PROCESS | 1656 | RSS: Main: 244482048 16:55:16 INFO - PROCESS | 1656 | 16:56:08 INFO - PROCESS | 1656 | 1467417368311 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] 16:58:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 16:58:20 INFO - PROCESS | 1656 | RSS: Main: 377483264 16:58:20 INFO - PROCESS | 1656 | 17:01:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 17:01:24 INFO - PROCESS | 1656 | RSS: Main: 382316544 17:01:24 INFO - PROCESS | 1656 | 17:04:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 17:04:27 INFO - PROCESS | 1656 | RSS: Main: 372412416 17:04:27 INFO - PROCESS | 1656 | 17:07:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 17:07:30 INFO - PROCESS | 1656 | RSS: Main: 371249152 17:07:30 INFO - PROCESS | 1656 | 17:10:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 17:10:33 INFO - PROCESS | 1656 | RSS: Main: 340660224 17:10:33 INFO - PROCESS | 1656 | 17:13:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 17:13:37 INFO - PROCESS | 1656 | RSS: Main: 406712320 17:13:37 INFO - PROCESS | 1656 | 17:13:37 INFO - PROCESS | 1656 | __start_tp_report 17:13:37 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 17:13:37 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 17:13:37 INFO - PROCESS | 1656 | |i|pagename|runs| 17:13:37 INFO - PROCESS | 1656 | |0;dojo.html;3304.273059926816;3312.0560640217536;3323.401787678217;3352.0111567087706;3407.4888766684994;5447.536959358459;5489.560835133189;5527.198556209347;5586.469285417884;5643.943560564393;5543.695096769676;5572.275604171462;5589.944100558993;5592.552595792337;5601.103823388259;5088.821891233817;5117.181250999822;5151.742412879341;5194.856971992429;5211.947880521184;5156.175011998081;5216.269722238871;5221.269022336875;5226.843194704165;5265.394479634852;5081.59347252219;5109.182530795074;5110.565601923841;5114.539691427789;5204.947950520503;5179.223116532531;5199.986002729469;5202.4277329493725;5232.659877107976;5263.552598029134;4980.975095124501;5039.874003149951;5074.238864170367;5110.616703747197;5135.12702840518;5459.017376872162;5507.7246137693355;5550.72246387679;5550.944490555124;5557.666540007613;6100.877982440371;6137.447629713347;6140.6929653517145;6141.938580614182;6145.139680444741;5996.550258730613;6044.455998960071;6129.38706129388;6212.347703491158;6263.4362907338555;5612.354579223401;5661.2923384576925;5675.2054712340305;5684.2326285951285;5738.68437235956;6301.495880329563;6305.653189074645;6312.400321969406;6321.841953951188;6326.841828954313;6036.366181550961;6093.2078829751845;6097.993831017921;6106.877862442726;6120.540959428061;5952.136940143655;6076.118962750376;6107.249501910372;6108.918862504643;6211.409916057967;6096.207493025879;6118.388161183846;6119.510439164945;6125.487061644556;6133.6319820810895;5049.8737531562;5961.493273071837;6059.757609695563;6162.7226774795245;6195.039768835838;4873.9025219496125;4897.853064408074;4946.629002824802;4948.406191257072;4952.207646776498;4363.86539499726;4395.077033822869;4396.494403143615;4398.054418300081;4412.426384569985;5445.591580631469;5558.055130627803;5582.876914089989;5639.802606908738;5646.20953066571;6068.33248342682;6096.02463605821;6132.8756461173125;6169.2288463942;6238.594491358047;5564.165375193672;5566.332040155207;5570.832875013756;5576.498115169654;5592.552595792326;5245.370555533361;5374.032674118618;5393.164059570773;5398.352197736218;5407.053765591006;6205.720742566596;6232.971559692691;6308.400701933309;6309.085182648582;6337.493000560036;6169.2288463942;6179.073139029181;6191.473724733448;6208.503319734501;6209.875802484015;6148.846278842886;6317.336679648754;6317.810465686037;6332.778352757631;6359.14151589548;4936.308916751729;5901.940980590139;5902.763889444546;5931.970340148272;6004.699765011819;6153.815385538441;6159.6612186330185;6161.537884658579;6180.011198208265;6184.5052395809125;5770.769169233352;5777.306723193244;5848.824535263949;5907.172995780508;5916.792912248051;5819.748754017906;5861.120831875252;5864.912026319694;5873.911891321548;5895.734691938787;5765.135229715576;5768.913466297925;5779.2775903012125;5782.363939966516;5847.795327163529;5209.6874187548865;5669.34802497718;5894;5946.345901950695;5963.254593175853;5674.9785038693435;5738.770449182153;5761.654300741969;5802.65184088956;5808.680522571299 17:13:37 INFO - PROCESS | 1656 | |1;ext.html;471.9716816990981;501.8006963357576;536.1582315764251;536.4876542901529;547.671397161703;29619.703802961965;30672.233194170156;31617.841910790416;31709.524357134662;31736.682633173663;31939.16729992404;32455.67544324556;41753.373699394455;42790.78604606973;43002.13995720092;9710.999999999982;9859.556319965619;9865.605375784995;9867.358621689567;9900.108990190882;9703.902960970407;9797.167240784505;9808.705738827846;9810.264230182765;9821.116099551038;8651.610677519495;8843.159899809507;11709.824352634718;11958.103142264321;12020.939895300511;11833.230839995373;11930.58242961501;11933.940330298294;11935.687074421807;11943.402829858473;8630.525321107336;11900.226485278428;11965.222260553037;11991.520339186422;12035.338056406894;39762.403563946566;40149.79925100385;40188.59811401907;42368.57631423708;42372.78813605912;3344.2308269098244;3388.6611338866164;3562.6615471530163;3664.7617904836366;3694.224212915291;30641.38717225643;31162;32128.357432851444;32374.83812580922;32414.351712965843;3242.935141297161;3344.347852168828;3346.0631023313513;3411.6076651185185;3436.6906978371817;424354.8782256069;462231;462271;464127;465904;60882.39117608856;61283.08075378873;61642.38357616456;61683.691581542706;61849.69075154595;64613.35386646167;64657;64700.794448360706;64730.352696473376;64792.028119578245;15890.841091588936;16352.75470867938;16375.918120409322;16402.179891005402;16432.178391080397;12713;12714.745705085847;12795.552155674693;12797;12799.424025918855;16248.51254462368;16368.918155409146;18886.150123244486;19013.42959711211;19157.712634310497;7196.4962452627815;7889.368850491946;7936.920630793618;8003.239692229229;8012.559309238048;859.9728954081597;971.3200759468259;974.356924429887;1000.7047920863217;1010.888802231754;26710.931562737274;29357.412851742847;30114.096577102722;30483.2379190522;30677.3325881159;1058.8041212375736;1106.2753896198003;1134.1267224237456;1189.7382575833303;1203.6449247472017;13627.863721362659;15782.842171578366;15863;16002.75995860063;16193.757093643604;709182;710905;717656;718650;720390;50574;56609;56994;58945.41054589399;59415.70292148511;1371.615947534692;1393.7560926837764;1395.44182327072;1403.1791402029926;1403.635054885737;30288.394232114795;30767.692323076484;31066.844665776527;31509.8424507876;31794.68205317917;49525.76185595389;49689.254661180836;50095;50191.243306484095;50202.246966294806;576394;607745;672387;681528;870043;25834.483310334064;26168.34579135537;26178.60732088982;26188;26296.474070518867;44770.55229447664;45440.77279613581;45837.541624584665;46300;46481.53518464772;29719.554206686484;38251.80874095612;38374.616253837106;38478;38491;36166.914992550264;36573;38481;38781.612183878926;39359.2128157441;36348.81825590961;37024.07439814026;37073.07317317089;37834;38022.809885950395;29173.124806255848;29660.258493537833;31934.28749222153;33532.83233583817;33858.49212261769;24831.503369932863;24857.751422485544;24992.000319986997;25370.1120460783;25558.872205638854 17:13:37 INFO - PROCESS | 1656 | |2;jquery.html;362.071287148464;369.46058754218836;388.87555982085746;391.3601261936734;408.0675656125753;15667.45163919263;15762.763558546616;18259.447946924513;19370.515737106543;20046.799532004676;19929.40211793645;20865.26971555996;21264.8936755316;21655.56688866222;21667.808270545112;4826.517348265163;5259.76331065102;5441.7279136043235;5450.972745136269;6051.0318349064155;3896.7272290939645;4094.263032654121;5982.730777115018;6335.714892829811;6620.503462240327;6289.7169627366875;6608.603483790964;6615.93384066158;6617.007448882658;6641.070250164981;5931.970340148293;5996;6001.969990150022;6002.459778619901;6009.008513595251;5122.795088196505;5474.39781624021;5814.622049566765;5823.456783952213;5832.562557808182;12905.419256133402;13132.474701011948;13137.014723895745;13155.60533184006;21383.67924481134;2255.661650752384;2302.9424264393356;2331.8717470539113;2381.0951838301507;2868.928276793076;9665.155145442695;9782.364146330467;9807.019298070136;10289.382637041726;10441.738956526071;1857.535616095976;1877.2303355624265;1904.6571617108914;1917.319351630165;1958.8530860185542;115174.42412787967;120967.79032209741;121940;125124;127623;9680.757981050388;10970.451477426097;11517.25137866036;11556.191066625255;11557.364344961108;14301.57095287143;14380.921430892726;14412;14416.711665766627;14447.855521444651;8477.70328038528;8675;8747.518886461306;8785.472871627595;8789.868151977726;8677.913220867711;8683.565821709015;8697.608607612547;8799.912000880036;8846.911530884609;4523.77857978344;4602.596208156478;4605.516420775837;4661.160991021648;4691.366665500182;1712.5547357686842;1730.8134541648806;1825.4706135220638;1829.890206587609;1832.7800663920148;781.4178437064423;800.6437135474658;849.3502470609989;858.4377232912452;881.2069137775925;17289.308427663003;19633.803661963484;21265.78734212669;21931.780682193294;22171.44571385696;862;878.4202426398547;899.8785164002907;930.1767935377104;992.161623428202;10828.512716927757;11000.944995274973;11027.779444411068;11089.112870970303;11130.944345278222;268455;633019;633987;645314;651141;129214;130191;139692;141286;144884.27557862355;860.8278344331034;970.5147426286857;977.4819345746623;983.2772911909741;1014.4876837197315;18015.66710158625;18220.544486387946;18285.26858925681;18447;18711.532211694815;58097.12854307274;58961;59169.70415147897;59462.10806837987;60158.39841601528;73609.89585156334;74859.62570187115;85831.57084214539;86564;87660.56169719111;7825.139234684067;8136.918630813853;8252.46358986664;8270.917290827256;8512.787180320542;9229.67696130659;13033.413496392495;13338.399772010089;13384.263865487492;13413.731725365244;6811.659417029228;11447.656570302905;11521.82717259225;11841.407929603658;11926.403679815801;10318.48407579633;11189.936955989167;11748.609500014818;11749.882501175221;11779.293242405483;11710.824337634773;11989.82015269789;12093.818592721293;12171.695707607381;12191.573294934635;6932;8403.453775504573;8462.746117616482;8491.83016339682;8605.526696031528;11546.653600392001;11726.530938762355;11886;11896.76206475883;11935.343556104497 17:13:37 INFO - PROCESS | 1656 | |3;mootools.html;1373.5124030969005;1493.8041079612972;1659.9253033613493;1840.5950690848008;1868.4768264885836;7119.216886142527;8840.60217290222;9013.05362936892;9027.90972090279;9028.322875784317;7811.609419529015;8041.316488098519;8299.668013279477;8558.186972237643;8824.691135810248;2359.079958816059;2381.9761802382013;2385.0459816073535;2385.9880700596473;2393.5093305872256;2360.1975328388344;2373.9169129080487;2391.234804862445;2395.0659242895285;2395.8682272475007;2108.947276318089;2188.026328283914;2191.8904054797276;2193.517426166241;2314.2478694424294;2301.1945818963313;2374.548835721225;2376.5840977828984;2378.881055947196;2386.510765293119;2342.320726989171;2357.3635118517905;2362.6928499295154;2371.940701482477;2372.6203807390734;8860.822783544358;8937.64249430022;8950.86573701395;8951.462912225223;8959.73120806377;5275.076861549213;5359.839204823861;5425.701586412746;5430.321209848769;5433.510984011458;3005.827727186399;3487.1805125795418;3575.713942884563;3632.382494975834;3712.3874560697454;1064.7444613292832;1066.477426061232;1081.6754973507916;1102.6857345656529;1112.9499172537255;11716.707082322924;11785.116116291312;11800.114991375596;11805.937465628134;11816.636672665501;4529.909401811945;4739;4825.9034819303415;4847.345608342828;4850.175470170079;9926.454045027593;9932.106110450095;9956.153726933291;9973.002699730114;9997.200223982063;5362.356517217959;5364.114921038063;5364.758585863646;5365.812196573102;5372.1941708744;4293.441852559147;4440.977795111004;4557.179707652588;4589.2886602576655;4926.531979461945;3932.528096628423;4053.6554392876938;4054.270231358325;4071.714979951434;4077.673786097105;1261.8611952685196;1280.647821848999;1282.9166104203384;1286.5689993851977;1305.8041293805816;904.977375565603;921.9950254222929;925.1257561604353;932.1563984593953;965.1940629574384;5912.940870591325;5933.732982015733;5953.1070339449425;6106.755729770848;6194.163787888578;429.98065087071154;443.7803287372771;454.1008802570928;457.6201752545379;460.09361557731216;3976.622220889011;4010.2380547695843;4055.4930633670792;4092.7135100542678;4115.835366585303;9888.65389711371;10815.891841081488;10900.618478353219;10916.563337466412;10949.616763413242;10169.338992965433;10741.731456713645;10761.246712730033;10885.891141088647;10924.344539327665;8121.837563248702;8165.428420010661;8200.958995204986;8309.086000539935;8343.41596088268;5981.850453738517;6003.549733770072;6100.603460775035;6127.387261274015;6287.622742635456;9770.706878793648;9867.950660246654;10323.019313165238;10350.068493835592;10356.067953883885;9395.577199025924;9715;10043.748906277402;10459.84310235332;10492.842607361048;6394.392532709385;7345.22875098107;7592.734254301073;7719.3052625264;7724.111727151225;8075.475094118863;8474.322054235523;8476.872846907178;8537.573121343783;8554.91445085541;7514.736984205527;7591.582462964369;7600.125985511737;7659;7797.688092476237;7393.667284972296;7414.036175297177;7467.850642987001;7510.737124200627;7555.282248186413;7038.577685338896;7155.177154627285;7158.248383919822;7172.964135179291;7175.641217939186;4911.140550403737;5012.398512178562;5058.696478211319;5072.34059572268;5153.7165455898785;5005.724685142352;5092.618053646065;5099.643024988215;5108.846734597968;5226.398964119175 17:13:37 INFO - PROCESS | 1656 | |4;prototype.html;259.93761497240655;291.96350456192977;298.11905818306906;305.25365481397984;309.40130846811405;5247.370315562129;5373.40892501825;5385.764907563356;5402.892871780009;5448.439233560221;4970.527799859016;5003.4996500350035;5128.871388453041;5249.343832020997;5319.175527793189;3832.8466861325514;4012.61880121388;4033.5966403359575;4045.63589276965;4090.877273681794;3328.3010567780793;4047.898802529931;4088.4889388826396;4097.664331331142;4106.67146628269;3700.2229531798353;3722.9069273268115;3825.9234815303676;3877.379619260919;4018.7588744675413;3593.838277277529;4044.5146582410007;4049.8582549610624;4058.8782336529953;4067.78305823327;2975.1256708273863;3038.468268053082;3419.401604719189;3511;3528.3825330567047;4971.229459433794;5156.896862062776;5163.586913046947;5172.663776854492;5182.274481572583;3929.7249192556533;4606.631469482433;4624.375709279271;4626.653001024936;4668.719876807369;5586.217929489874;5600.143926597424;5602.991461536922;5624.409437009096;5674.262345895048;3414.3854106260865;3647.3252448296885;3920.6471417572557;3926.1951299983434;3944.9013774655577;5788.305403351583;5848.678322692293;5878.41215878413;5882.6470411775;5902.498287645512;3501.6848483636595;3991.201759648082;4003.459532963071;4005.35914253718;4007.218592374459;4047.817848196838;4060.715749897479;4061.5329237138108;4074.005605879676;4081.9591004293716;4321.524632290446;4419.292913133883;4638.466576343763;4658.557437043475;4684.414448193976;4790.377250957354;5755.884882302415;5760.596758226968;5761.798337058267;5775.902578510069;1371.0471222500466;1388.5279005138302;1390.9721805564034;1396.5042409944526;1407.619942615488;773.8761798112275;812.7155495576501;833.8707500337457;859.8632398489748;874.0472884555737;3936.5472970608175;4245.214635292481;4467.347081579771;4494.247243575009;4522.412086428743;4188.465978083276;4865.805367785249;4877;4923.507649235119;4976.353074100416;3744.137311645577;4289.520115560127;4424.80088396023;4456.509783923766;4481.3726078349055;2201.721218538805;2385.200957679162;2553.7190909000365;2572.070946789908;2577.383955285387;4129.843643779746;4535.501094879561;4536.72779633223;4938.678985865908;5055.747212639427;4955.306257124005;4956.306117143603;4967.4784147664695;4970.801167953314;5307.283516725192;5050.444451110375;5415.566754659618;6061.454469097803;6108.786192483241;6131.294901086343;4980.178270585313;5628.457866458964;5802.622829516068;5923.703814809329;5966.343702192842;4850.369451971221;5180.766865490987;5696.094991554254;5776;5820.359760426434;4506.932396014128;5646.44722701244;5703.743331550173;5709.286339207599;6113.860148948011;3230.773845830863;3267.9509807352433;3557.395242808677;3915.5497117830673;3920.3923391874305;5724.398938111438;5886.587938844411;5902.1441890926435;5934.172458764159;5955.523558115254;4936.679115857458;5659.915101273402;5674.801381951612;5710.229119068873;5735.9139612905;4244.787760612019;4658.324542941186;5579.7489112990825;5734.684592347461;5756.913646295393;4580.427446569179;5082.491750824888;5097.337346144977;5237.292965449616;5591.30108736408;5638.4925356718095;5682.9147562785765;5683.346415162309;5695.2311437957005;5704.372519022987;4585.358049873087;5348.358197016383;5500.834974950758;5591.6365436246515;5601.663900166003 17:13:37 INFO - PROCESS | 1656 | |5;yui.html;646.6475770704965;728.7412968396219;761.9619019049046;796.2197046894044;812.3744716568244;15127;16082.678346433064;16095.517134485985;16101.35594576217;16162.83837161628;12579.937100314508;13020;14835.851641483581;14846.480373186942;14846.703065938702;680.1226417920876;845.5391811462767;852.3777642321078;862.1077185113409;865.4547634989948;701.5404909784099;845.3321875718175;851.3146916732028;875.382855087162;880.0363601855958;900.7928176519405;900.9909900900973;901.3825529512272;905.2622112977934;905.9365844390895;722.9710811567584;886.7827382291315;900.3472482450204;901.8872640919885;903.701778413122;675.8040168351172;677.11298199359;677.474956908395;678.6742363665419;679.5005670831935;12367.93816030914;15033.398664053535;15051.397944082333;15054.473093441677;15061.472848450252;11938.522459101625;13684.178949263076;13845.723085538333;13867.514636987758;14035.368408421646;12310.938445307806;12409.182215204364;12411.379431028412;12541.372931353395;13054.54309099177;4471.843485478025;4587.885302867422;4593.954060459386;4607.032523170138;4630.907381852344;112505.43747281293;113943.43028284889;119374;119538.4023079879;119615.4019229907;10689;10873.945630271797;10901.236913416144;11136.61021864247;11296;21280.148794048066;21301.36095917125;21305.573888522144;21316.655167931516;21379.465513361978;7631;7643.656035478417;7671.6164191790185;7677.808054798675;7770.45606807518;4244.617984381359;4260.978695106566;4307.440032795716;4331.133773245364;4457.665675074382;3419.4357930941114;3500.299940011957;3513.297340531904;3514.2444374459615;3524.154202991315;563.8956792993309;658.308775785433;665.8268850098913;667.1127400557248;667.1827011910468;606.666333516573;618.6721037849946;618.7432215630553;636.4971672378862;645.270843946347;7640.274173953466;8833.734987950373;8904.643814247358;8942.37403381757;9275.443473391619;517.2448225590605;568.9743961521741;589.5784514072459;618.6937465954381;640.4652115483531;3751.7748935063983;4168.687348448879;4256.553061928515;4385.429894113745;4440.955590444119;91677.54161229284;138111;151884.48115518925;173409.26590734185;173475.26524734843;144265.5573444273;144723;144977.27511362376;145003;153267.2336638332;113946;114777.85222147839;122940;123545.38227308985;124949;8407.327413807003;8478.618462169094;8534.658613655385;8774.166454186841;8907.866382004404;41884.79057604692;43243.13513729645;46267;55982.76068957196;58661;94205.5289723547;96697;101194;103808;105434;10570.365778053341;12169.391530423267;13029.674258143623;13270.27013514266;13789.931050345085;14603.561893143222;15234.314455849291;15563.922180389025;15581.610459738144;15662.451814186448;13872.445102196203;13969.231692257023;14003.089799163023;14013.719725605635;14149.858501414854;12613.936930315656;13400.396982136022;13706.725865482435;13973.441062357391;14007.719845602827;11679.299242045165;11781.469833857325;12933;13510.79733803974;13644.522441714493;11187.552497899993;11474.598389056342;11655.708607284885;11760.294382337088;12171.059154817756;11223.831642525205;11224.631775626705;11529.942350288531;11597.76804463923;11617.941910290394 17:13:37 INFO - PROCESS | 1656 | __end_tp_report 17:13:37 INFO - PROCESS | 1656 | __start_cc_report 17:13:37 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1332 17:13:37 INFO - PROCESS | 1656 | __end_cc_report 17:13:37 INFO - PROCESS | 1656 | __startTimestamp1467418417263__endTimestamp 17:13:37 INFO - PROCESS | 1656 | 17:13:37 INFO - PROCESS | 1656 | ------- Summary: start ------- 17:13:37 INFO - PROCESS | 1656 | Number of tests: 6 17:13:37 INFO - PROCESS | 1656 | 17:13:37 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5631.15 Median:5765.14 stddev:619.74 (10.7%) stddev-sans-first:594.15 17:13:37 INFO - PROCESS | 1656 | Values: 3304.3 3312.1 3323.4 3352.0 3407.5 5447.5 5489.6 5527.2 5586.5 5643.9 5543.7 5572.3 5589.9 5592.6 5601.1 5088.8 5117.2 5151.7 5194.9 5211.9 5156.2 5216.3 5221.3 5226.8 5265.4 5081.6 5109.2 5110.6 5114.5 5204.9 5179.2 5200.0 5202.4 5232.7 5263.6 4981.0 5039.9 5074.2 5110.6 5135.1 5459.0 5507.7 5550.7 5550.9 5557.7 6100.9 6137.4 6140.7 6141.9 6145.1 5996.6 6044.5 6129.4 6212.3 6263.4 5612.4 5661.3 5675.2 5684.2 5738.7 6301.5 6305.7 6312.4 6321.8 6326.8 6036.4 6093.2 6098.0 6106.9 6120.5 5952.1 6076.1 6107.2 6108.9 6211.4 6096.2 6118.4 6119.5 6125.5 6133.6 5049.9 5961.5 6059.8 6162.7 6195.0 4873.9 4897.9 4946.6 4948.4 4952.2 4363.9 4395.1 4396.5 4398.1 4412.4 5445.6 5558.1 5582.9 5639.8 5646.2 6068.3 6096.0 6132.9 6169.2 6238.6 5564.2 5566.3 5570.8 5576.5 5592.6 5245.4 5374.0 5393.2 5398.4 5407.1 6205.7 6233.0 6308.4 6309.1 6337.5 6169.2 6179.1 6191.5 6208.5 6209.9 6148.8 6317.3 6317.8 6332.8 6359.1 4936.3 5901.9 5902.8 5932.0 6004.7 6153.8 6159.7 6161.5 6180.0 6184.5 5770.8 5777.3 5848.8 5907.2 5916.8 5819.7 5861.1 5864.9 5873.9 5895.7 5765.1 5768.9 5779.3 5782.4 5847.8 5209.7 5669.3 5894.0 5946.3 5963.3 5675.0 5738.8 5761.7 5802.7 5808.7 17:13:37 INFO - PROCESS | 1656 | 17:13:37 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:73978.41 Median:36257.87 stddev:169509.05 (467.5%) stddev-sans-first:169894.72 17:13:37 INFO - PROCESS | 1656 | Values: 472.0 501.8 536.2 536.5 547.7 29619.7 30672.2 31617.8 31709.5 31736.7 31939.2 32455.7 41753.4 42790.8 43002.1 9711.0 9859.6 9865.6 9867.4 9900.1 9703.9 9797.2 9808.7 9810.3 9821.1 8651.6 8843.2 11709.8 11958.1 12020.9 11833.2 11930.6 11933.9 11935.7 11943.4 8630.5 11900.2 11965.2 11991.5 12035.3 39762.4 40149.8 40188.6 42368.6 42372.8 3344.2 3388.7 3562.7 3664.8 3694.2 30641.4 31162.0 32128.4 32374.8 32414.4 3242.9 3344.3 3346.1 3411.6 3436.7 424354.9 462231.0 462271.0 464127.0 465904.0 60882.4 61283.1 61642.4 61683.7 61849.7 64613.4 64657.0 64700.8 64730.4 64792.0 15890.8 16352.8 16375.9 16402.2 16432.2 12713.0 12714.7 12795.6 12797.0 12799.4 16248.5 16368.9 18886.2 19013.4 19157.7 7196.5 7889.4 7936.9 8003.2 8012.6 860.0 971.3 974.4 1000.7 1010.9 26710.9 29357.4 30114.1 30483.2 30677.3 1058.8 1106.3 1134.1 1189.7 1203.6 13627.9 15782.8 15863.0 16002.8 16193.8 709182.0 710905.0 717656.0 718650.0 720390.0 50574.0 56609.0 56994.0 58945.4 59415.7 1371.6 1393.8 1395.4 1403.2 1403.6 30288.4 30767.7 31066.8 31509.8 31794.7 49525.8 49689.3 50095.0 50191.2 50202.2 576394.0 607745.0 672387.0 681528.0 870043.0 25834.5 26168.3 26178.6 26188.0 26296.5 44770.6 45440.8 45837.5 46300.0 46481.5 29719.6 38251.8 38374.6 38478.0 38491.0 36166.9 36573.0 38481.0 38781.6 39359.2 36348.8 37024.1 37073.1 37834.0 38022.8 29173.1 29660.3 31934.3 33532.8 33858.5 24831.5 24857.8 24992.0 25370.1 25558.9 17:13:37 INFO - PROCESS | 1656 | 17:13:37 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:34453.89 Median:1615.50 stddev:98559.37 (6100.9%) stddev-sans-first:98802.61 17:13:37 INFO - PROCESS | 1656 | Values: 362.1 369.5 388.9 391.4 408.1 15667.5 15762.8 18259.4 19370.5 20046.8 19929.4 20865.3 21264.9 21655.6 21667.8 4826.5 5259.8 5441.7 5451.0 6051.0 3896.7 4094.3 5982.7 6335.7 6620.5 6289.7 6608.6 6615.9 6617.0 6641.1 5932.0 5996.0 6002.0 6002.5 6009.0 5122.8 5474.4 5814.6 5823.5 5832.6 12905.4 13132.5 13137.0 13155.6 21383.7 2255.7 2302.9 2331.9 2381.1 2868.9 9665.2 9782.4 9807.0 10289.4 10441.7 1857.5 1877.2 1904.7 1917.3 1958.9 115174.4 120967.8 121940.0 125124.0 127623.0 9680.8 10970.5 11517.3 11556.2 11557.4 14301.6 14380.9 14412.0 14416.7 14447.9 8477.7 8675.0 8747.5 8785.5 8789.9 8677.9 8683.6 8697.6 8799.9 8846.9 4523.8 4602.6 4605.5 4661.2 4691.4 1712.6 1730.8 1825.5 1829.9 1832.8 781.4 800.6 849.4 858.4 881.2 17289.3 19633.8 21265.8 21931.8 22171.4 862.0 878.4 899.9 930.2 992.2 10828.5 11000.9 11027.8 11089.1 11130.9 268455.0 633019.0 633987.0 645314.0 651141.0 129214.0 130191.0 139692.0 141286.0 144884.3 860.8 970.5 977.5 983.3 1014.5 18015.7 18220.5 18285.3 18447.0 18711.5 58097.1 58961.0 59169.7 59462.1 60158.4 73609.9 74859.6 85831.6 86564.0 87660.6 7825.1 8136.9 8252.5 8270.9 8512.8 9229.7 13033.4 13338.4 13384.3 13413.7 6811.7 11447.7 11521.8 11841.4 11926.4 10318.5 11189.9 11748.6 11749.9 11779.3 11710.8 11989.8 12093.8 12171.7 12191.6 6932.0 8403.5 8462.7 8491.8 8605.5 11546.7 11726.5 11886.0 11896.8 11935.3 17:13:37 INFO - PROCESS | 1656 | 17:13:37 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5732.64 Median:4966.13 stddev:3232.81 (65.1%) stddev-sans-first:3225.28 17:13:37 INFO - PROCESS | 1656 | Values: 1373.5 1493.8 1659.9 1840.6 1868.5 7119.2 8840.6 9013.1 9027.9 9028.3 7811.6 8041.3 8299.7 8558.2 8824.7 2359.1 2382.0 2385.0 2386.0 2393.5 2360.2 2373.9 2391.2 2395.1 2395.9 2108.9 2188.0 2191.9 2193.5 2314.2 2301.2 2374.5 2376.6 2378.9 2386.5 2342.3 2357.4 2362.7 2371.9 2372.6 8860.8 8937.6 8950.9 8951.5 8959.7 5275.1 5359.8 5425.7 5430.3 5433.5 3005.8 3487.2 3575.7 3632.4 3712.4 1064.7 1066.5 1081.7 1102.7 1112.9 11716.7 11785.1 11800.1 11805.9 11816.6 4529.9 4739.0 4825.9 4847.3 4850.2 9926.5 9932.1 9956.2 9973.0 9997.2 5362.4 5364.1 5364.8 5365.8 5372.2 4293.4 4441.0 4557.2 4589.3 4926.5 3932.5 4053.7 4054.3 4071.7 4077.7 1261.9 1280.6 1282.9 1286.6 1305.8 905.0 922.0 925.1 932.2 965.2 5912.9 5933.7 5953.1 6106.8 6194.2 430.0 443.8 454.1 457.6 460.1 3976.6 4010.2 4055.5 4092.7 4115.8 9888.7 10815.9 10900.6 10916.6 10949.6 10169.3 10741.7 10761.2 10885.9 10924.3 8121.8 8165.4 8201.0 8309.1 8343.4 5981.9 6003.5 6100.6 6127.4 6287.6 9770.7 9868.0 10323.0 10350.1 10356.1 9395.6 9715.0 10043.7 10459.8 10492.8 6394.4 7345.2 7592.7 7719.3 7724.1 8075.5 8474.3 8476.9 8537.6 8554.9 7514.7 7591.6 7600.1 7659.0 7797.7 7393.7 7414.0 7467.9 7510.7 7555.3 7038.6 7155.2 7158.2 7173.0 7175.6 4911.1 5012.4 5058.7 5072.3 5153.7 5005.7 5092.6 5099.6 5108.8 5226.4 17:13:37 INFO - PROCESS | 1656 | 17:13:37 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4423.25 Median:4858.09 stddev:1389.38 (28.6%) stddev-sans-first:1357.68 17:13:37 INFO - PROCESS | 1656 | Values: 259.9 292.0 298.1 305.3 309.4 5247.4 5373.4 5385.8 5402.9 5448.4 4970.5 5003.5 5128.9 5249.3 5319.2 3832.8 4012.6 4033.6 4045.6 4090.9 3328.3 4047.9 4088.5 4097.7 4106.7 3700.2 3722.9 3825.9 3877.4 4018.8 3593.8 4044.5 4049.9 4058.9 4067.8 2975.1 3038.5 3419.4 3511.0 3528.4 4971.2 5156.9 5163.6 5172.7 5182.3 3929.7 4606.6 4624.4 4626.7 4668.7 5586.2 5600.1 5603.0 5624.4 5674.3 3414.4 3647.3 3920.6 3926.2 3944.9 5788.3 5848.7 5878.4 5882.6 5902.5 3501.7 3991.2 4003.5 4005.4 4007.2 4047.8 4060.7 4061.5 4074.0 4082.0 4321.5 4419.3 4638.5 4658.6 4684.4 4790.4 5755.9 5760.6 5761.8 5775.9 1371.0 1388.5 1391.0 1396.5 1407.6 773.9 812.7 833.9 859.9 874.0 3936.5 4245.2 4467.3 4494.2 4522.4 4188.5 4865.8 4877.0 4923.5 4976.4 3744.1 4289.5 4424.8 4456.5 4481.4 2201.7 2385.2 2553.7 2572.1 2577.4 4129.8 4535.5 4536.7 4938.7 5055.7 4955.3 4956.3 4967.5 4970.8 5307.3 5050.4 5415.6 6061.5 6108.8 6131.3 4980.2 5628.5 5802.6 5923.7 5966.3 4850.4 5180.8 5696.1 5776.0 5820.4 4506.9 5646.4 5703.7 5709.3 6113.9 3230.8 3268.0 3557.4 3915.5 3920.4 5724.4 5886.6 5902.1 5934.2 5955.5 4936.7 5659.9 5674.8 5710.2 5735.9 4244.8 4658.3 5579.7 5734.7 5756.9 4580.4 5082.5 5097.3 5237.3 5591.3 5638.5 5682.9 5683.3 5695.2 5704.4 4585.4 5348.4 5500.8 5591.6 5601.7 17:13:37 INFO - PROCESS | 1656 | 17:13:37 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:25582.77 Median:21348.06 stddev:42205.70 (197.7%) stddev-sans-first:42282.57 17:13:37 INFO - PROCESS | 1656 | Values: 646.6 728.7 762.0 796.2 812.4 15127.0 16082.7 16095.5 16101.4 16162.8 12579.9 13020.0 14835.9 14846.5 14846.7 680.1 845.5 852.4 862.1 865.5 701.5 845.3 851.3 875.4 880.0 900.8 901.0 901.4 905.3 905.9 723.0 886.8 900.3 901.9 903.7 675.8 677.1 677.5 678.7 679.5 12367.9 15033.4 15051.4 15054.5 15061.5 11938.5 13684.2 13845.7 13867.5 14035.4 12310.9 12409.2 12411.4 12541.4 13054.5 4471.8 4587.9 4594.0 4607.0 4630.9 112505.4 113943.4 119374.0 119538.4 119615.4 10689.0 10873.9 10901.2 11136.6 11296.0 21280.1 21301.4 21305.6 21316.7 21379.5 7631.0 7643.7 7671.6 7677.8 7770.5 4244.6 4261.0 4307.4 4331.1 4457.7 3419.4 3500.3 3513.3 3514.2 3524.2 563.9 658.3 665.8 667.1 667.2 606.7 618.7 618.7 636.5 645.3 7640.3 8833.7 8904.6 8942.4 9275.4 517.2 569.0 589.6 618.7 640.5 3751.8 4168.7 4256.6 4385.4 4441.0 91677.5 138111.0 151884.5 173409.3 173475.3 144265.6 144723.0 144977.3 145003.0 153267.2 113946.0 114777.9 122940.0 123545.4 124949.0 8407.3 8478.6 8534.7 8774.2 8907.9 41884.8 43243.1 46267.0 55982.8 58661.0 94205.5 96697.0 101194.0 103808.0 105434.0 10570.4 12169.4 13029.7 13270.3 13789.9 14603.6 15234.3 15563.9 15581.6 15662.5 13872.4 13969.2 14003.1 14013.7 14149.9 12613.9 13400.4 13706.7 13973.4 14007.7 11679.3 11781.5 12933.0 13510.8 13644.5 11187.6 11474.6 11655.7 11760.3 12171.1 11223.8 11224.6 11529.9 11597.8 11617.9 17:13:37 INFO - PROCESS | 1656 | -------- Summary: end -------- 17:13:37 INFO - PROCESS | 1656 | 17:13:37 INFO - TEST-INFO | 1656: exit 0 17:13:37 INFO - TEST-OK | dromaeo_css | took 1116476ms 17:13:37 INFO - TEST-START | kraken 17:13:37 INFO - operating with platform_type : mac_ 17:13:37 INFO - Initialising browser for kraken test... 17:13:37 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmp5hGfCu/profile http://localhost:49267/getInfo.html) 17:13:40 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 17:13:40 INFO - PROCESS | 1661 | colorDepth:24 17:13:40 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 17:13:40 INFO - PROCESS | 1661 | __metrics 17:13:40 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:13:40 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:13:40 INFO - TEST-INFO | 1661: exit 0 17:13:40 INFO - Browser initialized. 17:13:40 INFO - Running cycle 1/1 for kraken test... 17:13:40 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmp5hGfCu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:13:52 INFO - PROCESS | 1662 | RSS: Main: 244711424 17:13:52 INFO - PROCESS | 1662 | 17:13:55 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 17:13:56 INFO - PROCESS | 1662 | RSS: Main: 294223872 17:13:56 INFO - PROCESS | 1662 | 17:13:58 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 17:13:59 INFO - PROCESS | 1662 | RSS: Main: 304390144 17:13:59 INFO - PROCESS | 1662 | 17:14:03 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 17:14:03 INFO - PROCESS | 1662 | RSS: Main: 388481024 17:14:03 INFO - PROCESS | 1662 | 17:14:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 17:14:06 INFO - PROCESS | 1662 | RSS: Main: 290299904 17:14:06 INFO - PROCESS | 1662 | 17:14:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:14:09 INFO - PROCESS | 1662 | RSS: Main: 294539264 17:14:09 INFO - PROCESS | 1662 | 17:14:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 17:14:12 INFO - PROCESS | 1662 | RSS: Main: 329588736 17:14:12 INFO - PROCESS | 1662 | 17:14:15 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 17:14:16 INFO - PROCESS | 1662 | RSS: Main: 332992512 17:14:16 INFO - PROCESS | 1662 | 17:14:19 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 17:14:19 INFO - PROCESS | 1662 | RSS: Main: 331788288 17:14:19 INFO - PROCESS | 1662 | 17:14:21 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:14:21 INFO - PROCESS | 1662 | RSS: Main: 313602048 17:14:21 INFO - PROCESS | 1662 | 17:14:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:14:24 INFO - PROCESS | 1662 | RSS: Main: 320598016 17:14:24 INFO - PROCESS | 1662 | 17:14:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:14:27 INFO - PROCESS | 1662 | RSS: Main: 317927424 17:14:27 INFO - PROCESS | 1662 | 17:14:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:14:30 INFO - PROCESS | 1662 | RSS: Main: 327278592 17:14:30 INFO - PROCESS | 1662 | 17:14:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:14:34 INFO - PROCESS | 1662 | RSS: Main: 329850880 17:14:34 INFO - PROCESS | 1662 | 17:14:35 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 17:14:36 INFO - PROCESS | 1662 | RSS: Main: 373137408 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | __start_tp_report 17:14:36 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 17:14:36 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 17:14:36 INFO - PROCESS | 1662 | |i|pagename|runs| 17:14:36 INFO - PROCESS | 1662 | |0;ai-astar;101;113;113;113;114;112;114;112;112;113 17:14:36 INFO - PROCESS | 1662 | |1;audio-beat-detection;142;111;108;155;99;125;162;105;138;104 17:14:36 INFO - PROCESS | 1662 | |2;audio-dft;182;185;180;183;184;184;180;183;183;185 17:14:36 INFO - PROCESS | 1662 | |3;audio-fft;77;72;59;107;58;57;63;68;110;81 17:14:36 INFO - PROCESS | 1662 | |4;audio-oscillator;82;88;93;70;71;67;97;72;97;135 17:14:36 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;87;85;87;84;87;85;87;85;87;84 17:14:36 INFO - PROCESS | 1662 | |6;imaging-darkroom;92;93;93;94;93;93;97;95;94;93 17:14:36 INFO - PROCESS | 1662 | |7;imaging-desaturate;84;82;134;141;88;84;85;71;134;142 17:14:36 INFO - PROCESS | 1662 | |8;json-parse-financial;58;56;56;58;57;54;54;54;57;55 17:14:36 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;66;72;81;75;70;70;73;69;68;70 17:14:36 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;85;82;81;84;84;80;81;84;85;86 17:14:36 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;137;134;135;135;134;132;136;134;222;123 17:14:36 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;163;161;162;163;162;164;164;163;162;162 17:14:36 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;66;65;68;65;66;65;67;68;61;59 17:14:36 INFO - PROCESS | 1662 | __end_tp_report 17:14:36 INFO - PROCESS | 1662 | __start_cc_report 17:14:36 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2081 17:14:36 INFO - PROCESS | 1662 | __end_cc_report 17:14:36 INFO - PROCESS | 1662 | __startTimestamp1467418476076__endTimestamp 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | ------- Summary: start ------- 17:14:36 INFO - PROCESS | 1662 | Number of tests: 14 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.83 (3.4%) stddev-sans-first:0.78 17:14:36 INFO - PROCESS | 1662 | Values: 101.0 113.0 113.0 113.0 114.0 112.0 114.0 112.0 112.0 113.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:140.00 stddev:22.92 (16.4%) stddev-sans-first:23.46 17:14:36 INFO - PROCESS | 1662 | Values: 142.0 111.0 108.0 155.0 99.0 125.0 162.0 105.0 138.0 104.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.50 stddev:1.79 (1.0%) stddev-sans-first:1.87 17:14:36 INFO - PROCESS | 1662 | Values: 182.0 185.0 180.0 183.0 184.0 184.0 180.0 183.0 183.0 185.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:75.20 Median:65.50 stddev:19.32 (29.5%) stddev-sans-first:20.48 17:14:36 INFO - PROCESS | 1662 | Values: 77.0 72.0 59.0 107.0 58.0 57.0 63.0 68.0 110.0 81.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:87.20 Median:85.00 stddev:20.37 (24.0%) stddev-sans-first:21.52 17:14:36 INFO - PROCESS | 1662 | Values: 82.0 88.0 93.0 70.0 71.0 67.0 97.0 72.0 97.0 135.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.32 (1.5%) stddev-sans-first:1.32 17:14:36 INFO - PROCESS | 1662 | Values: 87.0 85.0 87.0 84.0 87.0 85.0 87.0 85.0 87.0 84.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:93.50 stddev:1.42 (1.5%) stddev-sans-first:1.36 17:14:36 INFO - PROCESS | 1662 | Values: 92.0 93.0 93.0 94.0 93.0 93.0 97.0 95.0 94.0 93.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:104.50 Median:83.00 stddev:29.06 (35.0%) stddev-sans-first:29.86 17:14:36 INFO - PROCESS | 1662 | Values: 84.0 82.0 134.0 141.0 88.0 84.0 85.0 71.0 134.0 142.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.50 stddev:1.60 (2.8%) stddev-sans-first:1.50 17:14:36 INFO - PROCESS | 1662 | Values: 58.0 56.0 56.0 58.0 57.0 54.0 54.0 54.0 57.0 55.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:71.00 stddev:4.22 (5.9%) stddev-sans-first:4.00 17:14:36 INFO - PROCESS | 1662 | Values: 66.0 72.0 81.0 75.0 70.0 70.0 73.0 69.0 68.0 70.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:84.00 stddev:2.04 (2.4%) stddev-sans-first:2.06 17:14:36 INFO - PROCESS | 1662 | Values: 85.0 82.0 81.0 84.0 84.0 80.0 81.0 84.0 85.0 86.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:142.20 Median:135.00 stddev:28.31 (21.0%) stddev-sans-first:29.96 17:14:36 INFO - PROCESS | 1662 | Values: 137.0 134.0 135.0 135.0 134.0 132.0 136.0 134.0 222.0 123.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.60 Median:163.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 17:14:36 INFO - PROCESS | 1662 | Values: 163.0 161.0 162.0 163.0 162.0 164.0 164.0 163.0 162.0 162.0 17:14:36 INFO - PROCESS | 1662 | 17:14:36 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:66.00 stddev:2.91 (4.4%) stddev-sans-first:3.06 17:14:36 INFO - PROCESS | 1662 | Values: 66.0 65.0 68.0 65.0 66.0 65.0 67.0 68.0 61.0 59.0 17:14:36 INFO - PROCESS | 1662 | -------- Summary: end -------- 17:14:36 INFO - PROCESS | 1662 | 17:14:37 INFO - TEST-INFO | 1662: exit 0 17:14:37 INFO - TEST-OK | kraken | took 59198ms 17:14:37 INFO - SUITE-END | took 1175s 17:14:37 INFO - Completed test suite (00:19:35) 17:14:37 INFO - javascript benchmark 17:14:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3304.273059926816, 3312.0560640217536, 3323.401787678217, 3352.0111567087706, 3407.4888766684994, 5447.536959358459, 5489.560835133189, 5527.198556209347, 5586.469285417884, 5643.943560564393, 5543.695096769676, 5572.275604171462, 5589.944100558993, 5592.552595792337, 5601.103823388259, 5088.821891233817, 5117.181250999822, 5151.742412879341, 5194.856971992429, 5211.947880521184, 5156.175011998081, 5216.269722238871, 5221.269022336875, 5226.843194704165, 5265.394479634852, 5081.59347252219, 5109.182530795074, 5110.565601923841, 5114.539691427789, 5204.947950520503, 5179.223116532531, 5199.986002729469, 5202.4277329493725, 5232.659877107976, 5263.552598029134, 4980.975095124501, 5039.874003149951, 5074.238864170367, 5110.616703747197, 5135.12702840518, 5459.017376872162, 5507.7246137693355, 5550.72246387679, 5550.944490555124, 5557.666540007613, 6100.877982440371, 6137.447629713347, 6140.6929653517145, 6141.938580614182, 6145.139680444741, 5996.550258730613, 6044.455998960071, 6129.38706129388, 6212.347703491158, 6263.4362907338555, 5612.354579223401, 5661.2923384576925, 5675.2054712340305, 5684.2326285951285, 5738.68437235956, 6301.495880329563, 6305.653189074645, 6312.400321969406, 6321.841953951188, 6326.841828954313, 6036.366181550961, 6093.2078829751845, 6097.993831017921, 6106.877862442726, 6120.540959428061, 5952.136940143655, 6076.118962750376, 6107.249501910372, 6108.918862504643, 6211.409916057967, 6096.207493025879, 6118.388161183846, 6119.510439164945, 6125.487061644556, 6133.6319820810895, 5049.8737531562, 5961.493273071837, 6059.757609695563, 6162.7226774795245, 6195.039768835838, 4873.9025219496125, 4897.853064408074, 4946.629002824802, 4948.406191257072, 4952.207646776498, 4363.86539499726, 4395.077033822869, 4396.494403143615, 4398.054418300081, 4412.426384569985, 5445.591580631469, 5558.055130627803, 5582.876914089989, 5639.802606908738, 5646.20953066571, 6068.33248342682, 6096.02463605821, 6132.8756461173125, 6169.2288463942, 6238.594491358047, 5564.165375193672, 5566.332040155207, 5570.832875013756, 5576.498115169654, 5592.552595792326, 5245.370555533361, 5374.032674118618, 5393.164059570773, 5398.352197736218, 5407.053765591006, 6205.720742566596, 6232.971559692691, 6308.400701933309, 6309.085182648582, 6337.493000560036, 6169.2288463942, 6179.073139029181, 6191.473724733448, 6208.503319734501, 6209.875802484015, 6148.846278842886, 6317.336679648754, 6317.810465686037, 6332.778352757631, 6359.14151589548, 4936.308916751729, 5901.940980590139, 5902.763889444546, 5931.970340148272, 6004.699765011819, 6153.815385538441, 6159.6612186330185, 6161.537884658579, 6180.011198208265, 6184.5052395809125, 5770.769169233352, 5777.306723193244, 5848.824535263949, 5907.172995780508, 5916.792912248051, 5819.748754017906, 5861.120831875252, 5864.912026319694, 5873.911891321548, 5895.734691938787, 5765.135229715576, 5768.913466297925, 5779.2775903012125, 5782.363939966516, 5847.795327163529, 5209.6874187548865, 5669.34802497718, 5894.0, 5946.345901950695, 5963.254593175853, 5674.9785038693435, 5738.770449182153, 5761.654300741969, 5802.65184088956, 5808.680522571299], "value": 5592.660798900346, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.9716816990981, 501.8006963357576, 536.1582315764251, 536.4876542901529, 547.671397161703, 29619.703802961965, 30672.233194170156, 31617.841910790416, 31709.524357134662, 31736.682633173663, 31939.16729992404, 32455.67544324556, 41753.373699394455, 42790.78604606973, 43002.13995720092, 9710.999999999982, 9859.556319965619, 9865.605375784995, 9867.358621689567, 9900.108990190882, 9703.902960970407, 9797.167240784505, 9808.705738827846, 9810.264230182765, 9821.116099551038, 8651.610677519495, 8843.159899809507, 11709.824352634718, 11958.103142264321, 12020.939895300511, 11833.230839995373, 11930.58242961501, 11933.940330298294, 11935.687074421807, 11943.402829858473, 8630.525321107336, 11900.226485278428, 11965.222260553037, 11991.520339186422, 12035.338056406894, 39762.403563946566, 40149.79925100385, 40188.59811401907, 42368.57631423708, 42372.78813605912, 3344.2308269098244, 3388.6611338866164, 3562.6615471530163, 3664.7617904836366, 3694.224212915291, 30641.38717225643, 31162.0, 32128.357432851444, 32374.83812580922, 32414.351712965843, 3242.935141297161, 3344.347852168828, 3346.0631023313513, 3411.6076651185185, 3436.6906978371817, 424354.8782256069, 462231.0, 462271.0, 464127.0, 465904.0, 60882.39117608856, 61283.08075378873, 61642.38357616456, 61683.691581542706, 61849.69075154595, 64613.35386646167, 64657.0, 64700.794448360706, 64730.352696473376, 64792.028119578245, 15890.841091588936, 16352.75470867938, 16375.918120409322, 16402.179891005402, 16432.178391080397, 12713.0, 12714.745705085847, 12795.552155674693, 12797.0, 12799.424025918855, 16248.51254462368, 16368.918155409146, 18886.150123244486, 19013.42959711211, 19157.712634310497, 7196.4962452627815, 7889.368850491946, 7936.920630793618, 8003.239692229229, 8012.559309238048, 859.9728954081597, 971.3200759468259, 974.356924429887, 1000.7047920863217, 1010.888802231754, 26710.931562737274, 29357.412851742847, 30114.096577102722, 30483.2379190522, 30677.3325881159, 1058.8041212375736, 1106.2753896198003, 1134.1267224237456, 1189.7382575833303, 1203.6449247472017, 13627.863721362659, 15782.842171578366, 15863.0, 16002.75995860063, 16193.757093643604, 709182.0, 710905.0, 717656.0, 718650.0, 720390.0, 50574.0, 56609.0, 56994.0, 58945.41054589399, 59415.70292148511, 1371.615947534692, 1393.7560926837764, 1395.44182327072, 1403.1791402029926, 1403.635054885737, 30288.394232114795, 30767.692323076484, 31066.844665776527, 31509.8424507876, 31794.68205317917, 49525.76185595389, 49689.254661180836, 50095.0, 50191.243306484095, 50202.246966294806, 576394.0, 607745.0, 672387.0, 681528.0, 870043.0, 25834.483310334064, 26168.34579135537, 26178.60732088982, 26188.0, 26296.474070518867, 44770.55229447664, 45440.77279613581, 45837.541624584665, 46300.0, 46481.53518464772, 29719.554206686484, 38251.80874095612, 38374.616253837106, 38478.0, 38491.0, 36166.914992550264, 36573.0, 38481.0, 38781.612183878926, 39359.2128157441, 36348.81825590961, 37024.07439814026, 37073.07317317089, 37834.0, 38022.809885950395, 29173.124806255848, 29660.258493537833, 31934.28749222153, 33532.83233583817, 33858.49212261769, 24831.503369932863, 24857.751422485544, 24992.000319986997, 25370.1120460783, 25558.872205638854], "value": 20223.111189683794, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.071287148464, 369.46058754218836, 388.87555982085746, 391.3601261936734, 408.0675656125753, 15667.45163919263, 15762.763558546616, 18259.447946924513, 19370.515737106543, 20046.799532004676, 19929.40211793645, 20865.26971555996, 21264.8936755316, 21655.56688866222, 21667.808270545112, 4826.517348265163, 5259.76331065102, 5441.7279136043235, 5450.972745136269, 6051.0318349064155, 3896.7272290939645, 4094.263032654121, 5982.730777115018, 6335.714892829811, 6620.503462240327, 6289.7169627366875, 6608.603483790964, 6615.93384066158, 6617.007448882658, 6641.070250164981, 5931.970340148293, 5996.0, 6001.969990150022, 6002.459778619901, 6009.008513595251, 5122.795088196505, 5474.39781624021, 5814.622049566765, 5823.456783952213, 5832.562557808182, 12905.419256133402, 13132.474701011948, 13137.014723895745, 13155.60533184006, 21383.67924481134, 2255.661650752384, 2302.9424264393356, 2331.8717470539113, 2381.0951838301507, 2868.928276793076, 9665.155145442695, 9782.364146330467, 9807.019298070136, 10289.382637041726, 10441.738956526071, 1857.535616095976, 1877.2303355624265, 1904.6571617108914, 1917.319351630165, 1958.8530860185542, 115174.42412787967, 120967.79032209741, 121940.0, 125124.0, 127623.0, 9680.757981050388, 10970.451477426097, 11517.25137866036, 11556.191066625255, 11557.364344961108, 14301.57095287143, 14380.921430892726, 14412.0, 14416.711665766627, 14447.855521444651, 8477.70328038528, 8675.0, 8747.518886461306, 8785.472871627595, 8789.868151977726, 8677.913220867711, 8683.565821709015, 8697.608607612547, 8799.912000880036, 8846.911530884609, 4523.77857978344, 4602.596208156478, 4605.516420775837, 4661.160991021648, 4691.366665500182, 1712.5547357686842, 1730.8134541648806, 1825.4706135220638, 1829.890206587609, 1832.7800663920148, 781.4178437064423, 800.6437135474658, 849.3502470609989, 858.4377232912452, 881.2069137775925, 17289.308427663003, 19633.803661963484, 21265.78734212669, 21931.780682193294, 22171.44571385696, 862.0, 878.4202426398547, 899.8785164002907, 930.1767935377104, 992.161623428202, 10828.512716927757, 11000.944995274973, 11027.779444411068, 11089.112870970303, 11130.944345278222, 268455.0, 633019.0, 633987.0, 645314.0, 651141.0, 129214.0, 130191.0, 139692.0, 141286.0, 144884.27557862355, 860.8278344331034, 970.5147426286857, 977.4819345746623, 983.2772911909741, 1014.4876837197315, 18015.66710158625, 18220.544486387946, 18285.26858925681, 18447.0, 18711.532211694815, 58097.12854307274, 58961.0, 59169.70415147897, 59462.10806837987, 60158.39841601528, 73609.89585156334, 74859.62570187115, 85831.57084214539, 86564.0, 87660.56169719111, 7825.139234684067, 8136.918630813853, 8252.46358986664, 8270.917290827256, 8512.787180320542, 9229.67696130659, 13033.413496392495, 13338.399772010089, 13384.263865487492, 13413.731725365244, 6811.659417029228, 11447.656570302905, 11521.82717259225, 11841.407929603658, 11926.403679815801, 10318.48407579633, 11189.936955989167, 11748.609500014818, 11749.882501175221, 11779.293242405483, 11710.824337634773, 11989.82015269789, 12093.818592721293, 12171.695707607381, 12191.573294934635, 6932.0, 8403.453775504573, 8462.746117616482, 8491.83016339682, 8605.526696031528, 11546.653600392001, 11726.530938762355, 11886.0, 11896.76206475883, 11935.343556104497], "value": 9442.313271003522, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1373.5124030969005, 1493.8041079612972, 1659.9253033613493, 1840.5950690848008, 1868.4768264885836, 7119.216886142527, 8840.60217290222, 9013.05362936892, 9027.90972090279, 9028.322875784317, 7811.609419529015, 8041.316488098519, 8299.668013279477, 8558.186972237643, 8824.691135810248, 2359.079958816059, 2381.9761802382013, 2385.0459816073535, 2385.9880700596473, 2393.5093305872256, 2360.1975328388344, 2373.9169129080487, 2391.234804862445, 2395.0659242895285, 2395.8682272475007, 2108.947276318089, 2188.026328283914, 2191.8904054797276, 2193.517426166241, 2314.2478694424294, 2301.1945818963313, 2374.548835721225, 2376.5840977828984, 2378.881055947196, 2386.510765293119, 2342.320726989171, 2357.3635118517905, 2362.6928499295154, 2371.940701482477, 2372.6203807390734, 8860.822783544358, 8937.64249430022, 8950.86573701395, 8951.462912225223, 8959.73120806377, 5275.076861549213, 5359.839204823861, 5425.701586412746, 5430.321209848769, 5433.510984011458, 3005.827727186399, 3487.1805125795418, 3575.713942884563, 3632.382494975834, 3712.3874560697454, 1064.7444613292832, 1066.477426061232, 1081.6754973507916, 1102.6857345656529, 1112.9499172537255, 11716.707082322924, 11785.116116291312, 11800.114991375596, 11805.937465628134, 11816.636672665501, 4529.909401811945, 4739.0, 4825.9034819303415, 4847.345608342828, 4850.175470170079, 9926.454045027593, 9932.106110450095, 9956.153726933291, 9973.002699730114, 9997.200223982063, 5362.356517217959, 5364.114921038063, 5364.758585863646, 5365.812196573102, 5372.1941708744, 4293.441852559147, 4440.977795111004, 4557.179707652588, 4589.2886602576655, 4926.531979461945, 3932.528096628423, 4053.6554392876938, 4054.270231358325, 4071.714979951434, 4077.673786097105, 1261.8611952685196, 1280.647821848999, 1282.9166104203384, 1286.5689993851977, 1305.8041293805816, 904.977375565603, 921.9950254222929, 925.1257561604353, 932.1563984593953, 965.1940629574384, 5912.940870591325, 5933.732982015733, 5953.1070339449425, 6106.755729770848, 6194.163787888578, 429.98065087071154, 443.7803287372771, 454.1008802570928, 457.6201752545379, 460.09361557731216, 3976.622220889011, 4010.2380547695843, 4055.4930633670792, 4092.7135100542678, 4115.835366585303, 9888.65389711371, 10815.891841081488, 10900.618478353219, 10916.563337466412, 10949.616763413242, 10169.338992965433, 10741.731456713645, 10761.246712730033, 10885.891141088647, 10924.344539327665, 8121.837563248702, 8165.428420010661, 8200.958995204986, 8309.086000539935, 8343.41596088268, 5981.850453738517, 6003.549733770072, 6100.603460775035, 6127.387261274015, 6287.622742635456, 9770.706878793648, 9867.950660246654, 10323.019313165238, 10350.068493835592, 10356.067953883885, 9395.577199025924, 9715.0, 10043.748906277402, 10459.84310235332, 10492.842607361048, 6394.392532709385, 7345.22875098107, 7592.734254301073, 7719.3052625264, 7724.111727151225, 8075.475094118863, 8474.322054235523, 8476.872846907178, 8537.573121343783, 8554.91445085541, 7514.736984205527, 7591.582462964369, 7600.125985511737, 7659.0, 7797.688092476237, 7393.667284972296, 7414.036175297177, 7467.850642987001, 7510.737124200627, 7555.282248186413, 7038.577685338896, 7155.177154627285, 7158.248383919822, 7172.964135179291, 7175.641217939186, 4911.140550403737, 5012.398512178562, 5058.696478211319, 5072.34059572268, 5153.7165455898785, 5005.724685142352, 5092.618053646065, 5099.643024988215, 5108.846734597968, 5226.398964119175], "value": 4527.714424848983, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [259.93761497240655, 291.96350456192977, 298.11905818306906, 305.25365481397984, 309.40130846811405, 5247.370315562129, 5373.40892501825, 5385.764907563356, 5402.892871780009, 5448.439233560221, 4970.527799859016, 5003.4996500350035, 5128.871388453041, 5249.343832020997, 5319.175527793189, 3832.8466861325514, 4012.61880121388, 4033.5966403359575, 4045.63589276965, 4090.877273681794, 3328.3010567780793, 4047.898802529931, 4088.4889388826396, 4097.664331331142, 4106.67146628269, 3700.2229531798353, 3722.9069273268115, 3825.9234815303676, 3877.379619260919, 4018.7588744675413, 3593.838277277529, 4044.5146582410007, 4049.8582549610624, 4058.8782336529953, 4067.78305823327, 2975.1256708273863, 3038.468268053082, 3419.401604719189, 3511.0, 3528.3825330567047, 4971.229459433794, 5156.896862062776, 5163.586913046947, 5172.663776854492, 5182.274481572583, 3929.7249192556533, 4606.631469482433, 4624.375709279271, 4626.653001024936, 4668.719876807369, 5586.217929489874, 5600.143926597424, 5602.991461536922, 5624.409437009096, 5674.262345895048, 3414.3854106260865, 3647.3252448296885, 3920.6471417572557, 3926.1951299983434, 3944.9013774655577, 5788.305403351583, 5848.678322692293, 5878.41215878413, 5882.6470411775, 5902.498287645512, 3501.6848483636595, 3991.201759648082, 4003.459532963071, 4005.35914253718, 4007.218592374459, 4047.817848196838, 4060.715749897479, 4061.5329237138108, 4074.005605879676, 4081.9591004293716, 4321.524632290446, 4419.292913133883, 4638.466576343763, 4658.557437043475, 4684.414448193976, 4790.377250957354, 5755.884882302415, 5760.596758226968, 5761.798337058267, 5775.902578510069, 1371.0471222500466, 1388.5279005138302, 1390.9721805564034, 1396.5042409944526, 1407.619942615488, 773.8761798112275, 812.7155495576501, 833.8707500337457, 859.8632398489748, 874.0472884555737, 3936.5472970608175, 4245.214635292481, 4467.347081579771, 4494.247243575009, 4522.412086428743, 4188.465978083276, 4865.805367785249, 4877.0, 4923.507649235119, 4976.353074100416, 3744.137311645577, 4289.520115560127, 4424.80088396023, 4456.509783923766, 4481.3726078349055, 2201.721218538805, 2385.200957679162, 2553.7190909000365, 2572.070946789908, 2577.383955285387, 4129.843643779746, 4535.501094879561, 4536.72779633223, 4938.678985865908, 5055.747212639427, 4955.306257124005, 4956.306117143603, 4967.4784147664695, 4970.801167953314, 5307.283516725192, 5050.444451110375, 5415.566754659618, 6061.454469097803, 6108.786192483241, 6131.294901086343, 4980.178270585313, 5628.457866458964, 5802.622829516068, 5923.703814809329, 5966.343702192842, 4850.369451971221, 5180.766865490987, 5696.094991554254, 5776.0, 5820.359760426434, 4506.932396014128, 5646.44722701244, 5703.743331550173, 5709.286339207599, 6113.860148948011, 3230.773845830863, 3267.9509807352433, 3557.395242808677, 3915.5497117830673, 3920.3923391874305, 5724.398938111438, 5886.587938844411, 5902.1441890926435, 5934.172458764159, 5955.523558115254, 4936.679115857458, 5659.915101273402, 5674.801381951612, 5710.229119068873, 5735.9139612905, 4244.787760612019, 4658.324542941186, 5579.7489112990825, 5734.684592347461, 5756.913646295393, 4580.427446569179, 5082.491750824888, 5097.337346144977, 5237.292965449616, 5591.30108736408, 5638.4925356718095, 5682.9147562785765, 5683.346415162309, 5695.2311437957005, 5704.372519022987, 4585.358049873087, 5348.358197016383, 5500.834974950758, 5591.6365436246515, 5601.663900166003], "value": 3980.734819754432, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.6475770704965, 728.7412968396219, 761.9619019049046, 796.2197046894044, 812.3744716568244, 15127.0, 16082.678346433064, 16095.517134485985, 16101.35594576217, 16162.83837161628, 12579.937100314508, 13020.0, 14835.851641483581, 14846.480373186942, 14846.703065938702, 680.1226417920876, 845.5391811462767, 852.3777642321078, 862.1077185113409, 865.4547634989948, 701.5404909784099, 845.3321875718175, 851.3146916732028, 875.382855087162, 880.0363601855958, 900.7928176519405, 900.9909900900973, 901.3825529512272, 905.2622112977934, 905.9365844390895, 722.9710811567584, 886.7827382291315, 900.3472482450204, 901.8872640919885, 903.701778413122, 675.8040168351172, 677.11298199359, 677.474956908395, 678.6742363665419, 679.5005670831935, 12367.93816030914, 15033.398664053535, 15051.397944082333, 15054.473093441677, 15061.472848450252, 11938.522459101625, 13684.178949263076, 13845.723085538333, 13867.514636987758, 14035.368408421646, 12310.938445307806, 12409.182215204364, 12411.379431028412, 12541.372931353395, 13054.54309099177, 4471.843485478025, 4587.885302867422, 4593.954060459386, 4607.032523170138, 4630.907381852344, 112505.43747281293, 113943.43028284889, 119374.0, 119538.4023079879, 119615.4019229907, 10689.0, 10873.945630271797, 10901.236913416144, 11136.61021864247, 11296.0, 21280.148794048066, 21301.36095917125, 21305.573888522144, 21316.655167931516, 21379.465513361978, 7631.0, 7643.656035478417, 7671.6164191790185, 7677.808054798675, 7770.45606807518, 4244.617984381359, 4260.978695106566, 4307.440032795716, 4331.133773245364, 4457.665675074382, 3419.4357930941114, 3500.299940011957, 3513.297340531904, 3514.2444374459615, 3524.154202991315, 563.8956792993309, 658.308775785433, 665.8268850098913, 667.1127400557248, 667.1827011910468, 606.666333516573, 618.6721037849946, 618.7432215630553, 636.4971672378862, 645.270843946347, 7640.274173953466, 8833.734987950373, 8904.643814247358, 8942.37403381757, 9275.443473391619, 517.2448225590605, 568.9743961521741, 589.5784514072459, 618.6937465954381, 640.4652115483531, 3751.7748935063983, 4168.687348448879, 4256.553061928515, 4385.429894113745, 4440.955590444119, 91677.54161229284, 138111.0, 151884.48115518925, 173409.26590734185, 173475.26524734843, 144265.5573444273, 144723.0, 144977.27511362376, 145003.0, 153267.2336638332, 113946.0, 114777.85222147839, 122940.0, 123545.38227308985, 124949.0, 8407.327413807003, 8478.618462169094, 8534.658613655385, 8774.166454186841, 8907.866382004404, 41884.79057604692, 43243.13513729645, 46267.0, 55982.76068957196, 58661.0, 94205.5289723547, 96697.0, 101194.0, 103808.0, 105434.0, 10570.365778053341, 12169.391530423267, 13029.674258143623, 13270.27013514266, 13789.931050345085, 14603.561893143222, 15234.314455849291, 15563.922180389025, 15581.610459738144, 15662.451814186448, 13872.445102196203, 13969.231692257023, 14003.089799163023, 14013.719725605635, 14149.858501414854, 12613.936930315656, 13400.396982136022, 13706.725865482435, 13973.441062357391, 14007.719845602827, 11679.299242045165, 11781.469833857325, 12933.0, 13510.79733803974, 13644.522441714493, 11187.552497899993, 11474.598389056342, 11655.708607284885, 11760.294382337088, 12171.059154817756, 11223.831642525205, 11224.631775626705, 11529.942350288531, 11597.76804463923, 11617.941910290394], "value": 7793.633224016536, "unit": "score"}], "name": "dromaeo_css", "value": 7289.4693233364615, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 113.0, 114.0, 112.0, 114.0, 112.0, 112.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 108.0, 155.0, 99.0, 125.0, 162.0, 105.0, 138.0, 104.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 180.0, 183.0, 184.0, 184.0, 180.0, 183.0, 183.0, 185.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 59.0, 107.0, 58.0, 57.0, 63.0, 68.0, 110.0, 81.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 88.0, 93.0, 70.0, 71.0, 67.0, 97.0, 72.0, 97.0, 135.0], "value": 87.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 87.0, 85.0, 87.0, 85.0, 87.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 93.0, 93.0, 97.0, 95.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 134.0, 141.0, 88.0, 84.0, 85.0, 71.0, 134.0, 142.0], "value": 104.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 58.0, 57.0, 54.0, 54.0, 54.0, 57.0, 55.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 81.0, 75.0, 70.0, 70.0, 73.0, 69.0, 68.0, 70.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 81.0, 84.0, 84.0, 80.0, 81.0, 84.0, 85.0, 86.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 134.0, 135.0, 135.0, 134.0, 132.0, 136.0, 134.0, 222.0, 123.0], "value": 142.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 163.0, 162.0, 164.0, 164.0, 163.0, 162.0, 162.0], "value": 162.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 68.0, 65.0, 66.0, 65.0, 67.0, 68.0, 61.0, 59.0], "value": 65.0, "unit": "score"}], "name": "kraken", "value": 1446.1999999999998, "alertThreshold": 2.0}]} 17:14:37 INFO - Return code: 0 17:14:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:14:37 INFO - # TBPL SUCCESS # 17:14:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:14:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:14:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:14:37 INFO - 2016-07-01 17:14:37.397 screenresolution[1666:10007] starting screenresolution argv=screenresolution get 17:14:37 INFO - 2016-07-01 17:14:37.400 screenresolution[1666:10007] Display 0: 1600x1200x32@60 17:14:37 INFO - 2016-07-01 17:14:37.406 screenresolution[1667:10010] starting screenresolution argv=screenresolution list 17:14:37 INFO - Available Modes on Display 0 17:14:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:14:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:14:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:14:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:14:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:14:37 INFO - Intel Iris: 17:14:37 INFO - Chipset Model: Intel Iris 17:14:37 INFO - Type: GPU 17:14:37 INFO - Bus: Built-In 17:14:37 INFO - VRAM (Dynamic, Max): 1536 MB 17:14:37 INFO - Vendor: Intel (0x8086) 17:14:37 INFO - Device ID: 0x0a2e 17:14:37 INFO - Revision ID: 0x0009 17:14:37 INFO - Displays: 17:14:37 INFO - Display: 17:14:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:14:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:14:37 INFO - Display Serial Number: 1600x1200 60 17:14:37 INFO - Main Display: Yes 17:14:37 INFO - Mirror: Off 17:14:37 INFO - Online: Yes 17:14:37 INFO - Rotation: Supported 17:14:37 INFO - Return code: 0 17:14:37 INFO - Running post-action listener: _resource_record_post_action 17:14:37 INFO - ##### 17:14:37 INFO - ##### Finished run-tests step (success) 17:14:37 INFO - ##### 17:14:37 INFO - Running post-run listener: _resource_record_post_run 17:14:37 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31456768; Write bytes: 705750528; Read time: 193; Write time: 1985 17:14:37 INFO - TinderboxPrint: CPU usage
53.3% 17:14:37 INFO - TinderboxPrint: I/O read bytes / time
31,456,768 / 193 17:14:37 INFO - TinderboxPrint: I/O write bytes / time
705,750,528 / 1,985 17:14:37 INFO - TinderboxPrint: CPU idle
2,264.3 (46.7%) 17:14:37 INFO - TinderboxPrint: CPU system
961.9 (19.9%) 17:14:37 INFO - TinderboxPrint: CPU user
1,619.5 (33.4%) 17:14:37 INFO - TinderboxPrint: Swap in / out
434,085,888 / 0 17:14:37 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392024576; Write bytes: 385848832; Read time: 29207; Write time: 832 17:14:37 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30531072; Write bytes: 296632320; Read time: 181; Write time: 1097 17:14:38 INFO - Running post-run listener: _upload_blobber_files 17:14:38 INFO - Blob upload gear active. 17:14:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:14:38 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 17:14:38 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:14:38 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:14:38 INFO - (blobuploader) - INFO - Open directory for files ... 17:14:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:14:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:14:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:14:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:14:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:14:39 INFO - (blobuploader) - INFO - Done attempting. 17:14:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:14:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:14:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:14:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:14:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:14:40 INFO - (blobuploader) - INFO - Done attempting. 17:14:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:14:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:14:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:14:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:14:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:14:42 INFO - (blobuploader) - INFO - Done attempting. 17:14:42 INFO - (blobuploader) - INFO - Iteration through files over. 17:14:42 INFO - Return code: 0 17:14:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:14:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:14:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7679fae53343ff4ebc8eeabdbb66ed792da3b3f811b38ce11727683997018185340282f9ce9cd895497512b8edea8e2cd5acf0f77dea88f5e9e2bc34bddd3968", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b8dd353845669f433dc8acb0715cfaa5b984c6b0615abce1dbf3d600082021c5ab266e02e193615b8d8c7ab60639bbd9c9ae3a0ff085bb2aae5c353c14fc74e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3fe771ee576e1d45c5362136b50b066996de4cf7104bb4fda1ade06120576ba839e5d83e5fddd352f4bcedf310905b00c6ecd76f6b213b2ee683f411908a3177"} 17:14:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:14:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:14:42 INFO - Contents: 17:14:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7679fae53343ff4ebc8eeabdbb66ed792da3b3f811b38ce11727683997018185340282f9ce9cd895497512b8edea8e2cd5acf0f77dea88f5e9e2bc34bddd3968", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b8dd353845669f433dc8acb0715cfaa5b984c6b0615abce1dbf3d600082021c5ab266e02e193615b8d8c7ab60639bbd9c9ae3a0ff085bb2aae5c353c14fc74e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3fe771ee576e1d45c5362136b50b066996de4cf7104bb4fda1ade06120576ba839e5d83e5fddd352f4bcedf310905b00c6ecd76f6b213b2ee683f411908a3177"} 17:14:42 INFO - Running post-run listener: copy_logs_to_upload_dir 17:14:42 INFO - Copying logs to upload dir... 17:14:42 INFO - mkdir: /builds/slave/test/build/upload/logs 17:14:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1298.492202 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2016-07-01 17:14:42.646539) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-01 17:14:42.650146) ========= 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.yknRzcgS4U/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.MqSZHeQlUM/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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/Autoland/sha512/7679fae53343ff4ebc8eeabdbb66ed792da3b3f811b38ce11727683997018185340282f9ce9cd895497512b8edea8e2cd5acf0f77dea88f5e9e2bc34bddd3968", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b8dd353845669f433dc8acb0715cfaa5b984c6b0615abce1dbf3d600082021c5ab266e02e193615b8d8c7ab60639bbd9c9ae3a0ff085bb2aae5c353c14fc74e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3fe771ee576e1d45c5362136b50b066996de4cf7104bb4fda1ade06120576ba839e5d83e5fddd352f4bcedf310905b00c6ecd76f6b213b2ee683f411908a3177"} build_url:https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011388 build_url: 'https://queue.taskcluster.net/v1/task/CiyjT4o9Qxat62sWgG4vRA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7679fae53343ff4ebc8eeabdbb66ed792da3b3f811b38ce11727683997018185340282f9ce9cd895497512b8edea8e2cd5acf0f77dea88f5e9e2bc34bddd3968", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b8dd353845669f433dc8acb0715cfaa5b984c6b0615abce1dbf3d600082021c5ab266e02e193615b8d8c7ab60639bbd9c9ae3a0ff085bb2aae5c353c14fc74e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3fe771ee576e1d45c5362136b50b066996de4cf7104bb4fda1ade06120576ba839e5d83e5fddd352f4bcedf310905b00c6ecd76f6b213b2ee683f411908a3177"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-01 17:14:42.683916) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 17:14:42.684242) ========= 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.yknRzcgS4U/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.MqSZHeQlUM/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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.005024 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-01 17:14:42.736280) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-01 17:14:42.736965) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-01 17:14:42.737647) ========= ========= Total master_lag: 0.19 =========