Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.01.2022 22: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 16524840 kB
MemAvailable 31301136 kB
Buffers 0 kB
Cached 15123364 kB
SwapCached 1196 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 2022-01-02 22:38:01
DTS_END                         2022-01-02 22:38:01.2050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39486
TRN_ID                          241966
ATT_ID                          721
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: 9253.35 20001 1110402
customer order: creation 174.57 395 20948
customer order: refuse 14.42 334 1730
order to supplier: creation 47.69 1280 5723
order to supplier: removal 3.74 88562 449
invoice (draft): creation 46.15 3508 5538
invoice (draft): removal 52.14 812 6257
invoice accept: apply 42.39 17809 5087
invoice accept: cancel 46.71 735 5605
customer reserve: creation 3902.37 177 468284
customer reserve: removal 4624.00 56 554880
realization accept: apply 86.03 177 10323
realization accept: cancel 81.81 116 9817
payment from customer: creation 60.48 108 7257
payment from customer: removal 5.63 69 675
payment to supplier: creation 49.39 120 5927
payment to supplier: removal 3.38 74 406
service: total inventory turnovers 4.86 1155 583
service: total monetary turnovers 6.78 20 814
service: aggregate perf. data 0.79 15860 95
service: refresh index statistics 0.03 268646 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7179
TEST_TIME 2 5922
TEST_TIME 3 5733
TEST_TIME 4 6044
TEST_TIME 5 5676
TEST_TIME 6 6000
TEST_TIME 7 5648
TEST_TIME 8 5760
TEST_TIME 9 5742
TEST_TIME 10 5908
TEST_TIME 11 5898
TEST_TIME 12 6474
TEST_TIME 13 6321
TEST_TIME 14 6477
TEST_TIME 15 6474
TEST_TIME 16 6736
TEST_TIME 17 6829
TEST_TIME 18 7410
TEST_TIME 19 8129
TEST_TIME 20 8024
TEST_TIME 21 8137
TEST_TIME 22 8159
TEST_TIME 23 8589
TEST_TIME 24 8997
TEST_TIME 25 9176
TEST_TIME 26 9463
TEST_TIME 27 10460
TEST_TIME 28 10619
TEST_TIME 29 10664
TEST_TIME 30 10443
TEST_TIME 31 10293
TEST_TIME 32 10183
TEST_TIME 33 10261
TEST_TIME 34 10049
TEST_TIME 35 10134
TEST_TIME 36 10089
TEST_TIME 37 9996
TEST_TIME 38 9815
TEST_TIME 39 9787
TEST_TIME 40 9987
TEST_TIME 41 9893
TEST_TIME 42 9900
TEST_TIME 43 9782
TEST_TIME 44 9758
TEST_TIME 45 9604
TEST_TIME 46 9681
TEST_TIME 47 9655
TEST_TIME 48 9704
TEST_TIME 49 9696
TEST_TIME 50 9632
TEST_TIME 51 9652
TEST_TIME 52 9742
TEST_TIME 53 9789
TEST_TIME 54 9712
TEST_TIME 55 9729
TEST_TIME 56 9870
TEST_TIME 57 9927
TEST_TIME 58 9914
TEST_TIME 59 9885
TEST_TIME 60 9779
TEST_TIME 61 9680
TEST_TIME 62 9610
TEST_TIME 63 9550
TEST_TIME 64 9534
TEST_TIME 65 9498
TEST_TIME 66 9525
TEST_TIME 67 9539
TEST_TIME 68 9536
TEST_TIME 69 9512
TEST_TIME 70 9534
TEST_TIME 71 9554
TEST_TIME 72 9574
TEST_TIME 73 9611
TEST_TIME 74 9528
TEST_TIME 75 9600
TEST_TIME 76 9687
TEST_TIME 77 9669
TEST_TIME 78 9671
TEST_TIME 79 9697
TEST_TIME 80 9665
TEST_TIME 81 9633
TEST_TIME 82 9559
TEST_TIME 83 9528
TEST_TIME 84 9527
TEST_TIME 85 9482
TEST_TIME 86 9481
TEST_TIME 87 9499
TEST_TIME 88 9481
TEST_TIME 89 9398
TEST_TIME 90 9352
TEST_TIME 91 9299
TEST_TIME 92 9323
TEST_TIME 93 9298
TEST_TIME 94 9279
TEST_TIME 95 9308
TEST_TIME 96 9284
TEST_TIME 97 9322
TEST_TIME 98 9318
TEST_TIME 99 9385
TEST_TIME 100 9400
TEST_TIME 101 9454
TEST_TIME 102 9466
TEST_TIME 103 9434
TEST_TIME 104 9466
TEST_TIME 105 9504
TEST_TIME 106 9464
TEST_TIME 107 9403
TEST_TIME 108 9387
TEST_TIME 109 9376
TEST_TIME 110 9356
TEST_TIME 111 9308
TEST_TIME 112 9278
TEST_TIME 113 9265
TEST_TIME 114 9304
TEST_TIME 115 9295
TEST_TIME 116 9329
TEST_TIME 117 9312
TEST_TIME 118 9348
TEST_TIME 119 9336
TEST_TIME 120 9258

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-01-2022 22:39:07 to 02-01-2022 22:39:11.

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 1545615 1545615 0 0.00 0 29051 6 0 0
doc_list_biud 1545614 1545614 0 0.00 0 37151 26 0 0
sp_add_invoice_to_stock 5209 5087 122 2.34 32 218484 17810 0 122
sp_cancel_adding_invoice 5605 5605 0 0.00 6 90796 736 0 0
sp_cancel_client_order 1730 1730 0 0.00 4 10831 334 0 0
sp_cancel_customer_reserve 554880 554880 0 0.00 1 37157 56 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 2 5050 69 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 2 3578 75 0 0
sp_cancel_supplier_invoice 6266 6257 9 0.14 2 11554 812 0 9
sp_cancel_supplier_order 449 449 0 0.00 5 1033355 88562 0 0
sp_cancel_write_off 9817 9817 0 0.00 1 10677 116 0 0
sp_client_order 20948 20948 0 0.00 3 57138 396 0 0
sp_customer_reserve 468284 468284 0 0.00 0 56486 178 0 0
sp_fill_shopping_cart 32197 32197 0 0.00 1 18680 39 0 0
sp_fill_shopping_cart_clo_res 468278 468278 0 0.00 0 13948 15 0 0
sp_get_clo_for_invoice 5081 5081 0 0.00 0 25334 317 0 0
sp_kill_cost_storno 16503 16503 0 0.00 0 6760 53 0 0
sp_kill_qstorno_ret_qs2qd 1126283 1126283 0 0.00 0 37140 31 0 0
sp_kill_qty_storno 594141 594141 0 0.00 0 37151 67 0 0
sp_lock_selected_doc 21094 21094 0 0.00 0 9739 6 0 0
sp_make_cost_storno 28588 28588 0 0.00 0 12962 51 0 0
sp_multiply_rows_for_pdistr 28587 28587 0 0.00 0 6144 26 0 0
sp_multiply_rows_for_qdistr 20948 20948 0 0.00 0 57128 202 0 0
sp_pay_from_customer 7257 7257 0 0.00 4 12151 108 0 0
sp_pay_to_supplier 5927 5927 0 0.00 5 12974 120 0 0
sp_payment_common 13184 13184 0 0.00 1 9580 20 0 0
sp_qd_handle_on_cancel_clo 1730 1730 0 0.00 0 10785 305 0 0
sp_qd_handle_on_invoice_upd_sts 10685 10685 0 0.00 0 36369 308 0 0
sp_qd_handle_on_reserve_upd_sts 10323 10323 0 0.00 0 12689 62 0 0
sp_reserve_write_off 10323 10323 0 0.00 6 14571 177 0 0
sp_supplier_invoice 5538 5538 0 0.00 6 98351 3509 0 0
sp_supplier_order 5723 5723 0 0.00 5 48680 1281 0 0
srv_aggregate_perf_data 95 95 0 0.00 2568 103315 15860 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1639 147582 48095 0 0
srv_find_qd_qs_mism 1062754 1062754 0 0.00 0 29049 5 0 0
srv_make_invnt_saldo 583 583 0 0.00 31 113213 1155 0 0
srv_make_money_saldo 814 814 0 0.00 1 1353 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 147777 437041 268646 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5087 5087 0 0.00 0 9414 154 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 4547 96 0 0
t$perf-norm:sp_cancel_client_order 1730 1730 0 0.00 0 1506 10 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 1537 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 2759 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 1408 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 6100 33 0 0
t$perf-norm:sp_cancel_supplier_order 449 449 0 0.00 0 17790 1284 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 3933 12 0 0
t$perf-norm:sp_client_order 20948 20948 0 0.00 0 5294 17 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 1174 5 0 0
t$perf-norm:sp_pay_from_customer 7257 7257 0 0.00 0 5642 11 0 0
t$perf-norm:sp_pay_to_supplier 5927 5927 0 0.00 0 6304 11 0 0
t$perf-norm:sp_reserve_write_off 10323 10323 0 0.00 0 6442 12 0 0
t$perf-norm:sp_supplier_invoice 5538 5538 0 0.00 0 4284 16 0 0
t$perf-norm:sp_supplier_order 5723 5723 0 0.00 0 7456 21 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 2067 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 241 4 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 2803 24 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 2149 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10402 10158 244 2.34 0 10679 19 0 244
v_all_customers 37722 37722 0 0.00 0 18 0 0 0
v_all_suppliers 31034 31034 0 0.00 0 121 1 0 0
v_all_wares 41892 41892 0 0.00 0 131 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 1264 10 0 0
v_cancel_client_order 3460 3460 0 0.00 0 6190 6 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 5045 8 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 244 3 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 729 4 0 0
v_cancel_supplier_invoice 812 794 18 2.21 0 525 13 0 18
v_cancel_supplier_order 898 898 0 0.00 0 4226 9 0 0
v_cancel_write_off 1910 1910 0 0.00 0 691 11 0 0
v_max_id_clo_ord 5722 5722 0 0.00 0 2421 2 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 3986 18 0 0
v_max_id_ord_sup 5529 5529 0 0.00 0 4259 5 0 0
v_max_non_paid_invoice 1661 1661 0 0.00 0 358 2 0 0
v_max_non_paid_realizn 4031 4031 0 0.00 0 6697 4 0 0
v_min_id_clo_ord 5722 5722 0 0.00 0 14352 20 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 1880 4 0 0
v_min_id_ord_sup 5529 5529 0 0.00 0 5611 8 0 0
v_min_non_paid_invoice 5927 5927 0 0.00 0 5015 10 0 0
v_min_non_paid_realizn 7257 7257 0 0.00 0 6685 14 0 0
v_our_firm 10622 10622 0 0.00 0 18 1 0 0
v_reserve_write_off 20644 20644 0 0.00 0 14227 9 0 0
x_lock_depdocs_on_canc_invoice 11465 11465 0 0.00 0 19174 104 0 0
x_lock_depdocs_on_canc_sup_ord 449 449 0 0.00 0 18446 362 0 0
x_make_qstorno_1000_1200 5723 5723 0 0.00 1 48630 1188 0 0
x_make_qstorno_1200_2000 5538 5538 0 0.00 2 95283 3379 0 0
x_make_qstorno_2100_3300 468284 468284 0 0.00 0 47933 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2022 22:39:12 to 02-01-2022 22:39:16.

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:06 29516 8192000000 9914665488 10229805056 992146144 10.802 100 86 172 0 1019981744 5914240 21921360
20:10:38 25424 8192000000 9953809792 10277613568 1034280416 11.210 100 93 186 0 1066748752 8726528 23741808
20:12:11 16199 8192000000 9977470048 10312617984 1062520656 11.481 100 85 170 0 1094017024 10376496 21119872
20:13:33 12213 8192000000 10036436864 10388193280 1123550112 12.061 100 81 162 0 1153904032 11145472 19208448
20:14:48 8609 8192000000 10086076768 10449305600 1178861712 12.580 100 75 150 0 1207943568 12173600 16908256
20:15:58 5802 8192000000 10133176976 10503282688 1222449824 12.984 100 84 167 2 1254112064 12905824 18756416
20:17:05 11494 8192000000 10161245728 10541150208 1253950608 13.275 100 79 157 0 1284933312 14287744 16694960
20:18:21 1530 8192000000 10192135040 10575552512 1281680192 13.528 100 82 164 2 1311473600 11947600 17845808
20:19:24 6586 8192000000 10203517120 10600853504 1297909472 13.676 100 70 140 0 1326637888 13050672 15677744
20:20:47 6459 8192000000 10192818256 10605932544 1309871152 13.785 100 35 69 1 1330323472 12471696 7980624
20:21:55 6307 8192000000 10206908944 10620166144 1321695456 13.892 100 28 56 0 1334691200 6300512 6695232
20:23:05 3294 8192000000 10222764528 10632507392 1326645232 13.937 100 49 98 0 1346911792 9481520 10785040
20:24:12 52897 8192000000 10219478144 10645762048 1334657376 14.009 100 20 40 0 1344582912 5130160 4795376
20:26:05 9117 8192000000 10233310144 10643959808 1313702944 13.820 98 20 40 0 1323544928 5313632 4528352
20:27:15 7642 8192000000 10225084352 10644750336 1324520128 13.918 99 22 44 3 1335215632 5534736 5160768
20:28:26 11173 8192000000 10181107936 10580561920 1226347184 13.020 93 21 42 1 1240672784 8878784 5446816
20:29:40 28931 8192000000 10176899232 10586595328 1241960256 13.164 96 24 46 3 1253127632 5659792 5507584
20:31:15 5809 8192000000 10146973840 10547695616 1245004512 13.192 99 32 64 1 1258934480 6538656 7391312
20:32:22 8382 8192000000 10137919776 10535784448 1220739968 12.969 98 23 46 1 1230777200 4705328 5331904
20:33:31 60983 8192000000 10169422080 10583560192 1259954608 13.330 98 39 78 0 1278701232 10423952 8322672
20:35:35 8503 8192000000 10183807840 10599772160 1296313360 13.662 100 24 48 1 1307206176 5177504 5715312
20:36:44 9465 8192000000 10189117472 10610135040 1304898112 13.740 100 19 38 2 1314738896 5511088 4329696
20:37:56 10496 8192000000 10210135088 10622050304 1315485648 13.836 100 21 42 0 1325593280 5236768 4870864
20:39:12 68032 8192000000 10259615200 10671828992 1348765008 14.136 100 36 72 0 1363458080 6817792 7875280
20:41:25 16324 8192000000 10265666736 10681688064 1344416960 14.097 99 38 76 1 1366051872 11816672 9818240
20:42:46 9878 8192000000 10293665408 10707722240 1374980288 14.372 100 56 111 3 1400169808 12563968 12625552
20:44:01 14828 8192000000 10297337216 10720874496 1383279424 14.446 100 44 87 0 1409551024 15971888 10299712
20:45:29 23652 8192000000 10310850400 10736484352 1396467808 14.564 100 52 104 1 1425898688 17107296 12323584
20:46:56 11931 8192000000 10324805120 10749124608 1402359600 14.616 100 48 96 1 1430854256 16891248 11603408
20:48:11 7710 8192000000 10341170656 10760929280 1406716064 14.655 100 54 108 2 1443676112 24000928 12959120
20:49:24 12739 8192000000 10309301040 10729795584 1375169184 14.373 98 56 112 0 1414626704 25156368 14301152
20:50:49 7105 8192000000 10321636176 10736160768 1387369808 14.482 100 71 140 2 1435347040 31039376 16937856
20:52:02 6195 8192000000 10331345136 10746490880 1394532608 14.546 100 67 132 1 1440997632 30233136 16231888
20:53:23 3903 8192000000 10358164896 10775281664 1408518448 14.671 100 74 146 3 1463396848 37158896 17719504
20:54:30 4560 8192000000 10353921984 10765860864 1392424464 14.527 100 74 148 1 1458283472 46380880 19478128
20:55:40 3422 8192000000 10344853088 10752151552 1372378576 14.348 99 76 150 1 1438707824 47513424 18815824
20:56:50 1637 8192000000 10354626112 10757492736 1383026720 14.444 100 92 182 1 1456782016 50280544 23474752
20:57:54 12020 8192000000 10326881136 10748137472 1383556336 14.448 100 43 86 2 1431280480 36429296 11294848
20:59:11 13453 8192000000 10308993488 10731937792 1363117696 14.265 99 49 98 0 1420761520 44463072 13180752
21:00:32 8124 8192000000 10301035072 10720468992 1343085712 14.085 99 42 84 1 1404086816 49273344 11727760
21:01:42 22703 8192000000 10295964992 10726928384 1351846048 14.164 100 26 52 2 1403692656 44090240 7756368
21:03:06 28290 8192000000 10263699104 10702262272 1328270400 13.952 99 21 42 0 1372169008 37821344 6077264
21:04:51 14626 8192000000 10287124816 10713346048 1325925296 13.930 99 27 54 1 1372939312 39971568 7042448
21:06:06 61212 8192000000 10249964736 10699055104 1314379136 13.826 98 16 32 0 1343626832 24636704 4610992
21:08:25 60014 8192000000 10276872336 10708066304 1347450128 14.125 99 43 86 0 1368635536 11379744 9805664
21:10:48 82294 8192000000 10276756288 10713239552 1354401088 14.187 99 22 43 0 1367637424 8231216 5005120
21:13:16 60108 8192000000 10291007120 10729345024 1364730288 14.280 99 38 76 1 1382599888 8963520 8906080
21:15:18 79429 8192000000 10289723072 10739589120 1390272944 14.508 100 20 40 1 1403785200 8716032 4796224
21:17:39 35702 8192000000 10307497296 10759438336 1400791232 14.602 100 35 70 2 1423660864 13930720 8938912
21:19:33 75376 8192000000 10301269472 10770632704 1398093792 14.578 100 10 21 0 1417779424 17243632 2442000
21:23:01 64660 8192000000 10311962992 10775941120 1407196896 14.659 100 30 60 0 1430725648 16313248 7215504
21:25:07 46015 8192000000 10300850000 10757545984 1387499120 14.484 99 19 38 0 1403059616 10417840 5142656
21:26:54 51007 8192000000 10305546560 10773622784 1375939792 14.380 98 6 12 0 1389146960 11627104 1580064
21:28:51 73555 8192000000 10286864912 10750263296 1342246128 14.078 96 14 28 0 1361128944 15677840 3204976
21:31:11 52304 8192000000 10287420096 10735915008 1353568944 14.180 98 12 24 0 1366838960 10530416 2739600
21:33:06 45575 8192000000 10294787504 10738024448 1361919648 14.255 99 30 59 1 1384622400 15683744 7019008
21:34:55 48991 8192000000 10279793904 10730770432 1341984096 14.075 98 34 68 0 1360015600 10623152 7408352
21:36:44 42651 8192000000 10277939504 10718187520 1340995424 14.066 97 31 62 0 1360191952 12659360 6537168
21:38:39 115225 8192000000 10286744912 10737459200 1368794992 14.316 99 33 64 2 1388479792 12422528 7262272
21:41:35 70978 8192000000 10282546416 10754400256 1373980256 14.363 99 8 16 0 1396182944 20537568 1665120
21:43:46 31835 8192000000 10284538592 10744541184 1384032336 14.453 100 15 31 0 1398603808 10492448 4079024
21:45:18 66023 8192000000 10278642704 10740051968 1360623584 14.243 98 13 26 0 1373083504 9463024 2996896
21:47:25 83415 8192000000 10270972496 10712616960 1320203952 13.879 96 28 56 0 1337578992 10128768 7246272
21:49:56 107139 8192000000 10271519872 10727088128 1326384208 13.934 97 16 32 1 1341400432 11358368 3657856
21:52:44 59034 8192000000 10279634656 10727743488 1313872624 13.821 95 31 62 1 1334630448 13393248 7364576
21:54:49 91704 8192000000 10264706800 10713628672 1304856208 13.739 95 11 22 0 1319556400 12285104 2415088
21:57:29 72225 8192000000 10262808208 10717212672 1331681104 13.982 98 22 44 2 1356527664 19856512 4990048
22:00:12 61060 8192000000 10257017936 10700279808 1317057312 13.850 97 17 32 1 1330374800 9726656 3590832
22:02:13 38842 8192000000 10244073792 10700693504 1338084064 14.040 99 17 34 2 1349465632 7267984 4113584
22:04:45 47091 8192000000 10266371824 10725371904 1355916832 14.201 99 11 22 0 1371014800 12920624 2177344
22:06:35 65882 8192000000 10260752768 10721411072 1367365408 14.303 100 13 26 0 1378049552 6456256 4227888
22:08:51 65249 8192000000 10276131632 10735493120 1373158928 14.355 100 10 20 0 1381896400 6744304 1993168
22:10:56 43939 8192000000 10273449504 10737065984 1370166624 14.329 99 13 26 0 1383006608 10148784 2691200
22:12:42 69752 8192000000 10280057488 10738315264 1341902624 14.075 97 24 48 1 1360598160 13284512 5411024
22:14:55 41689 8192000000 10299520800 10742571008 1366578896 14.296 99 29 57 0 1384245776 10932112 6734768
22:16:48 49662 8192000000 10291990736 10745991168 1345486368 14.107 97 25 49 0 1367160432 15396336 6277728
22:18:38 70123 8192000000 10293275120 10762678272 1387376128 14.482 100 15 30 0 1405466112 14730656 3359328
22:20:51 93861 8192000000 10276012608 10735808512 1288874064 13.594 93 17 34 0 1307209904 14895952 3439888
22:23:30 147582 8192000000 10272730112 10719555584 1314000480 13.822 96 13 26 0 1327243344 9836128 3406736
22:27:01 47083 8192000000 10273667424 10731036672 1367541488 14.305 100 32 64 0 1384057824 9898080 6618256
22:29:04 65559 8192000000 10267576448 10727751680 1357467728 14.215 99 7 14 1 1366876736 7818592 1590416
22:31:09 76233 8192000000 10256382432 10729103360 1330543136 13.972 98 9 18 0 1352393200 19678688 2171376
22:33:28 83417 8192000000 10234909424 10683731968 1305513904 13.745 96 12 24 0 1318855712 10753712 2588096
22:35:52 64306 8192000000 10224739904 10676486144 1251613712 13.253 93 19 38 1 1264399216 8620736 4164768
22:37:57 20511 8192000000 8993335408 9191567360 159587264 1.910 13 3 4 1 164010928 3890688 532976

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-01-2022 22:39:18 to 02-01-2022 22:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-01-2022 22:39:33 to 02-01-2022 22:39:35.

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          243318
MON$OLDEST_ACTIVE               243319
MON$OLDEST_SNAPSHOT             243319
MON$NEXT_TRANSACTION            243319
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                       2027616
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.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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'

02.01.22 22:39:36. 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-01-02 22:39:39 No severe PSQL-related problems occured
02.01.22 22:39:40. End of report.