Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.03.2023 10: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 15021544 kB
MemAvailable 30410056 kB
Buffers 0 kB
Cached 16640156 kB
SwapCached 3028 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 2023-03-09 10:37:49
DTS_END                         2023-03-09 10:37:26.9720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35894
TRN_ID                          221560
ATT_ID                          259
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: 8849.63 29079 1061955
customer order: creation 166.23 468 19947
customer order: refuse 14.88 392 1786
order to supplier: creation 44.29 1386 5315
order to supplier: removal 3.39 163555 407
invoice (draft): creation 45.03 3316 5404
invoice (draft): removal 49.70 997 5964
invoice accept: apply 40.01 25364 4801
invoice accept: cancel 45.14 1151 5417
customer reserve: creation 3744.67 239 449360
customer reserve: removal 4411.84 98 529421
realization accept: apply 82.08 269 9850
realization accept: cancel 76.32 201 9158
payment from customer: creation 57.26 170 6871
payment from customer: removal 5.84 117 701
payment to supplier: creation 47.31 178 5677
payment to supplier: removal 3.49 146 419
service: total inventory turnovers 3.74 4118 449
service: total monetary turnovers 7.44 378 893
service: aggregate perf. data 0.92 18565 110
service: refresh index statistics 0.04 360475 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-03-2023 10:38:22 to 09-03-2023 10:38:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5658
TEST_TIME 2 4733
TEST_TIME 3 6085
TEST_TIME 4 5682
TEST_TIME 5 5985
TEST_TIME 6 5603
TEST_TIME 7 5515
TEST_TIME 8 5524
TEST_TIME 9 5664
TEST_TIME 10 5923
TEST_TIME 11 5805
TEST_TIME 12 6041
TEST_TIME 13 6336
TEST_TIME 14 6544
TEST_TIME 15 6692
TEST_TIME 16 7116
TEST_TIME 17 7226
TEST_TIME 18 7237
TEST_TIME 19 7267
TEST_TIME 20 7511
TEST_TIME 21 7631
TEST_TIME 22 7740
TEST_TIME 23 7864
TEST_TIME 24 7900
TEST_TIME 25 8531
TEST_TIME 26 8772
TEST_TIME 27 8769
TEST_TIME 28 9365
TEST_TIME 29 9937
TEST_TIME 30 10500
TEST_TIME 31 11096
TEST_TIME 32 11125
TEST_TIME 33 11021
TEST_TIME 34 10754
TEST_TIME 35 10716
TEST_TIME 36 10534
TEST_TIME 37 10336
TEST_TIME 38 10379
TEST_TIME 39 10370
TEST_TIME 40 10233
TEST_TIME 41 10154
TEST_TIME 42 10032
TEST_TIME 43 9960
TEST_TIME 44 9823
TEST_TIME 45 9712
TEST_TIME 46 9619
TEST_TIME 47 9726
TEST_TIME 48 9952
TEST_TIME 49 9904
TEST_TIME 50 9862
TEST_TIME 51 9813
TEST_TIME 52 9806
TEST_TIME 53 9624
TEST_TIME 54 9507
TEST_TIME 55 9372
TEST_TIME 56 9309
TEST_TIME 57 9337
TEST_TIME 58 9287
TEST_TIME 59 9295
TEST_TIME 60 9299
TEST_TIME 61 9293
TEST_TIME 62 9231
TEST_TIME 63 9169
TEST_TIME 64 9100
TEST_TIME 65 9154
TEST_TIME 66 9121
TEST_TIME 67 9137
TEST_TIME 68 9200
TEST_TIME 69 9168
TEST_TIME 70 9154
TEST_TIME 71 9170
TEST_TIME 72 9221
TEST_TIME 73 9220
TEST_TIME 74 9255
TEST_TIME 75 9249
TEST_TIME 76 9208
TEST_TIME 77 9189
TEST_TIME 78 9251
TEST_TIME 79 9143
TEST_TIME 80 9079
TEST_TIME 81 9029
TEST_TIME 82 9047
TEST_TIME 83 9034
TEST_TIME 84 8983
TEST_TIME 85 8965
TEST_TIME 86 8913
TEST_TIME 87 8867
TEST_TIME 88 8855
TEST_TIME 89 8835
TEST_TIME 90 8817
TEST_TIME 91 8802
TEST_TIME 92 8831
TEST_TIME 93 8856
TEST_TIME 94 8906
TEST_TIME 95 8908
TEST_TIME 96 9040
TEST_TIME 97 9007
TEST_TIME 98 8978
TEST_TIME 99 9134
TEST_TIME 100 9095
TEST_TIME 101 9010
TEST_TIME 102 9020
TEST_TIME 103 9014
TEST_TIME 104 8951
TEST_TIME 105 8903
TEST_TIME 106 8870
TEST_TIME 107 8871
TEST_TIME 108 8828
TEST_TIME 109 8796
TEST_TIME 110 8760
TEST_TIME 111 8767
TEST_TIME 112 8782
TEST_TIME 113 8769
TEST_TIME 114 8827
TEST_TIME 115 8814
TEST_TIME 116 8851
TEST_TIME 117 8835
TEST_TIME 118 8851
TEST_TIME 119 8940
TEST_TIME 120 8874

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-03-2023 10:38:26 to 09-03-2023 10:38:30.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1477188 1477188 0 0.00 0 19648 13 0 0
doc_list_biud 1477188 1477188 0 0.00 0 45181 41 0 0
sp_add_invoice_to_stock 4933 4801 132 2.67 28 196880 25365 0 132
sp_cancel_adding_invoice 5417 5417 0 0.00 7 74570 1151 0 0
sp_cancel_client_order 1786 1786 0 0.00 7 8563 392 0 0
sp_cancel_customer_reserve 529421 529421 0 0.00 1 25839 98 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 3 9198 118 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 4 17937 146 0 0
sp_cancel_supplier_invoice 5976 5964 12 0.20 3 37970 998 0 12
sp_cancel_supplier_order 407 407 0 0.00 8 1569923 163556 0 0
sp_cancel_write_off 9158 9158 0 0.00 2 36125 201 0 0
sp_client_order 19947 19947 0 0.00 5 37139 469 0 0
sp_customer_reserve 449360 449360 0 0.00 0 134132 240 0 0
sp_fill_shopping_cart 30659 30659 0 0.00 1 17571 90 0 0
sp_fill_shopping_cart_clo_res 449346 449346 0 0.00 0 13567 18 0 0
sp_get_clo_for_invoice 4761 4761 0 0.00 0 15497 349 0 0
sp_kill_cost_storno 15695 15695 0 0.00 0 17908 73 0 0
sp_kill_qstorno_ret_qs2qd 1074371 1074371 0 0.00 0 37955 43 0 0
sp_kill_qty_storno 566760 566760 0 0.00 0 45179 99 0 0
sp_lock_selected_doc 20394 20394 0 0.00 0 10009 8 0 0
sp_make_cost_storno 27155 27155 0 0.00 0 17398 77 0 0
sp_multiply_rows_for_pdistr 27155 27155 0 0.00 0 17844 37 0 0
sp_multiply_rows_for_qdistr 19947 19947 0 0.00 0 18981 201 0 0
sp_pay_from_customer 6871 6871 0 0.00 5 18070 171 0 0
sp_pay_to_supplier 5677 5677 0 0.00 5 17016 178 0 0
sp_payment_common 12548 12548 0 0.00 2 17910 48 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 1 8554 326 0 0
sp_qd_handle_on_invoice_upd_sts 10174 10174 0 0.00 0 45178 516 0 0
sp_qd_handle_on_reserve_upd_sts 9850 9850 0 0.00 0 19957 69 0 0
sp_reserve_write_off 9850 9850 0 0.00 8 19973 269 0 0
sp_supplier_invoice 5404 5404 0 0.00 7 98114 3316 0 0
sp_supplier_order 5315 5315 0 0.00 8 40778 1386 0 0
srv_aggregate_perf_data 111 110 1 0.90 2431 209881 18565 0 1
srv_fill_mon_cache_memory 68 68 0 0.00 862 151390 39629 0 0
srv_find_qd_qs_mism 1015148 1015148 0 0.00 0 13819 11 0 0
srv_make_invnt_saldo 449 449 0 0.00 58 140634 4118 0 0
srv_make_money_saldo 893 893 0 0.00 1 14623 379 0 0
srv_recalc_idx_stat 5 5 0 0.00 95969 566978 360476 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 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 4802 4802 0 0.00 0 16971 853 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 1 4793 420 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 3051 24 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 15265 49 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 1488 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 4950 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1077 32 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 106686 6757 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 2768 21 0 0
t$perf-norm:sp_client_order 19948 19948 0 0.00 0 14350 36 0 0
t$perf-norm:sp_customer_reserve 1003 1003 0 0.00 0 13640 33 0 0
t$perf-norm:sp_pay_from_customer 6871 6871 0 0.00 0 10795 21 0 0
t$perf-norm:sp_pay_to_supplier 5677 5677 0 0.00 0 8838 21 0 0
t$perf-norm:sp_reserve_write_off 9851 9851 0 0.00 0 17296 24 0 0
t$perf-norm:sp_supplier_invoice 5404 5404 0 0.00 0 2897 27 0 0
t$perf-norm:sp_supplier_order 5315 5315 0 0.00 0 13205 34 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 371 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 75 5 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 690 15 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 493 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 115 32 0 0
v_add_invoice_to_stock 9778 9514 264 2.69 0 6929 26 0 264
v_all_customers 36520 36520 0 0.00 0 69 4 0 0
v_all_suppliers 29460 29460 0 0.00 0 172 4 0 0
v_all_wares 39894 39894 0 0.00 0 80 4 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 1541 14 0 0
v_cancel_client_order 3572 3572 0 0.00 0 1076 9 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 1306 12 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 945 10 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 708 10 0 0
v_cancel_supplier_invoice 804 780 24 2.98 0 347 20 0 24
v_cancel_supplier_order 814 814 0 0.00 0 913 11 0 0
v_cancel_write_off 1992 1992 0 0.00 0 952 24 0 0
v_max_id_clo_ord 5315 5315 0 0.00 0 495 5 0 0
v_max_id_clo_res 1003 1003 0 0.00 0 2762 16 0 0
v_max_id_ord_sup 5397 5397 0 0.00 0 7501 8 0 0
v_max_non_paid_invoice 1659 1659 0 0.00 0 5870 14 0 0
v_max_non_paid_realizn 3620 3620 0 0.00 0 1659 7 0 0
v_min_id_clo_ord 5315 5315 0 0.00 0 2478 8 0 0
v_min_id_clo_res 1003 1003 0 0.00 0 4456 16 0 0
v_min_id_ord_sup 5397 5397 0 0.00 0 4763 11 0 0
v_min_non_paid_invoice 5677 5677 0 0.00 0 7872 14 0 0
v_min_non_paid_realizn 6871 6871 0 0.00 0 17297 22 0 0
v_our_firm 9876 9876 0 0.00 0 96 4 0 0
v_reserve_write_off 19700 19700 0 0.00 0 3643 9 0 0
x_lock_depdocs_on_canc_invoice 10991 10991 0 0.00 0 14236 118 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 21571 376 0 0
x_make_qstorno_1000_1200 5315 5315 0 0.00 3 38241 1238 0 0
x_make_qstorno_1200_2000 5404 5404 0 0.00 3 95809 3033 0 0
x_make_qstorno_2100_3300 449360 449360 0 0.00 0 134120 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-03-2023 10:38:31 to 09-03-2023 10:38:35.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:30 3 8192000000 8799530800 8811241472 5201728 0.063 3 1 2 0 5301152 63648 35776
08:08:31 20006 8192000000 9731588224 10036629504 816673232 9.065 100 82 164 0 843827248 7597744 19556272
08:09:55 27656 8192000000 9778867872 10089594880 849079424 9.391 100 92 182 0 883905056 12871440 21954192
08:11:34 14311 8192000000 9820049184 10141122560 878486096 9.685 100 85 170 0 922944192 24917344 19540752
08:12:55 7537 8192000000 9877196544 10212655104 917241696 10.069 100 81 162 0 992121248 56796256 18083296
08:14:10 3454 8192000000 9933274272 10279202816 964875088 10.537 100 89 177 0 1049107472 65490832 18741552
08:15:17 2088 8192000000 9963205760 10319020032 999113264 10.870 100 90 170 0 1082953104 67608512 16231328
08:16:23 929 8192000000 9999547088 10363547648 1028972304 11.159 100 98 189 3 1120870752 72958576 18939872
08:17:26 838 8192000000 10038908768 10412580864 1047654976 11.338 100 97 190 1 1160667296 93608512 19403808
08:18:28 842 8192000000 10054134752 10430738432 1065074944 11.505 100 97 189 2 1171440128 87204048 19161136
08:19:29 1186 8192000000 10031011360 10412195840 1064383728 11.498 100 92 173 0 1153599088 70982896 18232464
08:20:31 5184 8192000000 10006669392 10394710016 1069404000 11.546 100 71 125 0 1135079760 53037376 12638384
08:21:37 2822 8192000000 10041368992 10427416576 1072457808 11.576 100 85 162 2 1161199936 71984704 16757424
08:22:40 1253 8192000000 10050175664 10432311296 1078677696 11.635 100 98 187 2 1170904192 73681472 18545024
08:23:43 1137 8192000000 10058293984 10446917632 1088930656 11.732 100 93 170 1 1182126304 74622960 18572688
08:24:45 924 8192000000 10041009232 10428780544 1089909984 11.742 100 98 184 3 1164817936 55918432 18989520
08:25:47 1145 8192000000 10032609120 10419744768 1079943776 11.647 100 93 175 2 1157739680 57765456 20030448
08:26:49 1109 8192000000 10062952944 10453782528 1086244672 11.707 100 94 187 0 1184632272 79114896 19272704
08:27:51 1063 8192000000 10062348976 10448773120 1087607136 11.720 100 93 180 1 1186468416 81062048 17799232
08:28:53 926 8192000000 10061149536 10450792448 1087746304 11.721 100 84 157 2 1185637952 80407856 17483792
08:29:57 1260 8192000000 10073697520 10458529792 1081367264 11.660 100 96 179 0 1196461264 97229808 17864192
08:30:59 1367 8192000000 10039576608 10423590912 1069072704 11.543 99 80 153 1 1165457376 80164384 16220288
08:32:02 9918 8192000000 9996615808 10384629760 1055265200 11.411 99 56 107 1 1113022240 46420000 11337040
08:33:13 1436 8192000000 9983827136 10357542912 1039402112 11.259 100 72 128 2 1101998560 50117088 12479360
08:34:15 1044 8192000000 9981820432 10355818496 1036553504 11.232 99 92 171 3 1104933888 52073184 16307200
08:35:17 989 8192000000 9986094688 10352459776 1021893216 11.090 99 90 171 4 1109298000 70057680 17347104
08:36:19 1934 8192000000 10002638592 10381627392 1031800032 11.186 100 74 146 0 1114353168 67633312 14919824
08:37:21 1031 8192000000 9996169136 10369241088 1055086400 11.409 100 90 174 0 1118205936 44068992 19050544
08:38:31 25155 8192000000 10013523600 10403631104 1071050992 11.562 99 55 106 0 1122291744 40778416 10462336
08:40:01 1917 8192000000 10082999456 10472325120 1090495328 11.747 100 79 150 2 1212184240 104289296 17399616
08:41:05 1363 8192000000 10089557072 10470957056 1103956112 11.875 100 86 162 5 1199401296 77942288 17502896
08:42:12 6724 8192000000 10143491360 10537029632 1119612608 12.023 100 76 146 0 1253322352 119396560 14313184
08:43:21 7299 8192000000 10173607504 10567409664 1132932608 12.149 100 71 140 1 1284780384 136475040 15372736
08:44:31 8312 8192000000 10211008864 10610200576 1126864112 12.092 99 61 117 0 1319647536 180272800 12510624
08:45:41 1104 8192000000 10242532592 10634633216 1143211344 12.246 100 94 177 0 1358946272 194334528 21400400
08:46:47 4783 8192000000 10254542672 10664001536 1146900400 12.280 100 75 142 0 1375364176 212877216 15586560
08:47:54 7926 8192000000 10304275088 10715668480 1159228960 12.396 100 68 129 1 1419170240 245241136 14700144
08:49:09 1822 8192000000 10322872912 10740387840 1157436736 12.379 99 85 166 0 1436127712 259535072 19155904
08:50:15 1206 8192000000 10356956976 10772357120 1168385552 12.482 100 94 181 2 1473256704 282630400 22240752
08:51:18 1166 8192000000 10389327744 10792431616 1169379728 12.491 100 93 177 0 1498530368 309276464 19874176
08:52:23 1526 8192000000 10419604016 10841677824 1185307424 12.640 100 96 179 0 1532074144 323909184 22857536
08:53:28 1099 8192000000 10415973712 10864103424 1188976512 12.674 100 91 177 1 1522558272 312774944 20806816
08:54:31 861 8192000000 10455473872 10857410560 1185324464 12.640 100 94 178 2 1557790992 352047824 20418704
08:55:35 1006 8192000000 10495453664 10904825856 1192856224 12.710 100 90 176 1 1597933600 384741872 20335504
08:56:39 1365 8192000000 10528512704 10952523776 1185575920 12.642 100 97 186 0 1633552784 426502496 21474368
08:57:45 958 8192000000 10490038528 10948608000 1187381248 12.659 100 99 187 2 1594841440 384651248 22808944
08:58:48 1114 8192000000 10529271728 10976452608 1182166784 12.610 100 91 179 1 1631387184 427102224 22118176
08:59:55 1981 8192000000 10559165872 11004084224 1182315648 12.612 99 80 158 2 1665061424 462681888 20063888
09:01:01 2890 8192000000 10583872944 11015720960 1197128912 12.750 100 70 140 1 1691205536 476439568 17637056
09:02:07 1940 8192000000 10560636656 11046244352 1191460512 12.697 100 87 167 0 1662111968 450157232 20494224
09:03:10 22986 8192000000 10533971248 10990559232 1172057872 12.516 99 50 98 0 1634073424 448667152 13348400
09:04:36 17579 8192000000 10500495632 10992406528 1173440496 12.529 100 41 82 0 1607734656 423088720 11205440
09:05:56 9000 8192000000 10396387616 10926428160 1163904704 12.440 100 36 72 0 1516760160 343518480 9336976
09:07:08 31005 8192000000 10239476192 10730659840 1149952800 12.309 100 12 24 0 1368392256 215553600 2885856
09:09:29 57023 8192000000 10116817648 10558013440 1130391952 12.125 98 20 39 0 1177671872 43369248 3910672
09:11:27 18364 8192000000 10091036592 10519920640 1136889968 12.186 99 56 106 2 1184912256 37634880 10387408
09:12:46 55606 8192000000 10071994464 10525749248 1069344704 11.546 94 13 25 0 1130973808 59493840 2135264
09:14:54 91284 8192000000 10070832016 10529742848 1109708608 11.930 99 18 35 1 1166100416 53007472 3384336
09:17:26 46413 8192000000 10061058176 10503966720 1122849152 12.054 99 32 62 0 1155444800 27098368 5497280
09:19:15 75670 8192000000 10084871552 10536083456 1111636160 11.948 97 27 53 0 1147172288 30102496 5433632
09:21:34 26133 8192000000 10114876272 10554302464 1136293504 12.181 99 51 101 0 1199336608 52450000 10593104
09:23:01 109319 8192000000 10151598384 10663124992 1132347696 12.143 98 19 38 0 1270754256 133630992 4775568
09:25:58 133356 8192000000 10093995024 10560540672 1107019584 11.904 97 9 18 0 1145811776 37083984 1708208
09:29:13 126515 8192000000 10091636320 10568499200 1144857312 12.261 100 14 28 0 1176540800 28428352 3255136
09:32:44 151390 8192000000 10127907792 10600669184 1090856784 11.751 94 25 45 1 1173154432 77250448 5047200
09:36:28 55959 8192000000 10076802240 10580045824 1128412704 12.106 100 24 45 1 1218874320 85262352 5199264
09:38:26 142531 8192000000 10080703040 10537840640 1085372896 11.699 95 15 31 0 1120205008 31911744 2920368
09:42:28 64678 8192000000 10061036480 10522202112 1083528640 11.681 96 13 26 0 1143286256 57343760 2413856
09:45:10 51238 8192000000 10054703520 10485846016 1090624640 11.749 96 25 48 1 1127046880 32264784 4157456
09:47:04 23823 8192000000 10097128896 10524176384 1120640208 12.033 99 52 90 1 1183953600 54359344 8954048
09:48:29 24641 8192000000 10132645664 10610450432 1121090800 12.037 99 44 87 0 1243526480 113573872 8861808
09:50:00 59597 8192000000 10107755056 10551889920 1117307680 12.002 98 25 47 3 1184792192 62940240 4544272
09:52:10 17606 8192000000 10095728864 10528849920 1124152560 12.066 99 41 82 0 1183216976 51563056 7501360
09:53:37 9872 8192000000 10101281984 10521337856 1120705520 12.034 99 64 125 0 1181698512 48211664 12781328
09:54:47 100037 8192000000 10107678768 10602299392 1141704128 12.232 100 29 59 0 1247327248 99258032 6365088
09:57:55 19238 8192000000 10117495712 10561331200 1143401264 12.248 100 50 92 4 1221419392 67811984 10206144
09:59:15 110456 8192000000 10051257904 10526236672 1057553792 11.433 93 14 28 0 1114331056 54097056 2680208
10:02:05 66320 8192000000 10047328912 10527801344 1100003680 11.838 98 8 16 1 1137346432 35785072 1557680
10:04:12 27815 8192000000 10084538352 10527887360 1120877616 12.035 99 38 74 2 1173099600 43834560 8387424
10:05:47 29989 8192000000 10115113408 10565599232 1126927760 12.092 99 27 53 0 1193826176 60836976 6061440
10:07:32 45101 8192000000 10147318240 10595344384 1139171328 12.208 99 31 62 0 1256504608 110665184 6668096
10:09:18 22745 8192000000 10143808768 10594603008 1115462608 11.984 98 22 44 0 1230407504 109857776 5087120
10:10:42 56435 8192000000 10103558544 10536476672 1136217888 12.180 99 31 62 0 1241571936 98965504 6388544
10:12:41 29729 8192000000 10178399936 10633125888 1134875792 12.167 99 40 80 0 1241446304 98502704 8067808
10:14:13 63275 8192000000 10155825936 10584551424 1157915712 12.384 100 50 97 2 1241760880 73210480 10634688
10:16:17 82080 8192000000 10108249072 10581913600 1128420192 12.106 98 15 30 0 1211204560 79896912 2887456
10:18:39 80157 8192000000 10094782320 10582511616 1136767936 12.185 99 41 83 0 1189925328 43119232 10038160
10:21:02 46054 8192000000 10058349856 10568769536 1137156320 12.189 100 4 8 0 1176712896 38689648 866928
10:22:49 65234 8192000000 10090541504 10554802176 1133473696 12.154 99 16 31 0 1181478144 44999744 3004704
10:24:54 73338 8192000000 10077627552 10523549696 1131127824 12.132 99 27 49 1 1165514608 29502896 4883888
10:27:09 54752 8192000000 10138228512 10594238464 1135720336 12.175 99 18 34 0 1226551872 86912000 3919536
10:29:05 47037 8192000000 10152831952 10623328256 1133057840 12.150 99 29 58 0 1253539440 113945952 6535648
10:30:57 67976 8192000000 10126591760 10620694528 1087682768 11.721 96 32 62 0 1193749664 99529728 6537168
10:33:06 30297 8192000000 10116635472 10557648896 1112630112 11.957 99 39 78 0 1200433696 79170336 8633248
10:34:37 24247 8192000000 10132556848 10568110080 1094513808 11.786 97 33 66 0 1188595360 87032384 7049168
10:36:03 47137 8192000000 10120602240 10641825792 1106632752 11.901 98 18 36 0 1234848064 124004752 4210560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-03-2023 10:38:37 to 09-03-2023 10:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 132 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-03-2023 10:38:54 to 09-03-2023 10:38:56.

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          229408
MON$OLDEST_ACTIVE               229409
MON$OLDEST_SNAPSHOT             229409
MON$NEXT_TRANSACTION            229409
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                       2063328
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

09.03.23 10:38:57. 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
2023-03-09 10:39:00 No severe PSQL-related problems occured
09.03.23 10:39:01. End of report.