builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1471323650.95 results: success (0) buildid: 20160815203026 builduid: b9e84d86e2d64467a7481a6eb6c634ec revision: c9d9d817ae305b37464b7e5b222c1113c0646fd0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:50.955420) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:50.956190) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:50.956677) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:50.985003) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:50.985525) ========= 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=8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-15 22:00:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2016-08-15 22:00:51 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138712 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:51.140581) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:51.141098) ========= 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=8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020942 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:51.197337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:51.197999) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c9d9d817ae305b37464b7e5b222c1113c0646fd0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c9d9d817ae305b37464b7e5b222c1113c0646fd0 --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=8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-15 22:00:51,354 truncating revision to first 12 chars 2016-08-15 22:00:51,354 Setting DEBUG logging. 2016-08-15 22:00:51,354 attempt 1/10 2016-08-15 22:00:51,354 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c9d9d817ae30?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-15 22:00:51,660 unpacking tar archive at: fx-team-c9d9d817ae30/testing/mozharness/ program finished with exit code 0 elapsedTime=0.648810 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:51.861838) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:51.862220) ========= script_repo_revision: c9d9d817ae305b37464b7e5b222c1113c0646fd0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:51.862706) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:51.863028) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 22:00:51.872696) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-08-15 22:00:51.873034) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:00:52 INFO - MultiFileLogger online at 20160815 22:00:52 in /builds/slave/test 22:00:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:00:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:00:52 INFO - {'append_to_log': False, 22:00:52 INFO - 'base_work_dir': '/builds/slave/test', 22:00:52 INFO - 'blob_upload_branch': 'Fx-Team', 22:00:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:00:52 INFO - 'branch': 'Fx-Team', 22:00:52 INFO - 'buildbot_json_path': 'buildprops.json', 22:00:52 INFO - 'config_files': ('talos/linux_config.py',), 22:00:52 INFO - 'default_actions': ('clobber', 22:00:52 INFO - 'read-buildbot-config', 22:00:52 INFO - 'download-and-extract', 22:00:52 INFO - 'populate-webroot', 22:00:52 INFO - 'create-virtualenv', 22:00:52 INFO - 'install', 22:00:52 INFO - 'run-tests'), 22:00:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:00:52 INFO - 'download_minidump_stackwalk': True, 22:00:52 INFO - 'download_symbols': 'ondemand', 22:00:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:00:52 INFO - 'tooltool.py': '/tools/tooltool.py', 22:00:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:00:52 INFO - '/tools/misc-python/virtualenv.py')}, 22:00:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:00:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:00:52 INFO - 'installer_path': 'installer.exe', 22:00:52 INFO - 'log_level': 'info', 22:00:52 INFO - 'log_name': 'talos', 22:00:52 INFO - 'log_to_console': True, 22:00:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:00:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:00:52 INFO - 'opt_config_files': (), 22:00:52 INFO - 'pip_index': False, 22:00:52 INFO - 'sps_profile': False, 22:00:52 INFO - 'sps_profile_interval': 0, 22:00:52 INFO - 'suite': 'dromaeojs', 22:00:52 INFO - 'system_bits': '32', 22:00:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:00:52 INFO - 'title': 'talos-linux64-ix-001', 22:00:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:00:52 INFO - 'use_talos_json': True, 22:00:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:00:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:00:52 INFO - 'work_dir': 'build'} 22:00:52 INFO - [mozharness: 2016-08-16 05:00:52.125033Z] Running clobber step. 22:00:52 INFO - Running pre-action listener: _resource_record_pre_action 22:00:52 INFO - Running main action method: clobber 22:00:52 INFO - rmtree: /builds/slave/test/build 22:00:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:00:52 INFO - Running post-action listener: _resource_record_post_action 22:00:52 INFO - [mozharness: 2016-08-16 05:00:52.577550Z] Finished clobber step (success) 22:00:52 INFO - [mozharness: 2016-08-16 05:00:52.577651Z] Running read-buildbot-config step. 22:00:52 INFO - Running pre-action listener: _resource_record_pre_action 22:00:52 INFO - Running main action method: read_buildbot_config 22:00:52 INFO - Using buildbot properties: 22:00:52 INFO - { 22:00:52 INFO - "project": "", 22:00:52 INFO - "product": "firefox", 22:00:52 INFO - "script_repo_revision": "production", 22:00:52 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:00:52 INFO - "repository": "", 22:00:52 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 22:00:52 INFO - "buildid": "20160815203026", 22:00:52 INFO - "pgo_build": "True", 22:00:52 INFO - "basedir": "/builds/slave/test", 22:00:52 INFO - "buildnumber": 984, 22:00:52 INFO - "slavename": "talos-linux64-ix-001", 22:00:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:00:52 INFO - "platform": "ubuntu64_hw", 22:00:52 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:00:52 INFO - "branch": "fx-team", 22:00:52 INFO - "repo_path": "integration/fx-team", 22:00:52 INFO - "revision": "c9d9d817ae305b37464b7e5b222c1113c0646fd0", 22:00:52 INFO - "stage_platform": "linux64-pgo", 22:00:52 INFO - "builduid": "b9e84d86e2d64467a7481a6eb6c634ec", 22:00:52 INFO - "slavebuilddir": "test" 22:00:52 INFO - } 22:00:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:00:52 INFO - Running post-action listener: _resource_record_post_action 22:00:52 INFO - [mozharness: 2016-08-16 05:00:52.580065Z] Finished read-buildbot-config step (success) 22:00:52 INFO - [mozharness: 2016-08-16 05:00:52.580153Z] Running download-and-extract step. 22:00:52 INFO - Running pre-action listener: _resource_record_pre_action 22:00:52 INFO - Running main action method: download_and_extract 22:00:52 INFO - mkdir: /builds/slave/test/build/tests 22:00:52 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')]} 22:00:52 INFO - trying https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:00:52 INFO - Downloading https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:00:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:00:53 INFO - Downloaded 1361 bytes. 22:00:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:00:53 INFO - Using the following test package requirements: 22:00:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:00:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:00:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:53 INFO - u'jsshell-linux-x86_64.zip'], 22:00:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:00:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:00:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:00:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:00:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:00:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:00:53 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')]} 22:00:53 INFO - trying https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:00:53 INFO - Downloading https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:00:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:00:54 INFO - Downloaded 21837625 bytes. 22:00:54 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 22:00:58 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 22:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:00:58 INFO - trying https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:00:58 INFO - Downloading https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:00:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:00:59 INFO - Downloaded 21837625 bytes. 22:00:59 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 22:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:03 INFO - trying https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:01:03 INFO - Downloading https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:01:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:01:04 INFO - Downloaded 14413719 bytes. 22:01:04 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 22:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:04 INFO - trying https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:01:04 INFO - Downloading https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:01:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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 22:01:06 INFO - Downloaded 59436343 bytes. 22:01:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:01:06 INFO - mkdir: /builds/slave/test/properties 22:01:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:01:06 INFO - Writing to file /builds/slave/test/properties/build_url 22:01:06 INFO - Contents: 22:01:06 INFO - build_url:https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:01:07 INFO - Running post-action listener: _resource_record_post_action 22:01:07 INFO - Running post-action listener: set_extra_try_arguments 22:01:07 INFO - [mozharness: 2016-08-16 05:01:07.243921Z] Finished download-and-extract step (success) 22:01:07 INFO - [mozharness: 2016-08-16 05:01:07.244144Z] Running populate-webroot step. 22:01:07 INFO - Running pre-action listener: _resource_record_pre_action 22:01:07 INFO - Running main action method: populate_webroot 22:01:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:01:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:01:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:01:07 INFO - '--tppagecycles', 22:01:07 INFO - '7'], 22:01:07 INFO - 'tests': ['tsvgm']}}, 22:01:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:01:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:01:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:01:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:01:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:01:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:01:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:07 INFO - 'talos_options': ['--disable-e10s'], 22:01:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:07 INFO - 'talos_options': ['--disable-e10s'], 22:01:07 INFO - 'tests': ['damp', 'tps']}, 22:01:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:07 INFO - 'tests': ['damp', 'tps']}, 22:01:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:01:07 INFO - 'tests': ['dromaeo_dom']}, 22:01:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:01:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:01:07 INFO - 'tests': ['basic_compositor_video']}, 22:01:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:01:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:01:07 INFO - 'tests': ['a11yr', 22:01:07 INFO - 'ts_paint', 22:01:07 INFO - 'tpaint', 22:01:07 INFO - 'sessionrestore', 22:01:07 INFO - 'sessionrestore_no_auto_restore', 22:01:07 INFO - 'tabpaint']}, 22:01:07 INFO - 'other-e10s': {'tests': ['a11yr', 22:01:07 INFO - 'ts_paint', 22:01:07 INFO - 'tpaint', 22:01:07 INFO - 'sessionrestore', 22:01:07 INFO - 'sessionrestore_no_auto_restore', 22:01:07 INFO - 'tabpaint']}, 22:01:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:01:07 INFO - 'ts_paint', 22:01:07 INFO - 'tpaint', 22:01:07 INFO - 'sessionrestore', 22:01:07 INFO - 'sessionrestore_no_auto_restore', 22:01:07 INFO - 'tabpaint']}, 22:01:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:01:07 INFO - 'ts_paint', 22:01:07 INFO - 'tpaint', 22:01:07 INFO - 'sessionrestore', 22:01:07 INFO - 'sessionrestore_no_auto_restore', 22:01:07 INFO - 'tabpaint']}, 22:01:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:01:07 INFO - 'tests': ['a11yr', 22:01:07 INFO - 'ts_paint', 22:01:07 INFO - 'tpaint', 22:01:07 INFO - 'sessionrestore', 22:01:07 INFO - 'sessionrestore_no_auto_restore', 22:01:07 INFO - 'tabpaint']}, 22:01:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:01:07 INFO - 'tests': ['a11yr', 22:01:07 INFO - 'ts_paint', 22:01:07 INFO - 'tpaint', 22:01:07 INFO - 'sessionrestore', 22:01:07 INFO - 'sessionrestore_no_auto_restore', 22:01:07 INFO - 'tabpaint']}, 22:01:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:01:07 INFO - 'tests': ['tsvgx', 22:01:07 INFO - 'tsvgr_opacity', 22:01:07 INFO - 'tart', 22:01:07 INFO - 'tscrollx', 22:01:07 INFO - 'cart']}, 22:01:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:01:07 INFO - 'tsvgr_opacity', 22:01:07 INFO - 'tart', 22:01:07 INFO - 'tscrollx', 22:01:07 INFO - 'cart']}, 22:01:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:07 INFO - 'talos_options': ['--disable-e10s'], 22:01:07 INFO - 'tests': ['tp5o']}, 22:01:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:07 INFO - 'tests': ['tp5o']}, 22:01:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:07 INFO - 'talos_options': ['--disable-e10s', 22:01:07 INFO - '--xperf_path', 22:01:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:07 INFO - 'tests': ['tp5n']}, 22:01:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:07 INFO - 'talos_options': ['--xperf_path', 22:01:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:07 INFO - 'tests': ['tp5n']}}, 22:01:07 INFO - 'talos.zip': {'path': '', 22:01:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:01:07 INFO - Running post-action listener: _resource_record_post_action 22:01:07 INFO - [mozharness: 2016-08-16 05:01:07.265672Z] Finished populate-webroot step (success) 22:01:07 INFO - [mozharness: 2016-08-16 05:01:07.265759Z] Running create-virtualenv step. 22:01:07 INFO - Running pre-action listener: _resource_record_pre_action 22:01:07 INFO - Running main action method: create_virtualenv 22:01:07 INFO - Creating virtualenv /builds/slave/test/build/venv 22:01:07 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 22:01:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:01:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:01:07 INFO - Using real prefix '/usr' 22:01:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:01:07 INFO - Installing distribute.............................................................................................................................................................................................done. 22:01:09 INFO - Installing pip.................done. 22:01:09 INFO - Return code: 0 22:01:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:09 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 0x7f14098c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c9e850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c99370>, '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 0x2c9c8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c9cd80>, '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': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', '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 22:01:09 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 22:01:09 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 22:01:09 INFO - Using env: {'DISPLAY': ':0', 22:01:09 INFO - 'HOME': '/home/cltbld', 22:01:09 INFO - 'LANG': 'en_US.UTF-8', 22:01:09 INFO - 'LANGUAGE': 'en_US:en', 22:01:09 INFO - 'LOGNAME': 'cltbld', 22:01:09 INFO - 'MAIL': '/var/mail/cltbld', 22:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:09 INFO - 'MOZ_NO_REMOTE': '1', 22:01:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:09 INFO - 'NO_EM_RESTART': '1', 22:01:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:09 INFO - 'PWD': '/builds/slave/test', 22:01:09 INFO - 'SHELL': '/bin/bash', 22:01:09 INFO - 'SHLVL': '1', 22:01:09 INFO - 'TERM': 'linux', 22:01:09 INFO - 'TMOUT': '86400', 22:01:09 INFO - 'USER': 'cltbld', 22:01:09 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', 22:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:09 INFO - '_': '/tools/buildbot/bin/python'} 22:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:10 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 22:01:10 INFO - Cleaning up... 22:01:10 INFO - Return code: 0 22:01:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f14098c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c9e850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c99370>, '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 0x2c9c8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c9cd80>, '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': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', '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 22:01:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:01:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:01:10 INFO - Using env: {'DISPLAY': ':0', 22:01:10 INFO - 'HOME': '/home/cltbld', 22:01:10 INFO - 'LANG': 'en_US.UTF-8', 22:01:10 INFO - 'LANGUAGE': 'en_US:en', 22:01:10 INFO - 'LOGNAME': 'cltbld', 22:01:10 INFO - 'MAIL': '/var/mail/cltbld', 22:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:10 INFO - 'MOZ_NO_REMOTE': '1', 22:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:10 INFO - 'NO_EM_RESTART': '1', 22:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:10 INFO - 'PWD': '/builds/slave/test', 22:01:10 INFO - 'SHELL': '/bin/bash', 22:01:10 INFO - 'SHLVL': '1', 22:01:10 INFO - 'TERM': 'linux', 22:01:10 INFO - 'TMOUT': '86400', 22:01:10 INFO - 'USER': 'cltbld', 22:01:10 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', 22:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:10 INFO - '_': '/tools/buildbot/bin/python'} 22:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:10 INFO - Downloading/unpacking psutil>=3.1.1 22:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:01:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:12 INFO - Installing collected packages: psutil 22:01:12 INFO - Running setup.py install for psutil 22:01:12 INFO - building 'psutil._psutil_linux' extension 22:01:12 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 22:01:12 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 22:01:12 INFO - building 'psutil._psutil_posix' extension 22:01:12 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 22:01:13 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 22:01:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:13 INFO - Successfully installed psutil 22:01:13 INFO - Cleaning up... 22:01:13 INFO - Return code: 0 22:01:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:01:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:13 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 0x7f14098c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c9e850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c99370>, '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 0x2c9c8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c9cd80>, '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': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', '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 22:01:13 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 22:01:13 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 22:01:13 INFO - Using env: {'DISPLAY': ':0', 22:01:13 INFO - 'HOME': '/home/cltbld', 22:01:13 INFO - 'LANG': 'en_US.UTF-8', 22:01:13 INFO - 'LANGUAGE': 'en_US:en', 22:01:13 INFO - 'LOGNAME': 'cltbld', 22:01:13 INFO - 'MAIL': '/var/mail/cltbld', 22:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:13 INFO - 'MOZ_NO_REMOTE': '1', 22:01:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:13 INFO - 'NO_EM_RESTART': '1', 22:01:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:13 INFO - 'PWD': '/builds/slave/test', 22:01:13 INFO - 'SHELL': '/bin/bash', 22:01:13 INFO - 'SHLVL': '1', 22:01:13 INFO - 'TERM': 'linux', 22:01:13 INFO - 'TMOUT': '86400', 22:01:13 INFO - 'USER': 'cltbld', 22:01:13 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', 22:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:13 INFO - '_': '/tools/buildbot/bin/python'} 22:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:01:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:01:15 INFO - Installing collected packages: mozsystemmonitor 22:01:15 INFO - Running setup.py install for mozsystemmonitor 22:01:15 INFO - Successfully installed mozsystemmonitor 22:01:15 INFO - Cleaning up... 22:01:15 INFO - Return code: 0 22:01:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:01:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:15 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 0x7f14098c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c9e850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c99370>, '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 0x2c9c8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c9cd80>, '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': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', '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 22:01:15 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 22:01:15 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 22:01:15 INFO - Using env: {'DISPLAY': ':0', 22:01:15 INFO - 'HOME': '/home/cltbld', 22:01:15 INFO - 'LANG': 'en_US.UTF-8', 22:01:15 INFO - 'LANGUAGE': 'en_US:en', 22:01:15 INFO - 'LOGNAME': 'cltbld', 22:01:15 INFO - 'MAIL': '/var/mail/cltbld', 22:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:15 INFO - 'MOZ_NO_REMOTE': '1', 22:01:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:15 INFO - 'NO_EM_RESTART': '1', 22:01:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:15 INFO - 'PWD': '/builds/slave/test', 22:01:15 INFO - 'SHELL': '/bin/bash', 22:01:15 INFO - 'SHLVL': '1', 22:01:15 INFO - 'TERM': 'linux', 22:01:15 INFO - 'TMOUT': '86400', 22:01:15 INFO - 'USER': 'cltbld', 22:01:15 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', 22:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:15 INFO - '_': '/tools/buildbot/bin/python'} 22:01:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:15 INFO - Downloading/unpacking jsonschema==2.5.1 22:01:15 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 22:01:15 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 22:01:15 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 22:01:15 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 22:01:15 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 22:01:15 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 22:01:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:01:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:01:17 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 22:01:17 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 22:01:17 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 22:01:17 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 22:01:17 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 22:01:17 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 22:01:17 INFO - Downloading functools32-3.2.3-2.tar.gz 22:01:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:01:17 INFO - warning: no files found matching '*.txt' 22:01:17 INFO - no previously-included directories found matching 'build' 22:01:17 INFO - no previously-included directories found matching 'dist' 22:01:17 INFO - no previously-included directories found matching '.git*' 22:01:17 INFO - Installing collected packages: jsonschema, functools32 22:01:18 INFO - Running setup.py install for functools32 22:01:18 INFO - warning: no files found matching '*.txt' 22:01:18 INFO - no previously-included directories found matching 'build' 22:01:18 INFO - no previously-included directories found matching 'dist' 22:01:18 INFO - no previously-included directories found matching '.git*' 22:01:18 INFO - Successfully installed jsonschema functools32 22:01:18 INFO - Cleaning up... 22:01:18 INFO - Return code: 0 22:01:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:01:18 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')]} 22:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:18 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')]} 22:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:18 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 0x7f14098c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c9e850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c99370>, '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 0x2c9c8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c9cd80>, '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': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', '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 22:01:18 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 22:01:18 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 22:01:18 INFO - Using env: {'DISPLAY': ':0', 22:01:18 INFO - 'HOME': '/home/cltbld', 22:01:18 INFO - 'LANG': 'en_US.UTF-8', 22:01:18 INFO - 'LANGUAGE': 'en_US:en', 22:01:18 INFO - 'LOGNAME': 'cltbld', 22:01:18 INFO - 'MAIL': '/var/mail/cltbld', 22:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:18 INFO - 'MOZ_NO_REMOTE': '1', 22:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:18 INFO - 'NO_EM_RESTART': '1', 22:01:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:18 INFO - 'PWD': '/builds/slave/test', 22:01:18 INFO - 'SHELL': '/bin/bash', 22:01:18 INFO - 'SHLVL': '1', 22:01:18 INFO - 'TERM': 'linux', 22:01:18 INFO - 'TMOUT': '86400', 22:01:18 INFO - 'USER': 'cltbld', 22:01:18 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', 22:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:18 INFO - '_': '/tools/buildbot/bin/python'} 22:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:01:18 INFO - Cleaning up... 22:01:18 INFO - Return code: 0 22:01:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:01:18 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')]} 22:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:18 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')]} 22:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:18 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 0x7f14098c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c9e850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c99370>, '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 0x2c9c8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c9cd80>, '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': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', '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 22:01:18 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 22:01:18 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 22:01:18 INFO - Using env: {'DISPLAY': ':0', 22:01:18 INFO - 'HOME': '/home/cltbld', 22:01:18 INFO - 'LANG': 'en_US.UTF-8', 22:01:18 INFO - 'LANGUAGE': 'en_US:en', 22:01:18 INFO - 'LOGNAME': 'cltbld', 22:01:18 INFO - 'MAIL': '/var/mail/cltbld', 22:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:18 INFO - 'MOZ_NO_REMOTE': '1', 22:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:18 INFO - 'NO_EM_RESTART': '1', 22:01:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:18 INFO - 'PWD': '/builds/slave/test', 22:01:18 INFO - 'SHELL': '/bin/bash', 22:01:18 INFO - 'SHLVL': '1', 22:01:18 INFO - 'TERM': 'linux', 22:01:18 INFO - 'TMOUT': '86400', 22:01:18 INFO - 'USER': 'cltbld', 22:01:18 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', 22:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:18 INFO - '_': '/tools/buildbot/bin/python'} 22:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:18 INFO - Downloading/unpacking blobuploader==1.2.4 22:01:18 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 22:01:18 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 22:01:18 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 22:01:18 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 22:01:18 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 22:01:18 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 22:01:20 INFO - Downloading blobuploader-1.2.4.tar.gz 22:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:01:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:01:20 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 22:01:20 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 22:01:20 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 22:01:20 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 22:01:20 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 22:01:20 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 22:01:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:01:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:01:21 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 22:01:21 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 22:01:21 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 22:01:21 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 22:01:21 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 22:01:21 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 22:01:21 INFO - Downloading docopt-0.6.1.tar.gz 22:01:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:01:21 INFO - Installing collected packages: blobuploader, requests, docopt 22:01:21 INFO - Running setup.py install for blobuploader 22:01:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:01:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:01:21 INFO - Running setup.py install for requests 22:01:22 INFO - Running setup.py install for docopt 22:01:22 INFO - Successfully installed blobuploader requests docopt 22:01:22 INFO - Cleaning up... 22:01:22 INFO - Return code: 0 22:01:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:22 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 0x7f14098c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c9e850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c99370>, '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 0x2c9c8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c9cd80>, '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': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', '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 22:01:22 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 22:01:22 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 22:01:22 INFO - Using env: {'DISPLAY': ':0', 22:01:22 INFO - 'HOME': '/home/cltbld', 22:01:22 INFO - 'LANG': 'en_US.UTF-8', 22:01:22 INFO - 'LANGUAGE': 'en_US:en', 22:01:22 INFO - 'LOGNAME': 'cltbld', 22:01:22 INFO - 'MAIL': '/var/mail/cltbld', 22:01:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:22 INFO - 'MOZ_NO_REMOTE': '1', 22:01:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:22 INFO - 'NO_EM_RESTART': '1', 22:01:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:22 INFO - 'PWD': '/builds/slave/test', 22:01:22 INFO - 'SHELL': '/bin/bash', 22:01:22 INFO - 'SHLVL': '1', 22:01:22 INFO - 'TERM': 'linux', 22:01:22 INFO - 'TMOUT': '86400', 22:01:22 INFO - 'USER': 'cltbld', 22:01:22 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', 22:01:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:22 INFO - '_': '/tools/buildbot/bin/python'} 22:01:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:22 INFO - Running setup.py (path:/tmp/pip-wf3eR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:22 INFO - Running setup.py (path:/tmp/pip-CRN1Vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:22 INFO - Running setup.py (path:/tmp/pip-crLhUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:22 INFO - Running setup.py (path:/tmp/pip-rCHRvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:22 INFO - Running setup.py (path:/tmp/pip-NM5OeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:22 INFO - Running setup.py (path:/tmp/pip-JTOGdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:22 INFO - Running setup.py (path:/tmp/pip-pbYtA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:22 INFO - Running setup.py (path:/tmp/pip-1dlskM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:23 INFO - Running setup.py (path:/tmp/pip-T4a5vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:23 INFO - Running setup.py (path:/tmp/pip-R0gdyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:23 INFO - Running setup.py (path:/tmp/pip-7zhmFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:23 INFO - Running setup.py (path:/tmp/pip-w3ctmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:23 INFO - Running setup.py (path:/tmp/pip-NgReqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:23 INFO - Running setup.py (path:/tmp/pip-ZQV8j7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:23 INFO - Running setup.py (path:/tmp/pip-M7hlEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:23 INFO - Running setup.py (path:/tmp/pip-QSK4Y5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:23 INFO - Running setup.py (path:/tmp/pip-JX9mUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:01:23 INFO - Running setup.py install for manifestparser 22:01:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:01:23 INFO - Running setup.py install for mozcrash 22:01:23 INFO - Running setup.py install for mozdebug 22:01:23 INFO - Running setup.py install for mozdevice 22:01:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:01:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:01:24 INFO - Running setup.py install for mozfile 22:01:24 INFO - Running setup.py install for mozhttpd 22:01:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:01:24 INFO - Running setup.py install for mozinfo 22:01:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:01:24 INFO - Running setup.py install for mozInstall 22:01:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:01:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:01:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:01:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:01:24 INFO - Running setup.py install for mozleak 22:01:24 INFO - Running setup.py install for mozlog 22:01:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:01:24 INFO - Running setup.py install for moznetwork 22:01:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:01:24 INFO - Running setup.py install for mozprocess 22:01:24 INFO - Running setup.py install for mozprofile 22:01:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:01:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:01:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:01:25 INFO - Running setup.py install for mozrunner 22:01:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:01:25 INFO - Running setup.py install for mozscreenshot 22:01:25 INFO - Running setup.py install for moztest 22:01:25 INFO - Running setup.py install for mozversion 22:01:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:01:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:01:25 INFO - Cleaning up... 22:01:25 INFO - Return code: 0 22:01:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:25 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 0x7f14098c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c9e850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c99370>, '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 0x2c9c8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c9cd80>, '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': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', '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 22:01:25 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 22:01:25 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 22:01:25 INFO - Using env: {'DISPLAY': ':0', 22:01:25 INFO - 'HOME': '/home/cltbld', 22:01:25 INFO - 'LANG': 'en_US.UTF-8', 22:01:25 INFO - 'LANGUAGE': 'en_US:en', 22:01:25 INFO - 'LOGNAME': 'cltbld', 22:01:25 INFO - 'MAIL': '/var/mail/cltbld', 22:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:25 INFO - 'MOZ_NO_REMOTE': '1', 22:01:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:25 INFO - 'NO_EM_RESTART': '1', 22:01:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:25 INFO - 'PWD': '/builds/slave/test', 22:01:25 INFO - 'SHELL': '/bin/bash', 22:01:25 INFO - 'SHLVL': '1', 22:01:25 INFO - 'TERM': 'linux', 22:01:25 INFO - 'TMOUT': '86400', 22:01:25 INFO - 'USER': 'cltbld', 22:01:25 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', 22:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:25 INFO - '_': '/tools/buildbot/bin/python'} 22:01:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:25 INFO - Running setup.py (path:/tmp/pip-wxpD2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:25 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)) 22:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:25 INFO - Running setup.py (path:/tmp/pip-j9ms4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:25 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)) 22:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:25 INFO - Running setup.py (path:/tmp/pip-T59ByQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:25 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)) 22:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:25 INFO - Running setup.py (path:/tmp/pip-G9cSGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:25 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)) 22:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:25 INFO - Running setup.py (path:/tmp/pip-iG10SG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:26 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)) 22:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:26 INFO - Running setup.py (path:/tmp/pip-FnPlCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:26 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)) 22:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:26 INFO - Running setup.py (path:/tmp/pip-f5fBdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:26 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)) 22:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:26 INFO - Running setup.py (path:/tmp/pip-rTBs4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:26 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)) 22:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:26 INFO - Running setup.py (path:/tmp/pip-Be6MtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:26 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)) 22:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:26 INFO - Running setup.py (path:/tmp/pip-4cQh2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:26 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)) 22:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:26 INFO - Running setup.py (path:/tmp/pip-WnjKtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:26 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)) 22:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:26 INFO - Running setup.py (path:/tmp/pip-Cfm2_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:26 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)) 22:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:26 INFO - Running setup.py (path:/tmp/pip-r9CgN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:26 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)) 22:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:26 INFO - Running setup.py (path:/tmp/pip-h_EzjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:26 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)) 22:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:26 INFO - Running setup.py (path:/tmp/pip-Q0Kr44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:26 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)) 22:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:26 INFO - Running setup.py (path:/tmp/pip-uIr2NM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:26 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)) 22:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:26 INFO - Running setup.py (path:/tmp/pip-7NR3BA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:26 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)) 22:01:26 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)) 22:01:26 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)) 22:01:26 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)) 22:01:26 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)) 22:01:26 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)) 22:01:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:26 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 22:01:26 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 22:01:26 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 22:01:26 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 22:01:26 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 22:01:26 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 22:01:29 INFO - Downloading blessings-1.6.tar.gz 22:01:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:01:29 INFO - Installing collected packages: blessings 22:01:29 INFO - Running setup.py install for blessings 22:01:29 INFO - Successfully installed blessings 22:01:29 INFO - Cleaning up... 22:01:29 INFO - Return code: 0 22:01:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:01:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:29 INFO - Reading from file tmpfile_stdout 22:01:29 INFO - Current package versions: 22:01:29 INFO - argparse == 1.2.1 22:01:29 INFO - blessings == 1.6 22:01:29 INFO - blobuploader == 1.2.4 22:01:29 INFO - docopt == 0.6.1 22:01:29 INFO - functools32 == 3.2.3-2 22:01:29 INFO - jsonschema == 2.5.1 22:01:29 INFO - manifestparser == 1.1 22:01:29 INFO - mozInstall == 1.12 22:01:29 INFO - mozcrash == 0.17 22:01:29 INFO - mozdebug == 0.1 22:01:29 INFO - mozdevice == 0.48 22:01:29 INFO - mozfile == 1.2 22:01:29 INFO - mozhttpd == 0.7 22:01:29 INFO - mozinfo == 0.9 22:01:29 INFO - mozleak == 0.1 22:01:29 INFO - mozlog == 3.3 22:01:29 INFO - moznetwork == 0.27 22:01:29 INFO - mozprocess == 0.23 22:01:29 INFO - mozprofile == 0.28 22:01:29 INFO - mozrunner == 6.12 22:01:29 INFO - mozscreenshot == 0.1 22:01:29 INFO - mozsystemmonitor == 0.3 22:01:29 INFO - moztest == 0.7 22:01:29 INFO - mozversion == 1.4 22:01:29 INFO - psutil == 3.1.1 22:01:29 INFO - requests == 1.2.3 22:01:29 INFO - wsgiref == 0.1.2 22:01:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:29 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 0x7f14098c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c9e850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c99370>, '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 0x2c9c8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c9cd80>, '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': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', '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 22:01:29 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 22:01:29 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 22:01:29 INFO - Using env: {'DISPLAY': ':0', 22:01:29 INFO - 'HOME': '/home/cltbld', 22:01:29 INFO - 'LANG': 'en_US.UTF-8', 22:01:29 INFO - 'LANGUAGE': 'en_US:en', 22:01:29 INFO - 'LOGNAME': 'cltbld', 22:01:29 INFO - 'MAIL': '/var/mail/cltbld', 22:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:29 INFO - 'MOZ_NO_REMOTE': '1', 22:01:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:29 INFO - 'NO_EM_RESTART': '1', 22:01:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:29 INFO - 'PWD': '/builds/slave/test', 22:01:29 INFO - 'SHELL': '/bin/bash', 22:01:29 INFO - 'SHLVL': '1', 22:01:29 INFO - 'TERM': 'linux', 22:01:29 INFO - 'TMOUT': '86400', 22:01:29 INFO - 'USER': 'cltbld', 22:01:29 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', 22:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:29 INFO - '_': '/tools/buildbot/bin/python'} 22:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:29 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)) 22:01:29 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)) 22:01:29 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)) 22:01:29 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)) 22:01:29 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)) 22:01:29 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)) 22:01:29 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)) 22:01:29 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)) 22:01:29 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)) 22:01:29 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)) 22:01:29 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)) 22:01:29 INFO - Cleaning up... 22:01:29 INFO - Return code: 0 22:01:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f14098c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c9e850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c99370>, '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 0x2c9c8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c9cd80>, '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': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', '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 22:01:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:01:29 INFO - Using env: {'DISPLAY': ':0', 22:01:29 INFO - 'HOME': '/home/cltbld', 22:01:29 INFO - 'LANG': 'en_US.UTF-8', 22:01:29 INFO - 'LANGUAGE': 'en_US:en', 22:01:29 INFO - 'LOGNAME': 'cltbld', 22:01:29 INFO - 'MAIL': '/var/mail/cltbld', 22:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:29 INFO - 'MOZ_NO_REMOTE': '1', 22:01:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:29 INFO - 'NO_EM_RESTART': '1', 22:01:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:29 INFO - 'PWD': '/builds/slave/test', 22:01:29 INFO - 'SHELL': '/bin/bash', 22:01:29 INFO - 'SHLVL': '1', 22:01:29 INFO - 'TERM': 'linux', 22:01:29 INFO - 'TMOUT': '86400', 22:01:29 INFO - 'USER': 'cltbld', 22:01:29 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', 22:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:29 INFO - '_': '/tools/buildbot/bin/python'} 22:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:01:30 INFO - Cleaning up... 22:01:30 INFO - Return code: 0 22:01:30 INFO - Running post-action listener: _resource_record_post_action 22:01:30 INFO - Running post-action listener: _start_resource_monitoring 22:01:30 INFO - Starting resource monitoring. 22:01:30 INFO - [mozharness: 2016-08-16 05:01:30.052376Z] Finished create-virtualenv step (success) 22:01:30 INFO - [mozharness: 2016-08-16 05:01:30.052878Z] Running install step. 22:01:30 INFO - Running pre-action listener: _resource_record_pre_action 22:01:30 INFO - Running main action method: install 22:01:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:30 INFO - Reading from file tmpfile_stdout 22:01:30 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:01:30 INFO - Reading from file tmpfile_stdout 22:01:30 INFO - Output received: 22:01:30 INFO - Usage: mozinstall [options] installer 22:01:30 INFO - Options: 22:01:30 INFO - -h, --help show this help message and exit 22:01:30 INFO - -d DEST, --destination=DEST 22:01:30 INFO - Directory to install application into. [default: 22:01:30 INFO - "/builds/slave/test"] 22:01:30 INFO - --app=APP Application being installed. [default: firefox] 22:01:30 INFO - mkdir: /builds/slave/test/build/application 22:01:30 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'] 22:01:30 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 22:01:47 INFO - Reading from file tmpfile_stdout 22:01:47 INFO - Output received: 22:01:47 INFO - /builds/slave/test/build/application/firefox/firefox 22:01:47 INFO - Running post-action listener: _resource_record_post_action 22:01:47 INFO - [mozharness: 2016-08-16 05:01:47.291444Z] Finished install step (success) 22:01:47 INFO - [mozharness: 2016-08-16 05:01:47.291692Z] Running run-tests step. 22:01:47 INFO - Running pre-action listener: _resource_record_pre_action 22:01:47 INFO - Running main action method: run_tests 22:01:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:01:47 INFO - Python 2.7.3 22:01:47 INFO - Return code: 0 22:01:47 INFO - grabbing minidump binary from tooltool 22:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:47 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 0x2c99370>, '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 0x2c9c8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c9cd80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:01:47 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 22:01:47 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 22:01:47 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 22:01:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:01:47 INFO - Return code: 0 22:01:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:01:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:01:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:01:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:01:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:01:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 22:01:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --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 22:01:47 INFO - Using env: {'DISPLAY': ':0', 22:01:47 INFO - 'HOME': '/home/cltbld', 22:01:47 INFO - 'LANG': 'en_US.UTF-8', 22:01:47 INFO - 'LANGUAGE': 'en_US:en', 22:01:47 INFO - 'LOGNAME': 'cltbld', 22:01:47 INFO - 'MAIL': '/var/mail/cltbld', 22:01:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:01:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:47 INFO - 'MOZ_NO_REMOTE': '1', 22:01:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:01:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:47 INFO - 'NO_EM_RESTART': '1', 22:01:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:47 INFO - 'PWD': '/builds/slave/test', 22:01:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:01:47 INFO - 'SHELL': '/bin/bash', 22:01:47 INFO - 'SHLVL': '1', 22:01:47 INFO - 'TERM': 'linux', 22:01:47 INFO - 'TMOUT': '86400', 22:01:47 INFO - 'USER': 'cltbld', 22:01:47 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500', 22:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:47 INFO - '_': '/tools/buildbot/bin/python'} 22:01:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 22:01:47 INFO - mozversion application_buildid: 20160815203026 22:01:47 INFO - mozversion application_changeset: c9d9d817ae305b37464b7e5b222c1113c0646fd0 22:01:47 INFO - mozversion application_display_name: Nightly 22:01:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:01:47 INFO - mozversion application_name: Firefox 22:01:47 INFO - mozversion application_remotingname: firefox 22:01:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:01:47 INFO - mozversion application_vendor: Mozilla 22:01:47 INFO - mozversion application_version: 51.0a1 22:01:47 INFO - mozversion platform_buildid: 20160815203026 22:01:47 INFO - mozversion platform_changeset: c9d9d817ae305b37464b7e5b222c1113c0646fd0 22:01:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:01:47 INFO - mozversion platform_version: 51.0a1 22:01:47 INFO - using testdate: 1471323707 22:01:47 INFO - actual date: 1471323707 22:01:47 INFO - starting webserver on 'localhost:54555' 22:01:47 INFO - SUITE-START | Running 2 tests 22:01:47 INFO - TEST-START | dromaeo_css 22:01:47 INFO - operating with platform_type : linux_ 22:01:47 INFO - Initialising browser for dromaeo_css test... 22:01:47 INFO - TEST-INFO | started process 14527 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvXnJfy/profile http://localhost:54555/getInfo.html) 22:01:51 INFO - PROCESS | 14527 | __metrics Screen width/height:1600/1200 22:01:51 INFO - PROCESS | 14527 | colorDepth:24 22:01:51 INFO - PROCESS | 14527 | Browser inner width/height: 1024/697 22:01:51 INFO - PROCESS | 14527 | __metrics 22:01:52 INFO - PROCESS | 14527 | 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" 22:01:52 INFO - PROCESS | 14527 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:01:53 INFO - TEST-INFO | 14527: exit 0 22:01:53 INFO - Browser initialized. 22:01:53 INFO - Running cycle 1/1 for dromaeo_css test... 22:01:53 INFO - TEST-INFO | started process 14606 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvXnJfy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:02:05 INFO - PROCESS | 14606 | RSS: Main: 155447296 22:02:05 INFO - PROCESS | 14606 | 22:02:56 INFO - PROCESS | 14606 | 1471323776608 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] 22:05:05 INFO - PROCESS | 14606 | Cycle 1(1): loaded http://localhost:54555/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54555/tests/dromaeo/cssquery-ext.html) 22:05:06 INFO - PROCESS | 14606 | RSS: Main: 254291968 22:05:06 INFO - PROCESS | 14606 | 22:08:09 INFO - PROCESS | 14606 | Cycle 1(1): loaded http://localhost:54555/tests/dromaeo/cssquery-ext.html (next: http://localhost:54555/tests/dromaeo/cssquery-jquery.html) 22:08:09 INFO - PROCESS | 14606 | RSS: Main: 242970624 22:08:09 INFO - PROCESS | 14606 | 22:11:11 INFO - PROCESS | 14606 | Cycle 1(1): loaded http://localhost:54555/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54555/tests/dromaeo/cssquery-mootools.html) 22:11:12 INFO - PROCESS | 14606 | RSS: Main: 232022016 22:11:12 INFO - PROCESS | 14606 | 22:14:14 INFO - PROCESS | 14606 | Cycle 1(1): loaded http://localhost:54555/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54555/tests/dromaeo/cssquery-prototype.html) 22:14:15 INFO - PROCESS | 14606 | RSS: Main: 238051328 22:14:15 INFO - PROCESS | 14606 | 22:17:17 INFO - PROCESS | 14606 | Cycle 1(1): loaded http://localhost:54555/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54555/tests/dromaeo/cssquery-yui.html) 22:17:18 INFO - PROCESS | 14606 | RSS: Main: 186761216 22:17:18 INFO - PROCESS | 14606 | 22:20:20 INFO - PROCESS | 14606 | Cycle 1(1): loaded http://localhost:54555/tests/dromaeo/cssquery-yui.html (next: http://localhost:54555/tests/dromaeo/cssquery-dojo.html) 22:20:21 INFO - PROCESS | 14606 | RSS: Main: 278503424 22:20:21 INFO - PROCESS | 14606 | 22:20:21 INFO - PROCESS | 14606 | __start_tp_report 22:20:21 INFO - PROCESS | 14606 | _x_x_mozilla_page_load 22:20:21 INFO - PROCESS | 14606 | _x_x_mozilla_page_load_details 22:20:21 INFO - PROCESS | 14606 | |i|pagename|runs| 22:20:21 INFO - PROCESS | 14606 | |0;dojo.html;3003.6846131156226;3026.8940587079455;3040.239940014997;3050.435669401161;3056.9541456878137;5063.189889617662;5104;5186.507281808227;5256.842294731159;5361.303030606021;5216.8695782605455;5248.6063545234065;5275.36695596528;5293.232481290211;5326.094563924133;4668.06638672266;4736.502667219944;4785.425748910136;4790.640701947368;4903.8528844134735;4868.659193856432;4898.216285394337;4919.4096708395045;4929.778159982791;4937.77779999899;4681;4715.764211789431;4725.3384526166365;4728.692634978715;4821.7589120543835;4785.042991401733;4786.21027530457;4799.520047995207;4814.927776083362;4880.194767863299;4791.3531673224015;4825.010872771073;4841.612670986313;4854.587360074398;4888.584470320027;5107.361579802525;5160.5097515736325;5161.225816127573;5348.625596208267;5359.839204823823;5621.072523033695;5641.68970706611;5646.717664116819;5654.293213348332;5656.377798442168;5634.4365563443735;5635.80274690388;5673.432656734335;5684.118961560965;5688.8862222755315;5128;5213.32226810516;5265.552428043583;5272.051030814414;5288.153895376721;5863.208466857005;5865.88268234633;5873.911891321633;5876.177335173079;5882.117682347682;5560.277163968658;5580.665160090408;5611.0741727615305;5628.690422026828;5629.155626655953;5471.644343117764;5560.304961879765;5601.271834661468;5650.208970744099;5703.144528320785;5662.8301150965535;5689.146628005832;5708.80019199326;5710.942890571041;5714.085746280575;5604.887902242014;5664.178694089334;5666.433356664301;5668.631538949955;5679.574031947621;4222.451081359403;4362.476502819682;4370.169667763115;4376.365427013129;4378.452693413324;3796.3735983562956;3809.1619843634357;3816.580176180618;3837.7505462144873;3862.092408284051;5293.57651387888;5301;5303.284056652303;5338.759755810997;5341.439148889388;5492.780288788404;5559.8888022239325;5590.720463976785;5592.748326325325;5596.076647353141;5261.842144735664;5364.946350536523;5377.623566350396;5406.783728650888;5418.485243901823;4941.604671626261;4953.356063711694;4954.529319714621;4955.554000140005;4993.700377977333;5572.498475137257;5950.732217050157;5961.463468287875;5961.701914904324;5962.493188078975;5474.972625136849;5532.557395408277;5549.750261238335;5583.581231407579;5625.409332020243;5821.330546987151;5826.271716035496;5828.358880523054;5831.533477321889;5835.766569337179;5324;5475.260839786737;5481.945180548143;5492.450754924475;5495.945040549704;5311.681299122065;5543.52880005204;5604.047311957059;5645.661260324394;5688.516476099578;5500.064988951808;5519.199716041232;5526.309211348582;5531.059719847555;5537.141743029837;5295.920561191662;5500.339959204921;5520.337559492887;5528.751206195811;5562.805301814417;5355.892882142413;5394.460553944574;5416.133418652997;5469.863253418696;5506.366767821592;5558.666480011212;5575.776968921197;5606.747696353592;5612.130119831432;5617.578681598815;5245.265662807286;5447.346318441812;5447.754851031792;5451.918221226605;5461.972690136524 22:20:21 INFO - PROCESS | 14606 | |1;ext.html;570.8321585271144;574.3395095640013;601.3565484931123;615.6060121522223;618.5515501261584;32550.67449325506;43036.78481607592;43910;43955.3406698899;44001.55998440015;45021.549784502226;45204;45217.547824521745;45403.0919381613;45504;8946.329025323093;9093.727188184332;9096.545172741337;9102.590383432762;9103.453792772421;9061.728148155566;9161.45031298121;9164.633414663405;9168;9169.220616247627;9155.084491550859;9203.125703058233;9206.815863682756;9214.216791572724;9214.447133171998;9130.223930965843;9161.312901532412;9163.312751543594;9177.954110229406;9188.678396256098;9014.278857691435;9085.636574537011;9087;9090.727278181665;9091.181793638605;43032.78483607593;43320.3501947471;43366.56633433657;43401.78299108516;43402.131957360674;3582.4805403216646;3643.5627724672945;3664.596894341647;3697.7411581189326;4102.548719640837;31532.684673153435;33502.664973350446;33642.83178584116;33647.32705345904;35328;4394.670399720034;4415.923968963277;4451.3323001550025;4470.173017991649;4584.40402747644;350120;354003;354106;354232;355115;34968.65031349654;60485.39514604885;61037;61044.694776526725;61055;34076.659233407845;36056.45915311272;36073.45889811655;36104.819475902455;36265.818670906476;15102.093874367572;15232.847671523143;15247.847521524644;15248.161351125573;15250.313735881911;12528.874711252953;12571.811422828665;12608.180468269533;12613.495460181673;12617.810732839014;8465.873011904827;8470.915290847135;8504.149585041447;8545.401821872409;8552.059273479912;3291.6214635317247;3319.7012268895915;3332.866685332608;3345.565076540034;3364.1925937775245;1025.5487585462372;1034.7102811212872;1036.388530766836;1039.9616751167796;1063.9414832184304;31243.843780781404;31632.209194770316;32153.51769723456;32228.35543289121;32260.19349516281;1163.057923082306;1167.6613782003124;1172.1619042384737;1183.621241202824;1203.6268756685456;16267.349306027627;16410;16647.916760416363;17074;17207.483775486755;455283;460199;461048;462112;462766;54682.726586366814;55220.447795522334;55333.44666553364;55337.44662553404;55390.99999999919;1258.5217617305366;1361.46902707944;1372.9176249425066;1380.1029330934973;1391.512970460351;31155.90954316588;33623.495647564814;35047.824760876036;37009.8501155633;37250.627493724714;57844.71077644585;58582.4141758594;58634.413655862896;58647.12029319649;58684.706576466844;541224;544589;546816;547110;548419;34136.658633413346;36599.45100823436;37101.25797484089;44699;45118.32322515231;45159.32261016022;49457.25814112719;49476.50523494719;49536.75231623963;49758.25362619636;40653.796731016155;41525.792371037955;43220.56779432291;44042.559574403844;45474.54525454703;42369.15261694687;42499;42643.78678106589;42964.35553466763;43417.78291108524;42694;43254.783726081165;44027.77986110049;44240;44259.77870110629;34992.30015399627;37500;37810.62189378181;38529.2294154121;39056.41415378715;27928.619161943097;29217.123486295444;29283;29285.560716588843;29715.405691886474 22:20:21 INFO - PROCESS | 14606 | |2;jquery.html;390.66402893511594;400.7154920006795;403.21373322022043;407.85113433596746;409.5147250508148;12484.877570812961;18504.537386565342;19283.807161928376;19942.7008594871;20013.399598012045;19292.421227363167;19620.705689414634;19683.5079123022;19895.104720287585;20320.18719251232;5625.971870140644;5808.709564521778;5818.0400233961345;5823.126531020339;5836.445537673914;5677.290338707661;5759.366469688352;5762.884742305151;5767.740451679663;5770;4155.750654960697;4218.198542276958;4704.38842950416;6453.290138084831;6525.053867189255;6351.650659213741;6474;6604.966975165142;6609.537332386735;6612.041254018164;5735.340435849889;5775.537956963432;5785.2189954356045;5785.2189954356045;5787.102999035114;23246.53506929852;23501.647475287715;23905.760942390527;24071;24214.273571792874;2336.4626135988647;2425.4057755849753;2553.795696344288;2608.282722251377;2981.016264632666;14268.429262829475;14709.92645036768;14997.775033374508;15026.699466010728;15045.097294162386;1918.9424317270505;1968.114348543147;1968.4980330015896;1989.234144854227;2002.7496562929634;54039;71633;72583;81428.18571814324;81981.59009204915;11039.72400689973;11357.97778199966;11387.14596405273;11415.429228538706;11448.3703396314;15780.368785248462;15786.36854525829;15793.289301981438;15796.289166987513;15804.762928556082;8056.677732890619;8309;8376.204260595234;8402.159784021671;8603.784905377415;7670.846583068419;7873.803154921173;7874.685012599546;7906.9209307906185;8287.223780033635;4643.976780116078;4692.671512994126;4758.3100450434495;4800.831970881002;5031.723255220998;1966.125074341904;1995.4113536506766;2009.9196032158843;2010.4069299556375;2018.9394318170478;916.9312301577409;961.2502248246379;978.1196922769565;1009.4700282351824;1023.247912784103;19722.802771972096;20230.29193978233;20264.392068237976;20309.89845050765;20445.386638400872;1005.7887843552936;1015.746063484129;1018.2579022093776;1041.3231399590175;1068.2201992545486;10669.41318227505;11163.776724465628;11214.274341214981;11413.885861141449;11509.597164099216;463185;464907;477931;511677;515312;56241;95984;96184.51907740557;99324;101942;987.871576695025;998.2064258914036;1026.573971801709;1034.9793004140026;1035.5857656937285;13572.457101715821;17224.13879306055;17754.20106095203;18358.247320757513;18733.90633046826;40438.59561404466;41452;42509;42593.36109958291;43054.78472607617;53901;56058.439415606954;56535.71732141313;56553.71723141523;57349;5894.793682221101;6516.5112616553;8281.420300578902;8517.914820851713;9102.681406151018;11761.47073381717;13892.583222503341;14003.579892603238;14019.649508762363;14055.789163162357;12112.576059837864;12261.14172007951;12382.56661016896;12508.312042837731;12731.80902286448;11685.240459370114;11706.180567360203;11988.940055299669;12056.397180140782;12644.620661380173;12253.081018923438;12392.876071239172;12436.937815310866;12453.688657783629;12849.87150128524;8586.957065214634;8784.604692788713;8814;8815.691450799191;8952.209558088538;10456.68629941103;12043.578474753342;12165.81751273714;12626.242425454502;12650.367481625697 22:20:21 INFO - PROCESS | 14606 | |3;mootools.html;1424.992875035625;1493.3503925792281;1603.8556529912312;1730.5154556724106;1754.867835579771;8440.746777596674;8988.505632190227;9009.234215091708;9014.90985090149;9017;7995.360371170307;8698.91301086989;8782.253508451773;8980.506072166028;9023.819523609523;2347.9647805282893;2368.5736567417857;2377.3105799318178;2378.167641325531;2378.2151889876386;2377.7741114594173;2383.1301574925114;2434.573949558829;2435.3546310227803;2439.121916110199;2084.6351888419467;2139.60417322795;2141.0900367343893;2149.2262785397243;2357.6817129687443;2402.5795485789918;2422.8667423291713;2431.489387228684;2443.6945381827272;2444.963325550118;2405.338531903723;2413.915512957056;2420.346506443268;2423.2366804456715;2432.3554258121612;9210.539473026389;9255.213306868856;9275.350725449154;9279.118483744034;9284.535773211312;5233.738313084368;5289;5298;5447.972760136214;5458.972705136489;3455.9827200863833;3564.3940530109944;3565.144365352297;3589.892303230881;3593.1556084320164;1153.578943685557;1207.046433317678;1220.0056953582891;1225.4485481533345;1254.523281153165;12810.935945320307;12866.549670761571;12937;12967.092303538757;12968.80546791799;4535;4588.839390621312;4740.19416699162;4752.500987396343;4797.736124513185;10703.357798532114;10704.78590428187;10711.946440267904;10725.570977160825;10732.73168170802;5407.405185429679;5410;5416.864578385572;5417.620766546383;5421.241026256327;4802.327674125555;4848.9757551212015;4951.455339912607;4997.675151115166;5009.949900500948;4021.939670904939;4112.074783173822;4141.233871733739;4158.877103182124;4243.596858298457;1292.8060790881443;1305.2429590837291;1313.0611612696966;1320.1023304153075;1326.4893016188837;944.3861490031535;960.582146766159;965.3869792681558;971.820213260549;990.6730778842965;5741.971290143522;5886.293644762571;5895.852603684856;5978.461938425563;5981.654127821293;454.57043094275593;459.6943032883095;470.2710798262679;475.4603524999082;491.7148054128597;4360.694751367374;4373.956260437419;4380.4305440292555;4384.145091707151;4431.534688857688;11021.393823339793;11590.072794176445;11612;11616.651500454831;11618.360990145624;10859.34843909368;11312.321260724382;11378.715532111764;11493.138014648935;11625.069994400428;8416.769556067524;8620.52587107715;8622.051574326819;8631.482111073356;8633.050364459905;5758.654480731169;5818.796342128006;5935.071101891816;6088.512918966383;6260.655663938527;10504.791948925666;10512.737181570523;10527.105196058421;10647.89352106469;10723.03492685662;10179.541920613738;10539.314944528582;10962.780744385227;10967.890321096687;10993.340399576051;7928.207179282025;8010;8017.8797318039105;8021.358291336563;8032.678692852454;8286.70996515119;8446.239838414573;8447.45091569046;8462.788430289293;8508.489490630582;7560.395168386406;7620.085589729268;7623.352015078559;7626.847463050597;7634.6182690864125;7584.810379740551;7631.122420921665;7802.297793198412;7852.254035866584;7915.802104947192;6866.026556895533;6906.205786334636;7000;7007.474439417166;7022.508424410446;5180.430152683277;5244.1085015548215;5255.763490643007;5345.438728933406;5425.538829199562;5270.183121616155;5329.866753331198;5342;5390.946090539044;5567.025770490229 22:20:21 INFO - PROCESS | 14606 | |4;prototype.html;300.4291845493561;323.7345376791031;332.8186138554488;335.37117903930124;336.3625928864801;5142.640015198937;5287.788488460457;5293.89412211756;5296.496832800887;5361.437049109846;5061.4938506149365;5153.30430391897;5274.3913106502505;5276.736163191835;5277.683338999653;4110.1163249901265;4114.753114813121;4128.967758060485;4134.50385953686;4141.130362623852;3992.6207010334115;4026.899327516821;4109.506859176888;4117.794110294488;4164.604362585549;4083.7141400102005;4085.5710150434224;4092.529359123709;4101.589841015904;4116.691248156385;4126.360414135814;4148.191102734968;4153.480814898137;4162.105147393325;4163.5836416358425;3623.873164439232;3669.4495825626104;3670.4861319415304;3688.7417880748358;3728.067983004249;5066.164082926312;5082.669626474268;5083.415407228142;5088.898222035538;5091.41448733393;4669.136209801198;4670.322803193552;4673.135469938073;4679.128348660529;4683.812647494096;5256.316678831766;5382.470238987669;5417.756200970965;5472;5473.644213126174;3999.6600288975474;4008.198360327946;4012.4181993611055;4012.879613611596;4049.2104039712267;5688.601797874109;5696.658200507983;5713.74288157034;5721.05484135396;5722.37053924068;4002.9965795621238;4038.8384464621085;4090.550039495653;4123.773192474383;4124.072083781125;4166.1251137261515;4168.854090106832;4175.937360939588;4176.4570605821045;4195.97902010488;4469.374287599739;4494.258447356215;4594.793234304464;4656.231721765871;4680.648951328664;5443.374011988592;5451.182322651554;5457.653209120037;5461.433126430571;5499.367572729107;1428.2144820348974;1441.6900366421269;1445.4724025730743;1447.2691290898028;1452.5424491285212;918.8392031394479;972.1202311907734;972.295086062599;977.7555611097225;985.1724551376877;5254.3169387979315;5261.421573527927;5263.3947096083675;5303.655262407931;5354.866128346823;5552.583556233299;5567.331920169606;5601.215829783833;5619.915701264485;5647.830565083054;5182.481751824863;5268.868278293074;5292.761825717852;5298.231756395301;5337.226102215234;2955.3350496138196;2964.925876853096;2966.703329667016;2969.5545668149516;2983.4331477019296;5609.66342019472;5778.595498315078;5795.420457954171;5844.12338149281;5846.239988801429;5596.58025648078;5662.207290979266;5662.546996240373;5664.546836253172;5677.318721753416;5764.884702305931;5766;5774.249347584871;5777.24895763548;5815.41845815415;5302.628815982845;5456.645318054314;5462.6995515247045;5468.207109969112;5542.251796007487;5462.590305727166;5465.289512363367;5470.316210473691;5497.433231528995;5499.477549632778;5517.896420715792;5661.235733176133;5717.771289148387;5773.538116950718;5793.710314484343;4006.7395619284653;4010.2781499329235;4013.3545246449985;4015.799210039545;4049.696272779612;5290.301946007641;5449.482299181572;5469.781208751605;5495.752691128829;5608.775648974159;5367.8190798025935;5424.213489044146;5513.421090785409;5540.335159780852;5564.304461942257;5389.245505629294;5425.403205647454;5482.972585137049;5504.22940788282;5520.806771762969;5144.794208231779;5352.812651557177;5434.945650543602;5448.591355648263;5462.262594549685;5420.78032442713;5489.505944465017;5512.91730624049;5570.5543556516195;5577.247071645276;5159.896802064013;5327.573794096385;5333.0401627097335;5386.7575959082715;5434.184872269033 22:20:21 INFO - PROCESS | 14606 | |5;yui.html;778.097407007871;793.3415265329777;817.3542901108125;838.8532006898787;863.753830158405;14958.551243462687;16158;16161.757573636405;16304.429344972914;16379.83620163798;15027.474038408658;15498.767518487231;15509.844901550981;15535.300911458984;15539.92230038851;650.9446697030746;794.9920500794976;812.7683610171102;817.8854960305562;819.1399031017407;815.0219736316415;817.889584906039;818.3289702443998;818.5252553518944;818.5252553518944;691.1153723234269;864.356054739217;867.8004059066387;867.8351113288487;868.274990383031;846.7332790171107;871.0157522000131;876.6361959786681;880.6345366672823;880.8150288439435;633.7655067625009;636.1888592045133;637.9649119298437;638.5721566550376;638.7828138432955;14818.184999825005;14866.628334291621;14901.105933644016;14903.925480372529;14922.477713279983;15046.924765376103;15066.698666026728;15373.615659608487;15389.846101538953;15394.769078463833;11791.882081179165;11955;12092.637220883387;13248.602541923758;14475.638109047359;4567.634589232854;4589;4649.88375290617;4694.600958918496;4748.430188377382;85810.57094714486;87038.56480717556;88175.99999999936;88244;90047.54976225142;12265.949208744578;12415.379231038412;12461.626151215296;12481.500739970299;12801.103922725506;22998;23229.41926451852;23511.17710880111;23604.881975590357;23608.17371391993;8634.179752923463;8641.481511109228;8706.085860984505;8844.73465796027;9200.171984521425;4817.181079216542;4872.926906096412;4903.681260718042;4950.504949505093;4950.925736113961;3535;3643.307771523402;3644.872429465009;3645.3620616392027;3648.2521083177885;631.718885096137;632.4371309534517;632.6710110742388;648.3322178156507;661.4807376209652;581.682982774389;629.5750368501335;673.4074014866984;690.4441924250925;713.2439614009166;8951.970523389893;9404;9410.38832475887;9443.811123777487;9719.951400242953;650.4146268358421;685.5269863793966;699.0876905638191;711.3739908880152;711.5874988148521;4373.562643735665;4609;4623.4451865775645;4640.559146881041;4656.41794775653;127856.3607181958;127952.36023820007;130131;130705.34647326893;132953.33523332514;100058.499707501;100512.49743751381;101541.49229253954;102609.48695256625;105304;92183.53908230415;95392.52303738437;95752;96207;96946.51526742462;8405.327573794204;8520.3183745299;8575.3997220194;8632.482051076955;8682.78293042679;56606.43393566012;57717.4228257712;58022.7098864503;58126;58709;80352.19647803447;81947;83597.58201208955;84651.5767421159;86867.56566217128;12014.498187726535;12275.631731048083;12401.75196496047;12517.749645006867;12528.619562283136;11928;12009.819852702041;12104.576339828063;12486.500539978299;13227.941764658612;12479.812802808146;12507.124501285187;12558.560450384193;12712.237265764084;12786.296753678265;11845.763084738084;12026.579069732517;12122.333271670142;12349.073819463396;12542.498300067895;11200.215984881306;11324.150688698546;11455.770884582429;11549.6535103947;11997.40012999364;9492.667756628485;9625.759356016155;9742.610295588098;10227.7443063924;10424.583016679248;9801.656942006995;9926.25553083536;9966;10031.097201251923;10035.397876127456 22:20:21 INFO - PROCESS | 14606 | __end_tp_report 22:20:21 INFO - PROCESS | 14606 | __start_cc_report 22:20:21 INFO - PROCESS | 14606 | _x_x_mozilla_cycle_collect,897 22:20:21 INFO - PROCESS | 14606 | __end_cc_report 22:20:21 INFO - PROCESS | 14606 | __startTimestamp1471324821243__endTimestamp 22:20:21 INFO - PROCESS | 14606 | 22:20:21 INFO - PROCESS | 14606 | ------- Summary: start ------- 22:20:21 INFO - PROCESS | 14606 | Number of tests: 6 22:20:21 INFO - PROCESS | 14606 | 22:20:21 INFO - PROCESS | 14606 | [#0] dojo.html Cycles:165 Average:5238.17 Median:5447.35 stddev:595.73 (10.9%) stddev-sans-first:571.19 22:20:21 INFO - PROCESS | 14606 | Values: 3003.7 3026.9 3040.2 3050.4 3057.0 5063.2 5104.0 5186.5 5256.8 5361.3 5216.9 5248.6 5275.4 5293.2 5326.1 4668.1 4736.5 4785.4 4790.6 4903.9 4868.7 4898.2 4919.4 4929.8 4937.8 4681.0 4715.8 4725.3 4728.7 4821.8 4785.0 4786.2 4799.5 4814.9 4880.2 4791.4 4825.0 4841.6 4854.6 4888.6 5107.4 5160.5 5161.2 5348.6 5359.8 5621.1 5641.7 5646.7 5654.3 5656.4 5634.4 5635.8 5673.4 5684.1 5688.9 5128.0 5213.3 5265.6 5272.1 5288.2 5863.2 5865.9 5873.9 5876.2 5882.1 5560.3 5580.7 5611.1 5628.7 5629.2 5471.6 5560.3 5601.3 5650.2 5703.1 5662.8 5689.1 5708.8 5710.9 5714.1 5604.9 5664.2 5666.4 5668.6 5679.6 4222.5 4362.5 4370.2 4376.4 4378.5 3796.4 3809.2 3816.6 3837.8 3862.1 5293.6 5301.0 5303.3 5338.8 5341.4 5492.8 5559.9 5590.7 5592.7 5596.1 5261.8 5364.9 5377.6 5406.8 5418.5 4941.6 4953.4 4954.5 4955.6 4993.7 5572.5 5950.7 5961.5 5961.7 5962.5 5475.0 5532.6 5549.8 5583.6 5625.4 5821.3 5826.3 5828.4 5831.5 5835.8 5324.0 5475.3 5481.9 5492.5 5495.9 5311.7 5543.5 5604.0 5645.7 5688.5 5500.1 5519.2 5526.3 5531.1 5537.1 5295.9 5500.3 5520.3 5528.8 5562.8 5355.9 5394.5 5416.1 5469.9 5506.4 5558.7 5575.8 5606.7 5612.1 5617.6 5245.3 5447.3 5447.8 5451.9 5462.0 22:20:21 INFO - PROCESS | 14606 | 22:20:21 INFO - PROCESS | 14606 | [#1] ext.html Cycles:180 Average:60932.33 Median:43287.57 stddev:122120.53 (282.1%) stddev-sans-first:122379.02 22:20:21 INFO - PROCESS | 14606 | Values: 570.8 574.3 601.4 615.6 618.6 32550.7 43036.8 43910.0 43955.3 44001.6 45021.5 45204.0 45217.5 45403.1 45504.0 8946.3 9093.7 9096.5 9102.6 9103.5 9061.7 9161.5 9164.6 9168.0 9169.2 9155.1 9203.1 9206.8 9214.2 9214.4 9130.2 9161.3 9163.3 9178.0 9188.7 9014.3 9085.6 9087.0 9090.7 9091.2 43032.8 43320.4 43366.6 43401.8 43402.1 3582.5 3643.6 3664.6 3697.7 4102.5 31532.7 33502.7 33642.8 33647.3 35328.0 4394.7 4415.9 4451.3 4470.2 4584.4 350120.0 354003.0 354106.0 354232.0 355115.0 34968.7 60485.4 61037.0 61044.7 61055.0 34076.7 36056.5 36073.5 36104.8 36265.8 15102.1 15232.8 15247.8 15248.2 15250.3 12528.9 12571.8 12608.2 12613.5 12617.8 8465.9 8470.9 8504.1 8545.4 8552.1 3291.6 3319.7 3332.9 3345.6 3364.2 1025.5 1034.7 1036.4 1040.0 1063.9 31243.8 31632.2 32153.5 32228.4 32260.2 1163.1 1167.7 1172.2 1183.6 1203.6 16267.3 16410.0 16647.9 17074.0 17207.5 455283.0 460199.0 461048.0 462112.0 462766.0 54682.7 55220.4 55333.4 55337.4 55391.0 1258.5 1361.5 1372.9 1380.1 1391.5 31155.9 33623.5 35047.8 37009.9 37250.6 57844.7 58582.4 58634.4 58647.1 58684.7 541224.0 544589.0 546816.0 547110.0 548419.0 34136.7 36599.5 37101.3 44699.0 45118.3 45159.3 49457.3 49476.5 49536.8 49758.3 40653.8 41525.8 43220.6 44042.6 45474.5 42369.2 42499.0 42643.8 42964.4 43417.8 42694.0 43254.8 44027.8 44240.0 44259.8 34992.3 37500.0 37810.6 38529.2 39056.4 27928.6 29217.1 29283.0 29285.6 29715.4 22:20:21 INFO - PROCESS | 14606 | 22:20:21 INFO - PROCESS | 14606 | [#2] jquery.html Cycles:180 Average:28832.40 Median:24142.64 stddev:80148.15 (332.0%) stddev-sans-first:80344.53 22:20:21 INFO - PROCESS | 14606 | Values: 390.7 400.7 403.2 407.9 409.5 12484.9 18504.5 19283.8 19942.7 20013.4 19292.4 19620.7 19683.5 19895.1 20320.2 5626.0 5808.7 5818.0 5823.1 5836.4 5677.3 5759.4 5762.9 5767.7 5770.0 4155.8 4218.2 4704.4 6453.3 6525.1 6351.7 6474.0 6605.0 6609.5 6612.0 5735.3 5775.5 5785.2 5785.2 5787.1 23246.5 23501.6 23905.8 24071.0 24214.3 2336.5 2425.4 2553.8 2608.3 2981.0 14268.4 14709.9 14997.8 15026.7 15045.1 1918.9 1968.1 1968.5 1989.2 2002.7 54039.0 71633.0 72583.0 81428.2 81981.6 11039.7 11358.0 11387.1 11415.4 11448.4 15780.4 15786.4 15793.3 15796.3 15804.8 8056.7 8309.0 8376.2 8402.2 8603.8 7670.8 7873.8 7874.7 7906.9 8287.2 4644.0 4692.7 4758.3 4800.8 5031.7 1966.1 1995.4 2009.9 2010.4 2018.9 916.9 961.3 978.1 1009.5 1023.2 19722.8 20230.3 20264.4 20309.9 20445.4 1005.8 1015.7 1018.3 1041.3 1068.2 10669.4 11163.8 11214.3 11413.9 11509.6 463185.0 464907.0 477931.0 511677.0 515312.0 56241.0 95984.0 96184.5 99324.0 101942.0 987.9 998.2 1026.6 1035.0 1035.6 13572.5 17224.1 17754.2 18358.2 18733.9 40438.6 41452.0 42509.0 42593.4 43054.8 53901.0 56058.4 56535.7 56553.7 57349.0 5894.8 6516.5 8281.4 8517.9 9102.7 11761.5 13892.6 14003.6 14019.6 14055.8 12112.6 12261.1 12382.6 12508.3 12731.8 11685.2 11706.2 11988.9 12056.4 12644.6 12253.1 12392.9 12436.9 12453.7 12849.9 8587.0 8784.6 8814.0 8815.7 8952.2 10456.7 12043.6 12165.8 12626.2 12650.4 22:20:21 INFO - PROCESS | 14606 | 22:20:21 INFO - PROCESS | 14606 | [#3] mootools.html Cycles:180 Average:5953.07 Median:2607.83 stddev:3445.47 (132.1%) stddev-sans-first:3438.34 22:20:21 INFO - PROCESS | 14606 | Values: 1425.0 1493.4 1603.9 1730.5 1754.9 8440.7 8988.5 9009.2 9014.9 9017.0 7995.4 8698.9 8782.3 8980.5 9023.8 2348.0 2368.6 2377.3 2378.2 2378.2 2377.8 2383.1 2434.6 2435.4 2439.1 2084.6 2139.6 2141.1 2149.2 2357.7 2402.6 2422.9 2431.5 2443.7 2445.0 2405.3 2413.9 2420.3 2423.2 2432.4 9210.5 9255.2 9275.4 9279.1 9284.5 5233.7 5289.0 5298.0 5448.0 5459.0 3456.0 3564.4 3565.1 3589.9 3593.2 1153.6 1207.0 1220.0 1225.4 1254.5 12810.9 12866.5 12937.0 12967.1 12968.8 4535.0 4588.8 4740.2 4752.5 4797.7 10703.4 10704.8 10711.9 10725.6 10732.7 5407.4 5410.0 5416.9 5417.6 5421.2 4802.3 4849.0 4951.5 4997.7 5009.9 4021.9 4112.1 4141.2 4158.9 4243.6 1292.8 1305.2 1313.1 1320.1 1326.5 944.4 960.6 965.4 971.8 990.7 5742.0 5886.3 5895.9 5978.5 5981.7 454.6 459.7 470.3 475.5 491.7 4360.7 4374.0 4380.4 4384.1 4431.5 11021.4 11590.1 11612.0 11616.7 11618.4 10859.3 11312.3 11378.7 11493.1 11625.1 8416.8 8620.5 8622.1 8631.5 8633.1 5758.7 5818.8 5935.1 6088.5 6260.7 10504.8 10512.7 10527.1 10647.9 10723.0 10179.5 10539.3 10962.8 10967.9 10993.3 7928.2 8010.0 8017.9 8021.4 8032.7 8286.7 8446.2 8447.5 8462.8 8508.5 7560.4 7620.1 7623.4 7626.8 7634.6 7584.8 7631.1 7802.3 7852.3 7915.8 6866.0 6906.2 7000.0 7007.5 7022.5 5180.4 5244.1 5255.8 5345.4 5425.5 5270.2 5329.9 5342.0 5390.9 5567.0 22:20:21 INFO - PROCESS | 14606 | 22:20:21 INFO - PROCESS | 14606 | [#4] prototype.html Cycles:180 Average:4614.50 Median:5291.53 stddev:1332.27 (25.2%) stddev-sans-first:1296.07 22:20:21 INFO - PROCESS | 14606 | Values: 300.4 323.7 332.8 335.4 336.4 5142.6 5287.8 5293.9 5296.5 5361.4 5061.5 5153.3 5274.4 5276.7 5277.7 4110.1 4114.8 4129.0 4134.5 4141.1 3992.6 4026.9 4109.5 4117.8 4164.6 4083.7 4085.6 4092.5 4101.6 4116.7 4126.4 4148.2 4153.5 4162.1 4163.6 3623.9 3669.4 3670.5 3688.7 3728.1 5066.2 5082.7 5083.4 5088.9 5091.4 4669.1 4670.3 4673.1 4679.1 4683.8 5256.3 5382.5 5417.8 5472.0 5473.6 3999.7 4008.2 4012.4 4012.9 4049.2 5688.6 5696.7 5713.7 5721.1 5722.4 4003.0 4038.8 4090.6 4123.8 4124.1 4166.1 4168.9 4175.9 4176.5 4196.0 4469.4 4494.3 4594.8 4656.2 4680.6 5443.4 5451.2 5457.7 5461.4 5499.4 1428.2 1441.7 1445.5 1447.3 1452.5 918.8 972.1 972.3 977.8 985.2 5254.3 5261.4 5263.4 5303.7 5354.9 5552.6 5567.3 5601.2 5619.9 5647.8 5182.5 5268.9 5292.8 5298.2 5337.2 2955.3 2964.9 2966.7 2969.6 2983.4 5609.7 5778.6 5795.4 5844.1 5846.2 5596.6 5662.2 5662.5 5664.5 5677.3 5764.9 5766.0 5774.2 5777.2 5815.4 5302.6 5456.6 5462.7 5468.2 5542.3 5462.6 5465.3 5470.3 5497.4 5499.5 5517.9 5661.2 5717.8 5773.5 5793.7 4006.7 4010.3 4013.4 4015.8 4049.7 5290.3 5449.5 5469.8 5495.8 5608.8 5367.8 5424.2 5513.4 5540.3 5564.3 5389.2 5425.4 5483.0 5504.2 5520.8 5144.8 5352.8 5434.9 5448.6 5462.3 5420.8 5489.5 5512.9 5570.6 5577.2 5159.9 5327.6 5333.0 5386.8 5434.2 22:20:21 INFO - PROCESS | 14606 | 22:20:21 INFO - PROCESS | 14606 | [#5] yui.html Cycles:180 Average:22184.51 Median:4888.30 stddev:33334.23 (681.9%) stddev-sans-first:33388.99 22:20:21 INFO - PROCESS | 14606 | Values: 778.1 793.3 817.4 838.9 863.8 14958.6 16158.0 16161.8 16304.4 16379.8 15027.5 15498.8 15509.8 15535.3 15539.9 650.9 795.0 812.8 817.9 819.1 815.0 817.9 818.3 818.5 818.5 691.1 864.4 867.8 867.8 868.3 846.7 871.0 876.6 880.6 880.8 633.8 636.2 638.0 638.6 638.8 14818.2 14866.6 14901.1 14903.9 14922.5 15046.9 15066.7 15373.6 15389.8 15394.8 11791.9 11955.0 12092.6 13248.6 14475.6 4567.6 4589.0 4649.9 4694.6 4748.4 85810.6 87038.6 88176.0 88244.0 90047.5 12265.9 12415.4 12461.6 12481.5 12801.1 22998.0 23229.4 23511.2 23604.9 23608.2 8634.2 8641.5 8706.1 8844.7 9200.2 4817.2 4872.9 4903.7 4950.5 4950.9 3535.0 3643.3 3644.9 3645.4 3648.3 631.7 632.4 632.7 648.3 661.5 581.7 629.6 673.4 690.4 713.2 8952.0 9404.0 9410.4 9443.8 9720.0 650.4 685.5 699.1 711.4 711.6 4373.6 4609.0 4623.4 4640.6 4656.4 127856.4 127952.4 130131.0 130705.3 132953.3 100058.5 100512.5 101541.5 102609.5 105304.0 92183.5 95392.5 95752.0 96207.0 96946.5 8405.3 8520.3 8575.4 8632.5 8682.8 56606.4 57717.4 58022.7 58126.0 58709.0 80352.2 81947.0 83597.6 84651.6 86867.6 12014.5 12275.6 12401.8 12517.7 12528.6 11928.0 12009.8 12104.6 12486.5 13227.9 12479.8 12507.1 12558.6 12712.2 12786.3 11845.8 12026.6 12122.3 12349.1 12542.5 11200.2 11324.2 11455.8 11549.7 11997.4 9492.7 9625.8 9742.6 10227.7 10424.6 9801.7 9926.3 9966.0 10031.1 10035.4 22:20:21 INFO - PROCESS | 14606 | -------- Summary: end -------- 22:20:21 INFO - PROCESS | 14606 | 22:20:21 INFO - TEST-INFO | 14606: exit 0 22:20:22 INFO - TEST-OK | dromaeo_css | took 1114458ms 22:20:22 INFO - TEST-START | kraken 22:20:22 INFO - operating with platform_type : linux_ 22:20:22 INFO - Initialising browser for kraken test... 22:20:22 INFO - TEST-INFO | started process 14910 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPu3OWf/profile http://localhost:54555/getInfo.html) 22:20:25 INFO - PROCESS | 14910 | __metrics Screen width/height:1600/1200 22:20:25 INFO - PROCESS | 14910 | colorDepth:24 22:20:25 INFO - PROCESS | 14910 | Browser inner width/height: 1024/697 22:20:25 INFO - PROCESS | 14910 | __metrics 22:20:26 INFO - PROCESS | 14910 | 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" 22:20:26 INFO - PROCESS | 14910 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:20:27 INFO - PROCESS | 14910 | console.error: PushService: 22:20:27 INFO - PROCESS | 14910 | stateChangeProcessEnqueue: Error transitioning state 22:20:27 INFO - PROCESS | 14910 | UnknownError 22:20:27 INFO - TEST-INFO | 14910: exit 0 22:20:27 INFO - Browser initialized. 22:20:27 INFO - Running cycle 1/1 for kraken test... 22:20:27 INFO - TEST-INFO | started process 14991 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPu3OWf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:39 INFO - PROCESS | 14991 | RSS: Main: 156291072 22:20:39 INFO - PROCESS | 14991 | 22:20:41 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54555/tests/kraken/driver.html?testName=audio-beat-detection) 22:20:42 INFO - PROCESS | 14991 | RSS: Main: 204038144 22:20:42 INFO - PROCESS | 14991 | 22:20:45 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54555/tests/kraken/driver.html?testName=audio-dft) 22:20:45 INFO - PROCESS | 14991 | RSS: Main: 194863104 22:20:45 INFO - PROCESS | 14991 | 22:20:49 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54555/tests/kraken/driver.html?testName=audio-fft) 22:20:49 INFO - PROCESS | 14991 | RSS: Main: 276291584 22:20:49 INFO - PROCESS | 14991 | 22:20:51 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54555/tests/kraken/driver.html?testName=audio-oscillator) 22:20:52 INFO - PROCESS | 14991 | RSS: Main: 186937344 22:20:52 INFO - PROCESS | 14991 | 22:20:54 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54555/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:20:55 INFO - PROCESS | 14991 | RSS: Main: 190672896 22:20:55 INFO - PROCESS | 14991 | 22:20:58 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54555/tests/kraken/driver.html?testName=imaging-darkroom) 22:20:58 INFO - PROCESS | 14991 | RSS: Main: 225492992 22:20:58 INFO - PROCESS | 14991 | 22:21:02 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54555/tests/kraken/driver.html?testName=imaging-desaturate) 22:21:02 INFO - PROCESS | 14991 | RSS: Main: 226246656 22:21:02 INFO - PROCESS | 14991 | 22:21:05 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54555/tests/kraken/driver.html?testName=json-parse-financial) 22:21:06 INFO - PROCESS | 14991 | RSS: Main: 226406400 22:21:06 INFO - PROCESS | 14991 | 22:21:07 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54555/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:21:08 INFO - PROCESS | 14991 | RSS: Main: 188764160 22:21:08 INFO - PROCESS | 14991 | 22:21:10 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54555/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:21:10 INFO - PROCESS | 14991 | RSS: Main: 195878912 22:21:10 INFO - PROCESS | 14991 | 22:21:12 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54555/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:21:13 INFO - PROCESS | 14991 | RSS: Main: 193179648 22:21:13 INFO - PROCESS | 14991 | 22:21:15 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54555/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:21:16 INFO - PROCESS | 14991 | RSS: Main: 186671104 22:21:16 INFO - PROCESS | 14991 | 22:21:19 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54555/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:21:19 INFO - PROCESS | 14991 | RSS: Main: 192077824 22:21:19 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:54555/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54555/tests/kraken/driver.html?testName=ai-astar) 22:21:21 INFO - PROCESS | 14991 | RSS: Main: 242618368 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | __start_tp_report 22:21:21 INFO - PROCESS | 14991 | _x_x_mozilla_page_load 22:21:21 INFO - PROCESS | 14991 | _x_x_mozilla_page_load_details 22:21:21 INFO - PROCESS | 14991 | |i|pagename|runs| 22:21:21 INFO - PROCESS | 14991 | |0;ai-astar;104;112;111;111;106;111;111;111;112;110 22:21:21 INFO - PROCESS | 14991 | |1;audio-beat-detection;143;118;121;193;117;119;151;123;157;123 22:21:21 INFO - PROCESS | 14991 | |2;audio-dft;160;161;159;159;164;160;160;162;161;160 22:21:21 INFO - PROCESS | 14991 | |3;audio-fft;76;76;75;119;75;76;75;75;106;76 22:21:21 INFO - PROCESS | 14991 | |4;audio-oscillator;84;84;137;84;84;84;116;84;84;126 22:21:21 INFO - PROCESS | 14991 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;117;117;117 22:21:21 INFO - PROCESS | 14991 | |6;imaging-darkroom;145;146;146;146;146;146;146;146;146;145 22:21:21 INFO - PROCESS | 14991 | |7;imaging-desaturate;95;95;95;95;94;95;95;94;95;95 22:21:21 INFO - PROCESS | 14991 | |8;json-parse-financial;53;51;54;52;52;53;52;51;53;52 22:21:21 INFO - PROCESS | 14991 | |9;json-stringify-tinderbox;62;63;61;61;63;62;62;64;62;62 22:21:21 INFO - PROCESS | 14991 | |10;stanford-crypto-aes;79;78;78;81;77;79;77;86;82;78 22:21:21 INFO - PROCESS | 14991 | |11;stanford-crypto-ccm;125;124;130;123;124;129;133;130;138;132 22:21:21 INFO - PROCESS | 14991 | |12;stanford-crypto-pbkdf2;165;169;167;165;170;169;173;163;165;164 22:21:21 INFO - PROCESS | 14991 | |13;stanford-crypto-sha256-iterative;66;64;64;64;64;68;64;65;63;63 22:21:21 INFO - PROCESS | 14991 | __end_tp_report 22:21:21 INFO - PROCESS | 14991 | __start_cc_report 22:21:21 INFO - PROCESS | 14991 | _x_x_mozilla_cycle_collect,1670 22:21:21 INFO - PROCESS | 14991 | __end_cc_report 22:21:21 INFO - PROCESS | 14991 | __startTimestamp1471324881832__endTimestamp 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | ------- Summary: start ------- 22:21:21 INFO - PROCESS | 14991 | Number of tests: 14 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:2.69 (2.4%) stddev-sans-first:1.81 22:21:21 INFO - PROCESS | 14991 | Values: 104.0 112.0 111.0 111.0 106.0 111.0 111.0 111.0 112.0 110.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#1] audio-beat-detection Cycles:10 Average:136.50 Median:133.00 stddev:24.72 (18.6%) stddev-sans-first:26.10 22:21:21 INFO - PROCESS | 14991 | Values: 143.0 118.0 121.0 193.0 117.0 119.0 151.0 123.0 157.0 123.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:1.51 (0.9%) stddev-sans-first:1.58 22:21:21 INFO - PROCESS | 14991 | Values: 160.0 161.0 159.0 159.0 164.0 160.0 160.0 162.0 161.0 160.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.50 stddev:15.91 (21.1%) stddev-sans-first:16.67 22:21:21 INFO - PROCESS | 14991 | Values: 76.0 76.0 75.0 119.0 75.0 76.0 75.0 75.0 106.0 76.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:84.00 stddev:21.04 (25.0%) stddev-sans-first:21.81 22:21:21 INFO - PROCESS | 14991 | Values: 84.0 84.0 137.0 84.0 84.0 84.0 116.0 84.0 84.0 126.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:21:21 INFO - PROCESS | 14991 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 22:21:21 INFO - PROCESS | 14991 | Values: 145.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:21:21 INFO - PROCESS | 14991 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.50 stddev:0.95 (1.8%) stddev-sans-first:0.97 22:21:21 INFO - PROCESS | 14991 | Values: 53.0 51.0 54.0 52.0 52.0 53.0 52.0 51.0 53.0 52.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 22:21:21 INFO - PROCESS | 14991 | Values: 62.0 63.0 61.0 61.0 63.0 62.0 62.0 64.0 62.0 62.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:79.00 stddev:2.80 (3.5%) stddev-sans-first:2.96 22:21:21 INFO - PROCESS | 14991 | Values: 79.0 78.0 78.0 81.0 77.0 79.0 77.0 86.0 82.0 78.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#11] stanford-crypto-ccm Cycles:10 Average:128.80 Median:130.00 stddev:4.83 (3.7%) stddev-sans-first:4.92 22:21:21 INFO - PROCESS | 14991 | Values: 125.0 124.0 130.0 123.0 124.0 129.0 133.0 130.0 138.0 132.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:168.00 stddev:3.16 (1.9%) stddev-sans-first:3.27 22:21:21 INFO - PROCESS | 14991 | Values: 165.0 169.0 167.0 165.0 170.0 169.0 173.0 163.0 165.0 164.0 22:21:21 INFO - PROCESS | 14991 | 22:21:21 INFO - PROCESS | 14991 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:64.00 stddev:1.51 (2.4%) stddev-sans-first:1.50 22:21:21 INFO - PROCESS | 14991 | Values: 66.0 64.0 64.0 64.0 64.0 68.0 64.0 65.0 63.0 63.0 22:21:21 INFO - PROCESS | 14991 | -------- Summary: end -------- 22:21:21 INFO - PROCESS | 14991 | 22:21:22 INFO - TEST-INFO | 14991: exit 0 22:21:22 INFO - TEST-OK | kraken | took 60865ms 22:21:22 INFO - SUITE-END | took 1175s 22:21:22 INFO - Completed test suite (00:19:35) 22:21:22 INFO - javascript benchmark 22:21:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3003.6846131156226, 3026.8940587079455, 3040.239940014997, 3050.435669401161, 3056.9541456878137, 5063.189889617662, 5104.0, 5186.507281808227, 5256.842294731159, 5361.303030606021, 5216.8695782605455, 5248.6063545234065, 5275.36695596528, 5293.232481290211, 5326.094563924133, 4668.06638672266, 4736.502667219944, 4785.425748910136, 4790.640701947368, 4903.8528844134735, 4868.659193856432, 4898.216285394337, 4919.4096708395045, 4929.778159982791, 4937.77779999899, 4681.0, 4715.764211789431, 4725.3384526166365, 4728.692634978715, 4821.7589120543835, 4785.042991401733, 4786.21027530457, 4799.520047995207, 4814.927776083362, 4880.194767863299, 4791.3531673224015, 4825.010872771073, 4841.612670986313, 4854.587360074398, 4888.584470320027, 5107.361579802525, 5160.5097515736325, 5161.225816127573, 5348.625596208267, 5359.839204823823, 5621.072523033695, 5641.68970706611, 5646.717664116819, 5654.293213348332, 5656.377798442168, 5634.4365563443735, 5635.80274690388, 5673.432656734335, 5684.118961560965, 5688.8862222755315, 5128.0, 5213.32226810516, 5265.552428043583, 5272.051030814414, 5288.153895376721, 5863.208466857005, 5865.88268234633, 5873.911891321633, 5876.177335173079, 5882.117682347682, 5560.277163968658, 5580.665160090408, 5611.0741727615305, 5628.690422026828, 5629.155626655953, 5471.644343117764, 5560.304961879765, 5601.271834661468, 5650.208970744099, 5703.144528320785, 5662.8301150965535, 5689.146628005832, 5708.80019199326, 5710.942890571041, 5714.085746280575, 5604.887902242014, 5664.178694089334, 5666.433356664301, 5668.631538949955, 5679.574031947621, 4222.451081359403, 4362.476502819682, 4370.169667763115, 4376.365427013129, 4378.452693413324, 3796.3735983562956, 3809.1619843634357, 3816.580176180618, 3837.7505462144873, 3862.092408284051, 5293.57651387888, 5301.0, 5303.284056652303, 5338.759755810997, 5341.439148889388, 5492.780288788404, 5559.8888022239325, 5590.720463976785, 5592.748326325325, 5596.076647353141, 5261.842144735664, 5364.946350536523, 5377.623566350396, 5406.783728650888, 5418.485243901823, 4941.604671626261, 4953.356063711694, 4954.529319714621, 4955.554000140005, 4993.700377977333, 5572.498475137257, 5950.732217050157, 5961.463468287875, 5961.701914904324, 5962.493188078975, 5474.972625136849, 5532.557395408277, 5549.750261238335, 5583.581231407579, 5625.409332020243, 5821.330546987151, 5826.271716035496, 5828.358880523054, 5831.533477321889, 5835.766569337179, 5324.0, 5475.260839786737, 5481.945180548143, 5492.450754924475, 5495.945040549704, 5311.681299122065, 5543.52880005204, 5604.047311957059, 5645.661260324394, 5688.516476099578, 5500.064988951808, 5519.199716041232, 5526.309211348582, 5531.059719847555, 5537.141743029837, 5295.920561191662, 5500.339959204921, 5520.337559492887, 5528.751206195811, 5562.805301814417, 5355.892882142413, 5394.460553944574, 5416.133418652997, 5469.863253418696, 5506.366767821592, 5558.666480011212, 5575.776968921197, 5606.747696353592, 5612.130119831432, 5617.578681598815, 5245.265662807286, 5447.346318441812, 5447.754851031792, 5451.918221226605, 5461.972690136524], "value": 5197.889406813989, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.8321585271144, 574.3395095640013, 601.3565484931123, 615.6060121522223, 618.5515501261584, 32550.67449325506, 43036.78481607592, 43910.0, 43955.3406698899, 44001.55998440015, 45021.549784502226, 45204.0, 45217.547824521745, 45403.0919381613, 45504.0, 8946.329025323093, 9093.727188184332, 9096.545172741337, 9102.590383432762, 9103.453792772421, 9061.728148155566, 9161.45031298121, 9164.633414663405, 9168.0, 9169.220616247627, 9155.084491550859, 9203.125703058233, 9206.815863682756, 9214.216791572724, 9214.447133171998, 9130.223930965843, 9161.312901532412, 9163.312751543594, 9177.954110229406, 9188.678396256098, 9014.278857691435, 9085.636574537011, 9087.0, 9090.727278181665, 9091.181793638605, 43032.78483607593, 43320.3501947471, 43366.56633433657, 43401.78299108516, 43402.131957360674, 3582.4805403216646, 3643.5627724672945, 3664.596894341647, 3697.7411581189326, 4102.548719640837, 31532.684673153435, 33502.664973350446, 33642.83178584116, 33647.32705345904, 35328.0, 4394.670399720034, 4415.923968963277, 4451.3323001550025, 4470.173017991649, 4584.40402747644, 350120.0, 354003.0, 354106.0, 354232.0, 355115.0, 34968.65031349654, 60485.39514604885, 61037.0, 61044.694776526725, 61055.0, 34076.659233407845, 36056.45915311272, 36073.45889811655, 36104.819475902455, 36265.818670906476, 15102.093874367572, 15232.847671523143, 15247.847521524644, 15248.161351125573, 15250.313735881911, 12528.874711252953, 12571.811422828665, 12608.180468269533, 12613.495460181673, 12617.810732839014, 8465.873011904827, 8470.915290847135, 8504.149585041447, 8545.401821872409, 8552.059273479912, 3291.6214635317247, 3319.7012268895915, 3332.866685332608, 3345.565076540034, 3364.1925937775245, 1025.5487585462372, 1034.7102811212872, 1036.388530766836, 1039.9616751167796, 1063.9414832184304, 31243.843780781404, 31632.209194770316, 32153.51769723456, 32228.35543289121, 32260.19349516281, 1163.057923082306, 1167.6613782003124, 1172.1619042384737, 1183.621241202824, 1203.6268756685456, 16267.349306027627, 16410.0, 16647.916760416363, 17074.0, 17207.483775486755, 455283.0, 460199.0, 461048.0, 462112.0, 462766.0, 54682.726586366814, 55220.447795522334, 55333.44666553364, 55337.44662553404, 55390.99999999919, 1258.5217617305366, 1361.46902707944, 1372.9176249425066, 1380.1029330934973, 1391.512970460351, 31155.90954316588, 33623.495647564814, 35047.824760876036, 37009.8501155633, 37250.627493724714, 57844.71077644585, 58582.4141758594, 58634.413655862896, 58647.12029319649, 58684.706576466844, 541224.0, 544589.0, 546816.0, 547110.0, 548419.0, 34136.658633413346, 36599.45100823436, 37101.25797484089, 44699.0, 45118.32322515231, 45159.32261016022, 49457.25814112719, 49476.50523494719, 49536.75231623963, 49758.25362619636, 40653.796731016155, 41525.792371037955, 43220.56779432291, 44042.559574403844, 45474.54525454703, 42369.15261694687, 42499.0, 42643.78678106589, 42964.35553466763, 43417.78291108524, 42694.0, 43254.783726081165, 44027.77986110049, 44240.0, 44259.77870110629, 34992.30015399627, 37500.0, 37810.62189378181, 38529.2294154121, 39056.41415378715, 27928.619161943097, 29217.123486295444, 29283.0, 29285.560716588843, 29715.405691886474], "value": 19504.213378724307, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.66402893511594, 400.7154920006795, 403.21373322022043, 407.85113433596746, 409.5147250508148, 12484.877570812961, 18504.537386565342, 19283.807161928376, 19942.7008594871, 20013.399598012045, 19292.421227363167, 19620.705689414634, 19683.5079123022, 19895.104720287585, 20320.18719251232, 5625.971870140644, 5808.709564521778, 5818.0400233961345, 5823.126531020339, 5836.445537673914, 5677.290338707661, 5759.366469688352, 5762.884742305151, 5767.740451679663, 5770.0, 4155.750654960697, 4218.198542276958, 4704.38842950416, 6453.290138084831, 6525.053867189255, 6351.650659213741, 6474.0, 6604.966975165142, 6609.537332386735, 6612.041254018164, 5735.340435849889, 5775.537956963432, 5785.2189954356045, 5785.2189954356045, 5787.102999035114, 23246.53506929852, 23501.647475287715, 23905.760942390527, 24071.0, 24214.273571792874, 2336.4626135988647, 2425.4057755849753, 2553.795696344288, 2608.282722251377, 2981.016264632666, 14268.429262829475, 14709.92645036768, 14997.775033374508, 15026.699466010728, 15045.097294162386, 1918.9424317270505, 1968.114348543147, 1968.4980330015896, 1989.234144854227, 2002.7496562929634, 54039.0, 71633.0, 72583.0, 81428.18571814324, 81981.59009204915, 11039.72400689973, 11357.97778199966, 11387.14596405273, 11415.429228538706, 11448.3703396314, 15780.368785248462, 15786.36854525829, 15793.289301981438, 15796.289166987513, 15804.762928556082, 8056.677732890619, 8309.0, 8376.204260595234, 8402.159784021671, 8603.784905377415, 7670.846583068419, 7873.803154921173, 7874.685012599546, 7906.9209307906185, 8287.223780033635, 4643.976780116078, 4692.671512994126, 4758.3100450434495, 4800.831970881002, 5031.723255220998, 1966.125074341904, 1995.4113536506766, 2009.9196032158843, 2010.4069299556375, 2018.9394318170478, 916.9312301577409, 961.2502248246379, 978.1196922769565, 1009.4700282351824, 1023.247912784103, 19722.802771972096, 20230.29193978233, 20264.392068237976, 20309.89845050765, 20445.386638400872, 1005.7887843552936, 1015.746063484129, 1018.2579022093776, 1041.3231399590175, 1068.2201992545486, 10669.41318227505, 11163.776724465628, 11214.274341214981, 11413.885861141449, 11509.597164099216, 463185.0, 464907.0, 477931.0, 511677.0, 515312.0, 56241.0, 95984.0, 96184.51907740557, 99324.0, 101942.0, 987.871576695025, 998.2064258914036, 1026.573971801709, 1034.9793004140026, 1035.5857656937285, 13572.457101715821, 17224.13879306055, 17754.20106095203, 18358.247320757513, 18733.90633046826, 40438.59561404466, 41452.0, 42509.0, 42593.36109958291, 43054.78472607617, 53901.0, 56058.439415606954, 56535.71732141313, 56553.71723141523, 57349.0, 5894.793682221101, 6516.5112616553, 8281.420300578902, 8517.914820851713, 9102.681406151018, 11761.47073381717, 13892.583222503341, 14003.579892603238, 14019.649508762363, 14055.789163162357, 12112.576059837864, 12261.14172007951, 12382.56661016896, 12508.312042837731, 12731.80902286448, 11685.240459370114, 11706.180567360203, 11988.940055299669, 12056.397180140782, 12644.620661380173, 12253.081018923438, 12392.876071239172, 12436.937815310866, 12453.688657783629, 12849.87150128524, 8586.957065214634, 8784.604692788713, 8814.0, 8815.691450799191, 8952.209558088538, 10456.68629941103, 12043.578474753342, 12165.81751273714, 12626.242425454502, 12650.367481625697], "value": 9416.79175816726, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.992875035625, 1493.3503925792281, 1603.8556529912312, 1730.5154556724106, 1754.867835579771, 8440.746777596674, 8988.505632190227, 9009.234215091708, 9014.90985090149, 9017.0, 7995.360371170307, 8698.91301086989, 8782.253508451773, 8980.506072166028, 9023.819523609523, 2347.9647805282893, 2368.5736567417857, 2377.3105799318178, 2378.167641325531, 2378.2151889876386, 2377.7741114594173, 2383.1301574925114, 2434.573949558829, 2435.3546310227803, 2439.121916110199, 2084.6351888419467, 2139.60417322795, 2141.0900367343893, 2149.2262785397243, 2357.6817129687443, 2402.5795485789918, 2422.8667423291713, 2431.489387228684, 2443.6945381827272, 2444.963325550118, 2405.338531903723, 2413.915512957056, 2420.346506443268, 2423.2366804456715, 2432.3554258121612, 9210.539473026389, 9255.213306868856, 9275.350725449154, 9279.118483744034, 9284.535773211312, 5233.738313084368, 5289.0, 5298.0, 5447.972760136214, 5458.972705136489, 3455.9827200863833, 3564.3940530109944, 3565.144365352297, 3589.892303230881, 3593.1556084320164, 1153.578943685557, 1207.046433317678, 1220.0056953582891, 1225.4485481533345, 1254.523281153165, 12810.935945320307, 12866.549670761571, 12937.0, 12967.092303538757, 12968.80546791799, 4535.0, 4588.839390621312, 4740.19416699162, 4752.500987396343, 4797.736124513185, 10703.357798532114, 10704.78590428187, 10711.946440267904, 10725.570977160825, 10732.73168170802, 5407.405185429679, 5410.0, 5416.864578385572, 5417.620766546383, 5421.241026256327, 4802.327674125555, 4848.9757551212015, 4951.455339912607, 4997.675151115166, 5009.949900500948, 4021.939670904939, 4112.074783173822, 4141.233871733739, 4158.877103182124, 4243.596858298457, 1292.8060790881443, 1305.2429590837291, 1313.0611612696966, 1320.1023304153075, 1326.4893016188837, 944.3861490031535, 960.582146766159, 965.3869792681558, 971.820213260549, 990.6730778842965, 5741.971290143522, 5886.293644762571, 5895.852603684856, 5978.461938425563, 5981.654127821293, 454.57043094275593, 459.6943032883095, 470.2710798262679, 475.4603524999082, 491.7148054128597, 4360.694751367374, 4373.956260437419, 4380.4305440292555, 4384.145091707151, 4431.534688857688, 11021.393823339793, 11590.072794176445, 11612.0, 11616.651500454831, 11618.360990145624, 10859.34843909368, 11312.321260724382, 11378.715532111764, 11493.138014648935, 11625.069994400428, 8416.769556067524, 8620.52587107715, 8622.051574326819, 8631.482111073356, 8633.050364459905, 5758.654480731169, 5818.796342128006, 5935.071101891816, 6088.512918966383, 6260.655663938527, 10504.791948925666, 10512.737181570523, 10527.105196058421, 10647.89352106469, 10723.03492685662, 10179.541920613738, 10539.314944528582, 10962.780744385227, 10967.890321096687, 10993.340399576051, 7928.207179282025, 8010.0, 8017.8797318039105, 8021.358291336563, 8032.678692852454, 8286.70996515119, 8446.239838414573, 8447.45091569046, 8462.788430289293, 8508.489490630582, 7560.395168386406, 7620.085589729268, 7623.352015078559, 7626.847463050597, 7634.6182690864125, 7584.810379740551, 7631.122420921665, 7802.297793198412, 7852.254035866584, 7915.802104947192, 6866.026556895533, 6906.205786334636, 7000.0, 7007.474439417166, 7022.508424410446, 5180.430152683277, 5244.1085015548215, 5255.763490643007, 5345.438728933406, 5425.538829199562, 5270.183121616155, 5329.866753331198, 5342.0, 5390.946090539044, 5567.025770490229], "value": 4673.224065464997, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.4291845493561, 323.7345376791031, 332.8186138554488, 335.37117903930124, 336.3625928864801, 5142.640015198937, 5287.788488460457, 5293.89412211756, 5296.496832800887, 5361.437049109846, 5061.4938506149365, 5153.30430391897, 5274.3913106502505, 5276.736163191835, 5277.683338999653, 4110.1163249901265, 4114.753114813121, 4128.967758060485, 4134.50385953686, 4141.130362623852, 3992.6207010334115, 4026.899327516821, 4109.506859176888, 4117.794110294488, 4164.604362585549, 4083.7141400102005, 4085.5710150434224, 4092.529359123709, 4101.589841015904, 4116.691248156385, 4126.360414135814, 4148.191102734968, 4153.480814898137, 4162.105147393325, 4163.5836416358425, 3623.873164439232, 3669.4495825626104, 3670.4861319415304, 3688.7417880748358, 3728.067983004249, 5066.164082926312, 5082.669626474268, 5083.415407228142, 5088.898222035538, 5091.41448733393, 4669.136209801198, 4670.322803193552, 4673.135469938073, 4679.128348660529, 4683.812647494096, 5256.316678831766, 5382.470238987669, 5417.756200970965, 5472.0, 5473.644213126174, 3999.6600288975474, 4008.198360327946, 4012.4181993611055, 4012.879613611596, 4049.2104039712267, 5688.601797874109, 5696.658200507983, 5713.74288157034, 5721.05484135396, 5722.37053924068, 4002.9965795621238, 4038.8384464621085, 4090.550039495653, 4123.773192474383, 4124.072083781125, 4166.1251137261515, 4168.854090106832, 4175.937360939588, 4176.4570605821045, 4195.97902010488, 4469.374287599739, 4494.258447356215, 4594.793234304464, 4656.231721765871, 4680.648951328664, 5443.374011988592, 5451.182322651554, 5457.653209120037, 5461.433126430571, 5499.367572729107, 1428.2144820348974, 1441.6900366421269, 1445.4724025730743, 1447.2691290898028, 1452.5424491285212, 918.8392031394479, 972.1202311907734, 972.295086062599, 977.7555611097225, 985.1724551376877, 5254.3169387979315, 5261.421573527927, 5263.3947096083675, 5303.655262407931, 5354.866128346823, 5552.583556233299, 5567.331920169606, 5601.215829783833, 5619.915701264485, 5647.830565083054, 5182.481751824863, 5268.868278293074, 5292.761825717852, 5298.231756395301, 5337.226102215234, 2955.3350496138196, 2964.925876853096, 2966.703329667016, 2969.5545668149516, 2983.4331477019296, 5609.66342019472, 5778.595498315078, 5795.420457954171, 5844.12338149281, 5846.239988801429, 5596.58025648078, 5662.207290979266, 5662.546996240373, 5664.546836253172, 5677.318721753416, 5764.884702305931, 5766.0, 5774.249347584871, 5777.24895763548, 5815.41845815415, 5302.628815982845, 5456.645318054314, 5462.6995515247045, 5468.207109969112, 5542.251796007487, 5462.590305727166, 5465.289512363367, 5470.316210473691, 5497.433231528995, 5499.477549632778, 5517.896420715792, 5661.235733176133, 5717.771289148387, 5773.538116950718, 5793.710314484343, 4006.7395619284653, 4010.2781499329235, 4013.3545246449985, 4015.799210039545, 4049.696272779612, 5290.301946007641, 5449.482299181572, 5469.781208751605, 5495.752691128829, 5608.775648974159, 5367.8190798025935, 5424.213489044146, 5513.421090785409, 5540.335159780852, 5564.304461942257, 5389.245505629294, 5425.403205647454, 5482.972585137049, 5504.22940788282, 5520.806771762969, 5144.794208231779, 5352.812651557177, 5434.945650543602, 5448.591355648263, 5462.262594549685, 5420.78032442713, 5489.505944465017, 5512.91730624049, 5570.5543556516195, 5577.247071645276, 5159.896802064013, 5327.573794096385, 5333.0401627097335, 5386.7575959082715, 5434.184872269033], "value": 4191.483282675483, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.097407007871, 793.3415265329777, 817.3542901108125, 838.8532006898787, 863.753830158405, 14958.551243462687, 16158.0, 16161.757573636405, 16304.429344972914, 16379.83620163798, 15027.474038408658, 15498.767518487231, 15509.844901550981, 15535.300911458984, 15539.92230038851, 650.9446697030746, 794.9920500794976, 812.7683610171102, 817.8854960305562, 819.1399031017407, 815.0219736316415, 817.889584906039, 818.3289702443998, 818.5252553518944, 818.5252553518944, 691.1153723234269, 864.356054739217, 867.8004059066387, 867.8351113288487, 868.274990383031, 846.7332790171107, 871.0157522000131, 876.6361959786681, 880.6345366672823, 880.8150288439435, 633.7655067625009, 636.1888592045133, 637.9649119298437, 638.5721566550376, 638.7828138432955, 14818.184999825005, 14866.628334291621, 14901.105933644016, 14903.925480372529, 14922.477713279983, 15046.924765376103, 15066.698666026728, 15373.615659608487, 15389.846101538953, 15394.769078463833, 11791.882081179165, 11955.0, 12092.637220883387, 13248.602541923758, 14475.638109047359, 4567.634589232854, 4589.0, 4649.88375290617, 4694.600958918496, 4748.430188377382, 85810.57094714486, 87038.56480717556, 88175.99999999936, 88244.0, 90047.54976225142, 12265.949208744578, 12415.379231038412, 12461.626151215296, 12481.500739970299, 12801.103922725506, 22998.0, 23229.41926451852, 23511.17710880111, 23604.881975590357, 23608.17371391993, 8634.179752923463, 8641.481511109228, 8706.085860984505, 8844.73465796027, 9200.171984521425, 4817.181079216542, 4872.926906096412, 4903.681260718042, 4950.504949505093, 4950.925736113961, 3535.0, 3643.307771523402, 3644.872429465009, 3645.3620616392027, 3648.2521083177885, 631.718885096137, 632.4371309534517, 632.6710110742388, 648.3322178156507, 661.4807376209652, 581.682982774389, 629.5750368501335, 673.4074014866984, 690.4441924250925, 713.2439614009166, 8951.970523389893, 9404.0, 9410.38832475887, 9443.811123777487, 9719.951400242953, 650.4146268358421, 685.5269863793966, 699.0876905638191, 711.3739908880152, 711.5874988148521, 4373.562643735665, 4609.0, 4623.4451865775645, 4640.559146881041, 4656.41794775653, 127856.3607181958, 127952.36023820007, 130131.0, 130705.34647326893, 132953.33523332514, 100058.499707501, 100512.49743751381, 101541.49229253954, 102609.48695256625, 105304.0, 92183.53908230415, 95392.52303738437, 95752.0, 96207.0, 96946.51526742462, 8405.327573794204, 8520.3183745299, 8575.3997220194, 8632.482051076955, 8682.78293042679, 56606.43393566012, 57717.4228257712, 58022.7098864503, 58126.0, 58709.0, 80352.19647803447, 81947.0, 83597.58201208955, 84651.5767421159, 86867.56566217128, 12014.498187726535, 12275.631731048083, 12401.75196496047, 12517.749645006867, 12528.619562283136, 11928.0, 12009.819852702041, 12104.576339828063, 12486.500539978299, 13227.941764658612, 12479.812802808146, 12507.124501285187, 12558.560450384193, 12712.237265764084, 12786.296753678265, 11845.763084738084, 12026.579069732517, 12122.333271670142, 12349.073819463396, 12542.498300067895, 11200.215984881306, 11324.150688698546, 11455.770884582429, 11549.6535103947, 11997.40012999364, 9492.667756628485, 9625.759356016155, 9742.610295588098, 10227.7443063924, 10424.583016679248, 9801.656942006995, 9926.25553083536, 9966.0, 10031.097201251923, 10035.397876127456], "value": 7619.315153917517, "unit": "score"}], "name": "dromaeo_css", "value": 7227.153406853996, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 111.0, 111.0, 106.0, 111.0, 111.0, 111.0, 112.0, 110.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 118.0, 121.0, 193.0, 117.0, 119.0, 151.0, 123.0, 157.0, 123.0], "value": 136.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 159.0, 164.0, 160.0, 160.0, 162.0, 161.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 119.0, 75.0, 76.0, 75.0, 75.0, 106.0, 76.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 137.0, 84.0, 84.0, 84.0, 116.0, 84.0, 84.0, 126.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 54.0, 52.0, 52.0, 53.0, 52.0, 51.0, 53.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 61.0, 61.0, 63.0, 62.0, 62.0, 64.0, 62.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 81.0, 77.0, 79.0, 77.0, 86.0, 82.0, 78.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 124.0, 130.0, 123.0, 124.0, 129.0, 133.0, 130.0, 138.0, 132.0], "value": 128.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 169.0, 167.0, 165.0, 170.0, 169.0, 173.0, 163.0, 165.0, 164.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 64.0, 64.0, 68.0, 64.0, 65.0, 63.0, 63.0], "value": 64.5, "unit": "score"}], "name": "kraken", "value": 1498.6, "alertThreshold": 2.0}]} 22:21:23 INFO - Return code: 0 22:21:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:21:23 INFO - # TBPL SUCCESS # 22:21:23 INFO - Running post-action listener: _resource_record_post_action 22:21:23 INFO - [mozharness: 2016-08-16 05:21:23.126183Z] Finished run-tests step (success) 22:21:23 INFO - Running post-run listener: _resource_record_post_run 22:21:23 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 419672064; Read time: 16; Write time: 228264 22:21:23 INFO - TinderboxPrint: CPU usage
12.9% 22:21:23 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 22:21:23 INFO - TinderboxPrint: I/O write bytes / time
419,672,064 / 228,264 22:21:23 INFO - TinderboxPrint: CPU idle
8,289.0 (87.1%) 22:21:23 INFO - TinderboxPrint: CPU user
1,188.1 (12.5%) 22:21:23 INFO - TinderboxPrint: Swap in / out
0 / 0 22:21:23 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 129433600; Read time: 0; Write time: 76564 22:21:23 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 289878016; Read time: 0; Write time: 151700 22:21:23 INFO - Running post-run listener: _upload_blobber_files 22:21:23 INFO - Blob upload gear active. 22:21:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:21:23 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 22:21:23 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:21:23 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:21:24 INFO - (blobuploader) - INFO - Open directory for files ... 22:21:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:21:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:21:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:30 INFO - (blobuploader) - INFO - Done attempting. 22:21:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:21:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:21:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:30 INFO - (blobuploader) - INFO - Done attempting. 22:21:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:21:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:21:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:31 INFO - (blobuploader) - INFO - Done attempting. 22:21:31 INFO - (blobuploader) - INFO - Iteration through files over. 22:21:31 INFO - Return code: 0 22:21:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:21:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:21:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3d995748d85476ef6c409c6587286f2421459272b8d79a6e338e3282de1a8ff46f818e71836553a0d2a24a9dd521a52a30bfab30f6f3a7232bfd9194b8e47c45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7940143e23f74e4c8877bf24ef59489b86c9fe0b2e18784861ebe454ee051058b5671e47fe2fe939be02bd9cd47d2e3fea307fab790f331ee6c8820dfd299947", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f70c1e8e23e9423d1d6b46fb032449ec0f950d89da8bea942098855a515d2c4a64c6133a140fa8390c06ad073af1a38609b726a4742357ccfe0b5c5846e31a3"} 22:21:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:21:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:21:31 INFO - Contents: 22:21:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3d995748d85476ef6c409c6587286f2421459272b8d79a6e338e3282de1a8ff46f818e71836553a0d2a24a9dd521a52a30bfab30f6f3a7232bfd9194b8e47c45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7940143e23f74e4c8877bf24ef59489b86c9fe0b2e18784861ebe454ee051058b5671e47fe2fe939be02bd9cd47d2e3fea307fab790f331ee6c8820dfd299947", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f70c1e8e23e9423d1d6b46fb032449ec0f950d89da8bea942098855a515d2c4a64c6133a140fa8390c06ad073af1a38609b726a4742357ccfe0b5c5846e31a3"} 22:21:31 INFO - Running post-run listener: copy_logs_to_upload_dir 22:21:31 INFO - Copying logs to upload dir... 22:21:31 INFO - mkdir: /builds/slave/test/build/upload/logs 22:21:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.670602 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-08-15 22:21:31.560699) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 22:21:31.564801) ========= 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=8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3d995748d85476ef6c409c6587286f2421459272b8d79a6e338e3282de1a8ff46f818e71836553a0d2a24a9dd521a52a30bfab30f6f3a7232bfd9194b8e47c45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7940143e23f74e4c8877bf24ef59489b86c9fe0b2e18784861ebe454ee051058b5671e47fe2fe939be02bd9cd47d2e3fea307fab790f331ee6c8820dfd299947", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f70c1e8e23e9423d1d6b46fb032449ec0f950d89da8bea942098855a515d2c4a64c6133a140fa8390c06ad073af1a38609b726a4742357ccfe0b5c5846e31a3"} build_url:https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011392 build_url: 'https://queue.taskcluster.net/v1/task/brUXeocYRnWAfRDFS8axlw/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/Fx-Team/sha512/3d995748d85476ef6c409c6587286f2421459272b8d79a6e338e3282de1a8ff46f818e71836553a0d2a24a9dd521a52a30bfab30f6f3a7232bfd9194b8e47c45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7940143e23f74e4c8877bf24ef59489b86c9fe0b2e18784861ebe454ee051058b5671e47fe2fe939be02bd9cd47d2e3fea307fab790f331ee6c8820dfd299947", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f70c1e8e23e9423d1d6b46fb032449ec0f950d89da8bea942098855a515d2c4a64c6133a140fa8390c06ad073af1a38609b726a4742357ccfe0b5c5846e31a3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 22:21:31.591260) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:21:31.591611) ========= 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=8887e36bb8e381bfd44124ac000001a8-1471322397.264241-1550147500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004340 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 22:21:31.677211) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 22:21:31.677549) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 22:21:31.677861) ========= ========= Total master_lag: 0.18 =========