Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.11.2021 10: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 16541752 kB
MemAvailable 31518300 kB
Buffers 0 kB
Cached 15112256 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-11-28 10:37:09
DTS_END                         2021-11-28 10:37:09.0820
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(68:17)
IP                              ::1/35132
TRN_ID                          236670
ATT_ID                          671
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: 8859.22 24367 1063106
customer order: creation 172.85 417 20742
customer order: refuse 15.44 380 1853
order to supplier: creation 46.85 1401 5622
order to supplier: removal 3.28 121265 393
invoice (draft): creation 46.44 3709 5573
invoice (draft): removal 49.39 981 5927
invoice accept: apply 41.89 20924 5027
invoice accept: cancel 44.30 916 5316
customer reserve: creation 3826.63 210 459195
customer reserve: removal 4318.52 72 518222
realization accept: apply 84.18 217 10102
realization accept: cancel 78.88 132 9466
payment from customer: creation 60.15 117 7218
payment from customer: removal 5.68 105 682
payment to supplier: creation 49.98 140 5997
payment to supplier: removal 3.09 63 371
service: total inventory turnovers 4.20 3661 504
service: total monetary turnovers 6.53 558 783
service: aggregate perf. data 0.91 13925 109
service: refresh index statistics 0.03 318145 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-11-2021 10:38:25 to 28-11-2021 10:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2310
TEST_TIME 2 2245
TEST_TIME 3 2510
TEST_TIME 4 3552
TEST_TIME 5 4161
TEST_TIME 6 4212
TEST_TIME 7 4161
TEST_TIME 8 4737
TEST_TIME 9 4965
TEST_TIME 10 4956
TEST_TIME 11 5201
TEST_TIME 12 5468
TEST_TIME 13 5804
TEST_TIME 14 6081
TEST_TIME 15 6488
TEST_TIME 16 6643
TEST_TIME 17 6614
TEST_TIME 18 6616
TEST_TIME 19 7367
TEST_TIME 20 7653
TEST_TIME 21 7784
TEST_TIME 22 7931
TEST_TIME 23 8055
TEST_TIME 24 8288
TEST_TIME 25 8589
TEST_TIME 26 8982
TEST_TIME 27 9108
TEST_TIME 28 8964
TEST_TIME 29 9806
TEST_TIME 30 9796
TEST_TIME 31 10246
TEST_TIME 32 10362
TEST_TIME 33 10367
TEST_TIME 34 10212
TEST_TIME 35 10259
TEST_TIME 36 10116
TEST_TIME 37 9886
TEST_TIME 38 9676
TEST_TIME 39 9867
TEST_TIME 40 9694
TEST_TIME 41 9698
TEST_TIME 42 9667
TEST_TIME 43 9688
TEST_TIME 44 9656
TEST_TIME 45 9621
TEST_TIME 46 9607
TEST_TIME 47 9528
TEST_TIME 48 9483
TEST_TIME 49 9457
TEST_TIME 50 9547
TEST_TIME 51 9483
TEST_TIME 52 9461
TEST_TIME 53 9384
TEST_TIME 54 9403
TEST_TIME 55 9569
TEST_TIME 56 9533
TEST_TIME 57 9517
TEST_TIME 58 9502
TEST_TIME 59 9550
TEST_TIME 60 9580
TEST_TIME 61 9514
TEST_TIME 62 9650
TEST_TIME 63 9627
TEST_TIME 64 9644
TEST_TIME 65 9500
TEST_TIME 66 9481
TEST_TIME 67 9385
TEST_TIME 68 9388
TEST_TIME 69 9294
TEST_TIME 70 9239
TEST_TIME 71 9321
TEST_TIME 72 9225
TEST_TIME 73 9272
TEST_TIME 74 9253
TEST_TIME 75 9234
TEST_TIME 76 9192
TEST_TIME 77 9176
TEST_TIME 78 9186
TEST_TIME 79 9279
TEST_TIME 80 9264
TEST_TIME 81 9275
TEST_TIME 82 9302
TEST_TIME 83 9323
TEST_TIME 84 9304
TEST_TIME 85 9440
TEST_TIME 86 9410
TEST_TIME 87 9319
TEST_TIME 88 9290
TEST_TIME 89 9262
TEST_TIME 90 9237
TEST_TIME 91 9182
TEST_TIME 92 9159
TEST_TIME 93 9106
TEST_TIME 94 9064
TEST_TIME 95 9064
TEST_TIME 96 9038
TEST_TIME 97 9054
TEST_TIME 98 9016
TEST_TIME 99 9058
TEST_TIME 100 9186
TEST_TIME 101 9171
TEST_TIME 102 9156
TEST_TIME 103 9174
TEST_TIME 104 9115
TEST_TIME 105 9194
TEST_TIME 106 9195
TEST_TIME 107 9178
TEST_TIME 108 9109
TEST_TIME 109 9087
TEST_TIME 110 9069
TEST_TIME 111 9038
TEST_TIME 112 8991
TEST_TIME 113 8950
TEST_TIME 114 8943
TEST_TIME 115 8916
TEST_TIME 116 8952
TEST_TIME 117 8983
TEST_TIME 118 8951
TEST_TIME 119 8932
TEST_TIME 120 8862

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-11-2021 10:38:29 to 28-11-2021 10:38:32.

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 1487014 1487014 0 0.00 0 36824 8 0 0
doc_list_biud 1487003 1487003 0 0.00 0 50954 32 0 0
sp_add_invoice_to_stock 5138 5027 111 2.16 25 252450 20925 0 111
sp_cancel_adding_invoice 5316 5316 0 0.00 5 71537 916 0 0
sp_cancel_client_order 1853 1853 0 0.00 4 16950 381 0 0
sp_cancel_customer_reserve 518222 518222 0 0.00 1 50939 72 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 13756 105 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 3 3902 63 0 0
sp_cancel_supplier_invoice 5931 5927 4 0.06 2 50961 982 0 4
sp_cancel_supplier_order 393 393 0 0.00 8 1244602 121265 0 0
sp_cancel_write_off 9466 9466 0 0.00 1 7367 133 0 0
sp_client_order 20742 20742 0 0.00 4 21626 417 0 0
sp_customer_reserve 459195 459195 0 0.00 0 54916 210 0 0
sp_fill_shopping_cart 31936 31936 0 0.00 1 27510 43 0 0
sp_fill_shopping_cart_clo_res 459188 459188 0 0.00 0 24125 17 0 0
sp_get_clo_for_invoice 5019 5019 0 0.00 0 31334 373 0 0
sp_kill_cost_storno 15835 15835 0 0.00 0 13749 65 0 0
sp_kill_qstorno_ret_qs2qd 1052230 1052230 0 0.00 0 50953 39 0 0
sp_kill_qty_storno 556293 556293 0 0.00 0 50954 85 0 0
sp_lock_selected_doc 20989 20989 0 0.00 0 7474 5 0 0
sp_make_cost_storno 28336 28336 0 0.00 0 36822 72 0 0
sp_multiply_rows_for_pdistr 28328 28328 0 0.00 0 11990 29 0 0
sp_multiply_rows_for_qdistr 20742 20742 0 0.00 0 19147 214 0 0
sp_pay_from_customer 7218 7218 0 0.00 4 17986 117 0 0
sp_pay_to_supplier 5997 5997 0 0.00 5 21633 140 0 0
sp_payment_common 13212 13212 0 0.00 1 10421 16 0 0
sp_qd_handle_on_cancel_clo 1853 1853 0 0.00 0 16941 341 0 0
sp_qd_handle_on_invoice_upd_sts 10333 10333 0 0.00 0 21802 398 0 0
sp_qd_handle_on_reserve_upd_sts 10099 10099 0 0.00 0 24304 70 0 0
sp_reserve_write_off 10102 10102 0 0.00 7 36976 218 0 0
sp_supplier_invoice 5573 5573 0 0.00 6 113773 3710 0 0
sp_supplier_order 5622 5622 0 0.00 5 57733 1401 0 0
srv_aggregate_perf_data 109 109 0 0.00 2681 143077 13926 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1995 151038 42927 0 0
srv_find_qd_qs_mism 1014519 1014519 0 0.00 0 14368 7 0 0
srv_make_invnt_saldo 504 504 0 0.00 45 87588 3661 0 0
srv_make_money_saldo 783 783 0 0.00 1 15386 559 0 0
srv_recalc_idx_stat 4 4 0 0.00 209628 462633 318146 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5027 5027 0 0.00 0 12306 161 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 2138 72 0 0
t$perf-norm:sp_cancel_client_order 1853 1853 0 0.00 0 1553 9 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 4795 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 1118 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 991 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 1198 14 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 21693 1645 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 11926 19 0 0
t$perf-norm:sp_client_order 20742 20742 0 0.00 0 12647 19 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 12717 29 0 0
t$perf-norm:sp_pay_from_customer 7218 7218 0 0.00 0 5917 9 0 0
t$perf-norm:sp_pay_to_supplier 5997 5997 0 0.00 0 11769 11 0 0
t$perf-norm:sp_reserve_write_off 10102 10102 0 0.00 0 10593 13 0 0
t$perf-norm:sp_supplier_invoice 5573 5573 0 0.00 0 13857 28 0 0
t$perf-norm:sp_supplier_order 5622 5622 0 0.00 0 4591 20 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 540 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 88 2 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 2393 11 0 0
t$perf-norm:srv_make_money_saldo 783 783 0 0.00 0 6465 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 10256 10034 222 2.16 0 8454 19 0 222
v_all_customers 37988 37988 0 0.00 0 18 1 0 0
v_all_suppliers 31288 31288 0 0.00 0 19 1 0 0
v_all_wares 41484 41484 0 0.00 0 158 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 4880 13 0 0
v_cancel_client_order 3706 3706 0 0.00 0 9559 8 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 2603 11 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 4060 8 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 3853 10 0 0
v_cancel_supplier_invoice 872 864 8 0.91 0 4129 23 0 8
v_cancel_supplier_order 786 786 0 0.00 0 3931 11 0 0
v_cancel_write_off 2056 2056 0 0.00 0 3689 21 0 0
v_max_id_clo_ord 5622 5622 0 0.00 0 701 2 0 0
v_max_id_clo_res 981 981 0 0.00 0 2108 12 0 0
v_max_id_ord_sup 5572 5572 0 0.00 0 9029 6 0 0
v_max_non_paid_invoice 1545 1545 0 0.00 0 1850 3 0 0
v_max_non_paid_realizn 3720 3720 0 0.00 0 3106 3 0 0
v_min_id_clo_ord 5622 5622 0 0.00 0 21203 17 0 0
v_min_id_clo_res 981 981 0 0.00 0 8547 30 0 0
v_min_id_ord_sup 5572 5572 0 0.00 0 7296 8 0 0
v_min_non_paid_invoice 5995 5995 0 0.00 0 10416 11 0 0
v_min_non_paid_realizn 7217 7217 0 0.00 0 5361 10 0 0
v_our_firm 10490 10490 0 0.00 0 37 1 0 0
v_reserve_write_off 20198 20198 0 0.00 0 11936 8 0 0
x_lock_depdocs_on_canc_invoice 10811 10811 0 0.00 0 10344 120 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 6436 340 0 0
x_make_qstorno_1000_1200 5622 5622 0 0.00 2 36468 1299 0 0
x_make_qstorno_1200_2000 5573 5573 0 0.00 2 113388 3565 0 0
x_make_qstorno_2100_3300 459195 459195 0 0.00 0 46410 190 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-11-2021 10:38:33 to 28-11-2021 10:38:37.

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
08:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 36715 8192000000 9731168368 10028785664 800819888 8.905 100 94 188 0 831613952 7742160 23051904
08:09:56 24387 8192000000 9766149264 10078416896 841664144 9.316 100 86 172 0 875047488 12540224 20843120
08:11:29 21254 8192000000 9820249328 10142519296 871766816 9.618 100 74 148 0 920732096 31816720 17148560
08:12:58 19431 8192000000 9874144928 10207547392 922776848 10.123 100 75 150 0 980333360 41269056 16287456
08:14:22 13298 8192000000 9910924736 10257571840 952228864 10.413 100 66 129 1 1018893648 53560416 13104368
08:15:38 6236 8192000000 9970319072 10322616320 998144800 10.861 100 80 159 1 1075696000 61660976 15890224
08:16:46 2864 8192000000 10016865808 10380791808 1019496160 11.067 100 85 170 0 1129199360 92407632 17295568
08:17:51 1847 8192000000 10009285872 10378276864 1048325152 11.345 100 93 187 1 1124164992 56341968 19497872
08:18:55 1279 8192000000 10054109328 10424008704 1068058608 11.534 100 91 183 0 1167900400 81022704 18819088
08:19:57 4608 8192000000 10029201984 10429255680 1069060656 11.543 100 40 77 0 1146542128 69781760 7699712
08:21:04 6288 8192000000 9990850896 10391502848 1079577440 11.643 100 43 85 1 1124807424 36503968 8726016
08:22:11 11415 8192000000 10001872096 10408067072 1081609360 11.663 100 24 46 2 1108711552 21573216 5528976
08:23:23 44401 8192000000 10004039504 10399318016 1083634960 11.682 100 53 104 0 1107874592 11736256 12503376
08:25:08 6215 8192000000 10023970608 10420056064 1091669808 11.759 100 45 88 3 1114282752 13009936 9603008
08:26:15 9978 8192000000 9996949152 10409656320 1094557056 11.786 100 37 70 2 1124651792 22163680 7931056
08:27:25 6391 8192000000 10004473392 10397802496 1071796560 11.569 98 47 92 3 1107814768 25631904 10386304
08:28:34 15688 8192000000 9990762672 10402344960 1088952384 11.733 100 17 34 0 1117017088 24378432 3686272
08:29:51 7618 8192000000 9957259504 10340036608 980696608 10.691 93 33 64 1 1007631296 19982304 6952384
08:31:05 12133 8192000000 9949214800 10344513536 1005652112 10.933 98 28 56 3 1035173648 23609264 5912272
08:32:18 67007 8192000000 9937539936 10338656256 988916064 10.771 96 29 59 4 1012637536 16908400 6813072
08:34:26 6721 8192000000 9968281232 10348908544 1041046160 11.275 100 51 102 0 1075816176 23826848 10943168
08:35:35 6085 8192000000 9978585456 10365734912 1062216064 11.478 100 56 111 2 1088497264 12921136 13360064
08:36:44 7095 8192000000 9996499680 10395910144 1066892016 11.522 100 37 75 1 1101131296 26479472 7759808
08:37:56 137130 8192000000 10063548464 10476728320 1094979760 11.790 100 39 79 1 1136170128 32462528 8727840
08:41:20 12814 8192000000 10084478544 10485235712 1116255424 11.992 100 49 98 1 1200618048 72954928 11407696
08:42:46 43772 8192000000 10119871712 10530942976 1130608368 12.127 100 44 88 1 1221380400 81025872 9746160
08:44:44 22617 8192000000 10175358912 10587291648 1152064144 12.329 100 50 99 2 1279899248 115960976 11874128
08:46:22 7532 8192000000 10195419136 10610429952 1164605792 12.446 100 53 106 1 1308086704 130843264 12637648
08:47:45 3343 8192000000 10216534448 10628775936 1171774816 12.513 100 59 116 2 1323547200 138464384 13308000
08:48:55 15441 8192000000 10293525296 10705719296 1184414576 12.631 100 69 138 0 1393490096 193882720 15192800
08:50:20 6424 8192000000 10281996688 10695606272 1188853632 12.673 100 76 144 1 1383176576 178223936 16099008
08:51:31 3625 8192000000 10323288320 10737688576 1193187392 12.713 100 78 156 0 1423836096 212957616 17691088
08:52:42 3886 8192000000 10322668656 10741719040 1187592064 12.661 100 84 168 0 1423063168 216403440 19067664
08:53:53 4254 8192000000 10338365104 10747314176 1183367840 12.622 99 84 165 3 1429867936 229012816 17487280
08:55:02 4398 8192000000 10370416656 10775969792 1168998384 12.487 100 80 160 0 1465296784 278053824 18244576
08:56:13 1994 8192000000 10371995968 10781585408 1153883872 12.346 100 88 174 2 1475134128 300700512 20549744
08:57:21 3928 8192000000 10321180192 10731986944 1157567440 12.380 100 60 119 1 1420186608 246956512 15662656
08:58:27 8584 8192000000 10342462512 10769186816 1154373264 12.351 100 42 82 0 1455734064 290970816 10389984
08:59:41 2548 8192000000 10353339616 10777612288 1145011088 12.263 100 51 100 0 1453107520 296135776 11960656
09:00:52 4181 8192000000 10390193328 10808963072 1143542624 12.249 100 56 111 1 1501775952 344490848 13742480
09:01:57 6199 8192000000 10346209136 10804658176 1131462960 12.135 99 41 81 0 1460837904 319147968 10226976
09:03:08 16008 8192000000 10323682656 10776539136 1108734736 11.920 99 26 52 0 1429473648 313972240 6766672
09:04:26 18408 8192000000 10272316800 10705252352 1107419024 11.908 99 31 63 0 1365839312 251116016 7304272
09:06:31 21853 8192000000 10165757664 10647056384 1093514816 11.776 98 19 38 0 1294838928 196542896 4781216
09:07:58 16603 8192000000 10156614864 10628321280 1129474688 12.116 100 18 36 1 1277674448 144045792 4153968
09:09:51 71988 8192000000 10048627488 10488029184 1094194528 11.783 96 24 49 0 1127369360 27616368 5558464
09:12:03 55688 8192000000 10075916288 10506514432 1124525296 12.070 99 16 32 1 1162682192 34831184 3325712
09:14:02 103876 8192000000 10125510208 10551799808 1125482208 12.079 99 38 74 0 1195843120 62655104 7705808
09:17:00 62563 8192000000 10070628064 10511478784 1130876112 12.130 99 17 33 2 1160544592 25920464 3748016
09:19:04 21636 8192000000 10129942976 10562252800 1150230496 12.312 100 31 62 1 1224528912 68032480 6265936
09:20:27 37496 8192000000 10117504416 10586873856 1147974096 12.290 100 32 64 1 1213854208 57907232 7972880
09:22:11 49038 8192000000 10154941152 10614165504 1148302592 12.294 99 24 48 0 1253284816 99518848 5463376
09:24:01 43030 8192000000 10127669856 10615320576 1127400976 12.097 98 14 28 0 1208684336 78338640 2944720
09:25:45 29642 8192000000 10152928672 10609496064 1148700032 12.297 100 24 49 0 1259481664 105382304 5399328
09:27:16 62767 8192000000 10064060064 10556858368 1133904576 12.158 99 7 14 0 1166605776 31229216 1471984
09:29:40 48957 8192000000 10084333712 10541961216 1099492128 11.833 96 21 42 0 1148198016 44302128 4403760
09:31:30 81466 8192000000 10077512592 10605076480 1128962240 12.112 99 24 48 1 1204432176 70268240 5201696
09:34:01 32430 8192000000 10075169520 10498953216 1125366368 12.078 99 40 78 0 1155704160 22735280 7602512
09:35:34 20728 8192000000 10119295104 10543501312 1116728240 11.996 98 43 83 2 1190835328 65401968 8705120
09:36:55 151038 8192000000 10103187504 10569273344 1079636224 11.644 94 20 40 1 1192670656 108917024 4117408
09:40:34 25457 8192000000 10108527888 10537574400 1129892944 12.120 100 54 106 2 1204041968 62629520 11519504
09:41:59 146684 8192000000 10057603568 10513473536 1113811920 11.968 99 39 78 0 1167032080 45282224 7937936
09:45:28 52937 8192000000 10078142592 10532806656 1078744608 11.636 96 13 26 0 1157491568 76178592 2568368
09:47:24 62734 8192000000 10118660016 10567614464 1108077872 11.914 99 15 30 0 1188724448 77368672 3277904
09:49:29 43619 8192000000 10093251568 10537811968 1089210816 11.735 97 17 34 1 1175839776 83013280 3615680
09:51:13 59557 8192000000 10032802816 10507415552 1090857920 11.751 98 22 44 1 1139424176 43866656 4699600
09:53:17 30928 8192000000 10073715840 10500689920 1103126256 11.867 99 42 84 1 1156183680 44176320 8881104
09:54:51 47879 8192000000 10111054592 10543247360 1095935280 11.799 98 30 60 1 1175767408 73034640 6797488
09:56:49 52002 8192000000 10056470528 10483359744 1074901168 11.599 96 8 16 0 1138828816 62408976 1518672
09:58:47 45191 8192000000 10101397696 10577915904 1111855616 11.950 99 21 42 0 1179943664 63604272 4483776
10:00:32 24574 8192000000 10109904752 10541985792 1129354208 12.115 100 37 74 2 1187073264 50136256 7582800
10:02:04 54725 8192000000 10113479232 10642345984 1117206304 12.001 99 22 44 0 1210961616 89246320 4508992
10:04:00 106004 8192000000 10068701392 10523185152 1113242768 11.963 98 32 65 1 1172287136 52041488 7002880
10:06:48 57908 8192000000 10060163072 10536574976 1113010768 11.961 98 16 32 0 1157243856 40871264 3361824
10:08:49 43731 8192000000 10085534672 10574331904 1126750800 12.091 99 7 14 0 1184477968 56521328 1205840
10:11:31 61187 8192000000 10100171808 10544250880 1130399168 12.125 98 23 46 0 1197379312 62208960 4771184
10:13:33 53530 8192000000 10131067584 10606125056 1141474032 12.229 99 19 38 0 1233410112 87909136 4026944
10:15:27 22629 8192000000 10174105504 10606690304 1147425696 12.285 99 53 103 3 1260108432 101762048 10920688
10:16:51 58700 8192000000 10098083072 10585006080 1141718624 12.232 99 13 26 0 1229150176 84968128 2463424
10:18:56 19873 8192000000 10094377360 10535096320 1129485488 12.117 99 37 74 0 1158207376 21148944 7572944
10:20:19 84401 8192000000 10130801680 10560299008 1100600656 11.843 96 33 66 0 1168453296 60854880 6997760
10:22:49 61483 8192000000 10084302912 10529976320 1106963168 11.904 97 30 60 1 1158952848 46254992 5734688
10:24:51 13848 8192000000 10078821712 10498056192 1111535008 11.947 99 45 90 0 1165845440 44852864 9457568
10:26:32 47424 8192000000 10059848304 10515427328 1119025712 12.018 99 24 47 1 1157953856 34164720 4763424
10:28:20 63247 8192000000 10048930976 10518663168 1132440656 12.144 100 12 24 0 1161243984 26429792 2373536
10:31:08 81142 8192000000 10107547200 10580783104 1080127648 11.649 95 13 26 1 1139938976 57002688 2808640
10:33:38 72109 8192000000 10057231712 10504073216 1089815520 11.741 96 28 56 0 1144793952 48776032 6202400
10:35:51 58772 8192000000 10128952944 10567438336 1085061808 11.696 96 48 95 0 1158126864 62814672 10250384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-11-2021 10:38:39 to 28-11-2021 10:38:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-11-2021 10:38:55 to 28-11-2021 10:38:57.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          234740
MON$OLDEST_ACTIVE               237576
MON$OLDEST_SNAPSHOT             237576
MON$NEXT_TRANSACTION            237576
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-30 07:30:08.6030 GMT
MON$PAGES                       2054432
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             804
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2672 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2672 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2672 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2672 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:19: 
:20: mscs-test-161.budzdorov.ru    Sun Nov 28 10:37:28 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Sun Nov 28 10:37:32 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

28.11.21 10:38:58. There are 3 logs in /var/tmp/logs-oltp40 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-11-28 10:39:00 No severe PSQL-related problems occured
28.11.21 10:39:01. End of report.