Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.12.2021 22:39
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 16736376 kB
MemAvailable 31458308 kB
Buffers 0 kB
Cached 14964800 kB
SwapCached 0 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.9
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 2021-12-15 22:38:00
DTS_END                         2021-12-15 22:38:00.4580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39880
TRN_ID                          247421
ATT_ID                          700
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: 9126.96 20365 1095235
customer order: creation 185.27 340 22232
customer order: refuse 15.86 344 1903
order to supplier: creation 48.65 1293 5838
order to supplier: removal 3.44 97385 413
invoice (draft): creation 49.08 3354 5889
invoice (draft): removal 50.22 795 6026
invoice accept: apply 44.78 18123 5374
invoice accept: cancel 44.75 906 5370
customer reserve: creation 4026.28 184 483154
customer reserve: removal 4354.30 61 522516
realization accept: apply 88.82 177 10658
realization accept: cancel 77.06 110 9247
payment from customer: creation 63.23 117 7588
payment from customer: removal 6.00 73 720
payment to supplier: creation 52.41 122 6289
payment to supplier: removal 3.61 96 433
service: total inventory turnovers 5.27 929 632
service: total monetary turnovers 7.03 39 843
service: aggregate perf. data 0.88 18596 106
service: refresh index statistics 0.03 264246 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-12-2021 22:39:23 to 15-12-2021 22:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7920
TEST_TIME 2 8321
TEST_TIME 3 7153
TEST_TIME 4 7144
TEST_TIME 5 7080
TEST_TIME 6 6625
TEST_TIME 7 6733
TEST_TIME 8 6890
TEST_TIME 9 6919
TEST_TIME 10 7266
TEST_TIME 11 7150
TEST_TIME 12 7463
TEST_TIME 13 7900
TEST_TIME 14 7873
TEST_TIME 15 7985
TEST_TIME 16 7907
TEST_TIME 17 7938
TEST_TIME 18 8216
TEST_TIME 19 8507
TEST_TIME 20 8682
TEST_TIME 21 8628
TEST_TIME 22 9102
TEST_TIME 23 9088
TEST_TIME 24 8948
TEST_TIME 25 9022
TEST_TIME 26 9655
TEST_TIME 27 10403
TEST_TIME 28 10984
TEST_TIME 29 10943
TEST_TIME 30 10747
TEST_TIME 31 10934
TEST_TIME 32 10609
TEST_TIME 33 10471
TEST_TIME 34 10570
TEST_TIME 35 10568
TEST_TIME 36 10396
TEST_TIME 37 10329
TEST_TIME 38 10220
TEST_TIME 39 10219
TEST_TIME 40 10214
TEST_TIME 41 10194
TEST_TIME 42 10094
TEST_TIME 43 10076
TEST_TIME 44 9969
TEST_TIME 45 9976
TEST_TIME 46 10004
TEST_TIME 47 9845
TEST_TIME 48 9718
TEST_TIME 49 9907
TEST_TIME 50 9824
TEST_TIME 51 9788
TEST_TIME 52 9728
TEST_TIME 53 9772
TEST_TIME 54 9747
TEST_TIME 55 9808
TEST_TIME 56 9818
TEST_TIME 57 9787
TEST_TIME 58 9741
TEST_TIME 59 9858
TEST_TIME 60 9811
TEST_TIME 61 9813
TEST_TIME 62 9816
TEST_TIME 63 9772
TEST_TIME 64 9647
TEST_TIME 65 9687
TEST_TIME 66 9591
TEST_TIME 67 9518
TEST_TIME 68 9543
TEST_TIME 69 9478
TEST_TIME 70 9411
TEST_TIME 71 9451
TEST_TIME 72 9420
TEST_TIME 73 9384
TEST_TIME 74 9416
TEST_TIME 75 9415
TEST_TIME 76 9412
TEST_TIME 77 9409
TEST_TIME 78 9495
TEST_TIME 79 9492
TEST_TIME 80 9525
TEST_TIME 81 9479
TEST_TIME 82 9478
TEST_TIME 83 9604
TEST_TIME 84 9573
TEST_TIME 85 9578
TEST_TIME 86 9516
TEST_TIME 87 9462
TEST_TIME 88 9465
TEST_TIME 89 9401
TEST_TIME 90 9430
TEST_TIME 91 9363
TEST_TIME 92 9305
TEST_TIME 93 9318
TEST_TIME 94 9344
TEST_TIME 95 9389
TEST_TIME 96 9363
TEST_TIME 97 9343
TEST_TIME 98 9382
TEST_TIME 99 9478
TEST_TIME 100 9485
TEST_TIME 101 9473
TEST_TIME 102 9465
TEST_TIME 103 9552
TEST_TIME 104 9528
TEST_TIME 105 9540
TEST_TIME 106 9520
TEST_TIME 107 9550
TEST_TIME 108 9497
TEST_TIME 109 9439
TEST_TIME 110 9402
TEST_TIME 111 9395
TEST_TIME 112 9388
TEST_TIME 113 9342
TEST_TIME 114 9298
TEST_TIME 115 9292
TEST_TIME 116 9251
TEST_TIME 117 9247
TEST_TIME 118 9239
TEST_TIME 119 9208
TEST_TIME 120 9131

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-12-2021 22:39:26 to 15-12-2021 22:39:30.

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 1540965 1540965 0 0.00 0 21721 7 0 0
doc_list_biud 1540965 1540965 0 0.00 0 68673 27 0 0
sp_add_invoice_to_stock 5489 5374 115 2.09 32 182370 18123 0 115
sp_cancel_adding_invoice 5370 5370 0 0.00 5 79578 906 0 0
sp_cancel_client_order 1903 1903 0 0.00 4 12407 344 0 0
sp_cancel_customer_reserve 522516 522516 0 0.00 1 68674 62 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 2 5342 74 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 5280 97 0 0
sp_cancel_supplier_invoice 6029 6026 3 0.04 2 19827 795 0 3
sp_cancel_supplier_order 413 413 0 0.00 6 912623 97386 0 0
sp_cancel_write_off 9247 9247 0 0.00 1 17629 111 0 0
sp_client_order 22232 22232 0 0.00 3 29753 341 0 0
sp_customer_reserve 483154 483154 0 0.00 0 40179 185 0 0
sp_fill_shopping_cart 33959 33959 0 0.00 1 22408 36 0 0
sp_fill_shopping_cart_clo_res 483147 483147 0 0.00 0 12622 13 0 0
sp_get_clo_for_invoice 5362 5362 0 0.00 0 31674 277 0 0
sp_kill_cost_storno 15770 15770 0 0.00 0 21709 57 0 0
sp_kill_qstorno_ret_qs2qd 1060718 1060718 0 0.00 0 68660 33 0 0
sp_kill_qty_storno 561494 561494 0 0.00 0 68673 72 0 0
sp_lock_selected_doc 22151 22151 0 0.00 0 8172 7 0 0
sp_make_cost_storno 29896 29896 0 0.00 0 19347 51 0 0
sp_multiply_rows_for_pdistr 29896 29896 0 0.00 0 12730 30 0 0
sp_multiply_rows_for_qdistr 22232 22232 0 0.00 0 10773 155 0 0
sp_pay_from_customer 7588 7588 0 0.00 4 23190 118 0 0
sp_pay_to_supplier 6289 6289 0 0.00 5 16651 123 0 0
sp_payment_common 13877 13877 0 0.00 1 9344 30 0 0
sp_qd_handle_on_cancel_clo 1903 1903 0 0.00 0 12402 314 0 0
sp_qd_handle_on_invoice_upd_sts 10731 10731 0 0.00 0 18372 318 0 0
sp_qd_handle_on_reserve_upd_sts 10658 10658 0 0.00 0 18195 57 0 0
sp_reserve_write_off 10658 10658 0 0.00 6 18229 178 0 0
sp_supplier_invoice 5889 5889 0 0.00 5 93868 3354 0 0
sp_supplier_order 5838 5838 0 0.00 5 33646 1294 0 0
srv_aggregate_perf_data 106 106 0 0.00 3029 157049 18597 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1243 138356 42382 0 0
srv_find_qd_qs_mism 1043718 1043718 0 0.00 0 19816 6 0 0
srv_make_invnt_saldo 632 632 0 0.00 19 22618 930 0 0
srv_make_money_saldo 843 843 0 0.00 1 11007 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 142909 425469 264246 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5374 5374 0 0.00 0 10041 167 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 0 9892 129 0 0
t$perf-norm:sp_cancel_client_order 1903 1903 0 0.00 0 5020 19 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 2043 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 3341 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 3660 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 4654 24 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 24344 1499 0 0
t$perf-norm:sp_cancel_write_off 1080 1080 0 0.00 0 4194 10 0 0
t$perf-norm:sp_client_order 22232 22232 0 0.00 0 10967 19 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 5569 13 0 0
t$perf-norm:sp_pay_from_customer 7588 7588 0 0.00 0 3762 10 0 0
t$perf-norm:sp_pay_to_supplier 6289 6289 0 0.00 0 4765 11 0 0
t$perf-norm:sp_reserve_write_off 10658 10658 0 0.00 0 7915 11 0 0
t$perf-norm:sp_supplier_invoice 5889 5889 0 0.00 0 5920 24 0 0
t$perf-norm:sp_supplier_order 5838 5838 0 0.00 0 8132 17 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 321 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 9 1 0 0
t$perf-norm:srv_make_invnt_saldo 632 632 0 0.00 0 2121 28 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 1011 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10952 10722 230 2.10 0 5478 16 0 230
v_all_customers 40346 40346 0 0.00 0 91 1 0 0
v_all_suppliers 32370 32370 0 0.00 0 22 1 0 0
v_all_wares 44464 44464 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 356 7 0 0
v_cancel_client_order 3806 3806 0 0.00 0 1427 3 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 2369 7 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 0 1397 4 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 3190 10 0 0
v_cancel_supplier_invoice 848 842 6 0.70 0 929 14 0 6
v_cancel_supplier_order 826 826 0 0.00 0 3060 8 0 0
v_cancel_write_off 2160 2160 0 0.00 0 1274 12 0 0
v_max_id_clo_ord 5838 5838 0 0.00 0 536 1 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 7471 16 0 0
v_max_id_ord_sup 5889 5889 0 0.00 0 5014 4 0 0
v_max_non_paid_invoice 1831 1831 0 0.00 0 3191 4 0 0
v_max_non_paid_realizn 4045 4045 0 0.00 0 9340 7 0 0
v_min_id_clo_ord 5838 5838 0 0.00 0 3766 7 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 7735 17 0 0
v_min_id_ord_sup 5889 5889 0 0.00 0 6293 11 0 0
v_min_non_paid_invoice 6289 6289 0 0.00 0 6955 18 0 0
v_min_non_paid_realizn 7588 7588 0 0.00 0 8295 20 0 0
v_our_firm 11204 11204 0 0.00 0 18 1 0 0
v_reserve_write_off 21316 21316 0 0.00 0 3759 5 0 0
x_lock_depdocs_on_canc_invoice 10975 10975 0 0.00 0 13377 128 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 16391 402 0 0
x_make_qstorno_1000_1200 5838 5838 0 0.00 2 33639 1211 0 0
x_make_qstorno_1200_2000 5889 5889 0 0.00 3 92159 3234 0 0
x_make_qstorno_2100_3300 483154 483154 0 0.00 0 34843 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2021 22:39:31 to 15-12-2021 22:39:35.

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
20:08:04 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:05 33865 8192000000 9892437168 10203860992 971952736 10.606 100 85 170 0 1001920624 6703520 23264368
20:10:47 15726 8192000000 9943794240 10267992064 1030080832 11.169 100 93 186 0 1063759808 8988016 24690960
20:12:06 12146 8192000000 9981724240 10314797056 1060826960 11.464 100 78 155 0 1089853248 9771872 19254416
20:13:30 9838 8192000000 10031922352 10377003008 1115077392 11.980 100 83 162 1 1145221392 11283184 18860816
20:14:43 6197 8192000000 10072014528 10430939136 1163517504 12.436 100 72 142 2 1191704688 12003264 16183920
20:15:52 4780 8192000000 10111418384 10483576832 1204998880 12.823 100 69 138 0 1231248704 10488304 15761520
20:16:59 6249 8192000000 10152708400 10531393536 1245670160 13.198 100 74 148 0 1275119440 13331408 16117872
20:18:07 1757 8192000000 10197850656 10578935808 1282167200 13.533 100 84 168 1 1313673744 12861888 18644656
20:19:10 2137 8192000000 10215024720 10598473728 1300397712 13.699 100 89 178 2 1333845664 13144784 20303168
20:20:13 6514 8192000000 10199942800 10605293568 1309084480 13.778 100 33 66 1 1323390928 6656512 7649936
20:21:21 13628 8192000000 10197902880 10615558144 1317345216 13.853 100 16 32 1 1326676896 5016432 4315248
20:22:36 64243 8192000000 10225836640 10640359424 1329708736 13.965 100 40 80 2 1345206464 5025936 10471792
20:24:45 4638 8192000000 10230495104 10649706496 1337004400 14.030 100 44 87 3 1354700944 7493744 10202800
20:25:50 6820 8192000000 10232000448 10654490624 1340849280 14.065 100 27 54 2 1353113088 6094272 6169536
20:27:05 24009 8192000000 10188993856 10602205184 1271100880 13.432 96 29 58 2 1283219552 5644192 6474480
20:28:59 6216 8192000000 10207655376 10619117568 1288637216 13.592 98 30 61 2 1302255376 7180096 6438064
20:30:06 11876 8192000000 10135621920 10535051264 1158803600 12.392 91 25 48 1 1173897024 9079488 6013936
20:31:19 9264 8192000000 10146315280 10543050752 1225575280 13.013 98 24 47 2 1240004688 9553696 4875712
20:32:31 57745 8192000000 10157667664 10555531264 1216375024 12.928 96 21 42 0 1226230048 5149536 4705488
20:34:31 7463 8192000000 10184130112 10590060544 1286582992 13.573 100 33 66 2 1301446768 7263600 7600176
20:35:41 9593 8192000000 10188143824 10603499520 1302055424 13.714 100 27 54 3 1314524016 5541888 6926704
20:36:53 82963 8192000000 10217791952 10623385600 1312986512 13.813 100 42 82 4 1327639504 5080608 9572384
20:39:22 6723 8192000000 10250321024 10659229696 1342589536 14.081 100 50 100 0 1363595584 10055824 10950224
20:40:44 11432 8192000000 10262215648 10680385536 1357846160 14.218 100 45 90 1 1378381680 9041872 11493648
20:41:58 15677 8192000000 10285938864 10704957440 1372124544 14.346 100 51 102 0 1397060480 13090320 11845616
20:43:19 28670 8192000000 10290025056 10722119680 1386422208 14.474 100 39 78 1 1412150192 16345168 9382816
20:44:52 9665 8192000000 10303810496 10726932480 1387229824 14.481 100 40 80 0 1414251904 17712608 9309472
20:46:06 3024 8192000000 10309701904 10727510016 1386482016 14.474 100 66 132 3 1421526864 19738192 15306656
20:47:13 8808 8192000000 10302784912 10723332096 1368604992 14.315 99 66 128 1 1406080128 21991696 15483440
20:48:28 6896 8192000000 10313629792 10732199936 1378818960 14.406 99 60 120 0 1413826832 20724496 14283376
20:49:40 5764 8192000000 10327234048 10754244608 1398742272 14.584 100 46 92 2 1435966752 26498752 10725728
20:50:49 2375 8192000000 10342612768 10750861312 1375646704 14.378 99 91 182 3 1430314064 32466496 22200864
20:51:54 2551 8192000000 10328282576 10729963520 1376505504 14.385 100 86 173 0 1427128656 29924880 20698272
20:53:02 2701 8192000000 10306055696 10713665536 1353992080 14.183 99 68 136 0 1402087952 31364016 16731856
20:54:10 3105 8192000000 10310022640 10715279360 1353464160 14.179 99 72 142 1 1404134320 33072256 17597904
20:55:21 3969 8192000000 10342903984 10750640128 1388752544 14.495 100 66 131 2 1446492528 41313280 16426704
20:56:29 4162 8192000000 10350124384 10752954368 1385585472 14.466 100 76 150 1 1447401968 42975456 18841040
20:57:38 1774 8192000000 10346123392 10744569856 1377532800 14.394 98 87 175 1 1441394752 42549536 21312416
20:58:46 1237 8192000000 10382629744 10787594240 1407337136 14.660 100 87 172 2 1478769536 49316960 22115440
20:59:51 13459 8192000000 10357506912 10786312192 1404542640 14.635 100 40 80 1 1463050960 47238864 11269456
21:01:09 11125 8192000000 10330163312 10751762432 1372652608 14.351 98 45 91 3 1424047680 39270064 12125008
21:02:31 19958 8192000000 10326029472 10756124672 1358984512 14.228 98 38 76 1 1413910368 44263888 10661968
21:03:57 9241 8192000000 10318658800 10760925184 1369186352 14.320 100 25 50 0 1431110688 54247504 7676832
21:05:22 27569 8192000000 10285106176 10725036032 1328156544 13.950 98 12 24 1 1370516352 38419504 3940304
21:06:50 47099 8192000000 10294306240 10730848256 1359890752 14.236 99 14 28 0 1379007264 16036304 3080208
21:08:41 92625 8192000000 10290273680 10758144000 1389020672 14.497 100 15 30 1 1405350848 12737008 3593168
21:11:53 62387 8192000000 10300080752 10746458112 1341499776 14.071 96 36 72 0 1365446208 15563456 8382976
21:14:03 37272 8192000000 10271821744 10716430336 1348449744 14.134 97 10 19 0 1362457072 12246928 1760400
21:15:42 42769 8192000000 10303645296 10753486848 1384172688 14.454 99 20 40 0 1402595632 13075616 5347328
21:17:25 23258 8192000000 10312060192 10762825728 1396314320 14.562 100 36 72 0 1419469536 14974592 8180624
21:18:51 70491 8192000000 10303684528 10763952128 1395057456 14.551 100 15 30 1 1416647344 17884256 3705632
21:21:03 45447 8192000000 10286952688 10750099456 1372140608 14.346 99 11 22 0 1387221072 11986320 3094144
21:22:49 58180 8192000000 10297147472 10760593408 1379975856 14.416 99 19 38 1 1398189264 13150544 5062864
21:25:01 54903 8192000000 10261877808 10715328512 1330909072 13.975 96 23 44 1 1351565072 15632240 5023760
21:27:23 46402 8192000000 10285064640 10766630912 1383590912 14.449 100 7 14 1 1401410000 16329056 1490032
21:29:38 21631 8192000000 10309322944 10757963776 1397074000 14.569 100 35 70 0 1411325440 6679392 7572048
21:31:09 72376 8192000000 10311961552 10768453632 1384487936 14.457 99 17 34 0 1401102528 12658528 3956064
21:33:21 42469 8192000000 10302910800 10737414144 1365377920 14.286 99 37 74 1 1387988608 13718768 8891920
21:35:04 35038 8192000000 10280912368 10728890368 1340760544 14.064 97 24 48 0 1356021376 10129552 5131280
21:36:43 18186 8192000000 10293314160 10728222720 1344131104 14.095 98 43 86 1 1368119776 15001040 8987632
21:38:03 98158 8192000000 10305810528 10763988992 1369292240 14.321 98 13 27 0 1387185376 15149536 2743600
21:40:56 16437 8192000000 10279341728 10709852160 1330465072 13.971 98 47 93 0 1350816208 9784848 10566288
21:42:14 78124 8192000000 10267689680 10710786048 1335755328 14.019 98 35 70 1 1353045824 8754752 8535744
21:44:36 36670 8192000000 10268892208 10716921856 1355919792 14.201 99 34 68 2 1371592960 7528112 8145056
21:46:20 41495 8192000000 10264483760 10726289408 1370587856 14.332 100 19 37 0 1384025984 9392000 4046128
21:48:02 61411 8192000000 10267164272 10718130176 1361795936 14.253 99 14 28 1 1376533728 11433904 3303888
21:50:05 58523 8192000000 10269395200 10744692736 1358000464 14.219 99 4 8 0 1371488016 12494864 992688
21:52:03 65984 8192000000 10272973104 10727661568 1363706816 14.271 99 20 40 0 1377311024 9040816 4563392
21:54:11 28011 8192000000 10288506656 10732896256 1355788720 14.200 99 30 60 0 1376014064 12915248 7310096
21:56:03 45071 8192000000 10281686720 10746753024 1343505376 14.089 98 15 30 2 1360991200 14381200 3104624
21:57:51 42414 8192000000 10265024480 10718916608 1300603568 13.701 95 24 46 2 1314912864 8973680 5335616
21:59:34 113327 8192000000 10299789616 10755973120 1359118784 14.229 99 24 48 1 1379545456 14835904 5590768
22:02:28 30823 8192000000 10298524272 10734071808 1370523632 14.332 100 26 52 1 1382898224 6546592 5828000
22:04:00 99368 8192000000 10287069536 10733178880 1364489632 14.278 99 42 83 2 1382194416 7761056 9943728
22:06:42 55204 8192000000 10278595056 10731925504 1351572912 14.162 98 28 54 1 1368126112 10515632 6037568
22:08:46 46454 8192000000 10259600256 10730098688 1335513104 14.017 97 9 18 0 1345116992 7797792 1806096
22:11:05 68135 8192000000 10263367200 10736193536 1354030592 14.184 98 7 14 0 1366146896 10307520 1808784
22:13:21 66516 8192000000 10285069680 10733826048 1314581744 13.828 95 19 38 0 1331246800 12016112 4648944
22:15:58 43573 8192000000 10291699056 10748706816 1352416576 14.169 98 17 34 0 1377244672 20500864 4327232
22:17:44 79178 8192000000 10298353520 10758422528 1382034720 14.435 100 22 44 0 1404728640 17825328 4868592
22:20:17 44223 8192000000 10298867984 10754981888 1359974832 14.237 98 27 53 1 1384353824 17976816 6402176
22:22:02 138356 8192000000 10264246032 10709405696 1259971152 13.330 92 13 26 1 1276350976 14116160 2263664
22:25:21 79321 8192000000 10277061072 10715725824 1324523680 13.918 97 24 47 0 1340471408 9098896 6848832
22:27:41 97354 8192000000 10258527424 10715017216 1315911648 13.840 96 25 51 0 1332286304 10299408 6075248
22:30:19 38662 8192000000 10253477408 10717999104 1347011648 14.121 99 7 14 0 1355928304 7144656 1772000
22:32:29 74045 8192000000 10256092288 10722017280 1355232688 14.195 99 15 30 1 1368543920 9896400 3414832
22:34:45 87074 8192000000 10254002288 10722779136 1358669744 14.225 100 8 16 0 1367628256 7309984 1648528
22:37:13 52189 8192000000 9343132480 9640226816 482690896 5.564 35 8 16 0 492790592 8304528 1795168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-12-2021 22:39:37 to 15-12-2021 22:39:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 15-12-2021 22:39:53 to 15-12-2021 22:39:54.

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          247420
MON$OLDEST_ACTIVE               249067
MON$OLDEST_SNAPSHOT             249067
MON$NEXT_TRANSACTION            249067
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                       2020928
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33544 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33544 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33544 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33544 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 ---
--- end of diff output ---

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

15.12.21 22:39:55. 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
2021-12-15 22:39:58 No severe PSQL-related problems occured
15.12.21 22:39:59. End of report.