2022-05-05 11:50:25,779 INFO [decode.py:487] Decoding started
2022-05-05 11:50:25,779 INFO [decode.py:493] Device: cuda:0
2022-05-05 11:50:26,790 INFO [lexicon.py:176] Loading pre-compiled data/lang_char/Linv.pt
2022-05-05 11:50:26,872 INFO [decode.py:499] {'best_train_loss': inf, 'best_valid_loss': inf, 'best_train_epoch': -1, 'best_valid_epoch': -1, 'batch_idx_train': 0, 'log_interval': 1, 'reset_interval': 200, 'feature_dim': 80, 'subsampling_factor': 4, 'encoder_dim': 512, 'nhead': 8, 'dim_feedforward': 2048, 'num_encoder_layers': 12, 'decoder_dim': 512, 'joiner_dim': 512, 'env_info': {'k2-version': '1.14', 'k2-build-type': 'Release', 'k2-with-cuda': True, 'k2-git-sha1': '4fb6b88661cca73e5f66f03df16e5a1d0c4886f8', 'k2-git-date': 'Fri Apr 8 18:29:32 2022', 'lhotse-version': '1.2.0.dev+git.cda63b5.clean', 'torch-version': '1.11.0', 'torch-cuda-available': True, 'torch-cuda-version': '10.2', 'python-version': '3.8', 'icefall-git-branch': 'wenetspeech-pruned-transducer-stateless2', 'icefall-git-sha1': '4b567e4-dirty', 'icefall-git-date': 'Wed Apr 27 13:43:54 2022', 'icefall-path': '/ceph-meixu/luomingshuang/icefall', 'k2-path': '/ceph-ms/luomingshuang/k2/k2/python/k2/__init__.py', 'lhotse-path': '/ceph-meixu/luomingshuang/anaconda3/envs/k2-python/lib/python3.8/site-packages/lhotse-1.2.0.dev0+git.cda63b5.clean-py3.8.egg/lhotse/__init__.py', 'hostname': 'de-74279-k2-train-1-0307195509-54c966b95f-rtpfq', 'IP address': '10.177.22.9'}, 'epoch': 29, 'batch': None, 'avg': 11, 'avg_last_n': 0, 'exp_dir': PosixPath('pruned_transducer_stateless2/exp-char-M-8-gpus'), 'lang_dir': 'data/lang_char', 'token_type': 'char', 'decoding_method': 'fast_beam_search', 'beam_size': 4, 'beam': 4.0, 'max_contexts': 4, 'max_states': 8, 'context_size': 2, 'max_sym_per_frame': 1, 'manifest_dir': PosixPath('data/fbank'), 'max_duration': 1500, 'bucketing_sampler': True, 'num_buckets': 300, 'concatenate_cuts': False, 'duration_factor': 1.0, 'gap': 1.0, 'on_the_fly_feats': False, 'shuffle': True, 'return_cuts': True, 'num_workers': 2, 'enable_spec_aug': True, 'spec_aug_time_warp_factor': 80, 'enable_musan': True, 'lazy_load': True, 'training_subset': 'L', 'res_dir': PosixPath('pruned_transducer_stateless2/exp-char-M-8-gpus/fast_beam_search'), 'suffix': 'epoch-29-avg-11-beam-4.0-max-contexts-4-max-states-8', 'blank_id': 0, 'vocab_size': 5537}
2022-05-05 11:50:26,873 INFO [decode.py:501] About to create model
2022-05-05 11:50:27,387 INFO [decode.py:522] averaging ['pruned_transducer_stateless2/exp-char-M-8-gpus/epoch-19.pt', 'pruned_transducer_stateless2/exp-char-M-8-gpus/epoch-20.pt', 'pruned_transducer_stateless2/exp-char-M-8-gpus/epoch-21.pt', 'pruned_transducer_stateless2/exp-char-M-8-gpus/epoch-22.pt', 'pruned_transducer_stateless2/exp-char-M-8-gpus/epoch-23.pt', 'pruned_transducer_stateless2/exp-char-M-8-gpus/epoch-24.pt', 'pruned_transducer_stateless2/exp-char-M-8-gpus/epoch-25.pt', 'pruned_transducer_stateless2/exp-char-M-8-gpus/epoch-26.pt', 'pruned_transducer_stateless2/exp-char-M-8-gpus/epoch-27.pt', 'pruned_transducer_stateless2/exp-char-M-8-gpus/epoch-28.pt', 'pruned_transducer_stateless2/exp-char-M-8-gpus/epoch-29.pt']
2022-05-05 11:50:41,092 INFO [decode.py:536] Number of model parameters: 88978927
2022-05-05 11:50:41,094 INFO [asr_datamodule.py:359] About to create dev dataset
2022-05-05 11:50:44,404 INFO [asr_datamodule.py:380] About to create dev dataloader
2022-05-05 11:50:56,882 INFO [decode.py:407] batch 0/?, cuts processed until now is 208
2022-05-05 11:51:00,984 INFO [decode.py:407] batch 2/?, cuts processed until now is 624
2022-05-05 11:51:05,071 INFO [decode.py:407] batch 4/?, cuts processed until now is 1446
2022-05-05 11:51:09,076 INFO [decode.py:407] batch 6/?, cuts processed until now is 2316
2022-05-05 11:51:13,260 INFO [decode.py:407] batch 8/?, cuts processed until now is 2734
2022-05-05 11:51:17,583 INFO [decode.py:407] batch 10/?, cuts processed until now is 3071
2022-05-05 11:51:21,816 INFO [decode.py:407] batch 12/?, cuts processed until now is 3438
2022-05-05 11:51:25,886 INFO [decode.py:407] batch 14/?, cuts processed until now is 3886
2022-05-05 11:51:30,086 INFO [decode.py:407] batch 16/?, cuts processed until now is 4303
2022-05-05 11:51:34,194 INFO [decode.py:407] batch 18/?, cuts processed until now is 4814
2022-05-05 11:51:38,117 INFO [decode.py:407] batch 20/?, cuts processed until now is 5393
2022-05-05 11:51:42,000 INFO [decode.py:407] batch 22/?, cuts processed until now is 6059
2022-05-05 11:51:45,926 INFO [decode.py:407] batch 24/?, cuts processed until now is 6726
2022-05-05 11:51:49,866 INFO [decode.py:407] batch 26/?, cuts processed until now is 7397
2022-05-05 11:51:53,822 INFO [decode.py:407] batch 28/?, cuts processed until now is 8132
2022-05-05 11:51:57,725 INFO [decode.py:407] batch 30/?, cuts processed until now is 8780
2022-05-05 11:52:01,738 INFO [decode.py:407] batch 32/?, cuts processed until now is 9743
2022-05-05 11:52:05,922 INFO [decode.py:407] batch 34/?, cuts processed until now is 10528
2022-05-05 11:52:10,080 INFO [decode.py:407] batch 36/?, cuts processed until now is 10895
2022-05-05 11:52:13,928 INFO [decode.py:407] batch 38/?, cuts processed until now is 11382
2022-05-05 11:52:16,788 INFO [decode.py:407] batch 40/?, cuts processed until now is 11780
2022-05-05 11:52:18,671 INFO [decode.py:407] batch 42/?, cuts processed until now is 12052
2022-05-05 11:52:20,188 INFO [decode.py:407] batch 44/?, cuts processed until now is 12253
2022-05-05 11:52:22,465 INFO [decode.py:407] batch 46/?, cuts processed until now is 12443
2022-05-05 11:52:25,440 INFO [decode.py:407] batch 48/?, cuts processed until now is 12645
2022-05-05 11:52:27,966 INFO [decode.py:407] batch 50/?, cuts processed until now is 12848
2022-05-05 11:52:30,131 INFO [decode.py:407] batch 52/?, cuts processed until now is 13009
2022-05-05 11:52:32,746 INFO [decode.py:407] batch 54/?, cuts processed until now is 13222
2022-05-05 11:52:34,932 INFO [decode.py:407] batch 56/?, cuts processed until now is 13606
2022-05-05 11:52:36,275 INFO [decode.py:407] batch 58/?, cuts processed until now is 13760
2022-05-05 11:52:37,264 INFO [decode.py:424] The transcripts are stored in pruned_transducer_stateless2/exp-char-M-8-gpus/fast_beam_search/recogs-DEV-beam_4.0_max_contexts_4_max_states_8-epoch-29-avg-11-beam-4.0-max-contexts-4-max-states-8.txt
2022-05-05 11:52:37,627 INFO [utils.py:406] [DEV-beam_4.0_max_contexts_4_max_states_8] %WER 10.18% [33654 / 330498, 1580 ins, 15290 del, 16784 sub ]
2022-05-05 11:52:38,588 INFO [decode.py:437] Wrote detailed error stats to pruned_transducer_stateless2/exp-char-M-8-gpus/fast_beam_search/errs-DEV-beam_4.0_max_contexts_4_max_states_8-epoch-29-avg-11-beam-4.0-max-contexts-4-max-states-8.txt
2022-05-05 11:52:38,589 INFO [decode.py:454] 
For DEV, WER of different settings are:
beam_4.0_max_contexts_4_max_states_8	10.18	best for DEV

