2022-05-29 20:15:37,867 INFO [decode.py:470] Decoding started
2022-05-29 20:15:37,868 INFO [decode.py:476] Device: cuda:0
2022-05-29 20:15:39,105 INFO [lexicon.py:176] Loading pre-compiled data/lang_char/Linv.pt
2022-05-29 20:15:39,201 INFO [decode.py:482] {'best_train_loss': inf, 'best_valid_loss': inf, 'best_train_epoch': -1, 'best_valid_epoch': -1, 'batch_idx_train': 10, '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.15.1', 'k2-build-type': 'Release', 'k2-with-cuda': True, 'k2-git-sha1': 'f8d2dba06c000ffee36aab5b66f24e7c9809f116', 'k2-git-date': 'Thu Apr 21 12:20:34 2022', 'lhotse-version': '1.2.0.dev+git.de75634.dirty', '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_latest/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.de75634.dirty-py3.8.egg/lhotse/__init__.py', 'hostname': 'de-74279-k2-train-7-0309102938-68688b4cbd-xhtcg', 'IP address': '10.48.32.137'}, 'epoch': 29, 'batch': None, 'avg': 24, 'avg_last_n': 0, 'exp_dir': PosixPath('pruned_transducer_stateless2/exp-char-S-4-gpus'), 'lang_dir': 'data/lang_char', 'decoding_method': 'greedy_search', 'beam_size': 4, 'beam': 4, 'max_contexts': 4, 'max_states': 8, 'context_size': 2, 'max_sym_per_frame': 1, 'manifest_dir': PosixPath('data/fbank'), 'max_duration': 200.0, '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-S-4-gpus/greedy_search'), 'suffix': 'epoch-29-avg-24-context-2-max-sym-per-frame-1', 'blank_id': 0, 'vocab_size': 5537}
2022-05-29 20:15:39,201 INFO [decode.py:484] About to create model
2022-05-29 20:15:39,830 INFO [decode.py:505] averaging ['pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-6.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-7.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-8.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-9.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-10.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-11.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-12.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-13.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-14.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-15.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-16.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-17.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-18.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-19.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-20.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-21.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-22.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-23.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-24.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-25.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-26.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-27.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-28.pt', 'pruned_transducer_stateless2/exp-char-S-4-gpus/epoch-29.pt']
2022-05-29 20:19:25,994 INFO [decode.py:523] Number of model parameters: 88978927
2022-05-29 20:19:26,116 INFO [asr_datamodule.py:353] About to create dev dataset
2022-05-29 20:19:41,057 INFO [asr_datamodule.py:374] About to create dev dataloader
2022-05-29 20:20:00,361 INFO [decode.py:390] batch 0/?, cuts processed until now is 27
2022-05-29 20:20:29,689 INFO [decode.py:390] batch 100/?, cuts processed until now is 3620
2022-05-29 20:20:58,949 INFO [decode.py:390] batch 200/?, cuts processed until now is 7274
2022-05-29 20:21:28,111 INFO [decode.py:390] batch 300/?, cuts processed until now is 11229
2022-05-29 20:21:48,040 INFO [decode.py:407] The transcripts are stored in pruned_transducer_stateless2/exp-char-S-4-gpus/greedy_search/recogs-DEV-greedy_search-epoch-29-avg-24-context-2-max-sym-per-frame-1.txt
2022-05-29 20:21:48,465 INFO [utils.py:406] [DEV-greedy_search] %WER 19.77% [65341 / 330498, 1460 ins, 19553 del, 44328 sub ]
2022-05-29 20:21:49,582 INFO [decode.py:420] Wrote detailed error stats to pruned_transducer_stateless2/exp-char-S-4-gpus/greedy_search/errs-DEV-greedy_search-epoch-29-avg-24-context-2-max-sym-per-frame-1.txt
2022-05-29 20:21:49,590 INFO [decode.py:437] 
For DEV, WER of different settings are:
greedy_search	19.77	best for DEV

2022-05-29 20:21:55,442 INFO [decode.py:390] batch 0/?, cuts processed until now is 31
2022-05-29 20:22:25,537 INFO [decode.py:390] batch 100/?, cuts processed until now is 5697
2022-05-29 20:22:55,801 INFO [decode.py:390] batch 200/?, cuts processed until now is 11459
2022-05-29 20:23:24,906 INFO [decode.py:390] batch 300/?, cuts processed until now is 18125
2022-05-29 20:23:56,560 INFO [decode.py:390] batch 400/?, cuts processed until now is 23683
2022-05-29 20:24:03,939 INFO [decode.py:407] The transcripts are stored in pruned_transducer_stateless2/exp-char-S-4-gpus/greedy_search/recogs-TEST_NET-greedy_search-epoch-29-avg-24-context-2-max-sym-per-frame-1.txt
2022-05-29 20:24:04,503 INFO [utils.py:406] [TEST_NET-greedy_search] %WER 24.97% [103828 / 415747, 2475 ins, 21936 del, 79417 sub ]
2022-05-29 20:24:05,966 INFO [decode.py:420] Wrote detailed error stats to pruned_transducer_stateless2/exp-char-S-4-gpus/greedy_search/errs-TEST_NET-greedy_search-epoch-29-avg-24-context-2-max-sym-per-frame-1.txt
2022-05-29 20:24:05,974 INFO [decode.py:437] 
For TEST_NET, WER of different settings are:
greedy_search	24.97	best for TEST_NET

2022-05-29 20:24:09,584 INFO [decode.py:390] batch 0/?, cuts processed until now is 20
2022-05-29 20:24:44,561 INFO [decode.py:390] batch 100/?, cuts processed until now is 2825
2022-05-29 20:25:18,913 INFO [decode.py:390] batch 200/?, cuts processed until now is 5695
2022-05-29 20:25:46,949 INFO [decode.py:407] The transcripts are stored in pruned_transducer_stateless2/exp-char-S-4-gpus/greedy_search/recogs-TEST_MEETING-greedy_search-epoch-29-avg-24-context-2-max-sym-per-frame-1.txt
2022-05-29 20:25:47,245 INFO [utils.py:406] [TEST_MEETING-greedy_search] %WER 35.52% [78279 / 220385, 1650 ins, 29346 del, 47283 sub ]
2022-05-29 20:25:48,048 INFO [decode.py:420] Wrote detailed error stats to pruned_transducer_stateless2/exp-char-S-4-gpus/greedy_search/errs-TEST_MEETING-greedy_search-epoch-29-avg-24-context-2-max-sym-per-frame-1.txt
2022-05-29 20:25:48,056 INFO [decode.py:437] 
For TEST_MEETING, WER of different settings are:
greedy_search	35.52	best for TEST_MEETING

2022-05-29 20:25:48,056 INFO [decode.py:617] Done!