Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.04.2022 01:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16567824 kB
MemAvailable 31531868 kB
Buffers 0 kB
Cached 15119684 kB
SwapCached 2612 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-21 01:38:14
DTS_END                         2022-04-21 01:38:14.6550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36948
TRN_ID                          242162
ATT_ID                          622
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9079.41 22834 1089529
customer order: creation 168.55 367 20226
customer order: refuse 14.43 340 1731
order to supplier: creation 44.78 1335 5373
order to supplier: removal 3.38 107074 406
invoice (draft): creation 45.60 3484 5472
invoice (draft): removal 50.70 879 6084
invoice accept: apply 41.17 16616 4940
invoice accept: cancel 46.85 832 5622
customer reserve: creation 3758.37 165 451004
customer reserve: removal 4608.79 62 553055
realization accept: apply 82.20 166 9864
realization accept: cancel 85.49 109 10259
payment from customer: creation 58.37 99 7004
payment from customer: removal 5.75 71 690
payment to supplier: creation 49.33 120 5920
payment to supplier: removal 3.45 135 414
service: total inventory turnovers 4.28 1262 513
service: total monetary turnovers 7.03 28 844
service: aggregate perf. data 0.87 20752 104
service: refresh index statistics 0.03 302778 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-04-2022 01:39:21 to 21-04-2022 01:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5974
TEST_TIME 2 5030
TEST_TIME 3 4157
TEST_TIME 4 4061
TEST_TIME 5 4649
TEST_TIME 6 4728
TEST_TIME 7 4817
TEST_TIME 8 5247
TEST_TIME 9 5460
TEST_TIME 10 5750
TEST_TIME 11 5828
TEST_TIME 12 6548
TEST_TIME 13 7199
TEST_TIME 14 7156
TEST_TIME 15 7592
TEST_TIME 16 7914
TEST_TIME 17 8556
TEST_TIME 18 8375
TEST_TIME 19 8791
TEST_TIME 20 9041
TEST_TIME 21 9090
TEST_TIME 22 9213
TEST_TIME 23 9583
TEST_TIME 24 9500
TEST_TIME 25 9554
TEST_TIME 26 9565
TEST_TIME 27 9411
TEST_TIME 28 9822
TEST_TIME 29 9754
TEST_TIME 30 10203
TEST_TIME 31 10966
TEST_TIME 32 11021
TEST_TIME 33 10840
TEST_TIME 34 10529
TEST_TIME 35 10598
TEST_TIME 36 10484
TEST_TIME 37 10361
TEST_TIME 38 10212
TEST_TIME 39 10081
TEST_TIME 40 9926
TEST_TIME 41 9922
TEST_TIME 42 9812
TEST_TIME 43 9693
TEST_TIME 44 9651
TEST_TIME 45 9558
TEST_TIME 46 9547
TEST_TIME 47 9807
TEST_TIME 48 9667
TEST_TIME 49 9561
TEST_TIME 50 9596
TEST_TIME 51 9591
TEST_TIME 52 9485
TEST_TIME 53 9470
TEST_TIME 54 9334
TEST_TIME 55 9443
TEST_TIME 56 9335
TEST_TIME 57 9313
TEST_TIME 58 9258
TEST_TIME 59 9199
TEST_TIME 60 9241
TEST_TIME 61 9140
TEST_TIME 62 9298
TEST_TIME 63 9254
TEST_TIME 64 9323
TEST_TIME 65 9362
TEST_TIME 66 9333
TEST_TIME 67 9310
TEST_TIME 68 9347
TEST_TIME 69 9318
TEST_TIME 70 9322
TEST_TIME 71 9443
TEST_TIME 72 9462
TEST_TIME 73 9445
TEST_TIME 74 9422
TEST_TIME 75 9394
TEST_TIME 76 9319
TEST_TIME 77 9327
TEST_TIME 78 9243
TEST_TIME 79 9254
TEST_TIME 80 9232
TEST_TIME 81 9217
TEST_TIME 82 9218
TEST_TIME 83 9203
TEST_TIME 84 9144
TEST_TIME 85 9089
TEST_TIME 86 9091
TEST_TIME 87 9044
TEST_TIME 88 9051
TEST_TIME 89 9091
TEST_TIME 90 9096
TEST_TIME 91 9104
TEST_TIME 92 9093
TEST_TIME 93 9181
TEST_TIME 94 9177
TEST_TIME 95 9221
TEST_TIME 96 9252
TEST_TIME 97 9239
TEST_TIME 98 9299
TEST_TIME 99 9286
TEST_TIME 100 9306
TEST_TIME 101 9356
TEST_TIME 102 9352
TEST_TIME 103 9267
TEST_TIME 104 9238
TEST_TIME 105 9198
TEST_TIME 106 9182
TEST_TIME 107 9197
TEST_TIME 108 9147
TEST_TIME 109 9123
TEST_TIME 110 9132
TEST_TIME 111 9087
TEST_TIME 112 9080
TEST_TIME 113 9125
TEST_TIME 114 9116
TEST_TIME 115 9121
TEST_TIME 116 9131
TEST_TIME 117 9117
TEST_TIME 118 9185
TEST_TIME 119 9152
TEST_TIME 120 9083

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-04-2022 01:39:24 to 21-04-2022 01:39:28.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1507534 1507534 0 0.00 0 22878 6 0 0
doc_list_biud 1507527 1507527 0 0.00 0 41429 29 0 0
sp_add_invoice_to_stock 5034 4940 94 1.86 26 186731 16616 0 94
sp_cancel_adding_invoice 5622 5622 0 0.00 5 54760 832 0 0
sp_cancel_client_order 1731 1731 0 0.00 4 9727 340 0 0
sp_cancel_customer_reserve 553055 553055 0 0.00 1 41434 62 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 4491 72 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 6925 136 0 0
sp_cancel_supplier_invoice 6097 6084 13 0.21 2 18132 879 0 13
sp_cancel_supplier_order 406 406 0 0.00 6 881418 107074 0 0
sp_cancel_write_off 10259 10259 0 0.00 1 13160 109 0 0
sp_client_order 20226 20226 0 0.00 3 37311 368 0 0
sp_customer_reserve 451004 451004 0 0.00 0 37512 166 0 0
sp_fill_shopping_cart 31064 31064 0 0.00 1 22898 46 0 0
sp_fill_shopping_cart_clo_res 450988 450988 0 0.00 0 21745 14 0 0
sp_get_clo_for_invoice 4920 4920 0 0.00 0 21421 325 0 0
sp_kill_cost_storno 16985 16985 0 0.00 0 13074 55 0 0
sp_kill_qstorno_ret_qs2qd 1122859 1122859 0 0.00 0 41372 34 0 0
sp_kill_qty_storno 591937 591937 0 0.00 0 41429 73 0 0
sp_lock_selected_doc 20480 20480 0 0.00 0 9325 6 0 0
sp_make_cost_storno 27708 27708 0 0.00 0 22875 46 0 0
sp_multiply_rows_for_pdistr 27702 27702 0 0.00 0 12515 27 0 0
sp_multiply_rows_for_qdistr 20225 20225 0 0.00 0 20954 166 0 0
sp_pay_from_customer 7004 7004 0 0.00 4 14077 100 0 0
sp_pay_to_supplier 5920 5920 0 0.00 5 22901 121 0 0
sp_payment_common 12922 12922 0 0.00 1 14070 22 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 0 9721 310 0 0
sp_qd_handle_on_invoice_upd_sts 10539 10539 0 0.00 0 33897 332 0 0
sp_qd_handle_on_reserve_upd_sts 9863 9863 0 0.00 0 12517 54 0 0
sp_reserve_write_off 9864 9864 0 0.00 7 17014 166 0 0
sp_supplier_invoice 5472 5472 0 0.00 7 134530 3485 0 0
sp_supplier_order 5373 5373 0 0.00 5 34596 1336 0 0
srv_aggregate_perf_data 104 104 0 0.00 3155 154391 20753 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1969 133329 44947 0 0
srv_find_qd_qs_mism 1042726 1042726 0 0.00 0 18662 5 0 0
srv_make_invnt_saldo 513 513 0 0.00 41 61035 1262 0 0
srv_make_money_saldo 844 844 0 0.00 1 4418 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 225013 377614 302779 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4941 4941 0 0.00 0 18733 179 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 5558 104 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 12230 27 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 9611 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 2300 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 3634 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 812 21 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 27733 1727 0 0
t$perf-norm:sp_cancel_write_off 928 928 0 0.00 0 1474 8 0 0
t$perf-norm:sp_client_order 20226 20226 0 0.00 0 6126 18 0 0
t$perf-norm:sp_customer_reserve 932 932 0 0.00 0 1571 5 0 0
t$perf-norm:sp_pay_from_customer 7004 7004 0 0.00 0 6581 10 0 0
t$perf-norm:sp_pay_to_supplier 5920 5920 0 0.00 0 8131 13 0 0
t$perf-norm:sp_reserve_write_off 9864 9864 0 0.00 0 25102 15 0 0
t$perf-norm:sp_supplier_invoice 5472 5472 0 0.00 0 11297 30 0 0
t$perf-norm:sp_supplier_order 5373 5373 0 0.00 0 13282 19 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 308 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 9 1 0 0
t$perf-norm:srv_make_invnt_saldo 513 513 0 0.00 0 2198 20 0 0
t$perf-norm:srv_make_money_saldo 844 844 0 0.00 0 2005 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10022 9834 188 1.87 0 12340 20 0 188
v_all_customers 36378 36378 0 0.00 0 17 1 0 0
v_all_suppliers 30074 30074 0 0.00 0 17 1 0 0
v_all_wares 40450 40450 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 688 7 0 0
v_cancel_client_order 3462 3462 0 0.00 0 6079 4 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 4458 9 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 745 4 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 2108 6 0 0
v_cancel_supplier_invoice 820 794 26 3.17 0 9908 38 0 26
v_cancel_supplier_order 812 812 0 0.00 0 1086 4 0 0
v_cancel_write_off 1856 1856 0 0.00 0 4110 17 0 0
v_max_id_clo_ord 5373 5373 0 0.00 0 1678 2 0 0
v_max_id_clo_res 932 932 0 0.00 0 3549 13 0 0
v_max_id_ord_sup 5466 5466 0 0.00 0 4453 6 0 0
v_max_non_paid_invoice 1721 1721 0 0.00 0 191 2 0 0
v_max_non_paid_realizn 3967 3967 0 0.00 0 3665 4 0 0
v_min_id_clo_ord 5373 5373 0 0.00 0 7008 10 0 0
v_min_id_clo_res 932 932 0 0.00 0 3272 12 0 0
v_min_id_ord_sup 5466 5466 0 0.00 0 10296 11 0 0
v_min_non_paid_invoice 5919 5919 0 0.00 0 3717 12 0 0
v_min_non_paid_realizn 7003 7003 0 0.00 0 14060 20 0 0
v_our_firm 10144 10144 0 0.00 0 20 1 0 0
v_reserve_write_off 19726 19726 0 0.00 0 16943 9 0 0
x_lock_depdocs_on_canc_invoice 11309 11309 0 0.00 0 12591 120 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 2653 310 0 0
x_make_qstorno_1000_1200 5373 5373 0 0.00 1 34578 1236 0 0
x_make_qstorno_1200_2000 5472 5472 0 0.00 3 134510 3328 0 0
x_make_qstorno_2100_3300 451003 451003 0 0.00 0 37509 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-04-2022 01:39:29 to 21-04-2022 01:39:33.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:18 2 8192000000 8797969488 8808726528 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:19 20324 8192000000 9905766128 10216648704 991990752 10.801 100 87 174 0 1020813584 6179456 22643376
23:10:47 26677 8192000000 9956431904 10280579072 1031949696 11.187 100 91 182 2 1064605296 9314208 23341392
23:12:23 16822 8192000000 10004628256 10340421632 1081434832 11.661 100 87 174 0 1113976496 11377936 21163728
23:13:42 11528 8192000000 10033571024 10382057472 1119368336 12.021 100 86 172 1 1152783776 13005136 20410304
23:14:57 8174 8192000000 10085390832 10442014720 1171669424 12.512 100 78 156 0 1203264480 14191200 17403856
23:16:07 5614 8192000000 10123177328 10492387328 1211278224 12.881 100 84 168 0 1242873984 13483392 18112368
23:17:14 2207 8192000000 10165846496 10538721280 1249376960 13.232 100 92 183 0 1283818480 14183360 20258160
23:18:17 1679 8192000000 10205451744 10583105536 1280468256 13.517 100 95 194 1 1316250896 14554192 21228448
23:19:20 3695 8192000000 10198275152 10595348480 1294814752 13.648 100 62 125 0 1319112688 10435936 13862000
23:20:26 5144 8192000000 10197737120 10601635840 1304590176 13.737 100 36 72 2 1319454976 6628336 8236464
23:21:33 14776 8192000000 10199964240 10614128640 1318435696 13.863 100 12 24 1 1326716640 5361504 2919440
23:22:50 14909 8192000000 10235120592 10633605120 1321571968 13.891 100 70 142 1 1343702384 5258464 16871952
23:24:05 5620 8192000000 10213362048 10632314880 1313722016 13.820 99 17 34 2 1322932704 5551472 3659216
23:25:11 47608 8192000000 10233112656 10652987392 1335568896 14.017 100 35 70 0 1349928624 5954800 8404928
23:27:00 2788 8192000000 10237059632 10651852800 1323698080 13.910 99 30 59 1 1336692592 6650176 6344336
23:28:05 9018 8192000000 10200826096 10616680448 1292946880 13.631 98 26 52 1 1304515168 5378672 6189616
23:29:17 63073 8192000000 10143439808 10540789760 1183907024 12.627 92 31 63 1 1196545504 4530528 8107952
23:31:21 5945 8192000000 10130369760 10520981504 1198098800 12.759 96 37 75 3 1212910640 6010032 8801808
23:32:28 4408 8192000000 10151759312 10553548800 1254055968 13.275 100 27 53 1 1270968848 10639328 6273552
23:33:33 67487 8192000000 10179840144 10585923584 1272393472 13.444 99 33 66 0 1290314736 10253904 7667360
23:35:42 5705 8192000000 10189855200 10600222720 1293550560 13.637 100 31 62 2 1307216704 6475072 7191072
23:36:49 9842 8192000000 10199328992 10617040896 1311584208 13.800 100 29 58 1 1324125232 5301584 7239440
23:38:04 11557 8192000000 10221919680 10634903552 1324069312 13.914 100 23 46 0 1335005984 4856896 6079776
23:39:22 101501 8192000000 10257772992 10680168448 1348652528 14.135 100 36 72 0 1366516992 9166096 8698368
23:42:05 27366 8192000000 10300123440 10714419200 1376514544 14.385 100 48 96 1 1403639760 15980432 11144784
23:43:37 17176 8192000000 10301783216 10724679680 1386364960 14.473 100 36 72 2 1416057856 20539456 9153440
23:44:57 1966 8192000000 10311458896 10724970496 1378508304 14.403 99 61 119 0 1410084448 17186624 14389520
23:46:03 3600 8192000000 10320361776 10741706752 1396169280 14.561 100 50 101 0 1428828096 20753568 11905248
23:47:14 4994 8192000000 10332479472 10752733184 1404101088 14.631 100 58 114 0 1439248176 21469424 13677664
23:48:25 8187 8192000000 10337828112 10760175616 1410244208 14.686 100 58 117 0 1448990400 24657248 14088944
23:49:35 9449 8192000000 10334982304 10752110592 1398000352 14.577 100 62 123 2 1442769072 29412112 15356608
23:50:51 2065 8192000000 10352327776 10764718080 1408689216 14.672 100 86 170 2 1456530240 27495344 20345680
23:51:57 7626 8192000000 10329748432 10733924352 1378795504 14.406 99 81 160 2 1425032672 26022352 20214816
23:53:10 3158 8192000000 10315063840 10720137216 1360116064 14.238 99 77 154 0 1412669536 33056496 19496976
23:54:19 2411 8192000000 10329606640 10739998720 1385907872 14.469 100 80 159 2 1437215184 31475184 19832128
23:55:24 15038 8192000000 10293504896 10717126656 1363940928 14.273 99 48 96 0 1406135408 29446272 12748208
23:56:51 3344 8192000000 10312618704 10725502976 1354929280 14.192 99 61 122 0 1405645312 34948048 15767984
23:57:57 8349 8192000000 10300270208 10717016064 1349320192 14.141 99 41 82 1 1392525440 32527856 10677392
23:59:08 6387 8192000000 10307738896 10725367808 1362739280 14.262 100 49 98 1 1408184352 32323728 13121344
00:00:18 7789 8192000000 10323475856 10748248064 1382276032 14.437 100 35 70 2 1432224656 40353920 9594704
00:01:30 9058 8192000000 10340541872 10767659008 1389341024 14.500 100 43 84 1 1443456816 42911856 11203936
00:02:47 15771 8192000000 10348154304 10780823552 1396572864 14.564 100 32 64 1 1450602080 45510000 8519216
00:04:12 38066 8192000000 10338422976 10778718208 1370225280 14.329 98 25 50 3 1424319968 47186848 6907840
00:05:52 26718 8192000000 10342792528 10773610496 1373454464 14.358 100 26 52 0 1441524368 60690064 7379840
00:07:23 52443 8192000000 10322339280 10765897728 1361230768 14.248 100 22 44 0 1424180160 56805888 6143504
00:09:18 34641 8192000000 10305859216 10759524352 1364497520 14.278 99 29 58 1 1408563520 36587040 7478960
00:11:33 112217 8192000000 10294435440 10736693248 1374600672 14.368 100 19 38 1 1396665792 17528976 4536144
00:15:05 91431 8192000000 10289470960 10744418304 1389450496 14.501 100 16 28 2 1398260432 6793744 2016192
00:17:46 68333 8192000000 10298048336 10751553536 1376676288 14.387 99 24 49 0 1390830368 8484096 5669984
00:19:56 55353 8192000000 10303673616 10755756032 1388870752 14.496 99 10 20 1 1404521424 12814736 2835936
00:22:16 67186 8192000000 10334753616 10798104576 1416396752 14.741 100 16 32 0 1446221152 25374768 4449632
00:24:24 91422 8192000000 10323975296 10801631232 1386002976 14.470 98 24 48 0 1428553680 36132288 6418416
00:26:57 70088 8192000000 10326176064 10808913920 1399349792 14.589 99 12 24 0 1435763232 33505344 2908096
00:29:14 49389 8192000000 10311950400 10786816000 1402151232 14.614 100 11 22 0 1427943888 22856928 2935728
00:31:03 87409 8192000000 10304047504 10774085632 1383668736 14.449 98 13 26 1 1406138208 19434144 3035328
00:33:34 77087 8192000000 10294864976 10767093760 1336259216 14.024 95 7 14 0 1356203712 18649456 1295040
00:35:51 88956 8192000000 10293643504 10765733888 1376755728 14.388 98 7 14 0 1389137552 10321440 2060384
00:38:42 64234 8192000000 10289568240 10736467968 1306442112 13.754 94 9 18 0 1319701568 10710432 2549024
00:40:46 52137 8192000000 10282523664 10734600192 1351496384 14.161 98 18 36 1 1368257088 12765552 3995152
00:42:40 81890 8192000000 10288298368 10753335296 1361715200 14.253 98 7 14 1 1379463840 16370976 1377664
00:45:04 24152 8192000000 10305441168 10751062016 1387516736 14.484 100 38 76 1 1404646272 8071792 9057744
00:46:29 81344 8192000000 10305277280 10749161472 1350862608 14.155 97 28 56 0 1370142624 13509072 5770944
00:48:50 48986 8192000000 10298082288 10764521472 1372981936 14.354 99 21 42 0 1394563312 17279280 4302096
00:50:45 133329 8192000000 10293522080 10742251520 1291136848 13.615 93 12 24 0 1308331680 14812576 2382256
00:54:00 79760 8192000000 10271366224 10727960576 1328825088 13.957 96 29 58 0 1342657232 7126336 6705808
00:56:55 11944 8192000000 10261076592 10720014336 1365636384 14.288 100 16 32 0 1380023984 10655776 3731824
00:58:17 64632 8192000000 10264484912 10728665088 1344546944 14.098 98 8 16 0 1360535712 14076352 1912416
01:00:24 26557 8192000000 10237145248 10689130496 1321435472 13.890 98 16 33 0 1334841344 9444304 3961568
01:01:52 59353 8192000000 10240636304 10705711104 1353739728 14.181 100 7 14 0 1365412208 9914720 1757760
01:04:25 39475 8192000000 10270089488 10731016192 1352044736 14.166 99 21 42 1 1372479536 16223440 4211360
01:06:05 58585 8192000000 10261344688 10723241984 1361571552 14.251 100 14 28 0 1380202640 15251936 3379152
01:08:08 53224 8192000000 10273173600 10737393664 1372247728 14.347 99 17 34 0 1385960944 10270464 3442752
01:10:09 42893 8192000000 10309755696 10765684736 1400227296 14.597 100 23 46 0 1415469376 10246448 4995632
01:11:55 51615 8192000000 10298314480 10747547648 1380607840 14.422 99 27 53 1 1394521600 7985216 5928544
01:13:47 57670 8192000000 10303455824 10769674240 1387595216 14.484 99 12 24 0 1406128288 15906208 2626864
01:15:44 28664 8192000000 10310963904 10756358144 1367477888 14.304 98 45 91 1 1393383120 15055920 10849312
01:17:16 35667 8192000000 10298377968 10748616704 1361983536 14.255 98 16 32 0 1375488944 9769648 3735760
01:18:56 123648 8192000000 10302351792 10766036992 1367565376 14.305 98 16 30 2 1385494496 14740032 3189088
01:22:01 36111 8192000000 10295318032 10745135104 1367737648 14.307 98 32 64 1 1381488864 6956576 6794640
01:23:37 42069 8192000000 10291570160 10741846016 1377631424 14.395 100 27 54 1 1392440832 8928880 5880528
01:25:22 54282 8192000000 10265298528 10730516480 1345874048 14.110 97 10 20 0 1364702624 15827712 3000864
01:27:29 42252 8192000000 10289922080 10745376768 1367030384 14.300 99 20 40 0 1384542608 12873296 4638928
01:29:16 50008 8192000000 10261685600 10715033600 1351170672 14.158 99 17 34 1 1366648272 11006288 4471312
01:31:14 53323 8192000000 10263695392 10715709440 1321550576 13.891 96 12 24 0 1331496672 7086256 2859840
01:33:08 46176 8192000000 10272875424 10728550400 1358032928 14.220 99 35 71 0 1377734672 11395712 8306032
01:34:58 77513 8192000000 10292079840 10747584512 1356245824 14.204 98 12 24 0 1375325456 16399888 2679744
01:37:24 22286 8192000000 10284772240 10726318080 1368721728 14.316 99 29 58 0 1383593232 7988064 6883440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-04-2022 01:39:34 to 21-04-2022 01:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-04-2022 01:39:50 to 21-04-2022 01:39:52.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239711
MON$OLDEST_ACTIVE               242279
MON$OLDEST_SNAPSHOT             242279
MON$NEXT_TRANSACTION            242279
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2037664
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:1383: 
:1384: mscs-test-161.budzdorov.ru    Thu Apr 21 01:38:15 2022
:1385:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1386: 
:1387: 
:1388: mscs-test-161.budzdorov.ru    Thu Apr 21 01:38:28 2022
:1389:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1390: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

21.04.22 01:39:53. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-04-21 01:39:56 No severe PSQL-related problems occured
21.04.22 01:39:57. End of report.