2022-05-05 11:52:46,393 INFO [decode.py:407] batch 0/?, cuts processed until now is 234
2022-05-05 11:52:50,490 INFO [decode.py:407] batch 2/?, cuts processed until now is 703
2022-05-05 11:52:55,026 INFO [decode.py:407] batch 4/?, cuts processed until now is 2287
2022-05-05 11:52:59,653 INFO [decode.py:407] batch 6/?, cuts processed until now is 3973
2022-05-05 11:53:06,842 INFO [decode.py:407] batch 8/?, cuts processed until now is 4441
2022-05-05 11:53:13,397 INFO [decode.py:407] batch 10/?, cuts processed until now is 4776
2022-05-05 11:53:17,548 INFO [decode.py:407] batch 12/?, cuts processed until now is 5184
2022-05-05 11:53:21,617 INFO [decode.py:407] batch 14/?, cuts processed until now is 5736
2022-05-05 11:53:28,547 INFO [decode.py:407] batch 16/?, cuts processed until now is 6201
2022-05-05 11:53:37,276 INFO [decode.py:407] batch 18/?, cuts processed until now is 6542
2022-05-05 11:53:41,356 INFO [decode.py:407] batch 20/?, cuts processed until now is 7028
2022-05-05 11:53:45,484 INFO [decode.py:407] batch 22/?, cuts processed until now is 8076
2022-05-05 11:53:49,493 INFO [decode.py:407] batch 24/?, cuts processed until now is 9123
2022-05-05 11:53:53,492 INFO [decode.py:407] batch 26/?, cuts processed until now is 10161
2022-05-05 11:53:57,444 INFO [decode.py:407] batch 28/?, cuts processed until now is 11373
2022-05-05 11:54:01,464 INFO [decode.py:407] batch 30/?, cuts processed until now is 12393
2022-05-05 11:54:05,936 INFO [decode.py:407] batch 32/?, cuts processed until now is 14318
2022-05-05 11:54:10,469 INFO [decode.py:407] batch 34/?, cuts processed until now is 15828
2022-05-05 11:54:18,304 INFO [decode.py:407] batch 36/?, cuts processed until now is 16078
2022-05-05 11:54:22,376 INFO [decode.py:407] batch 38/?, cuts processed until now is 16925
2022-05-05 11:54:26,442 INFO [decode.py:407] batch 40/?, cuts processed until now is 17558
2022-05-05 11:54:30,057 INFO [decode.py:407] batch 42/?, cuts processed until now is 18754
2022-05-05 11:54:33,180 INFO [decode.py:407] batch 44/?, cuts processed until now is 19525
2022-05-05 11:54:35,700 INFO [decode.py:407] batch 46/?, cuts processed until now is 20053
2022-05-05 11:54:38,917 INFO [decode.py:407] batch 48/?, cuts processed until now is 20655
2022-05-05 11:54:42,385 INFO [decode.py:407] batch 50/?, cuts processed until now is 20968
2022-05-05 11:54:45,066 INFO [decode.py:407] batch 52/?, cuts processed until now is 22168
2022-05-05 11:54:48,598 INFO [decode.py:407] batch 54/?, cuts processed until now is 22509
2022-05-05 11:54:51,814 INFO [decode.py:407] batch 56/?, cuts processed until now is 23730
2022-05-05 11:54:55,302 INFO [decode.py:407] batch 58/?, cuts processed until now is 23919
2022-05-05 11:54:58,235 INFO [decode.py:407] batch 60/?, cuts processed until now is 24510
2022-05-05 11:55:00,523 INFO [decode.py:407] batch 62/?, cuts processed until now is 24774
2022-05-05 11:55:00,889 INFO [decode.py:424] The transcripts are stored in pruned_transducer_stateless2/exp-char-M-8-gpus/fast_beam_search/recogs-TEST_NET-beam_4.0_max_contexts_4_max_states_8-epoch-29-avg-11-beam-4.0-max-contexts-4-max-states-8.txt
2022-05-05 11:55:01,443 INFO [utils.py:406] [TEST_NET-beam_4.0_max_contexts_4_max_states_8] %WER 11.10% [46141 / 415747, 2271 ins, 11920 del, 31950 sub ]
2022-05-05 11:55:02,693 INFO [decode.py:437] Wrote detailed error stats to pruned_transducer_stateless2/exp-char-M-8-gpus/fast_beam_search/errs-TEST_NET-beam_4.0_max_contexts_4_max_states_8-epoch-29-avg-11-beam-4.0-max-contexts-4-max-states-8.txt
2022-05-05 11:55:02,694 INFO [decode.py:454] 
For TEST_NET, WER of different settings are:
beam_4.0_max_contexts_4_max_states_8	11.1	best for TEST_NET

