[01:17:54] [INFO] [mergernet.model.automl] start of optuna optimization
[01:17:55] [INFO] [optuna.storages._rdb.storage] A new study created in RDB with name: exp_1
[01:17:56] [INFO] [mergernet.data.dataset] Apply: load_jpg
[01:17:56] [INFO] [mergernet.data.dataset] Apply: one_hot
[01:17:56] [INFO] [mergernet.data.dataset] Apply: cache
[01:17:56] [INFO] [mergernet.data.dataset] Apply: shuffle
[01:17:56] [INFO] [mergernet.data.dataset] Apply: batch
[01:17:56] [INFO] [mergernet.data.dataset] Apply: prefetch
[01:17:56] [INFO] [mergernet.data.dataset] Apply: load_jpg
[01:17:56] [INFO] [mergernet.data.dataset] Apply: one_hot
[01:17:56] [INFO] [mergernet.data.dataset] Apply: cache
[01:17:56] [INFO] [mergernet.data.dataset] Apply: shuffle
[01:17:56] [INFO] [mergernet.data.dataset] Apply: batch
[01:17:56] [INFO] [mergernet.data.dataset] Apply: prefetch
[01:17:58] [DEBUG] [h5py._conv] Creating converter from 3 to 5
[01:17:58] [INFO] [mergernet.model.baseline] Trainable weights (CONV): 0
[01:17:59] [INFO] [mergernet.model.baseline] Trainable weights (TOTAL): 6
[01:17:59] [INFO] [mergernet.model.baseline] Start of training loop
[01:19:27] [INFO] [mergernet.model.baseline] End of training loop, duration: 00:01:27.
[01:19:27] [INFO] [mergernet.model.baseline] Start of training loop
[01:42:59] [INFO] [mergernet.model.callbacks] New best metric detected: val_loss = 0.07482672482728958s
[01:42:59] [DEBUG] [h5py._conv] Creating converter from 5 to 3
[01:43:00] [INFO] [mergernet.model.callbacks] Trial saved at experiments/1/7a7ead4f/exp_1.h5
[01:43:00] [INFO] [mergernet.model.baseline] End of training loop, duration: 00:23:33.
[01:43:01] [INFO] [optuna.study.study] Trial 0 finished with value: 0.07482672482728958 and parameters: {'dense_1_units': 384, 'dropout_1_rate': 0.4852142919229748, 'dense_2_units': 768, 'dropout_2_rate': 0.379597545259111, 'opt_lr': 2.0513382630874486e-05}. Best is trial 0 with value: 0.07482672482728958.
[01:43:01] [INFO] [mergernet.data.dataset] Apply: load_jpg
[01:43:01] [INFO] [mergernet.data.dataset] Apply: one_hot
[01:43:01] [INFO] [mergernet.data.dataset] Apply: cache
[01:43:01] [INFO] [mergernet.data.dataset] Apply: shuffle
[01:43:01] [INFO] [mergernet.data.dataset] Apply: batch
[01:43:01] [INFO] [mergernet.data.dataset] Apply: prefetch
[01:43:01] [INFO] [mergernet.data.dataset] Apply: load_jpg
[01:43:01] [INFO] [mergernet.data.dataset] Apply: one_hot
[01:43:01] [INFO] [mergernet.data.dataset] Apply: cache
[01:43:01] [INFO] [mergernet.data.dataset] Apply: shuffle
[01:43:01] [INFO] [mergernet.data.dataset] Apply: batch
[01:43:01] [INFO] [mergernet.data.dataset] Apply: prefetch
[01:43:03] [INFO] [mergernet.model.baseline] Trainable weights (CONV): 0
[01:43:03] [INFO] [mergernet.model.baseline] Trainable weights (TOTAL): 6
[01:43:03] [INFO] [mergernet.model.baseline] Start of training loop
[01:43:39] [INFO] [mergernet.model.baseline] End of training loop, duration: 00:00:35.
[01:43:39] [INFO] [mergernet.model.baseline] Start of training loop
[01:43:46] [WARNING] [tensorflow] Callback method `on_train_batch_end` is slow compared to the batch time (batch time: 0.0643s vs `on_train_batch_end` time: 0.0674s). Check your callbacks.
[02:06:52] [INFO] [mergernet.model.callbacks] New best metric detected: val_loss = 0.0s
[02:06:53] [INFO] [mergernet.model.callbacks] Trial saved at experiments/1/7a7ead4f/exp_1.h5
[02:07:10] [INFO] [mergernet.model.baseline] End of training loop, duration: 00:23:30.
[02:07:10] [INFO] [optuna.study.study] Trial 1 finished with value: 0.0 and parameters: {'dense_1_units': 192, 'dropout_1_rate': 0.21742508365045984, 'dense_2_units': 896, 'dropout_2_rate': 0.3803345035229626, 'opt_lr': 0.0002607024758370766}. Best is trial 1 with value: 0.0.
[02:07:10] [DEBUG] [urllib3.connectionpool] Starting new HTTPS connection (1): api.github.com:443
[02:07:10] [DEBUG] [urllib3.connectionpool] https://api.github.com:443 "GET /repos/nmcardoso/mergernet-experiments/contents/experiments/1/7a7ead4f/optuna.sqlite HTTP/1.1" 404 None
[02:07:10] [DEBUG] [urllib3.connectionpool] Starting new HTTPS connection (1): api.github.com:443
[02:07:11] [DEBUG] [urllib3.connectionpool] https://api.github.com:443 "PUT /repos/nmcardoso/mergernet-experiments/contents/experiments/1/7a7ead4f/optuna.sqlite HTTP/1.1" 201 2196
[02:07:11] [INFO] [mergernet.model.automl] optuna optimization finished in 00:49:16
[02:07:11] [INFO] [mergernet.model.automl] number of finished trials: 2
[02:07:11] [INFO] [mergernet.model.automl] ----- begin of best trial summary -----
[02:07:11] [INFO] [mergernet.model.automl] optimization score: 0.0
[02:07:11] [INFO] [mergernet.model.automl] params:
[02:07:11] [INFO] [mergernet.model.automl] dense_1_units: 192
[02:07:11] [INFO] [mergernet.model.automl] dense_2_units: 896
[02:07:11] [INFO] [mergernet.model.automl] dropout_1_rate: 0.21742508365045984
[02:07:11] [INFO] [mergernet.model.automl] dropout_2_rate: 0.3803345035229626
[02:07:11] [INFO] [mergernet.model.automl] opt_lr: 0.0002607024758370766
[02:07:11] [INFO] [mergernet.model.automl] ----- end of best trial summary -----
[02:07:11] [DEBUG] [urllib3.connectionpool] Starting new HTTPS connection (1): api.github.com:443
[02:07:11] [DEBUG] [urllib3.connectionpool] https://api.github.com:443 "GET /repos/nmcardoso/mergernet-experiments/contents/experiments/1/metadata.json HTTP/1.1" 404 None
[02:07:11] [DEBUG] [urllib3.connectionpool] Starting new HTTPS connection (1): api.github.com:443
[02:07:11] [DEBUG] [urllib3.connectionpool] https://api.github.com:443 "PUT /repos/nmcardoso/mergernet-experiments/contents/experiments/1/metadata.json HTTP/1.1" 201 2139