builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-010 starttime: 1472227186.47 results: success (0) buildid: 20160826072316 builduid: c224707d993a415b8eeead9f8c79965a revision: 69fcb7b0e100aeb41df63129d02222d16ae3fef9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:46.472527) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:46.472959) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:46.473254) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:46.502893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:46.504045) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-26 08:59:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-08-26 08:59:46 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119956 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:46.638420) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:46.638699) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015632 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:46.690530) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:46.690859) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 69fcb7b0e100aeb41df63129d02222d16ae3fef9 --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 69fcb7b0e100aeb41df63129d02222d16ae3fef9 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-26 08:59:46,838 truncating revision to first 12 chars 2016-08-26 08:59:46,838 Setting DEBUG logging. 2016-08-26 08:59:46,838 attempt 1/10 2016-08-26 08:59:46,838 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/69fcb7b0e100?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 08:59:47,121 unpacking tar archive at: autoland-69fcb7b0e100/testing/mozharness/ program finished with exit code 0 elapsedTime=0.610417 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:47.317597) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:47.317886) ========= script_repo_revision: 69fcb7b0e100aeb41df63129d02222d16ae3fef9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:47.318589) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:47.319127) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 08:59:47.330033) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-08-26 08:59:47.330323) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:59:47 INFO - MultiFileLogger online at 20160826 08:59:47 in /builds/slave/test 08:59:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 08:59:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:59:47 INFO - {'append_to_log': False, 08:59:47 INFO - 'base_work_dir': '/builds/slave/test', 08:59:47 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 08:59:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:59:47 INFO - 'branch': 'Autoland-Non-PGO', 08:59:47 INFO - 'buildbot_json_path': 'buildprops.json', 08:59:47 INFO - 'config_files': ('talos/linux_config.py',), 08:59:47 INFO - 'default_actions': ('clobber', 08:59:47 INFO - 'read-buildbot-config', 08:59:47 INFO - 'download-and-extract', 08:59:47 INFO - 'populate-webroot', 08:59:47 INFO - 'create-virtualenv', 08:59:47 INFO - 'install', 08:59:47 INFO - 'run-tests'), 08:59:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:59:47 INFO - 'download_minidump_stackwalk': True, 08:59:47 INFO - 'download_symbols': 'ondemand', 08:59:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:59:47 INFO - 'tooltool.py': '/tools/tooltool.py', 08:59:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:59:47 INFO - '/tools/misc-python/virtualenv.py')}, 08:59:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:59:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:59:47 INFO - 'installer_path': 'installer.exe', 08:59:47 INFO - 'log_level': 'info', 08:59:47 INFO - 'log_name': 'talos', 08:59:47 INFO - 'log_to_console': True, 08:59:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:59:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:59:47 INFO - 'opt_config_files': (), 08:59:47 INFO - 'pip_index': False, 08:59:47 INFO - 'sps_profile': False, 08:59:47 INFO - 'sps_profile_interval': 0, 08:59:47 INFO - 'suite': 'dromaeojs', 08:59:47 INFO - 'system_bits': '32', 08:59:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:59:47 INFO - 'title': 'talos-linux64-ix-010', 08:59:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:59:47 INFO - 'use_talos_json': True, 08:59:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:59:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:59:47 INFO - 'work_dir': 'build'} 08:59:47 INFO - [mozharness: 2016-08-26 15:59:47.572632Z] Running clobber step. 08:59:47 INFO - Running pre-action listener: _resource_record_pre_action 08:59:47 INFO - Running main action method: clobber 08:59:47 INFO - rmtree: /builds/slave/test/build 08:59:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:59:48 INFO - Running post-action listener: _resource_record_post_action 08:59:48 INFO - [mozharness: 2016-08-26 15:59:48.027670Z] Finished clobber step (success) 08:59:48 INFO - [mozharness: 2016-08-26 15:59:48.027773Z] Running read-buildbot-config step. 08:59:48 INFO - Running pre-action listener: _resource_record_pre_action 08:59:48 INFO - Running main action method: read_buildbot_config 08:59:48 INFO - Using buildbot properties: 08:59:48 INFO - { 08:59:48 INFO - "project": "", 08:59:48 INFO - "product": "firefox", 08:59:48 INFO - "script_repo_revision": "production", 08:59:48 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 08:59:48 INFO - "repository": "", 08:59:48 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 08:59:48 INFO - "buildid": "20160826072316", 08:59:48 INFO - "pgo_build": "False", 08:59:48 INFO - "basedir": "/builds/slave/test", 08:59:48 INFO - "buildnumber": 522, 08:59:48 INFO - "slavename": "talos-linux64-ix-010", 08:59:48 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:59:48 INFO - "platform": "ubuntu64_hw", 08:59:48 INFO - "scheduler": "tests-autoland-linux64-talos", 08:59:48 INFO - "branch": "autoland", 08:59:48 INFO - "repo_path": "integration/autoland", 08:59:48 INFO - "revision": "69fcb7b0e100aeb41df63129d02222d16ae3fef9", 08:59:48 INFO - "stage_platform": "linux64", 08:59:48 INFO - "builduid": "c224707d993a415b8eeead9f8c79965a", 08:59:48 INFO - "slavebuilddir": "test" 08:59:48 INFO - } 08:59:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:59:48 INFO - Running post-action listener: _resource_record_post_action 08:59:48 INFO - [mozharness: 2016-08-26 15:59:48.030248Z] Finished read-buildbot-config step (success) 08:59:48 INFO - [mozharness: 2016-08-26 15:59:48.030338Z] Running download-and-extract step. 08:59:48 INFO - Running pre-action listener: _resource_record_pre_action 08:59:48 INFO - Running main action method: download_and_extract 08:59:48 INFO - mkdir: /builds/slave/test/build/tests 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:48 INFO - trying https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:59:48 INFO - Downloading https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:59:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:59:48 INFO - Downloaded 1361 bytes. 08:59:48 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:59:48 INFO - Using the following test package requirements: 08:59:48 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:59:48 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:59:48 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:59:48 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'jsshell-linux-x86_64.zip'], 08:59:48 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:59:48 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:59:48 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:59:48 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:59:48 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:59:48 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:59:48 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:48 INFO - trying https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:59:48 INFO - Downloading https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:59:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:59:50 INFO - Downloaded 20304491 bytes. 08:59:50 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 08:59:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:54 INFO - trying https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:59:54 INFO - Downloading https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:59:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:59:55 INFO - Downloaded 20304491 bytes. 08:59:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 09:00:00 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:00 INFO - trying https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:00 INFO - Downloading https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:00:01 INFO - Downloaded 14413998 bytes. 09:00:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 09:00: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:01 INFO - trying https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:00:01 INFO - Downloading https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:00:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:00:05 INFO - Downloaded 55861396 bytes. 09:00:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:00:05 INFO - mkdir: /builds/slave/test/properties 09:00:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:05 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:05 INFO - Contents: 09:00:05 INFO - build_url:https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:00:06 INFO - Running post-action listener: _resource_record_post_action 09:00:06 INFO - Running post-action listener: set_extra_try_arguments 09:00:06 INFO - [mozharness: 2016-08-26 16:00:06.098812Z] Finished download-and-extract step (success) 09:00:06 INFO - [mozharness: 2016-08-26 16:00:06.099039Z] Running populate-webroot step. 09:00:06 INFO - Running pre-action listener: _resource_record_pre_action 09:00:06 INFO - Running main action method: populate_webroot 09:00:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:06 INFO - '--tppagecycles', 09:00:06 INFO - '7'], 09:00:06 INFO - 'tests': ['tsvgm']}}, 09:00:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:06 INFO - 'talos_options': ['--disable-e10s'], 09:00:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:06 INFO - 'talos_options': ['--disable-e10s'], 09:00:06 INFO - 'tests': ['damp', 'tps']}, 09:00:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:06 INFO - 'tests': ['damp', 'tps']}, 09:00:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:06 INFO - 'tests': ['dromaeo_dom']}, 09:00:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:00:06 INFO - 'tests': ['basic_compositor_video']}, 09:00:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:00:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:06 INFO - 'tests': ['a11yr', 09:00:06 INFO - 'ts_paint', 09:00:06 INFO - 'tpaint', 09:00:06 INFO - 'sessionrestore', 09:00:06 INFO - 'sessionrestore_no_auto_restore', 09:00:06 INFO - 'tabpaint']}, 09:00:06 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:06 INFO - 'ts_paint', 09:00:06 INFO - 'tpaint', 09:00:06 INFO - 'sessionrestore', 09:00:06 INFO - 'sessionrestore_no_auto_restore', 09:00:06 INFO - 'tabpaint']}, 09:00:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:06 INFO - 'ts_paint', 09:00:06 INFO - 'tpaint', 09:00:06 INFO - 'sessionrestore', 09:00:06 INFO - 'sessionrestore_no_auto_restore', 09:00:06 INFO - 'tabpaint']}, 09:00:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:06 INFO - 'ts_paint', 09:00:06 INFO - 'tpaint', 09:00:06 INFO - 'sessionrestore', 09:00:06 INFO - 'sessionrestore_no_auto_restore', 09:00:06 INFO - 'tabpaint']}, 09:00:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:06 INFO - 'tests': ['a11yr', 09:00:06 INFO - 'ts_paint', 09:00:06 INFO - 'tpaint', 09:00:06 INFO - 'sessionrestore', 09:00:06 INFO - 'sessionrestore_no_auto_restore', 09:00:06 INFO - 'tabpaint']}, 09:00:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:06 INFO - 'tests': ['a11yr', 09:00:06 INFO - 'ts_paint', 09:00:06 INFO - 'tpaint', 09:00:06 INFO - 'sessionrestore', 09:00:06 INFO - 'sessionrestore_no_auto_restore', 09:00:06 INFO - 'tabpaint']}, 09:00:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:06 INFO - 'tests': ['tsvgx', 09:00:06 INFO - 'tsvgr_opacity', 09:00:06 INFO - 'tart', 09:00:06 INFO - 'tscrollx', 09:00:06 INFO - 'cart']}, 09:00:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:06 INFO - 'tsvgr_opacity', 09:00:06 INFO - 'tart', 09:00:06 INFO - 'tscrollx', 09:00:06 INFO - 'cart']}, 09:00:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:06 INFO - 'talos_options': ['--disable-e10s'], 09:00:06 INFO - 'tests': ['tp5o']}, 09:00:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:06 INFO - 'tests': ['tp5o']}, 09:00:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:06 INFO - 'talos_options': ['--disable-e10s', 09:00:06 INFO - '--xperf_path', 09:00:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:06 INFO - 'tests': ['tp5n']}, 09:00:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:06 INFO - 'talos_options': ['--xperf_path', 09:00:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:06 INFO - 'tests': ['tp5n']}}, 09:00:06 INFO - 'talos.zip': {'path': '', 09:00:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:06 INFO - Running post-action listener: _resource_record_post_action 09:00:06 INFO - [mozharness: 2016-08-26 16:00:06.120424Z] Finished populate-webroot step (success) 09:00:06 INFO - [mozharness: 2016-08-26 16:00:06.120514Z] Running create-virtualenv step. 09:00:06 INFO - Running pre-action listener: _resource_record_pre_action 09:00:06 INFO - Running main action method: create_virtualenv 09:00:06 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:06 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 09:00:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:06 INFO - Using real prefix '/usr' 09:00:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:06 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:08 INFO - Installing pip.................done. 09:00:08 INFO - Return code: 0 09:00:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:08 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 0x7f1952836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df7420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e85d20>, '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 0x1e86c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e870b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:08 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 09:00:08 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 09:00:08 INFO - Using env: {'DISPLAY': ':0', 09:00:08 INFO - 'HOME': '/home/cltbld', 09:00:08 INFO - 'LANG': 'en_US.UTF-8', 09:00:08 INFO - 'LANGUAGE': 'en_US:en', 09:00:08 INFO - 'LOGNAME': 'cltbld', 09:00:08 INFO - 'MAIL': '/var/mail/cltbld', 09:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:08 INFO - 'MOZ_NO_REMOTE': '1', 09:00:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:08 INFO - 'NO_EM_RESTART': '1', 09:00:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:08 INFO - 'PWD': '/builds/slave/test', 09:00:08 INFO - 'SHELL': '/bin/bash', 09:00:08 INFO - 'SHLVL': '1', 09:00:08 INFO - 'TERM': 'linux', 09:00:08 INFO - 'TMOUT': '86400', 09:00:08 INFO - 'USER': 'cltbld', 09:00:08 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 09:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:08 INFO - '_': '/tools/buildbot/bin/python'} 09:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:08 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 09:00:08 INFO - Cleaning up... 09:00:08 INFO - Return code: 0 09:00:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:08 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 0x7f1952836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df7420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e85d20>, '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 0x1e86c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e870b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:08 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 09:00:08 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 09:00:08 INFO - Using env: {'DISPLAY': ':0', 09:00:08 INFO - 'HOME': '/home/cltbld', 09:00:08 INFO - 'LANG': 'en_US.UTF-8', 09:00:08 INFO - 'LANGUAGE': 'en_US:en', 09:00:08 INFO - 'LOGNAME': 'cltbld', 09:00:08 INFO - 'MAIL': '/var/mail/cltbld', 09:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:08 INFO - 'MOZ_NO_REMOTE': '1', 09:00:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:08 INFO - 'NO_EM_RESTART': '1', 09:00:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:08 INFO - 'PWD': '/builds/slave/test', 09:00:08 INFO - 'SHELL': '/bin/bash', 09:00:08 INFO - 'SHLVL': '1', 09:00:08 INFO - 'TERM': 'linux', 09:00:08 INFO - 'TMOUT': '86400', 09:00:08 INFO - 'USER': 'cltbld', 09:00:08 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 09:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:08 INFO - '_': '/tools/buildbot/bin/python'} 09:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:09 INFO - Downloading/unpacking psutil>=3.1.1 09:00:09 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 09:00:09 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 09:00:09 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 09:00:09 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 09:00:09 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 09:00:09 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 09:00:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:11 INFO - Installing collected packages: psutil 09:00:11 INFO - Running setup.py install for psutil 09:00:11 INFO - building 'psutil._psutil_linux' extension 09:00:11 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:00:11 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:00:11 INFO - building 'psutil._psutil_posix' extension 09:00:11 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:00:11 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:11 INFO - Successfully installed psutil 09:00:11 INFO - Cleaning up... 09:00:11 INFO - Return code: 0 09:00:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:00:11 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:11 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:11 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 0x7f1952836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df7420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e85d20>, '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 0x1e86c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e870b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:11 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 09:00:11 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 09:00:11 INFO - Using env: {'DISPLAY': ':0', 09:00:11 INFO - 'HOME': '/home/cltbld', 09:00:11 INFO - 'LANG': 'en_US.UTF-8', 09:00:11 INFO - 'LANGUAGE': 'en_US:en', 09:00:11 INFO - 'LOGNAME': 'cltbld', 09:00:11 INFO - 'MAIL': '/var/mail/cltbld', 09:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:11 INFO - 'MOZ_NO_REMOTE': '1', 09:00:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:11 INFO - 'NO_EM_RESTART': '1', 09:00:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:11 INFO - 'PWD': '/builds/slave/test', 09:00:11 INFO - 'SHELL': '/bin/bash', 09:00:11 INFO - 'SHLVL': '1', 09:00:11 INFO - 'TERM': 'linux', 09:00:11 INFO - 'TMOUT': '86400', 09:00:11 INFO - 'USER': 'cltbld', 09:00:11 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 09:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:11 INFO - '_': '/tools/buildbot/bin/python'} 09:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:00:13 INFO - Installing collected packages: mozsystemmonitor 09:00:13 INFO - Running setup.py install for mozsystemmonitor 09:00:13 INFO - Successfully installed mozsystemmonitor 09:00:13 INFO - Cleaning up... 09:00:14 INFO - Return code: 0 09:00:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:00:14 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:14 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:14 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 0x7f1952836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df7420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e85d20>, '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 0x1e86c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e870b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:14 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 09:00:14 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 09:00:14 INFO - Using env: {'DISPLAY': ':0', 09:00:14 INFO - 'HOME': '/home/cltbld', 09:00:14 INFO - 'LANG': 'en_US.UTF-8', 09:00:14 INFO - 'LANGUAGE': 'en_US:en', 09:00:14 INFO - 'LOGNAME': 'cltbld', 09:00:14 INFO - 'MAIL': '/var/mail/cltbld', 09:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:14 INFO - 'MOZ_NO_REMOTE': '1', 09:00:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:14 INFO - 'NO_EM_RESTART': '1', 09:00:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:14 INFO - 'PWD': '/builds/slave/test', 09:00:14 INFO - 'SHELL': '/bin/bash', 09:00:14 INFO - 'SHLVL': '1', 09:00:14 INFO - 'TERM': 'linux', 09:00:14 INFO - 'TMOUT': '86400', 09:00:14 INFO - 'USER': 'cltbld', 09:00:14 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 09:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:14 INFO - '_': '/tools/buildbot/bin/python'} 09:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:14 INFO - Downloading/unpacking jsonschema==2.5.1 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:00:16 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 09:00:16 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 09:00:16 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 09:00:16 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 09:00:16 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 09:00:16 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 09:00:16 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:16 INFO - warning: no files found matching '*.txt' 09:00:16 INFO - no previously-included directories found matching 'build' 09:00:16 INFO - no previously-included directories found matching 'dist' 09:00:16 INFO - no previously-included directories found matching '.git*' 09:00:16 INFO - Installing collected packages: jsonschema, functools32 09:00:16 INFO - Running setup.py install for functools32 09:00:16 INFO - warning: no files found matching '*.txt' 09:00:16 INFO - no previously-included directories found matching 'build' 09:00:16 INFO - no previously-included directories found matching 'dist' 09:00:16 INFO - no previously-included directories found matching '.git*' 09:00:16 INFO - Successfully installed jsonschema functools32 09:00:16 INFO - Cleaning up... 09:00:16 INFO - Return code: 0 09:00:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:00:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1952836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df7420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e85d20>, '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 0x1e86c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e870b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:16 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:00:16 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 functools32==3.2.3-2 09:00:16 INFO - Using env: {'DISPLAY': ':0', 09:00:16 INFO - 'HOME': '/home/cltbld', 09:00:16 INFO - 'LANG': 'en_US.UTF-8', 09:00:16 INFO - 'LANGUAGE': 'en_US:en', 09:00:16 INFO - 'LOGNAME': 'cltbld', 09:00:16 INFO - 'MAIL': '/var/mail/cltbld', 09:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:16 INFO - 'MOZ_NO_REMOTE': '1', 09:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:16 INFO - 'NO_EM_RESTART': '1', 09:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:16 INFO - 'PWD': '/builds/slave/test', 09:00:16 INFO - 'SHELL': '/bin/bash', 09:00:16 INFO - 'SHLVL': '1', 09:00:16 INFO - 'TERM': 'linux', 09:00:16 INFO - 'TMOUT': '86400', 09:00:16 INFO - 'USER': 'cltbld', 09:00:16 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 09:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:16 INFO - '_': '/tools/buildbot/bin/python'} 09:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:00:16 INFO - Cleaning up... 09:00:16 INFO - Return code: 0 09:00:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 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 0x7f1952836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df7420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e85d20>, '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 0x1e86c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e870b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:16 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 09:00:16 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 09:00:16 INFO - Using env: {'DISPLAY': ':0', 09:00:16 INFO - 'HOME': '/home/cltbld', 09:00:16 INFO - 'LANG': 'en_US.UTF-8', 09:00:16 INFO - 'LANGUAGE': 'en_US:en', 09:00:16 INFO - 'LOGNAME': 'cltbld', 09:00:16 INFO - 'MAIL': '/var/mail/cltbld', 09:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:16 INFO - 'MOZ_NO_REMOTE': '1', 09:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:16 INFO - 'NO_EM_RESTART': '1', 09:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:16 INFO - 'PWD': '/builds/slave/test', 09:00:16 INFO - 'SHELL': '/bin/bash', 09:00:16 INFO - 'SHLVL': '1', 09:00:16 INFO - 'TERM': 'linux', 09:00:16 INFO - 'TMOUT': '86400', 09:00:16 INFO - 'USER': 'cltbld', 09:00:16 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 09:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:16 INFO - '_': '/tools/buildbot/bin/python'} 09:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:16 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:16 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 09:00:16 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 09:00:16 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 09:00:16 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 09:00:16 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 09:00:16 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 09:00:19 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:19 INFO - Downloading docopt-0.6.1.tar.gz 09:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:19 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:19 INFO - Running setup.py install for blobuploader 09:00:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:19 INFO - Running setup.py install for requests 09:00:20 INFO - Running setup.py install for docopt 09:00:20 INFO - Successfully installed blobuploader requests docopt 09:00:20 INFO - Cleaning up... 09:00:20 INFO - Return code: 0 09:00:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:20 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 0x7f1952836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df7420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e85d20>, '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 0x1e86c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e870b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:20 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 09:00:20 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 09:00:20 INFO - Using env: {'DISPLAY': ':0', 09:00:20 INFO - 'HOME': '/home/cltbld', 09:00:20 INFO - 'LANG': 'en_US.UTF-8', 09:00:20 INFO - 'LANGUAGE': 'en_US:en', 09:00:20 INFO - 'LOGNAME': 'cltbld', 09:00:20 INFO - 'MAIL': '/var/mail/cltbld', 09:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:20 INFO - 'MOZ_NO_REMOTE': '1', 09:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:20 INFO - 'NO_EM_RESTART': '1', 09:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:20 INFO - 'PWD': '/builds/slave/test', 09:00:20 INFO - 'SHELL': '/bin/bash', 09:00:20 INFO - 'SHLVL': '1', 09:00:20 INFO - 'TERM': 'linux', 09:00:20 INFO - 'TMOUT': '86400', 09:00:20 INFO - 'USER': 'cltbld', 09:00:20 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 09:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:20 INFO - '_': '/tools/buildbot/bin/python'} 09:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:20 INFO - Running setup.py (path:/tmp/pip-YrXdx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:20 INFO - Running setup.py (path:/tmp/pip-6W1px2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:20 INFO - Running setup.py (path:/tmp/pip-5kcEZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:20 INFO - Running setup.py (path:/tmp/pip-sJTa4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:21 INFO - Running setup.py (path:/tmp/pip-MhkGdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:21 INFO - Running setup.py (path:/tmp/pip-QEBYHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:21 INFO - Running setup.py (path:/tmp/pip-5Hhdi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:21 INFO - Running setup.py (path:/tmp/pip-JQUgqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:21 INFO - Running setup.py (path:/tmp/pip-Jxyuob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:21 INFO - Running setup.py (path:/tmp/pip-VhMluy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:21 INFO - Running setup.py (path:/tmp/pip-RqOXnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:21 INFO - Running setup.py (path:/tmp/pip-uVcOVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:21 INFO - Running setup.py (path:/tmp/pip-i28NtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:21 INFO - Running setup.py (path:/tmp/pip-WEkRjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:21 INFO - Running setup.py (path:/tmp/pip-QvIMvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:21 INFO - Running setup.py (path:/tmp/pip-SFkgL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:21 INFO - Running setup.py (path:/tmp/pip-2rEBkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:21 INFO - Running setup.py install for manifestparser 09:00:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Running setup.py install for mozcrash 09:00:22 INFO - Running setup.py install for mozdebug 09:00:22 INFO - Running setup.py install for mozdevice 09:00:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Running setup.py install for mozfile 09:00:22 INFO - Running setup.py install for mozhttpd 09:00:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Running setup.py install for mozinfo 09:00:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Running setup.py install for mozInstall 09:00:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Running setup.py install for mozleak 09:00:22 INFO - Running setup.py install for mozlog 09:00:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Running setup.py install for moznetwork 09:00:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for mozprocess 09:00:23 INFO - Running setup.py install for mozprofile 09:00:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for mozrunner 09:00:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for mozscreenshot 09:00:23 INFO - Running setup.py install for moztest 09:00:23 INFO - Running setup.py install for mozversion 09:00:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:23 INFO - Cleaning up... 09:00:23 INFO - Return code: 0 09:00:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:23 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 0x7f1952836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df7420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e85d20>, '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 0x1e86c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e870b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:23 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 09:00:23 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 09:00:23 INFO - Using env: {'DISPLAY': ':0', 09:00:23 INFO - 'HOME': '/home/cltbld', 09:00:23 INFO - 'LANG': 'en_US.UTF-8', 09:00:23 INFO - 'LANGUAGE': 'en_US:en', 09:00:23 INFO - 'LOGNAME': 'cltbld', 09:00:23 INFO - 'MAIL': '/var/mail/cltbld', 09:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:23 INFO - 'MOZ_NO_REMOTE': '1', 09:00:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:23 INFO - 'NO_EM_RESTART': '1', 09:00:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:23 INFO - 'PWD': '/builds/slave/test', 09:00:23 INFO - 'SHELL': '/bin/bash', 09:00:23 INFO - 'SHLVL': '1', 09:00:23 INFO - 'TERM': 'linux', 09:00:23 INFO - 'TMOUT': '86400', 09:00:23 INFO - 'USER': 'cltbld', 09:00:23 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 09:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:23 INFO - '_': '/tools/buildbot/bin/python'} 09:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:23 INFO - Running setup.py (path:/tmp/pip-Mv2reO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:24 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:24 INFO - Running setup.py (path:/tmp/pip-EbXBLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:24 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:24 INFO - Running setup.py (path:/tmp/pip-bPHzCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:24 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:24 INFO - Running setup.py (path:/tmp/pip-C6cHcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:24 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:24 INFO - Running setup.py (path:/tmp/pip-wCcqHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:24 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:24 INFO - Running setup.py (path:/tmp/pip-WdWU4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:24 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:24 INFO - Running setup.py (path:/tmp/pip-1dAQQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:24 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:24 INFO - Running setup.py (path:/tmp/pip-t1NjNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:24 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:24 INFO - Running setup.py (path:/tmp/pip-4frIaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:24 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:24 INFO - Running setup.py (path:/tmp/pip-9__y6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:24 INFO - Running setup.py (path:/tmp/pip-A_UjK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:24 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:24 INFO - Running setup.py (path:/tmp/pip-W2Gm5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:24 INFO - Running setup.py (path:/tmp/pip-9WWvJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:24 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:24 INFO - Running setup.py (path:/tmp/pip-Nei8XV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:25 INFO - Running setup.py (path:/tmp/pip-JylWgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:25 INFO - Running setup.py (path:/tmp/pip-zq0elK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:25 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)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:25 INFO - Running setup.py (path:/tmp/pip-s4CFOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:25 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)) 09:00:25 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)) 09:00:25 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)) 09:00:25 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)) 09:00:25 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)) 09:00:25 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)) 09:00:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:27 INFO - Downloading blessings-1.6.tar.gz 09:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:27 INFO - Installing collected packages: blessings 09:00:27 INFO - Running setup.py install for blessings 09:00:27 INFO - Successfully installed blessings 09:00:27 INFO - Cleaning up... 09:00:27 INFO - Return code: 0 09:00:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:27 INFO - Reading from file tmpfile_stdout 09:00:27 INFO - Current package versions: 09:00:27 INFO - argparse == 1.2.1 09:00:27 INFO - blessings == 1.6 09:00:27 INFO - blobuploader == 1.2.4 09:00:27 INFO - docopt == 0.6.1 09:00:27 INFO - functools32 == 3.2.3-2 09:00:27 INFO - jsonschema == 2.5.1 09:00:27 INFO - manifestparser == 1.1 09:00:27 INFO - mozInstall == 1.12 09:00:27 INFO - mozcrash == 0.17 09:00:27 INFO - mozdebug == 0.1 09:00:27 INFO - mozdevice == 0.48 09:00:27 INFO - mozfile == 1.2 09:00:27 INFO - mozhttpd == 0.7 09:00:27 INFO - mozinfo == 0.9 09:00:27 INFO - mozleak == 0.1 09:00:27 INFO - mozlog == 3.3 09:00:27 INFO - moznetwork == 0.27 09:00:27 INFO - mozprocess == 0.23 09:00:27 INFO - mozprofile == 0.28 09:00:27 INFO - mozrunner == 6.12 09:00:27 INFO - mozscreenshot == 0.1 09:00:27 INFO - mozsystemmonitor == 0.3 09:00:27 INFO - moztest == 0.7 09:00:27 INFO - mozversion == 1.4 09:00:27 INFO - psutil == 3.1.1 09:00:27 INFO - requests == 1.2.3 09:00:27 INFO - wsgiref == 0.1.2 09:00:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:27 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 0x7f1952836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df7420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e85d20>, '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 0x1e86c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e870b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:27 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 09:00:27 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 09:00:27 INFO - Using env: {'DISPLAY': ':0', 09:00:27 INFO - 'HOME': '/home/cltbld', 09:00:27 INFO - 'LANG': 'en_US.UTF-8', 09:00:27 INFO - 'LANGUAGE': 'en_US:en', 09:00:27 INFO - 'LOGNAME': 'cltbld', 09:00:27 INFO - 'MAIL': '/var/mail/cltbld', 09:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:27 INFO - 'MOZ_NO_REMOTE': '1', 09:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:27 INFO - 'NO_EM_RESTART': '1', 09:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:27 INFO - 'PWD': '/builds/slave/test', 09:00:27 INFO - 'SHELL': '/bin/bash', 09:00:27 INFO - 'SHLVL': '1', 09:00:27 INFO - 'TERM': 'linux', 09:00:27 INFO - 'TMOUT': '86400', 09:00:27 INFO - 'USER': 'cltbld', 09:00:27 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 09:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:27 INFO - '_': '/tools/buildbot/bin/python'} 09:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:27 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)) 09:00:27 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)) 09:00:27 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)) 09:00:27 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)) 09:00:27 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)) 09:00:27 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)) 09:00:27 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)) 09:00:27 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)) 09:00:27 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)) 09:00:27 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)) 09:00:27 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)) 09:00:27 INFO - Cleaning up... 09:00:27 INFO - Return code: 0 09:00:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1952836f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df7420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e85d20>, '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 0x1e86c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e870b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:00:27 INFO - Using env: {'DISPLAY': ':0', 09:00:27 INFO - 'HOME': '/home/cltbld', 09:00:27 INFO - 'LANG': 'en_US.UTF-8', 09:00:27 INFO - 'LANGUAGE': 'en_US:en', 09:00:27 INFO - 'LOGNAME': 'cltbld', 09:00:27 INFO - 'MAIL': '/var/mail/cltbld', 09:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:27 INFO - 'MOZ_NO_REMOTE': '1', 09:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:27 INFO - 'NO_EM_RESTART': '1', 09:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:27 INFO - 'PWD': '/builds/slave/test', 09:00:27 INFO - 'SHELL': '/bin/bash', 09:00:27 INFO - 'SHLVL': '1', 09:00:27 INFO - 'TERM': 'linux', 09:00:27 INFO - 'TMOUT': '86400', 09:00:27 INFO - 'USER': 'cltbld', 09:00:27 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 09:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:27 INFO - '_': '/tools/buildbot/bin/python'} 09:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:00:27 INFO - Cleaning up... 09:00:27 INFO - Return code: 0 09:00:27 INFO - Running post-action listener: _resource_record_post_action 09:00:27 INFO - Running post-action listener: _start_resource_monitoring 09:00:28 INFO - Starting resource monitoring. 09:00:28 INFO - [mozharness: 2016-08-26 16:00:28.015171Z] Finished create-virtualenv step (success) 09:00:28 INFO - [mozharness: 2016-08-26 16:00:28.015637Z] Running install step. 09:00:28 INFO - Running pre-action listener: _resource_record_pre_action 09:00:28 INFO - Running main action method: install 09:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:28 INFO - Reading from file tmpfile_stdout 09:00:28 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:28 INFO - Reading from file tmpfile_stdout 09:00:28 INFO - Output received: 09:00:28 INFO - Usage: mozinstall [options] installer 09:00:28 INFO - Options: 09:00:28 INFO - -h, --help show this help message and exit 09:00:28 INFO - -d DEST, --destination=DEST 09:00:28 INFO - Directory to install application into. [default: 09:00:28 INFO - "/builds/slave/test"] 09:00:28 INFO - --app=APP Application being installed. [default: firefox] 09:00:28 INFO - mkdir: /builds/slave/test/build/application 09:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:00:44 INFO - Reading from file tmpfile_stdout 09:00:44 INFO - Output received: 09:00:44 INFO - /builds/slave/test/build/application/firefox/firefox 09:00:44 INFO - Running post-action listener: _resource_record_post_action 09:00:44 INFO - [mozharness: 2016-08-26 16:00:44.438365Z] Finished install step (success) 09:00:44 INFO - [mozharness: 2016-08-26 16:00:44.438645Z] Running run-tests step. 09:00:44 INFO - Running pre-action listener: _resource_record_pre_action 09:00:44 INFO - Running main action method: run_tests 09:00:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:00:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:00:44 INFO - Python 2.7.3 09:00:44 INFO - Return code: 0 09:00:44 INFO - grabbing minidump binary from tooltool 09:00:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:44 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/linux64/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 0x1e85d20>, '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 0x1e86c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e870b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:00:44 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:00:44 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/linux64/releng.manifest -o -c /builds/tooltool_cache 09:00:44 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:00:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:00:44 INFO - Return code: 0 09:00:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:00:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:00:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:00:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:00:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:00:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 09:00:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 09:00:44 INFO - Using env: {'DISPLAY': ':0', 09:00:44 INFO - 'HOME': '/home/cltbld', 09:00:44 INFO - 'LANG': 'en_US.UTF-8', 09:00:44 INFO - 'LANGUAGE': 'en_US:en', 09:00:44 INFO - 'LOGNAME': 'cltbld', 09:00:44 INFO - 'MAIL': '/var/mail/cltbld', 09:00:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:00:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:00:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:44 INFO - 'MOZ_NO_REMOTE': '1', 09:00:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:00:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:44 INFO - 'NO_EM_RESTART': '1', 09:00:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:44 INFO - 'PWD': '/builds/slave/test', 09:00:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:00:44 INFO - 'SHELL': '/bin/bash', 09:00:44 INFO - 'SHLVL': '1', 09:00:44 INFO - 'TERM': 'linux', 09:00:44 INFO - 'TMOUT': '86400', 09:00:44 INFO - 'USER': 'cltbld', 09:00:44 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526', 09:00:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:44 INFO - '_': '/tools/buildbot/bin/python'} 09:00:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 09:00:44 INFO - mozversion application_buildid: 20160826072316 09:00:44 INFO - mozversion application_changeset: 69fcb7b0e100aeb41df63129d02222d16ae3fef9 09:00:44 INFO - mozversion application_display_name: Nightly 09:00:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:00:44 INFO - mozversion application_name: Firefox 09:00:44 INFO - mozversion application_remotingname: firefox 09:00:44 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:00:44 INFO - mozversion application_vendor: Mozilla 09:00:44 INFO - mozversion application_version: 51.0a1 09:00:44 INFO - mozversion platform_buildid: 20160826072316 09:00:44 INFO - mozversion platform_changeset: 69fcb7b0e100aeb41df63129d02222d16ae3fef9 09:00:44 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:00:44 INFO - mozversion platform_version: 51.0a1 09:00:44 INFO - using testdate: 1472227244 09:00:44 INFO - actual date: 1472227244 09:00:44 INFO - starting webserver on 'localhost:33996' 09:00:44 INFO - SUITE-START | Running 2 tests 09:00:44 INFO - TEST-START | dromaeo_css 09:00:44 INFO - operating with platform_type : linux_ 09:00:44 INFO - Initialising browser for dromaeo_css test... 09:00:44 INFO - TEST-INFO | started process 11720 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW6DV7o/profile http://localhost:33996/getInfo.html) 09:00:49 INFO - PROCESS | 11720 | __metrics Screen width/height:1600/1200 09:00:49 INFO - PROCESS | 11720 | colorDepth:24 09:00:49 INFO - PROCESS | 11720 | Browser inner width/height: 1024/697 09:00:49 INFO - PROCESS | 11720 | __metrics 09:00:50 INFO - PROCESS | 11720 | 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" 09:00:50 INFO - PROCESS | 11720 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:00:50 INFO - PROCESS | 11720 | console.error: PushService: 09:00:50 INFO - PROCESS | 11720 | stateChangeProcessEnqueue: Error transitioning state 09:00:50 INFO - PROCESS | 11720 | UnknownError 09:00:51 INFO - TEST-INFO | 11720: exit 0 09:00:51 INFO - Browser initialized. 09:00:51 INFO - Running cycle 1/1 for dromaeo_css test... 09:00:51 INFO - TEST-INFO | started process 11800 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW6DV7o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:03 INFO - PROCESS | 11800 | RSS: Main: 180977664 09:01:03 INFO - PROCESS | 11800 | 09:01:55 INFO - PROCESS | 11800 | 1472227315138 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] 09:04:08 INFO - PROCESS | 11800 | Cycle 1(1): loaded http://localhost:33996/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33996/tests/dromaeo/cssquery-ext.html) 09:04:09 INFO - PROCESS | 11800 | RSS: Main: 284725248 09:04:09 INFO - PROCESS | 11800 | 09:07:12 INFO - PROCESS | 11800 | Cycle 1(1): loaded http://localhost:33996/tests/dromaeo/cssquery-ext.html (next: http://localhost:33996/tests/dromaeo/cssquery-jquery.html) 09:07:12 INFO - PROCESS | 11800 | RSS: Main: 275783680 09:07:12 INFO - PROCESS | 11800 | 09:10:15 INFO - PROCESS | 11800 | Cycle 1(1): loaded http://localhost:33996/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33996/tests/dromaeo/cssquery-mootools.html) 09:10:15 INFO - PROCESS | 11800 | RSS: Main: 255934464 09:10:15 INFO - PROCESS | 11800 | 09:13:17 INFO - PROCESS | 11800 | Cycle 1(1): loaded http://localhost:33996/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33996/tests/dromaeo/cssquery-prototype.html) 09:13:18 INFO - PROCESS | 11800 | RSS: Main: 217616384 09:13:18 INFO - PROCESS | 11800 | 09:16:21 INFO - PROCESS | 11800 | Cycle 1(1): loaded http://localhost:33996/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33996/tests/dromaeo/cssquery-yui.html) 09:16:22 INFO - PROCESS | 11800 | RSS: Main: 217657344 09:16:22 INFO - PROCESS | 11800 | 09:19:25 INFO - PROCESS | 11800 | Cycle 1(1): loaded http://localhost:33996/tests/dromaeo/cssquery-yui.html (next: http://localhost:33996/tests/dromaeo/cssquery-dojo.html) 09:19:25 INFO - PROCESS | 11800 | RSS: Main: 314937344 09:19:25 INFO - PROCESS | 11800 | 09:19:25 INFO - PROCESS | 11800 | __start_tp_report 09:19:25 INFO - PROCESS | 11800 | _x_x_mozilla_page_load 09:19:25 INFO - PROCESS | 11800 | _x_x_mozilla_page_load_details 09:19:25 INFO - PROCESS | 11800 | |i|pagename|runs| 09:19:25 INFO - PROCESS | 11800 | |0;dojo.html;2607.035396903146;2617.685877694677;2618.908338208163;2656.0969270448045;2669.2259244819;4644.419447569054;4677.462091859437;4693;4759.071980963709;4838.34682317887;4747.311639812225;4749.572538471537;4791.329213910047;4794.448638406584;4817.325574419575;4256.574342565749;4315.762633055181;4383.627391671712;4438.400815889862;4568.565986231302;4466.865994020184;4499;4510.030343476168;4531.886702832439;4546.568076032771;4296.978515107436;4334.956650433487;4342.066455712037;4358.455193100863;4400.7579583122915;4347.913041739179;4353.978230108861;4364.127174565068;4364.410804541378;4415.977920110379;4375.671824613167;4384.430024096847;4385.758783266919;4440.222960981815;4441.800118994621;4656.8602941911795;4714.057188562301;4763.928541071852;4791.616670666338;4799.7360145192;5123.564497017758;5124.795008199668;5125.15434953236;5129.12804823181;5198.324217851655;5143.434222235551;5153.6907785532985;5162.870928226786;5169.741512924339;5170.534651881311;4616.3537104805355;4768.976155119202;4802.543758342987;4802.975985120052;4817.13291607507;5248.08158572256;5267.683938963674;5279.551238144801;5314.73426328682;5323.52088312046;5056.064628043824;5059.848204553869;5064.898702025939;5121.28302037715;5124.256982737482;4928.827491037797;5047.293378926953;5106.872328191825;5146.562542183956;5149.124648809711;5063;5067.797288108508;5074.061298659686;5101.489851014943;5130.97434512825;5051.090803655377;5052.317937078521;5052.747362631853;5054.519820617035;5110.872228194325;4251.702380833374;4307.8492252771;4318.676099292566;4328.43730315057;4382.693211475166;3781.3193625147737;3799.2211596622624;3821.9871733990517;3859.8456061757215;3885.9611403886165;4388.232059389594;4394.692371534026;4395.1868904252815;4396.318570621559;4448.221561226837;4904.6566740327835;5046.318746969112;5095.515925987025;5112.846614601568;5173.63784535079;4382.255016647177;4390.551118131009;4405.625521830616;4457.197704413172;4457.8439754608435;4622.19111655459;4629.629629629621;4630.166570017362;4680.391549098664;4686.601638860735;4674.322223277538;4792.448868380181;4837.32277481153;4838.903221935612;4840.515948405272;5035.899282014266;5055.620828437955;5081.263216833612;5135.229715542699;5135.3324067870935;4785.952140478551;4792.808287668454;4796.808127674994;4801.927971080367;4847;4936.185529387611;4998.3252260945765;5029.84910452687;5032.572231360376;5101.311322971352;5079.796808127633;5101.642884998015;5126.179811230185;5137.743112844268;5214.035403450373;4988.575971042502;4992.500749924979;4997.425296090996;5007.424146223231;5062.620303477328;4829.830955916526;4956.430010548689;4961.355023846877;4961.5286547777905;4969.956911886762;4863.294822250683;4903.872109414783;4904.779284932258;4914.631402644887;4930.013997200646;4968.801247950041;4978.2781496682055;4987.401511818605;4989.800407983786;5042.268871013609;4792.209285467999;4842.8789280266865;4883.926741098958;4899.240617704261;4907.975460122677 09:19:25 INFO - PROCESS | 11800 | |1;ext.html;539.9460053994601;556.8607848037991;559.6289091725268;566.4420545762424;575.1832397994847;27966.720332796667;38522.80738596307;39604.80197599015;39664.60335396645;43955;39489.40765888514;39863.80068099655;39971;39977.600223997826;40009.39985900214;7121.715131394738;7752.26353496417;7912.367010639135;7914.089879663826;7922.603869806516;7175.856482870366;7423.035005449284;7426.925730742704;9892.25808064398;10152.847707284396;10267.743306417324;10324.328918620266;10328.07047365737;10336.844947325797;10337.173026157927;10166.847497287547;10219.131373833234;10232.948835255851;10233.590656373735;10234.539445724922;9704.854427183598;9796.559154838049;9796.755081122958;9802.852957205647;9803.31376803624;37512.43731344032;37701.24597508035;37797;37821.62178378236;37917.43123853144;3163.44639688056;3170.793898396597;3259.6740325967453;3438.398280300937;3594.28114377123;28787;28877.85561072202;29100.12699619015;29338;30492;3285.4128067689885;3510.666486683761;3540.2565461253166;3573.446115852021;3659.7804131751914;159390;159625;160088;160154.3984560151;160319;30921;54442.72778636082;54958.45041549613;54964.877634177596;54973;31042.534361984588;32994.67005329964;33191.66808331886;33193.83403082969;33239;13094.279814610098;13305.800412993813;13338.266395348155;13368.598942031755;13371.331433428484;10925.399103049249;11009.449527523591;11020.614278500214;11021.614243501599;11024.889751102546;7243.239459856639;7575.924240757632;7867.685292588347;7907.56508392044;7981.241782030698;3057.831819249918;3091.4280858041334;3102.4415605191284;3125.9531107033413;3129.3115514586752;788.9958460811405;841.6086519768214;868.778461492328;887.9822403551893;945.4516380499294;25152.496950060897;26544.60183097255;27236;27551.586726199122;27966.3008424791;896.551724137931;917.4357769971552;954.71358592422;996.5864166370875;1041.286718597763;14000.579982600539;14210;14624.341904614317;14784.926075369769;14976.326065327086;341986;345067;346311;351243;353235;46921;47361.28958065632;47468.5253147471;47473.81315467141;47519.76240118777;1042.2339580408393;1142.0692135909208;1153.3310679805525;1173.8180582009802;1177.8527684039495;26767.866160669073;28837.567436488887;29011.274718132216;29018.854905725337;29721.851390742908;40562.59437405588;46330.536694632625;48214.27678584754;48282;52975.470245298595;268741;269775;270052;270286;270931;23985;24908.50182996366;25044.499110017332;34341.82829085938;34793.826030870696;40743.1851362977;40835.38746918858;41001.38497922474;41186.38220426636;41192.79403602963;34688.306233874675;34894.3829212805;34985.65014349824;35685.64314356927;36053.81973090118;34479.827600861834;34756.47865282073;34971.65028349684;35245.64754352424;35457.29085418329;34307.828460857534;34308.48537271893;34906.080165591135;35333;35420.11449713778;30049.3990120192;31618;31857.8407107963;32033;32045.359092817547;24095.638565421883;24165.154219602933;24455.51088978246;24976.37559061038;25202.36994075163 09:19:25 INFO - PROCESS | 11800 | |2;jquery.html;326.8708860000298;343.3407856914724;348.9616142224356;350.2994011976047;370.497975243545;12304.5693400731;15592.610184745372;15871.920640396796;17609.11954402278;17800.109994500286;16475;16628.916855415737;16634;16702.66594668109;16858.578535536588;4693.788779504922;5113.386393632769;6474.320196379383;6862.279460656634;6909.3090690931;3954.3673012318036;4164.646005089571;6905.205901321337;7076.36312731854;7078.044463997346;4292.849750258742;7002.229754726976;7024.61364624948;7121.8219544511285;7144.714211431563;6769.966150169218;7010.859782804341;7028.54314469563;7065.2228254891925;7107.8933815992805;6877.58734475928;6891.517593768438;6895.241523432418;6898.931010689879;6899.517033807636;22023.449413764625;22528.719543286723;22728.545429091326;22870;22930.08279668811;2014.4863059919774;2111.419359676086;2279.076973825603;2305.7002589663234;2307.227078928561;14459.56621301352;14547.927260363736;14613.488527901472;14778.556643300719;15464.922675386664;1850.5651171974575;1904.3239649924355;1907.9427617171507;1955;1965.066593368153;78409;80891;81982;83268;83860;16347.919559278062;16826.831731682527;16840.410585629445;16972.15139243033;17650.205740741698;28627.856860715565;28652.426951460853;28775.978522255282;28787.136385908037;29107.708922910922;8639.308855291561;8745;8847.69033083839;9052.683156089506;9080.954595226982;7189.281071892873;7231.4937954342695;7254.492185547067;7430.702771889172;7493.288137626917;8223.588820558949;8356.373272004625;8397.496150230883;8464.153584641486;8474.322054235647;3130.4678204705256;3210.6147262328204;3305.405027095146;3406.6593340665736;3417.419038763416;597.1461192667978;716.913970323554;816.1838161838161;877.3069275272463;910.8815853938945;15402.46091386796;16216;16440.588985275463;16457.670846583;16969.406070787463;772.5712229712451;804.955727434985;811.2009670474578;831.966721331152;925.4262357338494;9056;9400.717978460658;9408.388454750419;9410.435373877452;9484.146426821619;277399;279061;353219;354801;355489;83745;85273.57363213145;85318.62879882382;86170.56914715511;88170.11829881619;881.8104107616894;890.2077151335187;927.4249965021729;930.5254320296698;933.6498812945146;15368.231588420758;15418;16026.519204423887;16135.919320403324;16326.346946122498;36447.27105457929;37449;37616.62383376131;37930.81034594809;38410;48859.511404885496;48941.02638214808;52294;53037.734811325696;56876.71561642165;6681.665916704243;6839.863202736017;7006.124234470692;7447.590382529013;7803.258690424401;11234.269772464764;12060.311556381937;12285.46431696038;12691.80962285585;12804.359782011048;9732.75668108303;9780.315377923476;9814.607415703293;10344.741381465223;11012.614558490415;10393.740156496147;10695.411752353384;10717.517711703147;10739.409332486788;10775.137988960707;10439.060484556427;10481.528331224961;10689.625863094754;10781.568737250736;11153.219274650697;7583.734569290048;7602.657880395506;7791.454598178073;7795.571243581658;7877.542654608916;8150.959245203736;10436.217283703601;10753.247272690836;10806.837897431387;10966.396848173426 09:19:25 INFO - PROCESS | 11800 | |3;mootools.html;1343.2075075705336;1376.9931150344246;1454.9272536373178;1634.7139250631137;1678.2615649114382;7190.604516751577;7773.455858089929;7775;7784.026996625422;7787.688492460302;6658.966705166467;6841.828954276146;6841.965790171054;7021.332973367535;7648.120466146394;2073.626747185506;2140.422086036769;2141.1970511058353;2155.461134716321;2163.816075633565;2133.3173384516963;2147.7852214778554;2154.7522034965946;2157.2880949286778;2169.1215057901577;2078.272604588397;2084.6560317547587;2086.2802333195023;2087.8225350845196;2091.4875855415444;2061.1034200122917;2091.9581608367826;2118.5339225370394;2120.3108989578295;2122.8195603373733;2053.291614393032;2069.1206237349065;2074.502119491311;2082.312836763864;2094.0786054136133;7739.380849532024;7741.18717534662;7820;7822.256885595859;7860.135385107691;4428.512863585003;4543.840965566189;4553.157665831832;4555.06621142665;4565.680402371835;2702.68919074307;2843.45974264889;2872.8276303421862;2877.1512403840916;2883.1927060423113;931.7344556801347;943.9103285187965;952.0431965874252;969.3408482232079;986.063239922077;10590.205734569938;10612.893871061267;10637.308574942605;10641.361518308926;10653.84019239712;4009.959900401017;4094.160697057096;4213.157368526246;4219.2194444027955;4233.026403927114;8352.498850069016;8431.620577073923;8431.95784021076;8435.24082832548;8458.36562257833;4447.73313601185;4465.59809617136;4468.352088947149;4468.664850136252;4469.217886869785;4103.7948102594755;4155.439015732837;4156.168766246763;4159.064210552662;4204.768737719393;3019.7502860368727;3255.4465740823644;3357.496375543688;3367.478040903664;3415.419378705626;998.3560603410759;998.6904059741504;1010.0404615615048;1031.5512751952924;1034.984475232872;758.9924100759032;801.1988011988012;803.2289002557493;815.3599424451895;836.3142223376773;5077.847664570068;5100.617453690988;5201.765920533585;5219.217117432416;5291.788328466818;739.5673530984327;740.9925900741032;763.8395936853212;764.0792844622217;782.9373650107977;3486.825658717105;3665.5601327840295;3669.174435751988;3726.6087060858763;3751.362268414376;8994.370394072352;9365.859512107323;9395.389299695498;9429.434233945985;9450.621975120917;9330.626774928927;9387.859182112274;9388.34281600295;9395.530223488935;9418.387804792803;7223.602701851343;7304.379127774092;7304.561726296439;7304.5982470963545;7305.89041164383;5113.590912727047;5157.432682404858;5220.817271395483;5273.868153296198;5454.318210223722;8765.693200738198;8881.600327985127;8992.100789920956;9052.864207036768;9082.228010619172;8853.22935412933;9152.039035901134;9165.908340916772;9202.769930751785;9214.631414743335;6176.413240742122;6353.174087368612;6367.649779262185;6370.1718776558755;6377.23473183221;6542.476601871744;6578.111954886029;6829.6243706596615;7196.136463624399;7429.702811887681;6725.9663701681175;6847.109875716125;6870.965645171742;6900.964855271549;6940.861182776417;6692.130023096966;6719.126513553207;6728.663566821737;6746.9325306746305;6778.356056174655;6217.222847144107;6316.11574379578;6320.336364681826;6324.114623952558;6328.177336946167;4439.667024973204;4466.5086840446875;4487.50637429889;4492.124035812985;4579.35888975537;4580.931286030779;4631.073785243032;4636.860894173045;4739.952600473951;4742 09:19:25 INFO - PROCESS | 11800 | |4;prototype.html;284.86611292692436;287.1385842472582;289.17584883083214;291.91242627211847;293.2902376249476;4755.571998520132;4780.4741478437345;4781.832635857746;4795.571166615869;4802.735849528274;4672.976635116828;4689.57793798558;4700.553447422498;4755.833545825897;4756.90486190276;3519.912002199946;3666.9083272918124;3675.3751862183494;3690.9480797972583;3717.070732316921;3629.981850090746;3781.470594116826;3781.5819067849557;3796.886093417202;3807.3717836556934;3734.421164719473;3771.1326394929183;3774.2451509698035;3779.79211143387;3790.0903783092135;3499.61504234534;3694.4827724118645;3763.284975854579;3767.416050512175;3774.9056273593105;3207.7273431758326;3208.0375887233968;3210.6628803975714;3214.035789263235;3223.09753269085;4674.439067311911;4678.450244616658;4696.459907110692;4703.576678098988;4724.173269677793;4216.915661686749;4229.133027729308;4231.87304380869;4232.682548808821;4234.640055595278;4784.617230621577;5009.12340340439;5022.773975171126;5038.874028149289;5057.9494205057845;3480.686738193575;3485.8082805445692;3497.545319108525;3499.91250218744;3505.894823155284;5160;5272.446393128705;5274.709890955995;5316.574674026068;5352.625316227826;3465.4455287154437;3536.2750636119536;3557.4308110702164;3557.7547858249923;3578.300307353986;3588.6949609282983;3603.2973570154095;3615.204654975901;3617.811224209368;3636.472711456824;4145.834166633361;4158.542560318362;4209.24233637942;4210.7684077375425;4218.713292445834;4985.975070124626;5015.724135172601;5015.8212819987275;5024.447310795809;5050.419201791768;1201.2312120243053;1246.5450110709708;1254.172246317426;1259.1815320041862;1259.817326487654;767.2289349204015;789.7591234673365;803.7548547692893;804.4368941740805;806.6732973145886;4210.894727631834;4348.478182618042;4466.352378905106;4467.195904737178;4488.865334039984;5140.3317568716575;5147.202183661464;5149.407818100966;5150.97424512875;5173.793048278102;4428.247197976351;4448.688591798543;4453.085173375415;4455.376247325377;4481.686281960231;2579.496998085393;2699.6490456240954;2717.483678101194;2726.931826704348;2740.7670348020642;4819.5782244238035;4829.782659780319;4860.1737704590305;4860.975695121572;4890.95109048912;5110.514501122388;5299.6290259681455;5301.575873930076;5313.495217954288;5318.361796584357;4871.488493708181;4872.707637541759;4878.8780280492565;4888.5111488850835;4896.6572339935865;5130.281760553451;5134.922976155286;5137.9486205138965;5144.279800827812;5171.63798534099;4846.345743324747;5055.862430953006;5075.7969681212335;5098.413682426569;5111.156659151198;4886.53577910098;4932.136876046749;4946.530079642428;4960.30555722192;4964.602831773522;3616.5660120785674;3638.1268495561403;3641.8361173747776;3643.3624115779103;3651.9366557894705;5116.878703990637;5280.4455532170105;5284.577233821208;5285.2336411219385;5288.550473209666;5025.497450254945;5029.1953287473825;5048.7728052237;5054.797808087782;5063.1898896176435;5050.242463630485;5071.974640126776;5077.162268225701;5102.617303702312;5103.489651034867;4707;5000.849974500771;5014.724190169576;5030.352994079263;5031.22016087507;4797;4898.755062246944;5043.193089105872;5079.949200507947;5082.720450375265;4967.7019378837385;4967.801287948441;4993.2510123480315;5028.396592408934;5033.119204139188 09:19:25 INFO - PROCESS | 11800 | |5;yui.html;547.2502671340264;609.481940350702;635.5551114220045;660.3429587560378;711.5695004522265;12463.376831158439;13732.931335343334;13765.449382024732;13777.035607507478;13779.10435821671;10802.569421880093;12631.557895473661;12682.302473363961;12691.492340306377;13561.796573051412;752.849430113978;773.3272053313597;776.3633820267383;776.3866545429104;777.0481160578274;767.1331395523051;768.7002069193019;769.3921801776589;769.6421164158666;769.734441617641;830.3647709502236;831.2435683527991;831.7296878514506;832.2967092806568;832.4838600067966;825.47582285249;825.529448214518;826.0294154368623;826.1243082332686;826.3471857232779;595.8272101090679;597.3936454498687;599.1970759182716;599.8200539838031;600.1987346891905;13346.199228046251;13413.329333533382;13615.387307571184;13617.114887532278;13626;13647.249401282925;13731.725365492734;13983.161010339412;14072.507462238773;14295.928520357436;10583.523741431654;10587.523561439753;10635.149188064936;10648.094911932494;10766.676999690022;3325.2518183408783;3337.5494308268317;3368.713659338959;3453.067671728644;3493.8777142800136;64455.35544644541;64978.3502164977;68575.65712171407;68830.65584672044;68950.65524672344;10215.642452514126;10422.374657520573;10470.685879423629;10520.579176832995;10835.241533092609;19341.709874351895;19353.80646193548;19378.612427751366;19380.806191938183;19386.321478748177;7208.711651533985;7301.269873012762;7416.85166296671;7428.665710043061;7560;3709.925801483955;3721.348763966295;3749.8125093745202;3765.6610905018133;3826.7895265760094;2980.2698338907107;3076.92307692305;3082;3085.6142982127235;3087.5677405163296;513.9845804625793;546.51906322436;558.606182641237;564.0608387035621;577.1227733844532;476.85932649868374;480.5098799224771;510.27540891933546;516.1277441124488;517.7980587570851;8238.546879921661;8463.23830855226;8495.915040849512;8661;8835.425697329652;575.7466714645543;579.0532479396218;584.7748616782569;588.7762650192899;599.8500374906273;4086.3053280942313;4098.631123198927;4148.605882441163;4215.346621273708;4319.308910574293;58079.419205807404;58080.70959645175;58183.70908145432;59894.10158847621;72937;66571.66999155006;74771.62614186894;75265;76423.47153056908;79035.60482197552;64836.351636483974;69092.654536727;70252.64873675599;70876.64561677158;71271.64364178145;8152.592370381339;8717;8789.34079943994;8812.471251724917;8885.155910188521;41729.37405938974;41888;41965.37051944284;42846;42896.35655465108;38544.42183367308;39158;39834.601653984246;55070.44929550653;56421.71789141028;10729.839052414063;11344.319340839576;11465.885341146482;11486.762116071437;12091.516339346328;12941.741165176833;13221.471141154247;13366.331683415596;13485.39315730814;13541.255230961997;11509.433019815033;11971.16201865861;12283.815742763687;12385.318807465315;12390.938045309716;11143.442827858413;11310.60412885545;11320.490577923849;11826.231294965799;11827.40862956866;11441.656750297505;11445.885541144482;11732.12009099338;12207.694807629525;12289.69275768113;9400.388974716896;9564.282678798978;9565.234781217347;9675.371100878421;9837.546918753622;9443.858342124737;9753.268504862306;9799.853002205115;9823.85264221023;10090.8486372703 09:19:25 INFO - PROCESS | 11800 | __end_tp_report 09:19:25 INFO - PROCESS | 11800 | __start_cc_report 09:19:25 INFO - PROCESS | 11800 | _x_x_mozilla_cycle_collect,977 09:19:25 INFO - PROCESS | 11800 | __end_cc_report 09:19:25 INFO - PROCESS | 11800 | __startTimestamp1472228365274__endTimestamp 09:19:25 INFO - PROCESS | 11800 | 09:19:25 INFO - PROCESS | 11800 | ------- Summary: start ------- 09:19:25 INFO - PROCESS | 11800 | Number of tests: 6 09:19:25 INFO - PROCESS | 11800 | 09:19:25 INFO - PROCESS | 11800 | [#0] dojo.html Cycles:165 Average:4721.34 Median:4837.32 stddev:496.48 (10.3%) stddev-sans-first:469.48 09:19:25 INFO - PROCESS | 11800 | Values: 2607.0 2617.7 2618.9 2656.1 2669.2 4644.4 4677.5 4693.0 4759.1 4838.3 4747.3 4749.6 4791.3 4794.4 4817.3 4256.6 4315.8 4383.6 4438.4 4568.6 4466.9 4499.0 4510.0 4531.9 4546.6 4297.0 4335.0 4342.1 4358.5 4400.8 4347.9 4354.0 4364.1 4364.4 4416.0 4375.7 4384.4 4385.8 4440.2 4441.8 4656.9 4714.1 4763.9 4791.6 4799.7 5123.6 5124.8 5125.2 5129.1 5198.3 5143.4 5153.7 5162.9 5169.7 5170.5 4616.4 4769.0 4802.5 4803.0 4817.1 5248.1 5267.7 5279.6 5314.7 5323.5 5056.1 5059.8 5064.9 5121.3 5124.3 4928.8 5047.3 5106.9 5146.6 5149.1 5063.0 5067.8 5074.1 5101.5 5131.0 5051.1 5052.3 5052.7 5054.5 5110.9 4251.7 4307.8 4318.7 4328.4 4382.7 3781.3 3799.2 3822.0 3859.8 3886.0 4388.2 4394.7 4395.2 4396.3 4448.2 4904.7 5046.3 5095.5 5112.8 5173.6 4382.3 4390.6 4405.6 4457.2 4457.8 4622.2 4629.6 4630.2 4680.4 4686.6 4674.3 4792.4 4837.3 4838.9 4840.5 5035.9 5055.6 5081.3 5135.2 5135.3 4786.0 4792.8 4796.8 4801.9 4847.0 4936.2 4998.3 5029.8 5032.6 5101.3 5079.8 5101.6 5126.2 5137.7 5214.0 4988.6 4992.5 4997.4 5007.4 5062.6 4829.8 4956.4 4961.4 4961.5 4970.0 4863.3 4903.9 4904.8 4914.6 4930.0 4968.8 4978.3 4987.4 4989.8 5042.3 4792.2 4842.9 4883.9 4899.2 4908.0 09:19:25 INFO - PROCESS | 11800 | 09:19:25 INFO - PROCESS | 11800 | [#1] ext.html Cycles:180 Average:41459.44 Median:18959.11 stddev:71161.46 (375.3%) stddev-sans-first:71294.77 09:19:25 INFO - PROCESS | 11800 | Values: 539.9 556.9 559.6 566.4 575.2 27966.7 38522.8 39604.8 39664.6 43955.0 39489.4 39863.8 39971.0 39977.6 40009.4 7121.7 7752.3 7912.4 7914.1 7922.6 7175.9 7423.0 7426.9 9892.3 10152.8 10267.7 10324.3 10328.1 10336.8 10337.2 10166.8 10219.1 10232.9 10233.6 10234.5 9704.9 9796.6 9796.8 9802.9 9803.3 37512.4 37701.2 37797.0 37821.6 37917.4 3163.4 3170.8 3259.7 3438.4 3594.3 28787.0 28877.9 29100.1 29338.0 30492.0 3285.4 3510.7 3540.3 3573.4 3659.8 159390.0 159625.0 160088.0 160154.4 160319.0 30921.0 54442.7 54958.5 54964.9 54973.0 31042.5 32994.7 33191.7 33193.8 33239.0 13094.3 13305.8 13338.3 13368.6 13371.3 10925.4 11009.4 11020.6 11021.6 11024.9 7243.2 7575.9 7867.7 7907.6 7981.2 3057.8 3091.4 3102.4 3126.0 3129.3 789.0 841.6 868.8 888.0 945.5 25152.5 26544.6 27236.0 27551.6 27966.3 896.6 917.4 954.7 996.6 1041.3 14000.6 14210.0 14624.3 14784.9 14976.3 341986.0 345067.0 346311.0 351243.0 353235.0 46921.0 47361.3 47468.5 47473.8 47519.8 1042.2 1142.1 1153.3 1173.8 1177.9 26767.9 28837.6 29011.3 29018.9 29721.9 40562.6 46330.5 48214.3 48282.0 52975.5 268741.0 269775.0 270052.0 270286.0 270931.0 23985.0 24908.5 25044.5 34341.8 34793.8 40743.2 40835.4 41001.4 41186.4 41192.8 34688.3 34894.4 34985.7 35685.6 36053.8 34479.8 34756.5 34971.7 35245.6 35457.3 34307.8 34308.5 34906.1 35333.0 35420.1 30049.4 31618.0 31857.8 32033.0 32045.4 24095.6 24165.2 24455.5 24976.4 25202.4 09:19:25 INFO - PROCESS | 11800 | 09:19:25 INFO - PROCESS | 11800 | [#2] jquery.html Cycles:180 Average:24278.92 Median:21182.18 stddev:54777.29 (258.6%) stddev-sans-first:54901.44 09:19:25 INFO - PROCESS | 11800 | Values: 326.9 343.3 349.0 350.3 370.5 12304.6 15592.6 15871.9 17609.1 17800.1 16475.0 16628.9 16634.0 16702.7 16858.6 4693.8 5113.4 6474.3 6862.3 6909.3 3954.4 4164.6 6905.2 7076.4 7078.0 4292.8 7002.2 7024.6 7121.8 7144.7 6770.0 7010.9 7028.5 7065.2 7107.9 6877.6 6891.5 6895.2 6898.9 6899.5 22023.4 22528.7 22728.5 22870.0 22930.1 2014.5 2111.4 2279.1 2305.7 2307.2 14459.6 14547.9 14613.5 14778.6 15464.9 1850.6 1904.3 1907.9 1955.0 1965.1 78409.0 80891.0 81982.0 83268.0 83860.0 16347.9 16826.8 16840.4 16972.2 17650.2 28627.9 28652.4 28776.0 28787.1 29107.7 8639.3 8745.0 8847.7 9052.7 9081.0 7189.3 7231.5 7254.5 7430.7 7493.3 8223.6 8356.4 8397.5 8464.2 8474.3 3130.5 3210.6 3305.4 3406.7 3417.4 597.1 716.9 816.2 877.3 910.9 15402.5 16216.0 16440.6 16457.7 16969.4 772.6 805.0 811.2 832.0 925.4 9056.0 9400.7 9408.4 9410.4 9484.1 277399.0 279061.0 353219.0 354801.0 355489.0 83745.0 85273.6 85318.6 86170.6 88170.1 881.8 890.2 927.4 930.5 933.6 15368.2 15418.0 16026.5 16135.9 16326.3 36447.3 37449.0 37616.6 37930.8 38410.0 48859.5 48941.0 52294.0 53037.7 56876.7 6681.7 6839.9 7006.1 7447.6 7803.3 11234.3 12060.3 12285.5 12691.8 12804.4 9732.8 9780.3 9814.6 10344.7 11012.6 10393.7 10695.4 10717.5 10739.4 10775.1 10439.1 10481.5 10689.6 10781.6 11153.2 7583.7 7602.7 7791.5 7795.6 7877.5 8151.0 10436.2 10753.2 10806.8 10966.4 09:19:25 INFO - PROCESS | 11800 | 09:19:25 INFO - PROCESS | 11800 | [#3] mootools.html Cycles:180 Average:5026.74 Median:5282.83 stddev:2825.37 (53.5%) stddev-sans-first:2819.74 09:19:25 INFO - PROCESS | 11800 | Values: 1343.2 1377.0 1454.9 1634.7 1678.3 7190.6 7773.5 7775.0 7784.0 7787.7 6659.0 6841.8 6842.0 7021.3 7648.1 2073.6 2140.4 2141.2 2155.5 2163.8 2133.3 2147.8 2154.8 2157.3 2169.1 2078.3 2084.7 2086.3 2087.8 2091.5 2061.1 2092.0 2118.5 2120.3 2122.8 2053.3 2069.1 2074.5 2082.3 2094.1 7739.4 7741.2 7820.0 7822.3 7860.1 4428.5 4543.8 4553.2 4555.1 4565.7 2702.7 2843.5 2872.8 2877.2 2883.2 931.7 943.9 952.0 969.3 986.1 10590.2 10612.9 10637.3 10641.4 10653.8 4010.0 4094.2 4213.2 4219.2 4233.0 8352.5 8431.6 8432.0 8435.2 8458.4 4447.7 4465.6 4468.4 4468.7 4469.2 4103.8 4155.4 4156.2 4159.1 4204.8 3019.8 3255.4 3357.5 3367.5 3415.4 998.4 998.7 1010.0 1031.6 1035.0 759.0 801.2 803.2 815.4 836.3 5077.8 5100.6 5201.8 5219.2 5291.8 739.6 741.0 763.8 764.1 782.9 3486.8 3665.6 3669.2 3726.6 3751.4 8994.4 9365.9 9395.4 9429.4 9450.6 9330.6 9387.9 9388.3 9395.5 9418.4 7223.6 7304.4 7304.6 7304.6 7305.9 5113.6 5157.4 5220.8 5273.9 5454.3 8765.7 8881.6 8992.1 9052.9 9082.2 8853.2 9152.0 9165.9 9202.8 9214.6 6176.4 6353.2 6367.6 6370.2 6377.2 6542.5 6578.1 6829.6 7196.1 7429.7 6726.0 6847.1 6871.0 6901.0 6940.9 6692.1 6719.1 6728.7 6746.9 6778.4 6217.2 6316.1 6320.3 6324.1 6328.2 4439.7 4466.5 4487.5 4492.1 4579.4 4580.9 4631.1 4636.9 4740.0 4742.0 09:19:25 INFO - PROCESS | 11800 | 09:19:25 INFO - PROCESS | 11800 | [#4] prototype.html Cycles:180 Average:4159.01 Median:4756.37 stddev:1231.88 (25.9%) stddev-sans-first:1200.53 09:19:25 INFO - PROCESS | 11800 | Values: 284.9 287.1 289.2 291.9 293.3 4755.6 4780.5 4781.8 4795.6 4802.7 4673.0 4689.6 4700.6 4755.8 4756.9 3519.9 3666.9 3675.4 3690.9 3717.1 3630.0 3781.5 3781.6 3796.9 3807.4 3734.4 3771.1 3774.2 3779.8 3790.1 3499.6 3694.5 3763.3 3767.4 3774.9 3207.7 3208.0 3210.7 3214.0 3223.1 4674.4 4678.5 4696.5 4703.6 4724.2 4216.9 4229.1 4231.9 4232.7 4234.6 4784.6 5009.1 5022.8 5038.9 5057.9 3480.7 3485.8 3497.5 3499.9 3505.9 5160.0 5272.4 5274.7 5316.6 5352.6 3465.4 3536.3 3557.4 3557.8 3578.3 3588.7 3603.3 3615.2 3617.8 3636.5 4145.8 4158.5 4209.2 4210.8 4218.7 4986.0 5015.7 5015.8 5024.4 5050.4 1201.2 1246.5 1254.2 1259.2 1259.8 767.2 789.8 803.8 804.4 806.7 4210.9 4348.5 4466.4 4467.2 4488.9 5140.3 5147.2 5149.4 5151.0 5173.8 4428.2 4448.7 4453.1 4455.4 4481.7 2579.5 2699.6 2717.5 2726.9 2740.8 4819.6 4829.8 4860.2 4861.0 4891.0 5110.5 5299.6 5301.6 5313.5 5318.4 4871.5 4872.7 4878.9 4888.5 4896.7 5130.3 5134.9 5137.9 5144.3 5171.6 4846.3 5055.9 5075.8 5098.4 5111.2 4886.5 4932.1 4946.5 4960.3 4964.6 3616.6 3638.1 3641.8 3643.4 3651.9 5116.9 5280.4 5284.6 5285.2 5288.6 5025.5 5029.2 5048.8 5054.8 5063.2 5050.2 5072.0 5077.2 5102.6 5103.5 4707.0 5000.8 5014.7 5030.4 5031.2 4797.0 4898.8 5043.2 5079.9 5082.7 4967.7 4967.8 4993.3 5028.4 5033.1 09:19:25 INFO - PROCESS | 11800 | 09:19:25 INFO - PROCESS | 11800 | [#5] yui.html Cycles:180 Average:16080.16 Median:495.39 stddev:21015.89 (4242.3%) stddev-sans-first:21042.47 09:19:25 INFO - PROCESS | 11800 | Values: 547.3 609.5 635.6 660.3 711.6 12463.4 13732.9 13765.4 13777.0 13779.1 10802.6 12631.6 12682.3 12691.5 13561.8 752.8 773.3 776.4 776.4 777.0 767.1 768.7 769.4 769.6 769.7 830.4 831.2 831.7 832.3 832.5 825.5 825.5 826.0 826.1 826.3 595.8 597.4 599.2 599.8 600.2 13346.2 13413.3 13615.4 13617.1 13626.0 13647.2 13731.7 13983.2 14072.5 14295.9 10583.5 10587.5 10635.1 10648.1 10766.7 3325.3 3337.5 3368.7 3453.1 3493.9 64455.4 64978.4 68575.7 68830.7 68950.7 10215.6 10422.4 10470.7 10520.6 10835.2 19341.7 19353.8 19378.6 19380.8 19386.3 7208.7 7301.3 7416.9 7428.7 7560.0 3709.9 3721.3 3749.8 3765.7 3826.8 2980.3 3076.9 3082.0 3085.6 3087.6 514.0 546.5 558.6 564.1 577.1 476.9 480.5 510.3 516.1 517.8 8238.5 8463.2 8495.9 8661.0 8835.4 575.7 579.1 584.8 588.8 599.9 4086.3 4098.6 4148.6 4215.3 4319.3 58079.4 58080.7 58183.7 59894.1 72937.0 66571.7 74771.6 75265.0 76423.5 79035.6 64836.4 69092.7 70252.6 70876.6 71271.6 8152.6 8717.0 8789.3 8812.5 8885.2 41729.4 41888.0 41965.4 42846.0 42896.4 38544.4 39158.0 39834.6 55070.4 56421.7 10729.8 11344.3 11465.9 11486.8 12091.5 12941.7 13221.5 13366.3 13485.4 13541.3 11509.4 11971.2 12283.8 12385.3 12390.9 11143.4 11310.6 11320.5 11826.2 11827.4 11441.7 11445.9 11732.1 12207.7 12289.7 9400.4 9564.3 9565.2 9675.4 9837.5 9443.9 9753.3 9799.9 9823.9 10090.8 09:19:25 INFO - PROCESS | 11800 | -------- Summary: end -------- 09:19:25 INFO - PROCESS | 11800 | 09:19:26 INFO - TEST-INFO | 11800: exit 0 09:19:26 INFO - TEST-OK | dromaeo_css | took 1121587ms 09:19:26 INFO - TEST-START | kraken 09:19:26 INFO - operating with platform_type : linux_ 09:19:26 INFO - Initialising browser for kraken test... 09:19:26 INFO - TEST-INFO | started process 12105 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphfCcmv/profile http://localhost:33996/getInfo.html) 09:19:31 INFO - PROCESS | 12105 | __metrics Screen width/height:1600/1200 09:19:31 INFO - PROCESS | 12105 | colorDepth:24 09:19:31 INFO - PROCESS | 12105 | Browser inner width/height: 1024/697 09:19:31 INFO - PROCESS | 12105 | __metrics 09:19:31 INFO - PROCESS | 12105 | 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" 09:19:31 INFO - PROCESS | 12105 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:19:33 INFO - TEST-INFO | 12105: exit 0 09:19:33 INFO - Browser initialized. 09:19:33 INFO - Running cycle 1/1 for kraken test... 09:19:33 INFO - TEST-INFO | started process 12189 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphfCcmv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:45 INFO - PROCESS | 12189 | RSS: Main: 181202944 09:19:45 INFO - PROCESS | 12189 | 09:19:47 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33996/tests/kraken/driver.html?testName=audio-beat-detection) 09:19:48 INFO - PROCESS | 12189 | RSS: Main: 234323968 09:19:48 INFO - PROCESS | 12189 | 09:19:51 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33996/tests/kraken/driver.html?testName=audio-dft) 09:19:52 INFO - PROCESS | 12189 | RSS: Main: 225075200 09:19:52 INFO - PROCESS | 12189 | 09:19:55 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33996/tests/kraken/driver.html?testName=audio-fft) 09:19:56 INFO - PROCESS | 12189 | RSS: Main: 304234496 09:19:56 INFO - PROCESS | 12189 | 09:19:58 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33996/tests/kraken/driver.html?testName=audio-oscillator) 09:19:58 INFO - PROCESS | 12189 | RSS: Main: 213643264 09:19:58 INFO - PROCESS | 12189 | 09:20:01 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33996/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:01 INFO - PROCESS | 12189 | RSS: Main: 215072768 09:20:01 INFO - PROCESS | 12189 | 09:20:04 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33996/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:05 INFO - PROCESS | 12189 | RSS: Main: 252866560 09:20:05 INFO - PROCESS | 12189 | 09:20:09 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33996/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:09 INFO - PROCESS | 12189 | RSS: Main: 247648256 09:20:09 INFO - PROCESS | 12189 | 09:20:12 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33996/tests/kraken/driver.html?testName=json-parse-financial) 09:20:13 INFO - PROCESS | 12189 | RSS: Main: 250802176 09:20:13 INFO - PROCESS | 12189 | 09:20:14 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33996/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:15 INFO - PROCESS | 12189 | RSS: Main: 214384640 09:20:15 INFO - PROCESS | 12189 | 09:20:17 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33996/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:18 INFO - PROCESS | 12189 | RSS: Main: 220139520 09:20:18 INFO - PROCESS | 12189 | 09:20:20 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33996/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:20 INFO - PROCESS | 12189 | RSS: Main: 222416896 09:20:20 INFO - PROCESS | 12189 | 09:20:23 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:24 INFO - PROCESS | 12189 | RSS: Main: 223494144 09:20:24 INFO - PROCESS | 12189 | 09:20:27 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:27 INFO - PROCESS | 12189 | RSS: Main: 221171712 09:20:27 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | Cycle 1(1): loaded http://localhost:33996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33996/tests/kraken/driver.html?testName=ai-astar) 09:20:29 INFO - PROCESS | 12189 | RSS: Main: 271462400 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | __start_tp_report 09:20:29 INFO - PROCESS | 12189 | _x_x_mozilla_page_load 09:20:29 INFO - PROCESS | 12189 | _x_x_mozilla_page_load_details 09:20:29 INFO - PROCESS | 12189 | |i|pagename|runs| 09:20:29 INFO - PROCESS | 12189 | |0;ai-astar;113;115;133;115;117;115;114;113;113;114 09:20:29 INFO - PROCESS | 12189 | |1;audio-beat-detection;159;123;152;195;125;149;154;130;181;121 09:20:29 INFO - PROCESS | 12189 | |2;audio-dft;161;162;161;161;164;162;163;163;162;164 09:20:29 INFO - PROCESS | 12189 | |3;audio-fft;77;76;75;167;77;77;77;76;157;75 09:20:29 INFO - PROCESS | 12189 | |4;audio-oscillator;85;85;139;83;84;84;142;85;85;141 09:20:29 INFO - PROCESS | 12189 | |5;imaging-gaussian-blur;117;117;117;118;118;118;118;118;117;118 09:20:29 INFO - PROCESS | 12189 | |6;imaging-darkroom;148;148;147;146;147;148;146;147;147;147 09:20:29 INFO - PROCESS | 12189 | |7;imaging-desaturate;94;95;95;95;96;95;95;96;95;95 09:20:29 INFO - PROCESS | 12189 | |8;json-parse-financial;63;64;64;65;72;65;64;65;65;64 09:20:29 INFO - PROCESS | 12189 | |9;json-stringify-tinderbox;65;65;65;65;66;65;66;66;65;65 09:20:29 INFO - PROCESS | 12189 | |10;stanford-crypto-aes;82;83;82;82;85;82;81;82;82;89 09:20:29 INFO - PROCESS | 12189 | |11;stanford-crypto-ccm;137;135;133;131;134;132;130;144;173;132 09:20:29 INFO - PROCESS | 12189 | |12;stanford-crypto-pbkdf2;176;179;173;173;175;172;171;177;177;174 09:20:29 INFO - PROCESS | 12189 | |13;stanford-crypto-sha256-iterative;69;74;70;73;70;70;70;72;68;67 09:20:29 INFO - PROCESS | 12189 | __end_tp_report 09:20:29 INFO - PROCESS | 12189 | __start_cc_report 09:20:29 INFO - PROCESS | 12189 | _x_x_mozilla_cycle_collect,2000 09:20:29 INFO - PROCESS | 12189 | __end_cc_report 09:20:29 INFO - PROCESS | 12189 | __startTimestamp1472228429926__endTimestamp 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | ------- Summary: start ------- 09:20:29 INFO - PROCESS | 12189 | Number of tests: 14 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#0] ai-astar Cycles:10 Average:116.20 Median:115.00 stddev:6.03 (5.2%) stddev-sans-first:6.29 09:20:29 INFO - PROCESS | 12189 | Values: 113.0 115.0 133.0 115.0 117.0 115.0 114.0 113.0 113.0 114.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#1] audio-beat-detection Cycles:10 Average:148.90 Median:153.00 stddev:25.10 (16.4%) stddev-sans-first:26.36 09:20:29 INFO - PROCESS | 12189 | Values: 159.0 123.0 152.0 195.0 125.0 149.0 154.0 130.0 181.0 121.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.50 stddev:1.16 (0.7%) stddev-sans-first:1.13 09:20:29 INFO - PROCESS | 12189 | Values: 161.0 162.0 161.0 161.0 164.0 162.0 163.0 163.0 162.0 164.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#3] audio-fft Cycles:10 Average:93.40 Median:76.50 stddev:36.24 (47.4%) stddev-sans-first:37.95 09:20:29 INFO - PROCESS | 12189 | Values: 77.0 76.0 75.0 167.0 77.0 77.0 77.0 76.0 157.0 75.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#4] audio-oscillator Cycles:10 Average:101.30 Median:84.50 stddev:27.18 (32.2%) stddev-sans-first:28.18 09:20:29 INFO - PROCESS | 12189 | Values: 85.0 85.0 139.0 83.0 84.0 84.0 142.0 85.0 85.0 141.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 09:20:29 INFO - PROCESS | 12189 | Values: 117.0 117.0 117.0 118.0 118.0 118.0 118.0 118.0 117.0 118.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 09:20:29 INFO - PROCESS | 12189 | Values: 148.0 148.0 147.0 146.0 147.0 148.0 146.0 147.0 147.0 147.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 09:20:29 INFO - PROCESS | 12189 | Values: 94.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 95.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:65.00 stddev:2.51 (3.9%) stddev-sans-first:2.55 09:20:29 INFO - PROCESS | 12189 | Values: 63.0 64.0 64.0 65.0 72.0 65.0 64.0 65.0 65.0 64.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#9] json-stringify-tinderbox Cycles:10 Average:65.30 Median:65.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 09:20:29 INFO - PROCESS | 12189 | Values: 65.0 65.0 65.0 65.0 66.0 65.0 66.0 66.0 65.0 65.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:82.00 stddev:2.36 (2.9%) stddev-sans-first:2.47 09:20:29 INFO - PROCESS | 12189 | Values: 82.0 83.0 82.0 82.0 85.0 82.0 81.0 82.0 82.0 89.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#11] stanford-crypto-ccm Cycles:10 Average:138.10 Median:134.50 stddev:12.90 (9.6%) stddev-sans-first:13.67 09:20:29 INFO - PROCESS | 12189 | Values: 137.0 135.0 133.0 131.0 134.0 132.0 130.0 144.0 173.0 132.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:175.50 stddev:2.54 (1.4%) stddev-sans-first:2.65 09:20:29 INFO - PROCESS | 12189 | Values: 176.0 179.0 173.0 173.0 175.0 172.0 171.0 177.0 177.0 174.0 09:20:29 INFO - PROCESS | 12189 | 09:20:29 INFO - PROCESS | 12189 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.00 stddev:2.16 (3.1%) stddev-sans-first:2.24 09:20:29 INFO - PROCESS | 12189 | Values: 69.0 74.0 70.0 73.0 70.0 70.0 70.0 72.0 68.0 67.0 09:20:29 INFO - PROCESS | 12189 | -------- Summary: end -------- 09:20:29 INFO - PROCESS | 12189 | 09:20:31 INFO - TEST-INFO | 12189: exit 0 09:20:31 INFO - TEST-OK | kraken | took 64992ms 09:20:31 INFO - SUITE-END | took 1186s 09:20:31 INFO - Completed test suite (00:19:46) 09:20:31 INFO - javascript benchmark 09:20:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2607.035396903146, 2617.685877694677, 2618.908338208163, 2656.0969270448045, 2669.2259244819, 4644.419447569054, 4677.462091859437, 4693.0, 4759.071980963709, 4838.34682317887, 4747.311639812225, 4749.572538471537, 4791.329213910047, 4794.448638406584, 4817.325574419575, 4256.574342565749, 4315.762633055181, 4383.627391671712, 4438.400815889862, 4568.565986231302, 4466.865994020184, 4499.0, 4510.030343476168, 4531.886702832439, 4546.568076032771, 4296.978515107436, 4334.956650433487, 4342.066455712037, 4358.455193100863, 4400.7579583122915, 4347.913041739179, 4353.978230108861, 4364.127174565068, 4364.410804541378, 4415.977920110379, 4375.671824613167, 4384.430024096847, 4385.758783266919, 4440.222960981815, 4441.800118994621, 4656.8602941911795, 4714.057188562301, 4763.928541071852, 4791.616670666338, 4799.7360145192, 5123.564497017758, 5124.795008199668, 5125.15434953236, 5129.12804823181, 5198.324217851655, 5143.434222235551, 5153.6907785532985, 5162.870928226786, 5169.741512924339, 5170.534651881311, 4616.3537104805355, 4768.976155119202, 4802.543758342987, 4802.975985120052, 4817.13291607507, 5248.08158572256, 5267.683938963674, 5279.551238144801, 5314.73426328682, 5323.52088312046, 5056.064628043824, 5059.848204553869, 5064.898702025939, 5121.28302037715, 5124.256982737482, 4928.827491037797, 5047.293378926953, 5106.872328191825, 5146.562542183956, 5149.124648809711, 5063.0, 5067.797288108508, 5074.061298659686, 5101.489851014943, 5130.97434512825, 5051.090803655377, 5052.317937078521, 5052.747362631853, 5054.519820617035, 5110.872228194325, 4251.702380833374, 4307.8492252771, 4318.676099292566, 4328.43730315057, 4382.693211475166, 3781.3193625147737, 3799.2211596622624, 3821.9871733990517, 3859.8456061757215, 3885.9611403886165, 4388.232059389594, 4394.692371534026, 4395.1868904252815, 4396.318570621559, 4448.221561226837, 4904.6566740327835, 5046.318746969112, 5095.515925987025, 5112.846614601568, 5173.63784535079, 4382.255016647177, 4390.551118131009, 4405.625521830616, 4457.197704413172, 4457.8439754608435, 4622.19111655459, 4629.629629629621, 4630.166570017362, 4680.391549098664, 4686.601638860735, 4674.322223277538, 4792.448868380181, 4837.32277481153, 4838.903221935612, 4840.515948405272, 5035.899282014266, 5055.620828437955, 5081.263216833612, 5135.229715542699, 5135.3324067870935, 4785.952140478551, 4792.808287668454, 4796.808127674994, 4801.927971080367, 4847.0, 4936.185529387611, 4998.3252260945765, 5029.84910452687, 5032.572231360376, 5101.311322971352, 5079.796808127633, 5101.642884998015, 5126.179811230185, 5137.743112844268, 5214.035403450373, 4988.575971042502, 4992.500749924979, 4997.425296090996, 5007.424146223231, 5062.620303477328, 4829.830955916526, 4956.430010548689, 4961.355023846877, 4961.5286547777905, 4969.956911886762, 4863.294822250683, 4903.872109414783, 4904.779284932258, 4914.631402644887, 4930.013997200646, 4968.801247950041, 4978.2781496682055, 4987.401511818605, 4989.800407983786, 5042.268871013609, 4792.209285467999, 4842.8789280266865, 4883.926741098958, 4899.240617704261, 4907.975460122677], "value": 4689.287842363917, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [539.9460053994601, 556.8607848037991, 559.6289091725268, 566.4420545762424, 575.1832397994847, 27966.720332796667, 38522.80738596307, 39604.80197599015, 39664.60335396645, 43955.0, 39489.40765888514, 39863.80068099655, 39971.0, 39977.600223997826, 40009.39985900214, 7121.715131394738, 7752.26353496417, 7912.367010639135, 7914.089879663826, 7922.603869806516, 7175.856482870366, 7423.035005449284, 7426.925730742704, 9892.25808064398, 10152.847707284396, 10267.743306417324, 10324.328918620266, 10328.07047365737, 10336.844947325797, 10337.173026157927, 10166.847497287547, 10219.131373833234, 10232.948835255851, 10233.590656373735, 10234.539445724922, 9704.854427183598, 9796.559154838049, 9796.755081122958, 9802.852957205647, 9803.31376803624, 37512.43731344032, 37701.24597508035, 37797.0, 37821.62178378236, 37917.43123853144, 3163.44639688056, 3170.793898396597, 3259.6740325967453, 3438.398280300937, 3594.28114377123, 28787.0, 28877.85561072202, 29100.12699619015, 29338.0, 30492.0, 3285.4128067689885, 3510.666486683761, 3540.2565461253166, 3573.446115852021, 3659.7804131751914, 159390.0, 159625.0, 160088.0, 160154.3984560151, 160319.0, 30921.0, 54442.72778636082, 54958.45041549613, 54964.877634177596, 54973.0, 31042.534361984588, 32994.67005329964, 33191.66808331886, 33193.83403082969, 33239.0, 13094.279814610098, 13305.800412993813, 13338.266395348155, 13368.598942031755, 13371.331433428484, 10925.399103049249, 11009.449527523591, 11020.614278500214, 11021.614243501599, 11024.889751102546, 7243.239459856639, 7575.924240757632, 7867.685292588347, 7907.56508392044, 7981.241782030698, 3057.831819249918, 3091.4280858041334, 3102.4415605191284, 3125.9531107033413, 3129.3115514586752, 788.9958460811405, 841.6086519768214, 868.778461492328, 887.9822403551893, 945.4516380499294, 25152.496950060897, 26544.60183097255, 27236.0, 27551.586726199122, 27966.3008424791, 896.551724137931, 917.4357769971552, 954.71358592422, 996.5864166370875, 1041.286718597763, 14000.579982600539, 14210.0, 14624.341904614317, 14784.926075369769, 14976.326065327086, 341986.0, 345067.0, 346311.0, 351243.0, 353235.0, 46921.0, 47361.28958065632, 47468.5253147471, 47473.81315467141, 47519.76240118777, 1042.2339580408393, 1142.0692135909208, 1153.3310679805525, 1173.8180582009802, 1177.8527684039495, 26767.866160669073, 28837.567436488887, 29011.274718132216, 29018.854905725337, 29721.851390742908, 40562.59437405588, 46330.536694632625, 48214.27678584754, 48282.0, 52975.470245298595, 268741.0, 269775.0, 270052.0, 270286.0, 270931.0, 23985.0, 24908.50182996366, 25044.499110017332, 34341.82829085938, 34793.826030870696, 40743.1851362977, 40835.38746918858, 41001.38497922474, 41186.38220426636, 41192.79403602963, 34688.306233874675, 34894.3829212805, 34985.65014349824, 35685.64314356927, 36053.81973090118, 34479.827600861834, 34756.47865282073, 34971.65028349684, 35245.64754352424, 35457.29085418329, 34307.828460857534, 34308.48537271893, 34906.080165591135, 35333.0, 35420.11449713778, 30049.3990120192, 31618.0, 31857.8407107963, 32033.0, 32045.359092817547, 24095.638565421883, 24165.154219602933, 24455.51088978246, 24976.37559061038, 25202.36994075163], "value": 16437.24205791821, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.8708860000298, 343.3407856914724, 348.9616142224356, 350.2994011976047, 370.497975243545, 12304.5693400731, 15592.610184745372, 15871.920640396796, 17609.11954402278, 17800.109994500286, 16475.0, 16628.916855415737, 16634.0, 16702.66594668109, 16858.578535536588, 4693.788779504922, 5113.386393632769, 6474.320196379383, 6862.279460656634, 6909.3090690931, 3954.3673012318036, 4164.646005089571, 6905.205901321337, 7076.36312731854, 7078.044463997346, 4292.849750258742, 7002.229754726976, 7024.61364624948, 7121.8219544511285, 7144.714211431563, 6769.966150169218, 7010.859782804341, 7028.54314469563, 7065.2228254891925, 7107.8933815992805, 6877.58734475928, 6891.517593768438, 6895.241523432418, 6898.931010689879, 6899.517033807636, 22023.449413764625, 22528.719543286723, 22728.545429091326, 22870.0, 22930.08279668811, 2014.4863059919774, 2111.419359676086, 2279.076973825603, 2305.7002589663234, 2307.227078928561, 14459.56621301352, 14547.927260363736, 14613.488527901472, 14778.556643300719, 15464.922675386664, 1850.5651171974575, 1904.3239649924355, 1907.9427617171507, 1955.0, 1965.066593368153, 78409.0, 80891.0, 81982.0, 83268.0, 83860.0, 16347.919559278062, 16826.831731682527, 16840.410585629445, 16972.15139243033, 17650.205740741698, 28627.856860715565, 28652.426951460853, 28775.978522255282, 28787.136385908037, 29107.708922910922, 8639.308855291561, 8745.0, 8847.69033083839, 9052.683156089506, 9080.954595226982, 7189.281071892873, 7231.4937954342695, 7254.492185547067, 7430.702771889172, 7493.288137626917, 8223.588820558949, 8356.373272004625, 8397.496150230883, 8464.153584641486, 8474.322054235647, 3130.4678204705256, 3210.6147262328204, 3305.405027095146, 3406.6593340665736, 3417.419038763416, 597.1461192667978, 716.913970323554, 816.1838161838161, 877.3069275272463, 910.8815853938945, 15402.46091386796, 16216.0, 16440.588985275463, 16457.670846583, 16969.406070787463, 772.5712229712451, 804.955727434985, 811.2009670474578, 831.966721331152, 925.4262357338494, 9056.0, 9400.717978460658, 9408.388454750419, 9410.435373877452, 9484.146426821619, 277399.0, 279061.0, 353219.0, 354801.0, 355489.0, 83745.0, 85273.57363213145, 85318.62879882382, 86170.56914715511, 88170.11829881619, 881.8104107616894, 890.2077151335187, 927.4249965021729, 930.5254320296698, 933.6498812945146, 15368.231588420758, 15418.0, 16026.519204423887, 16135.919320403324, 16326.346946122498, 36447.27105457929, 37449.0, 37616.62383376131, 37930.81034594809, 38410.0, 48859.511404885496, 48941.02638214808, 52294.0, 53037.734811325696, 56876.71561642165, 6681.665916704243, 6839.863202736017, 7006.124234470692, 7447.590382529013, 7803.258690424401, 11234.269772464764, 12060.311556381937, 12285.46431696038, 12691.80962285585, 12804.359782011048, 9732.75668108303, 9780.315377923476, 9814.607415703293, 10344.741381465223, 11012.614558490415, 10393.740156496147, 10695.411752353384, 10717.517711703147, 10739.409332486788, 10775.137988960707, 10439.060484556427, 10481.528331224961, 10689.625863094754, 10781.568737250736, 11153.219274650697, 7583.734569290048, 7602.657880395506, 7791.454598178073, 7795.571243581658, 7877.542654608916, 8150.959245203736, 10436.217283703601, 10753.247272690836, 10806.837897431387, 10966.396848173426], "value": 9264.15548757645, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1343.2075075705336, 1376.9931150344246, 1454.9272536373178, 1634.7139250631137, 1678.2615649114382, 7190.604516751577, 7773.455858089929, 7775.0, 7784.026996625422, 7787.688492460302, 6658.966705166467, 6841.828954276146, 6841.965790171054, 7021.332973367535, 7648.120466146394, 2073.626747185506, 2140.422086036769, 2141.1970511058353, 2155.461134716321, 2163.816075633565, 2133.3173384516963, 2147.7852214778554, 2154.7522034965946, 2157.2880949286778, 2169.1215057901577, 2078.272604588397, 2084.6560317547587, 2086.2802333195023, 2087.8225350845196, 2091.4875855415444, 2061.1034200122917, 2091.9581608367826, 2118.5339225370394, 2120.3108989578295, 2122.8195603373733, 2053.291614393032, 2069.1206237349065, 2074.502119491311, 2082.312836763864, 2094.0786054136133, 7739.380849532024, 7741.18717534662, 7820.0, 7822.256885595859, 7860.135385107691, 4428.512863585003, 4543.840965566189, 4553.157665831832, 4555.06621142665, 4565.680402371835, 2702.68919074307, 2843.45974264889, 2872.8276303421862, 2877.1512403840916, 2883.1927060423113, 931.7344556801347, 943.9103285187965, 952.0431965874252, 969.3408482232079, 986.063239922077, 10590.205734569938, 10612.893871061267, 10637.308574942605, 10641.361518308926, 10653.84019239712, 4009.959900401017, 4094.160697057096, 4213.157368526246, 4219.2194444027955, 4233.026403927114, 8352.498850069016, 8431.620577073923, 8431.95784021076, 8435.24082832548, 8458.36562257833, 4447.73313601185, 4465.59809617136, 4468.352088947149, 4468.664850136252, 4469.217886869785, 4103.7948102594755, 4155.439015732837, 4156.168766246763, 4159.064210552662, 4204.768737719393, 3019.7502860368727, 3255.4465740823644, 3357.496375543688, 3367.478040903664, 3415.419378705626, 998.3560603410759, 998.6904059741504, 1010.0404615615048, 1031.5512751952924, 1034.984475232872, 758.9924100759032, 801.1988011988012, 803.2289002557493, 815.3599424451895, 836.3142223376773, 5077.847664570068, 5100.617453690988, 5201.765920533585, 5219.217117432416, 5291.788328466818, 739.5673530984327, 740.9925900741032, 763.8395936853212, 764.0792844622217, 782.9373650107977, 3486.825658717105, 3665.5601327840295, 3669.174435751988, 3726.6087060858763, 3751.362268414376, 8994.370394072352, 9365.859512107323, 9395.389299695498, 9429.434233945985, 9450.621975120917, 9330.626774928927, 9387.859182112274, 9388.34281600295, 9395.530223488935, 9418.387804792803, 7223.602701851343, 7304.379127774092, 7304.561726296439, 7304.5982470963545, 7305.89041164383, 5113.590912727047, 5157.432682404858, 5220.817271395483, 5273.868153296198, 5454.318210223722, 8765.693200738198, 8881.600327985127, 8992.100789920956, 9052.864207036768, 9082.228010619172, 8853.22935412933, 9152.039035901134, 9165.908340916772, 9202.769930751785, 9214.631414743335, 6176.413240742122, 6353.174087368612, 6367.649779262185, 6370.1718776558755, 6377.23473183221, 6542.476601871744, 6578.111954886029, 6829.6243706596615, 7196.136463624399, 7429.702811887681, 6725.9663701681175, 6847.109875716125, 6870.965645171742, 6900.964855271549, 6940.861182776417, 6692.130023096966, 6719.126513553207, 6728.663566821737, 6746.9325306746305, 6778.356056174655, 6217.222847144107, 6316.11574379578, 6320.336364681826, 6324.114623952558, 6328.177336946167, 4439.667024973204, 4466.5086840446875, 4487.50637429889, 4492.124035812985, 4579.35888975537, 4580.931286030779, 4631.073785243032, 4636.860894173045, 4739.952600473951, 4742.0], "value": 4031.153247080475, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.86611292692436, 287.1385842472582, 289.17584883083214, 291.91242627211847, 293.2902376249476, 4755.571998520132, 4780.4741478437345, 4781.832635857746, 4795.571166615869, 4802.735849528274, 4672.976635116828, 4689.57793798558, 4700.553447422498, 4755.833545825897, 4756.90486190276, 3519.912002199946, 3666.9083272918124, 3675.3751862183494, 3690.9480797972583, 3717.070732316921, 3629.981850090746, 3781.470594116826, 3781.5819067849557, 3796.886093417202, 3807.3717836556934, 3734.421164719473, 3771.1326394929183, 3774.2451509698035, 3779.79211143387, 3790.0903783092135, 3499.61504234534, 3694.4827724118645, 3763.284975854579, 3767.416050512175, 3774.9056273593105, 3207.7273431758326, 3208.0375887233968, 3210.6628803975714, 3214.035789263235, 3223.09753269085, 4674.439067311911, 4678.450244616658, 4696.459907110692, 4703.576678098988, 4724.173269677793, 4216.915661686749, 4229.133027729308, 4231.87304380869, 4232.682548808821, 4234.640055595278, 4784.617230621577, 5009.12340340439, 5022.773975171126, 5038.874028149289, 5057.9494205057845, 3480.686738193575, 3485.8082805445692, 3497.545319108525, 3499.91250218744, 3505.894823155284, 5160.0, 5272.446393128705, 5274.709890955995, 5316.574674026068, 5352.625316227826, 3465.4455287154437, 3536.2750636119536, 3557.4308110702164, 3557.7547858249923, 3578.300307353986, 3588.6949609282983, 3603.2973570154095, 3615.204654975901, 3617.811224209368, 3636.472711456824, 4145.834166633361, 4158.542560318362, 4209.24233637942, 4210.7684077375425, 4218.713292445834, 4985.975070124626, 5015.724135172601, 5015.8212819987275, 5024.447310795809, 5050.419201791768, 1201.2312120243053, 1246.5450110709708, 1254.172246317426, 1259.1815320041862, 1259.817326487654, 767.2289349204015, 789.7591234673365, 803.7548547692893, 804.4368941740805, 806.6732973145886, 4210.894727631834, 4348.478182618042, 4466.352378905106, 4467.195904737178, 4488.865334039984, 5140.3317568716575, 5147.202183661464, 5149.407818100966, 5150.97424512875, 5173.793048278102, 4428.247197976351, 4448.688591798543, 4453.085173375415, 4455.376247325377, 4481.686281960231, 2579.496998085393, 2699.6490456240954, 2717.483678101194, 2726.931826704348, 2740.7670348020642, 4819.5782244238035, 4829.782659780319, 4860.1737704590305, 4860.975695121572, 4890.95109048912, 5110.514501122388, 5299.6290259681455, 5301.575873930076, 5313.495217954288, 5318.361796584357, 4871.488493708181, 4872.707637541759, 4878.8780280492565, 4888.5111488850835, 4896.6572339935865, 5130.281760553451, 5134.922976155286, 5137.9486205138965, 5144.279800827812, 5171.63798534099, 4846.345743324747, 5055.862430953006, 5075.7969681212335, 5098.413682426569, 5111.156659151198, 4886.53577910098, 4932.136876046749, 4946.530079642428, 4960.30555722192, 4964.602831773522, 3616.5660120785674, 3638.1268495561403, 3641.8361173747776, 3643.3624115779103, 3651.9366557894705, 5116.878703990637, 5280.4455532170105, 5284.577233821208, 5285.2336411219385, 5288.550473209666, 5025.497450254945, 5029.1953287473825, 5048.7728052237, 5054.797808087782, 5063.1898896176435, 5050.242463630485, 5071.974640126776, 5077.162268225701, 5102.617303702312, 5103.489651034867, 4707.0, 5000.849974500771, 5014.724190169576, 5030.352994079263, 5031.22016087507, 4797.0, 4898.755062246944, 5043.193089105872, 5079.949200507947, 5082.720450375265, 4967.7019378837385, 4967.801287948441, 4993.2510123480315, 5028.396592408934, 5033.119204139188], "value": 3760.537088847839, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.2502671340264, 609.481940350702, 635.5551114220045, 660.3429587560378, 711.5695004522265, 12463.376831158439, 13732.931335343334, 13765.449382024732, 13777.035607507478, 13779.10435821671, 10802.569421880093, 12631.557895473661, 12682.302473363961, 12691.492340306377, 13561.796573051412, 752.849430113978, 773.3272053313597, 776.3633820267383, 776.3866545429104, 777.0481160578274, 767.1331395523051, 768.7002069193019, 769.3921801776589, 769.6421164158666, 769.734441617641, 830.3647709502236, 831.2435683527991, 831.7296878514506, 832.2967092806568, 832.4838600067966, 825.47582285249, 825.529448214518, 826.0294154368623, 826.1243082332686, 826.3471857232779, 595.8272101090679, 597.3936454498687, 599.1970759182716, 599.8200539838031, 600.1987346891905, 13346.199228046251, 13413.329333533382, 13615.387307571184, 13617.114887532278, 13626.0, 13647.249401282925, 13731.725365492734, 13983.161010339412, 14072.507462238773, 14295.928520357436, 10583.523741431654, 10587.523561439753, 10635.149188064936, 10648.094911932494, 10766.676999690022, 3325.2518183408783, 3337.5494308268317, 3368.713659338959, 3453.067671728644, 3493.8777142800136, 64455.35544644541, 64978.3502164977, 68575.65712171407, 68830.65584672044, 68950.65524672344, 10215.642452514126, 10422.374657520573, 10470.685879423629, 10520.579176832995, 10835.241533092609, 19341.709874351895, 19353.80646193548, 19378.612427751366, 19380.806191938183, 19386.321478748177, 7208.711651533985, 7301.269873012762, 7416.85166296671, 7428.665710043061, 7560.0, 3709.925801483955, 3721.348763966295, 3749.8125093745202, 3765.6610905018133, 3826.7895265760094, 2980.2698338907107, 3076.92307692305, 3082.0, 3085.6142982127235, 3087.5677405163296, 513.9845804625793, 546.51906322436, 558.606182641237, 564.0608387035621, 577.1227733844532, 476.85932649868374, 480.5098799224771, 510.27540891933546, 516.1277441124488, 517.7980587570851, 8238.546879921661, 8463.23830855226, 8495.915040849512, 8661.0, 8835.425697329652, 575.7466714645543, 579.0532479396218, 584.7748616782569, 588.7762650192899, 599.8500374906273, 4086.3053280942313, 4098.631123198927, 4148.605882441163, 4215.346621273708, 4319.308910574293, 58079.419205807404, 58080.70959645175, 58183.70908145432, 59894.10158847621, 72937.0, 66571.66999155006, 74771.62614186894, 75265.0, 76423.47153056908, 79035.60482197552, 64836.351636483974, 69092.654536727, 70252.64873675599, 70876.64561677158, 71271.64364178145, 8152.592370381339, 8717.0, 8789.34079943994, 8812.471251724917, 8885.155910188521, 41729.37405938974, 41888.0, 41965.37051944284, 42846.0, 42896.35655465108, 38544.42183367308, 39158.0, 39834.601653984246, 55070.44929550653, 56421.71789141028, 10729.839052414063, 11344.319340839576, 11465.885341146482, 11486.762116071437, 12091.516339346328, 12941.741165176833, 13221.471141154247, 13366.331683415596, 13485.39315730814, 13541.255230961997, 11509.433019815033, 11971.16201865861, 12283.815742763687, 12385.318807465315, 12390.938045309716, 11143.442827858413, 11310.60412885545, 11320.490577923849, 11826.231294965799, 11827.40862956866, 11441.656750297505, 11445.885541144482, 11732.12009099338, 12207.694807629525, 12289.69275768113, 9400.388974716896, 9564.282678798978, 9565.234781217347, 9675.371100878421, 9837.546918753622, 9443.858342124737, 9753.268504862306, 9799.853002205115, 9823.85264221023, 10090.8486372703], "value": 6462.519650607214, "unit": "score"}], "name": "dromaeo_css", "value": 6419.169663373155, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 133.0, 115.0, 117.0, 115.0, 114.0, 113.0, 113.0, 114.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 123.0, 152.0, 195.0, 125.0, 149.0, 154.0, 130.0, 181.0, 121.0], "value": 148.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 164.0, 162.0, 163.0, 163.0, 162.0, 164.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 75.0, 167.0, 77.0, 77.0, 77.0, 76.0, 157.0, 75.0], "value": 93.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 139.0, 83.0, 84.0, 84.0, 142.0, 85.0, 85.0, 141.0], "value": 101.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 118.0, 118.0, 118.0, 118.0, 117.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 146.0, 147.0, 148.0, 146.0, 147.0, 147.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 65.0, 72.0, 65.0, 64.0, 65.0, 65.0, 64.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 66.0, 66.0, 65.0, 65.0], "value": 65.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 82.0, 85.0, 82.0, 81.0, 82.0, 82.0, 89.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 133.0, 131.0, 134.0, 132.0, 130.0, 144.0, 173.0, 132.0], "value": 138.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 179.0, 173.0, 173.0, 175.0, 172.0, 171.0, 177.0, 177.0, 174.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 70.0, 73.0, 70.0, 70.0, 70.0, 72.0, 68.0, 67.0], "value": 70.3, "unit": "score"}], "name": "kraken", "value": 1578.3999999999999, "alertThreshold": 2.0}]} 09:20:31 INFO - Return code: 0 09:20:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:20:31 INFO - # TBPL SUCCESS # 09:20:31 INFO - Running post-action listener: _resource_record_post_action 09:20:31 INFO - [mozharness: 2016-08-26 16:20:31.727524Z] Finished run-tests step (success) 09:20:31 INFO - Running post-run listener: _resource_record_post_run 09:20:31 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 143360; Write bytes: 405454848; Read time: 12; Write time: 371316 09:20:31 INFO - TinderboxPrint: CPU usage
13.0% 09:20:31 INFO - TinderboxPrint: I/O read bytes / time
143,360 / 12 09:20:31 INFO - TinderboxPrint: I/O write bytes / time
405,454,848 / 371,316 09:20:31 INFO - TinderboxPrint: CPU idle
8,348.4 (87.0%) 09:20:31 INFO - TinderboxPrint: CPU user
1,199.1 (12.5%) 09:20:31 INFO - TinderboxPrint: Swap in / out
0 / 0 09:20:31 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114298880; Read time: 0; Write time: 102932 09:20:31 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 139264; Write bytes: 291155968; Read time: 0; Write time: 268384 09:20:32 INFO - Running post-run listener: _upload_blobber_files 09:20:32 INFO - Blob upload gear active. 09:20:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:32 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 09:20:32 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:20:32 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:20:32 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:20:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:20:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:33 INFO - (blobuploader) - INFO - Done attempting. 09:20:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:20:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:20:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:38 INFO - (blobuploader) - INFO - Done attempting. 09:20:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:20:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:20:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:39 INFO - (blobuploader) - INFO - Done attempting. 09:20:39 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:39 INFO - Return code: 0 09:20:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/95903e3c98d27114bc528f02e906630afd4b02a5b605b12d44b681e054d5b4c8b1a7c3825d11deb9013b62f7ba64fdc2423c0a2ba5e42480036fdf7220f5fdb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e53143d02cabb14a9e122e95a1890e5fe48239ea1e8b95cb1d15d6732c05dfc2f62ab263229180befda20b920286763ebd9e1ce079606e8ed485175fe10f3c2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/034ee942232062251feddca70745f9656e21df66380ff73b8627cfad53d25b4a8f9048944e6388c9ff4700abc5f22cc5981540201f2dcba36cf3b394c23477ae"} 09:20:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:39 INFO - Contents: 09:20:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/95903e3c98d27114bc528f02e906630afd4b02a5b605b12d44b681e054d5b4c8b1a7c3825d11deb9013b62f7ba64fdc2423c0a2ba5e42480036fdf7220f5fdb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e53143d02cabb14a9e122e95a1890e5fe48239ea1e8b95cb1d15d6732c05dfc2f62ab263229180befda20b920286763ebd9e1ce079606e8ed485175fe10f3c2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/034ee942232062251feddca70745f9656e21df66380ff73b8627cfad53d25b4a8f9048944e6388c9ff4700abc5f22cc5981540201f2dcba36cf3b394c23477ae"} 09:20:39 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:39 INFO - Copying logs to upload dir... 09:20:39 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.717015 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-08-26 09:20:39.064563) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 09:20:39.068495) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/95903e3c98d27114bc528f02e906630afd4b02a5b605b12d44b681e054d5b4c8b1a7c3825d11deb9013b62f7ba64fdc2423c0a2ba5e42480036fdf7220f5fdb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e53143d02cabb14a9e122e95a1890e5fe48239ea1e8b95cb1d15d6732c05dfc2f62ab263229180befda20b920286763ebd9e1ce079606e8ed485175fe10f3c2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/034ee942232062251feddca70745f9656e21df66380ff73b8627cfad53d25b4a8f9048944e6388c9ff4700abc5f22cc5981540201f2dcba36cf3b394c23477ae"} build_url:https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011466 build_url: 'https://queue.taskcluster.net/v1/task/R2nU96diRm6DdKXGaYqEag/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/95903e3c98d27114bc528f02e906630afd4b02a5b605b12d44b681e054d5b4c8b1a7c3825d11deb9013b62f7ba64fdc2423c0a2ba5e42480036fdf7220f5fdb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e53143d02cabb14a9e122e95a1890e5fe48239ea1e8b95cb1d15d6732c05dfc2f62ab263229180befda20b920286763ebd9e1ce079606e8ed485175fe10f3c2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/034ee942232062251feddca70745f9656e21df66380ff73b8627cfad53d25b4a8f9048944e6388c9ff4700abc5f22cc5981540201f2dcba36cf3b394c23477ae"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 09:20:39.096812) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:20:39.097490) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=eca2ab57546de7b41f256b19000001ef-1472226871.263831-1505151526 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004485 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:20:39.181952) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 09:20:39.182695) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 09:20:39.183402) ========= ========= Total master_lag: 0.18 =========