2022-05-05 11:55:07,999 INFO [decode.py:407] batch 0/?, cuts processed until now is 150
2022-05-05 11:55:12,668 INFO [decode.py:407] batch 2/?, cuts processed until now is 433
2022-05-05 11:55:17,365 INFO [decode.py:407] batch 4/?, cuts processed until now is 1139
2022-05-05 11:55:21,655 INFO [decode.py:407] batch 6/?, cuts processed until now is 1932
2022-05-05 11:55:26,144 INFO [decode.py:407] batch 8/?, cuts processed until now is 2279
2022-05-05 11:55:30,758 INFO [decode.py:407] batch 10/?, cuts processed until now is 2566
2022-05-05 11:55:35,992 INFO [decode.py:407] batch 12/?, cuts processed until now is 2839
2022-05-05 11:55:42,080 INFO [decode.py:407] batch 14/?, cuts processed until now is 3216
2022-05-05 11:55:46,220 INFO [decode.py:407] batch 16/?, cuts processed until now is 3689
2022-05-05 11:55:50,382 INFO [decode.py:407] batch 18/?, cuts processed until now is 4240
2022-05-05 11:55:55,180 INFO [decode.py:407] batch 20/?, cuts processed until now is 4658
2022-05-05 11:56:00,232 INFO [decode.py:407] batch 22/?, cuts processed until now is 4980
2022-05-05 11:56:03,575 INFO [decode.py:407] batch 24/?, cuts processed until now is 5386
2022-05-05 11:56:07,084 INFO [decode.py:407] batch 26/?, cuts processed until now is 5766
2022-05-05 11:56:10,712 INFO [decode.py:407] batch 28/?, cuts processed until now is 6231
2022-05-05 11:56:14,155 INFO [decode.py:407] batch 30/?, cuts processed until now is 6929
2022-05-05 11:56:18,097 INFO [decode.py:407] batch 32/?, cuts processed until now is 7521
2022-05-05 11:56:22,393 INFO [decode.py:407] batch 34/?, cuts processed until now is 7728
2022-05-05 11:56:26,820 INFO [decode.py:407] batch 36/?, cuts processed until now is 8007
2022-05-05 11:56:30,255 INFO [decode.py:407] batch 38/?, cuts processed until now is 8229
2022-05-05 11:56:32,612 INFO [decode.py:424] The transcripts are stored in pruned_transducer_stateless2/exp-char-M-8-gpus/fast_beam_search/recogs-TEST_MEETING-beam_4.0_max_contexts_4_max_states_8-epoch-29-avg-11-beam-4.0-max-contexts-4-max-states-8.txt
2022-05-05 11:56:32,855 INFO [utils.py:406] [TEST_MEETING-beam_4.0_max_contexts_4_max_states_8] %WER 19.32% [42572 / 220385, 1400 ins, 24244 del, 16928 sub ]
2022-05-05 11:56:33,509 INFO [decode.py:437] Wrote detailed error stats to pruned_transducer_stateless2/exp-char-M-8-gpus/fast_beam_search/errs-TEST_MEETING-beam_4.0_max_contexts_4_max_states_8-epoch-29-avg-11-beam-4.0-max-contexts-4-max-states-8.txt
2022-05-05 11:56:33,510 INFO [decode.py:454] 
For TEST_MEETING, WER of different settings are:
beam_4.0_max_contexts_4_max_states_8	19.32	best for TEST_MEETING

2022-05-05 11:56:33,510 INFO [decode.py:630] Done!