Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.09.2022 07:37
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 15314144 kB
MemAvailable 30550744 kB
Buffers 0 kB
Cached 16367684 kB
SwapCached 2048 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.3
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 2022-09-30 07:37:18
DTS_END                         2022-09-30 07:37:18.8980
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(68:17)
IP                              ::1/60070
TRN_ID                          219857
ATT_ID                          592
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: 9212.66 20556 1105519
customer order: creation 168.28 430 20193
customer order: refuse 13.94 389 1673
order to supplier: creation 44.42 1275 5330
order to supplier: removal 3.60 142776 432
invoice (draft): creation 45.18 3108 5421
invoice (draft): removal 52.45 948 6294
invoice accept: apply 40.76 25251 4891
invoice accept: cancel 46.39 1117 5567
customer reserve: creation 3880.98 235 465717
customer reserve: removal 4621.93 87 554631
realization accept: apply 82.50 273 9900
realization accept: cancel 83.23 150 9988
payment from customer: creation 58.88 173 7065
payment from customer: removal 5.86 116 703
payment to supplier: creation 47.95 163 5754
payment to supplier: removal 3.71 118 445
service: total inventory turnovers 4.07 3799 488
service: total monetary turnovers 7.58 361 909
service: aggregate perf. data 0.95 14731 114
service: refresh index statistics 0.03 215625 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-09-2022 07:38:13 to 30-09-2022 07:38:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8646
TEST_TIME 2 7004
TEST_TIME 3 6756
TEST_TIME 4 6890
TEST_TIME 5 6418
TEST_TIME 6 6064
TEST_TIME 7 5963
TEST_TIME 8 6372
TEST_TIME 9 6260
TEST_TIME 10 6626
TEST_TIME 11 6754
TEST_TIME 12 6811
TEST_TIME 13 6862
TEST_TIME 14 6922
TEST_TIME 15 6996
TEST_TIME 16 7062
TEST_TIME 17 7085
TEST_TIME 18 7293
TEST_TIME 19 7738
TEST_TIME 20 7943
TEST_TIME 21 8305
TEST_TIME 22 8250
TEST_TIME 23 8186
TEST_TIME 24 8243
TEST_TIME 25 8486
TEST_TIME 26 8885
TEST_TIME 27 9023
TEST_TIME 28 9491
TEST_TIME 29 10315
TEST_TIME 30 10566
TEST_TIME 31 10440
TEST_TIME 32 10438
TEST_TIME 33 10411
TEST_TIME 34 10324
TEST_TIME 35 10306
TEST_TIME 36 10368
TEST_TIME 37 10520
TEST_TIME 38 10369
TEST_TIME 39 10348
TEST_TIME 40 10262
TEST_TIME 41 10175
TEST_TIME 42 10078
TEST_TIME 43 10050
TEST_TIME 44 10068
TEST_TIME 45 10157
TEST_TIME 46 10107
TEST_TIME 47 10117
TEST_TIME 48 9931
TEST_TIME 49 9890
TEST_TIME 50 9842
TEST_TIME 51 9755
TEST_TIME 52 9698
TEST_TIME 53 9650
TEST_TIME 54 9733
TEST_TIME 55 9751
TEST_TIME 56 9665
TEST_TIME 57 9697
TEST_TIME 58 9608
TEST_TIME 59 9487
TEST_TIME 60 9575
TEST_TIME 61 9560
TEST_TIME 62 9470
TEST_TIME 63 9559
TEST_TIME 64 9607
TEST_TIME 65 9550
TEST_TIME 66 9550
TEST_TIME 67 9555
TEST_TIME 68 9526
TEST_TIME 69 9633
TEST_TIME 70 9755
TEST_TIME 71 9724
TEST_TIME 72 9764
TEST_TIME 73 9791
TEST_TIME 74 9773
TEST_TIME 75 9790
TEST_TIME 76 9708
TEST_TIME 77 9623
TEST_TIME 78 9575
TEST_TIME 79 9633
TEST_TIME 80 9563
TEST_TIME 81 9534
TEST_TIME 82 9482
TEST_TIME 83 9434
TEST_TIME 84 9366
TEST_TIME 85 9308
TEST_TIME 86 9254
TEST_TIME 87 9326
TEST_TIME 88 9390
TEST_TIME 89 9371
TEST_TIME 90 9363
TEST_TIME 91 9342
TEST_TIME 92 9317
TEST_TIME 93 9294
TEST_TIME 94 9352
TEST_TIME 95 9431
TEST_TIME 96 9411
TEST_TIME 97 9438
TEST_TIME 98 9474
TEST_TIME 99 9434
TEST_TIME 100 9498
TEST_TIME 101 9540
TEST_TIME 102 9478
TEST_TIME 103 9402
TEST_TIME 104 9377
TEST_TIME 105 9370
TEST_TIME 106 9344
TEST_TIME 107 9315
TEST_TIME 108 9302
TEST_TIME 109 9285
TEST_TIME 110 9245
TEST_TIME 111 9210
TEST_TIME 112 9263
TEST_TIME 113 9244
TEST_TIME 114 9267
TEST_TIME 115 9236
TEST_TIME 116 9247
TEST_TIME 117 9276
TEST_TIME 118 9263
TEST_TIME 119 9292
TEST_TIME 120 9238

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-09-2022 07:38:16 to 30-09-2022 07:38:20.

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 1533513 1533513 0 0.00 0 18132 13 0 0
doc_list_biud 1533505 1533505 0 0.00 0 28738 38 0 0
sp_add_invoice_to_stock 5027 4891 136 2.70 52 249262 25252 0 136
sp_cancel_adding_invoice 5567 5567 0 0.00 7 113837 1118 0 0
sp_cancel_client_order 1673 1673 0 0.00 6 8289 389 0 0
sp_cancel_customer_reserve 554631 554631 0 0.00 1 23003 87 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 2701 116 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 3 3862 118 0 0
sp_cancel_supplier_invoice 6308 6294 14 0.22 3 28784 949 0 14
sp_cancel_supplier_order 432 432 0 0.00 18 1423238 142776 0 0
sp_cancel_write_off 9988 9988 0 0.00 2 12244 151 0 0
sp_client_order 20193 20193 0 0.00 5 20506 430 0 0
sp_customer_reserve 465717 465717 0 0.00 0 162503 235 0 0
sp_fill_shopping_cart 30926 30926 0 0.00 1 21763 72 0 0
sp_fill_shopping_cart_clo_res 465693 465693 0 0.00 0 14728 17 0 0
sp_get_clo_for_invoice 4861 4861 0 0.00 0 23909 301 0 0
sp_kill_cost_storno 16703 16703 0 0.00 0 7785 64 0 0
sp_kill_qstorno_ret_qs2qd 1125976 1125976 0 0.00 0 28736 40 0 0
sp_kill_qty_storno 593343 593343 0 0.00 0 28738 90 0 0
sp_lock_selected_doc 20455 20455 0 0.00 0 7215 7 0 0
sp_make_cost_storno 27579 27579 0 0.00 0 14599 79 0 0
sp_multiply_rows_for_pdistr 27577 27577 0 0.00 0 13283 38 0 0
sp_multiply_rows_for_qdistr 20193 20193 0 0.00 0 9970 161 0 0
sp_pay_from_customer 7065 7065 0 0.00 5 13307 174 0 0
sp_pay_to_supplier 5754 5754 0 0.00 7 14623 163 0 0
sp_payment_common 12819 12819 0 0.00 2 7918 38 0 0
sp_qd_handle_on_cancel_clo 1673 1673 0 0.00 0 8275 323 0 0
sp_qd_handle_on_invoice_upd_sts 10425 10425 0 0.00 0 16951 474 0 0
sp_qd_handle_on_reserve_upd_sts 9899 9899 0 0.00 0 25551 74 0 0
sp_reserve_write_off 9900 9900 0 0.00 9 25669 274 0 0
sp_supplier_invoice 5421 5421 0 0.00 9 94108 3108 0 0
sp_supplier_order 5330 5330 0 0.00 7 23477 1275 0 0
srv_aggregate_perf_data 115 114 1 0.86 3612 117718 14732 0 1
srv_fill_mon_cache_memory 68 68 0 0.00 739 179330 37100 0 0
srv_find_qd_qs_mism 1056453 1056453 0 0.00 0 13136 10 0 0
srv_make_invnt_saldo 488 488 0 0.00 64 93359 3799 0 0
srv_make_money_saldo 909 909 0 0.00 1 12193 362 0 0
srv_recalc_idx_stat 4 4 0 0.00 62007 307544 215626 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4894 4894 0 0.00 1 14359 788 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 1 6742 469 0 0
t$perf-norm:sp_cancel_client_order 1673 1673 0 0.00 0 5854 26 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 1145 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 1472 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 186 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 1674 38 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 95597 5366 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 1525 21 0 0
t$perf-norm:sp_client_order 20194 20194 0 0.00 0 6903 32 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 2590 15 0 0
t$perf-norm:sp_pay_from_customer 7065 7065 0 0.00 0 3488 16 0 0
t$perf-norm:sp_pay_to_supplier 5754 5754 0 0.00 0 5063 16 0 0
t$perf-norm:sp_reserve_write_off 9900 9900 0 0.00 0 6226 21 0 0
t$perf-norm:sp_supplier_invoice 5421 5421 0 0.00 0 1540 24 0 0
t$perf-norm:sp_supplier_order 5330 5330 0 0.00 0 6970 29 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 166 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 87 6 0 0
t$perf-norm:srv_make_invnt_saldo 488 488 0 0.00 0 709 13 0 0
t$perf-norm:srv_make_money_saldo 909 909 0 0.00 0 1353 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 10 148 55 0 0
v_add_invoice_to_stock 9980 9708 272 2.72 0 6841 24 0 272
v_all_customers 37022 37022 0 0.00 0 142 3 0 0
v_all_suppliers 29928 29928 0 0.00 0 86 4 0 0
v_all_wares 40376 40376 0 0.00 0 157 3 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 2706 16 0 0
v_cancel_client_order 3346 3346 0 0.00 0 4202 11 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 2647 12 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 2824 14 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 3539 17 0 0
v_cancel_supplier_invoice 798 770 28 3.50 0 2852 27 0 28
v_cancel_supplier_order 864 864 0 0.00 0 1318 10 0 0
v_cancel_write_off 1976 1976 0 0.00 0 2240 23 0 0
v_max_id_clo_ord 5326 5326 0 0.00 0 115 4 0 0
v_max_id_clo_res 960 960 0 0.00 0 2201 17 0 0
v_max_id_ord_sup 5410 5410 0 0.00 0 2835 8 0 0
v_max_non_paid_invoice 1525 1525 0 0.00 0 105 7 0 0
v_max_non_paid_realizn 3609 3609 0 0.00 0 4122 9 0 0
v_min_id_clo_ord 5326 5326 0 0.00 0 2747 10 0 0
v_min_id_clo_res 960 960 0 0.00 0 2781 14 0 0
v_min_id_ord_sup 5409 5409 0 0.00 0 5523 10 0 0
v_min_non_paid_invoice 5754 5754 0 0.00 0 4101 10 0 0
v_min_non_paid_realizn 7065 7065 0 0.00 0 4389 16 0 0
v_our_firm 10266 10266 0 0.00 0 61 4 0 0
v_reserve_write_off 19798 19798 0 0.00 0 4209 10 0 0
x_lock_depdocs_on_canc_invoice 11476 11476 0 0.00 0 21788 121 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 3775 282 0 0
x_make_qstorno_1000_1200 5330 5330 0 0.00 2 23460 1137 0 0
x_make_qstorno_1200_2000 5421 5421 0 0.00 4 94010 2900 0 0
x_make_qstorno_2100_3300 465716 465716 0 0.00 0 162492 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-09-2022 07:38:21 to 30-09-2022 07:38:25.

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
05:07:22 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:24 39429 8192000000 9722186704 10020716544 786764672 8.762 100 88 176 0 816157936 7452608 21940656
05:10:08 15928 8192000000 9755833152 10059821056 828931216 9.188 100 89 178 0 863275520 12866160 21478144
05:11:27 15642 8192000000 9800871152 10120421376 862655520 9.527 100 80 160 0 908765648 27790816 18319312
05:12:48 5072 8192000000 9859498208 10194206720 917799264 10.074 100 76 151 0 974274896 40499008 15976624
05:13:56 6880 8192000000 9917084576 10260430848 957833248 10.468 100 79 158 0 1030985952 56465520 16687184
05:15:06 2286 8192000000 9966373296 10318479360 993317568 10.814 100 93 183 0 1080623200 68048432 19257200
05:16:12 1080 8192000000 9997388960 10360520704 1026061680 11.130 100 98 190 1 1115471200 70211968 19197552
05:17:15 969 8192000000 10023229376 10393235456 1048290672 11.344 100 93 179 0 1142347424 77028992 17027760
05:18:17 1405 8192000000 10039035952 10415038464 1069600464 11.548 100 95 183 2 1158568560 69796768 19171328
05:19:19 12381 8192000000 10044737744 10423599104 1074600080 11.596 100 86 169 2 1166300352 74595408 17104864
05:20:32 13417 8192000000 10054632640 10445590528 1073211984 11.583 100 66 125 1 1173149696 87919056 12018656
05:21:47 4183 8192000000 10043128736 10433449984 1076389360 11.613 100 62 120 3 1175672880 87057088 12226432
05:22:52 1027 8192000000 10032388880 10417082368 1087673520 11.721 100 97 186 2 1154044496 47004096 19366880
05:23:53 2047 8192000000 10053790192 10446565376 1089529152 11.738 100 84 158 2 1174599808 68111264 16959392
05:24:57 1212 8192000000 10040167840 10433425408 1091555216 11.757 100 81 153 2 1163312624 56139360 15618048
05:25:59 1131 8192000000 10050545632 10440511488 1093031616 11.771 100 93 177 0 1172641568 62097280 17512672
05:27:01 949 8192000000 10067835408 10455760896 1089702576 11.740 100 96 187 2 1184385600 75917104 18765920
05:28:02 979 8192000000 10055574128 10445946880 1091426480 11.756 100 75 144 2 1173139392 67634544 14078368
05:29:05 1385 8192000000 10037760592 10426613760 1090161232 11.744 100 88 161 4 1158325632 51623872 16540528
05:30:08 1374 8192000000 10036940160 10427310080 1083080048 11.677 100 76 143 5 1158192992 60518992 14593952
05:31:11 1708 8192000000 9988836112 10378395648 1061961376 11.475 100 69 114 3 1111848944 39150000 10737568
05:32:13 1105 8192000000 9990389680 10366263296 1039249552 11.257 100 98 177 3 1110397472 53970320 17177600
05:33:15 1041 8192000000 9996358848 10371514368 1027603792 11.145 98 95 184 4 1122699232 76173536 18921904
05:34:17 4728 8192000000 10003644912 10378309632 1038574976 11.251 100 67 130 0 1117459968 66037136 12847856
05:35:22 3950 8192000000 9961481536 10341068800 1026470832 11.134 99 65 114 0 1083367856 46333168 10563856
05:36:27 1663 8192000000 9961681392 10332299264 1029273600 11.161 100 83 150 5 1081975472 38185424 14516448
05:37:31 877 8192000000 9994423744 10372845568 1047545488 11.337 100 95 173 2 1119356336 52866016 18944832
05:38:40 22401 8192000000 10035124272 10426810368 1069420288 11.547 100 58 115 0 1151469104 70447504 11601312
05:40:16 9096 8192000000 10040881504 10431569920 1086082032 11.705 100 67 120 1 1166077808 66300672 13695104
05:41:28 1346 8192000000 10060695008 10451136512 1105017424 11.885 100 86 168 0 1178600976 55011264 18572288
05:42:35 6908 8192000000 10111794112 10510757888 1119785088 12.025 100 75 145 0 1232999408 98030320 15184000
05:43:47 1591 8192000000 10178988640 10571784192 1140170976 12.217 100 90 175 1 1297594160 138686480 18736704
05:44:53 1429 8192000000 10210435728 10606923776 1148700320 12.297 100 94 173 2 1323471344 156308016 18463008
05:45:57 7863 8192000000 10222790720 10626334720 1152871712 12.336 100 69 119 2 1328944080 162347216 13725152
05:47:11 1694 8192000000 10246988144 10666926080 1169461856 12.492 100 92 175 1 1354860352 166304256 19094240
05:48:15 2647 8192000000 10275746160 10680266752 1174991552 12.543 100 89 174 0 1389839680 194607520 20240608
05:49:21 1492 8192000000 10333001456 10747437056 1184048448 12.628 100 92 178 1 1444912704 240825712 20038544
05:50:28 6692 8192000000 10364484608 10775597056 1198602736 12.763 100 81 159 0 1478170720 260649920 18918064
05:51:41 722 8192000000 10392349344 10803822592 1192682096 12.708 100 90 172 0 1497958640 285612048 19664496
05:52:46 1253 8192000000 10454163520 10864549888 1206383808 12.836 100 91 170 0 1556956064 329565856 21006400
05:53:53 4054 8192000000 10485035824 10908012544 1210708208 12.876 100 86 167 3 1591717312 361501344 19507760
05:54:59 1951 8192000000 10461135888 10908803072 1190434400 12.687 100 94 182 0 1568702880 357191296 21077184
05:56:05 1600 8192000000 10466473744 10940645376 1204088752 12.814 100 93 178 0 1572261744 345888976 22284016
05:57:09 1233 8192000000 10477981904 10909077504 1212669776 12.894 100 92 183 1 1581961824 347007056 22284992
05:58:13 4230 8192000000 10497871824 10939510784 1201876032 12.794 100 77 150 1 1602554320 382563296 18114992
05:59:20 11650 8192000000 10482434576 10918510592 1194584112 12.726 99 49 94 0 1585353168 378285904 12483152
06:00:52 5323 8192000000 10529003456 10965983232 1187025136 12.656 100 52 100 0 1637797808 437815456 12957216
06:02:00 1321 8192000000 10496547984 10933579776 1162586336 12.427 100 61 122 0 1601853040 423007728 16258976
06:03:15 14325 8192000000 10461331600 10944008192 1160191024 12.405 100 35 70 0 1568084720 398350768 9542928
06:04:32 18858 8192000000 10317956800 10834636800 1149883552 12.308 100 28 56 1 1489268048 332143824 7240672
06:05:52 22138 8192000000 10205440928 10729259008 1124533760 12.070 99 29 58 0 1345204672 213244144 7426768
06:07:31 51201 8192000000 10094862000 10542727168 1057737184 11.435 92 26 52 0 1148696896 85378480 5581232
06:09:34 67796 8192000000 10051414832 10494533632 1084808176 11.693 97 31 62 0 1132876112 40630944 7436992
06:11:44 55707 8192000000 10112234288 10572644352 1137430384 12.191 100 18 37 0 1210516032 68667936 4417712
06:13:42 107727 8192000000 10080352144 10564931584 1109370208 11.926 97 17 34 0 1169077840 56114992 3592640
06:16:35 80145 8192000000 10033499504 10466291712 1078580880 11.634 96 21 41 1 1126815328 44076912 4157536
06:18:58 2613 8192000000 10071178512 10476965888 1120598752 12.033 100 82 147 3 1159400032 23688496 15112784
06:20:01 51155 8192000000 10099783840 10542346240 1095824160 11.798 97 24 47 0 1163272496 62313360 5134976
06:22:01 104650 8192000000 10105836400 10606194688 1102879056 11.865 98 7 12 0 1192528176 88310272 1338848
06:24:46 62198 8192000000 10083521504 10513088512 1124310512 12.068 100 39 64 1 1166843712 37577104 4956096
06:26:49 179329 8192000000 10122389824 10563076096 1073874032 11.589 94 22 44 0 1172693664 94215568 4604064
06:30:56 52736 8192000000 10131315136 10654470144 1137182048 12.189 100 30 61 1 1278370576 134022608 7165920
06:33:20 68059 8192000000 10106078912 10569412608 1123123584 12.056 98 24 46 0 1186057104 58078944 4854576
06:35:28 52581 8192000000 10102312400 10574258176 1145473968 12.267 100 23 45 0 1200239888 49777840 4988080
06:37:21 46196 8192000000 10078925232 10599788544 1120638464 12.033 98 15 30 1 1198207200 74694480 2874256
06:39:09 66431 8192000000 10146068224 10603417600 1146914384 12.281 99 24 43 1 1204545200 53242624 4388192
06:42:04 36187 8192000000 10127255952 10574008320 1150121904 12.311 99 25 50 0 1196562784 41219504 5221376
06:43:50 27988 8192000000 10158809296 10623610880 1154365328 12.350 99 26 52 0 1233651920 73732368 5554224
06:45:24 45803 8192000000 10221486544 10701578240 1175788496 12.551 100 37 73 0 1319370672 135677248 7904928
06:47:14 34099 8192000000 10098972992 10540490752 1140345936 12.219 98 15 28 0 1198211536 54982912 2882688
06:48:49 25350 8192000000 10156588512 10600296448 1151944112 12.328 99 33 63 0 1232894256 74671888 6278256
06:50:15 53939 8192000000 10125560240 10582224896 1125242016 12.076 98 32 61 1 1181429184 49516592 6670576
06:52:12 39835 8192000000 10098405504 10533675008 1093582448 11.777 95 47 91 1 1155195552 52443088 9170016
06:53:52 67523 8192000000 10155526864 10597253120 1130483072 12.126 99 56 112 0 1228847712 86575328 11789312
06:56:33 51213 8192000000 10062557856 10572972032 1123572288 12.061 99 12 22 0 1175294672 49175856 2546528
06:58:24 46801 8192000000 10073530368 10544402432 1143924080 12.252 100 17 34 0 1184572704 37359104 3289520
07:00:20 66370 8192000000 10100242064 10546393088 1125008832 12.074 98 19 38 0 1184275072 53761696 5504544
07:02:27 42408 8192000000 10140339088 10607771648 1114425664 11.974 98 22 44 0 1240272096 120770976 5075456
07:04:32 71405 8192000000 10114598656 10588794880 1102296992 11.859 97 18 36 0 1227663392 121153536 4212864
07:06:44 63224 8192000000 10067786224 10514759680 1083219696 11.678 97 27 50 0 1140579600 52569232 4790672
07:08:47 10105 8192000000 10090955504 10522308608 1126976080 12.093 100 42 84 0 1188775840 53582512 8217248
07:09:58 39750 8192000000 10137821920 10571509760 1118805792 12.016 99 37 74 0 1227248816 101094688 7348336
07:11:40 45854 8192000000 10073467232 10533421056 1095706800 11.797 98 22 44 0 1158502464 58578912 4216752
07:14:12 25162 8192000000 10082242528 10509824000 1100218112 11.840 100 39 78 0 1193789360 86049776 7521472
07:16:25 46257 8192000000 10092304240 10551136256 1094842128 11.789 99 20 40 0 1159775152 60994384 3938640
07:19:37 67512 8192000000 10035101536 10478993408 1111521408 11.947 99 25 44 1 1139550496 23784656 4244432
07:21:50 53182 8192000000 10095676720 10538684416 1130412256 12.125 100 26 52 1 1191923040 55346416 6164368
07:23:44 60122 8192000000 10083417968 10551779328 1095801936 11.798 97 13 26 0 1160413440 61609744 3001760
07:25:45 58660 8192000000 10082326800 10565214208 1097037280 11.810 98 16 32 0 1179011888 78223296 3751312
07:28:51 59443 8192000000 10043252048 10490626048 1103877072 11.874 99 13 25 0 1176635168 70321504 2436592
07:30:54 68263 8192000000 10088858992 10575872000 1128583248 12.108 100 23 46 0 1185952144 52615760 4753136
07:33:09 40430 8192000000 10098612848 10554396672 1129981408 12.121 99 28 56 0 1204522896 69048384 5493104
07:34:58 33105 8192000000 10133100336 10576601088 1123817600 12.063 98 52 100 0 1202513744 68386480 10309664
07:36:38 41256 8192000000 10038007200 10464247808 1039116432 11.256 91 39 78 0 1099032448 51775232 8140784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-09-2022 07:38:27 to 30-09-2022 07:38:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 136 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-09-2022 07:38:44 to 30-09-2022 07:38:46.

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          217490
MON$OLDEST_ACTIVE               220146
MON$OLDEST_SNAPSHOT             220146
MON$NEXT_TRANSACTION            220146
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                       2060768
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2850 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2850 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2850 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2850 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 ---
:134: 
:135: mscs-test-161.budzdorov.ru    Fri Sep 30 07:37:27 2022
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:137: 
:138: 
:139: mscs-test-161.budzdorov.ru    Fri Sep 30 07:37:28 2022
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:141: 
--- end of diff output ---

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

30.09.22 07:38:47. 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
2022-09-30 07:38:49 No severe PSQL-related problems occured
30.09.22 07:38:51. End of report.