Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.02.2023 04: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 14979616 kB
MemAvailable 30365880 kB
Buffers 20 kB
Cached 16639236 kB
SwapCached 3712 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-02-01 04:37:26
DTS_END                         2023-02-01 04:37:26.1720
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/44552
TRN_ID                          231924
ATT_ID                          634
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: 9149.78 20377 1097973
customer order: creation 187.13 493 22455
customer order: refuse 15.43 397 1852
order to supplier: creation 49.17 1431 5900
order to supplier: removal 3.53 158522 424
invoice (draft): creation 47.65 3240 5718
invoice (draft): removal 48.93 1010 5871
invoice accept: apply 44.13 26513 5296
invoice accept: cancel 43.44 1258 5213
customer reserve: creation 4124.24 249 494909
customer reserve: removal 4281.54 102 513785
realization accept: apply 89.11 290 10693
realization accept: cancel 76.04 185 9125
payment from customer: creation 63.75 195 7650
payment from customer: removal 6.02 125 722
payment to supplier: creation 52.52 190 6302
payment to supplier: removal 3.82 129 458
service: total inventory turnovers 4.45 3628 534
service: total monetary turnovers 7.79 376 935
service: aggregate perf. data 1.05 14626 126
service: refresh index statistics 0.04 194590 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-02-2023 04:38:07 to 01-02-2023 04:38:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5622
TEST_TIME 2 4977
TEST_TIME 3 4392
TEST_TIME 4 5114
TEST_TIME 5 5851
TEST_TIME 6 5944
TEST_TIME 7 5686
TEST_TIME 8 5904
TEST_TIME 9 5925
TEST_TIME 10 6291
TEST_TIME 11 6300
TEST_TIME 12 6558
TEST_TIME 13 6651
TEST_TIME 14 6968
TEST_TIME 15 7068
TEST_TIME 16 7656
TEST_TIME 17 7695
TEST_TIME 18 7839
TEST_TIME 19 7877
TEST_TIME 20 7971
TEST_TIME 21 8084
TEST_TIME 22 8167
TEST_TIME 23 8252
TEST_TIME 24 8166
TEST_TIME 25 8155
TEST_TIME 26 8550
TEST_TIME 27 8530
TEST_TIME 28 8786
TEST_TIME 29 9302
TEST_TIME 30 9387
TEST_TIME 31 9609
TEST_TIME 32 10244
TEST_TIME 33 10306
TEST_TIME 34 10860
TEST_TIME 35 10982
TEST_TIME 36 10868
TEST_TIME 37 10720
TEST_TIME 38 10512
TEST_TIME 39 10455
TEST_TIME 40 10419
TEST_TIME 41 10296
TEST_TIME 42 10200
TEST_TIME 43 10110
TEST_TIME 44 10067
TEST_TIME 45 10031
TEST_TIME 46 10006
TEST_TIME 47 10019
TEST_TIME 48 9926
TEST_TIME 49 9895
TEST_TIME 50 9786
TEST_TIME 51 9795
TEST_TIME 52 9828
TEST_TIME 53 9770
TEST_TIME 54 9772
TEST_TIME 55 9800
TEST_TIME 56 9744
TEST_TIME 57 9802
TEST_TIME 58 9817
TEST_TIME 59 9825
TEST_TIME 60 9823
TEST_TIME 61 9848
TEST_TIME 62 9775
TEST_TIME 63 9703
TEST_TIME 64 9886
TEST_TIME 65 9855
TEST_TIME 66 9782
TEST_TIME 67 9741
TEST_TIME 68 9646
TEST_TIME 69 9561
TEST_TIME 70 9539
TEST_TIME 71 9500
TEST_TIME 72 9432
TEST_TIME 73 9438
TEST_TIME 74 9341
TEST_TIME 75 9279
TEST_TIME 76 9355
TEST_TIME 77 9370
TEST_TIME 78 9317
TEST_TIME 79 9329
TEST_TIME 80 9330
TEST_TIME 81 9418
TEST_TIME 82 9457
TEST_TIME 83 9388
TEST_TIME 84 9397
TEST_TIME 85 9462
TEST_TIME 86 9449
TEST_TIME 87 9385
TEST_TIME 88 9302
TEST_TIME 89 9282
TEST_TIME 90 9245
TEST_TIME 91 9199
TEST_TIME 92 9187
TEST_TIME 93 9173
TEST_TIME 94 9193
TEST_TIME 95 9180
TEST_TIME 96 9181
TEST_TIME 97 9156
TEST_TIME 98 9152
TEST_TIME 99 9138
TEST_TIME 100 9157
TEST_TIME 101 9217
TEST_TIME 102 9275
TEST_TIME 103 9253
TEST_TIME 104 9268
TEST_TIME 105 9275
TEST_TIME 106 9252
TEST_TIME 107 9178
TEST_TIME 108 9182
TEST_TIME 109 9146
TEST_TIME 110 9127
TEST_TIME 111 9100
TEST_TIME 112 9075
TEST_TIME 113 9139
TEST_TIME 114 9119
TEST_TIME 115 9100
TEST_TIME 116 9091
TEST_TIME 117 9144
TEST_TIME 118 9151
TEST_TIME 119 9186
TEST_TIME 120 9172

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-02-2023 04:38:10 to 01-02-2023 04:38:14.

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 1552297 1552297 0 0.00 0 17610 14 0 0
doc_list_biud 1552288 1552288 0 0.00 0 42828 40 0 0
sp_add_invoice_to_stock 5460 5296 164 3.00 44 198518 26513 0 164
sp_cancel_adding_invoice 5213 5213 0 0.00 6 106866 1259 0 0
sp_cancel_client_order 1852 1852 0 0.00 6 8094 397 0 0
sp_cancel_customer_reserve 513785 513785 0 0.00 1 23184 103 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 4 6500 125 0 0
sp_cancel_pay_to_supplier 458 458 0 0.00 4 8538 129 0 0
sp_cancel_supplier_invoice 5885 5871 14 0.23 3 42835 1011 0 14
sp_cancel_supplier_order 424 424 0 0.00 17 1613126 158523 0 0
sp_cancel_write_off 9125 9125 0 0.00 2 23238 186 0 0
sp_client_order 22455 22455 0 0.00 6 32685 494 0 0
sp_customer_reserve 494909 494909 0 0.00 1 90437 250 0 0
sp_fill_shopping_cart 34069 34069 0 0.00 1 26667 89 0 0
sp_fill_shopping_cart_clo_res 494879 494879 0 0.00 0 19948 20 0 0
sp_get_clo_for_invoice 5264 5264 0 0.00 0 30992 289 0 0
sp_kill_cost_storno 15518 15518 0 0.00 0 7902 72 0 0
sp_kill_qstorno_ret_qs2qd 1042990 1042990 0 0.00 0 42822 44 0 0
sp_kill_qty_storno 552226 552226 0 0.00 0 42825 102 0 0
sp_lock_selected_doc 22111 22111 0 0.00 0 8823 9 0 0
sp_make_cost_storno 29909 29909 0 0.00 0 9731 79 0 0
sp_multiply_rows_for_pdistr 29908 29908 0 0.00 0 7932 41 0 0
sp_multiply_rows_for_qdistr 22455 22455 0 0.00 0 25375 185 0 0
sp_pay_from_customer 7650 7650 0 0.00 5 17346 196 0 0
sp_pay_to_supplier 6302 6302 0 0.00 6 17015 191 0 0
sp_payment_common 13952 13952 0 0.00 2 13873 50 0 0
sp_qd_handle_on_cancel_clo 1852 1852 0 0.00 0 8076 316 0 0
sp_qd_handle_on_invoice_upd_sts 10476 10476 0 0.00 0 25372 535 0 0
sp_qd_handle_on_reserve_upd_sts 10693 10693 0 0.00 0 17942 59 0 0
sp_reserve_write_off 10693 10693 0 0.00 8 17989 290 0 0
sp_supplier_invoice 5718 5718 0 0.00 11 82271 3240 0 0
sp_supplier_order 5900 5900 0 0.00 7 55344 1432 0 0
srv_aggregate_perf_data 126 126 0 0.00 2580 135783 14627 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 854 134093 31621 0 0
srv_find_qd_qs_mism 1044699 1044699 0 0.00 0 17610 11 0 0
srv_make_invnt_saldo 534 534 0 0.00 55 65325 3628 0 0
srv_make_money_saldo 935 935 0 0.00 1 11834 377 0 0
srv_recalc_idx_stat 5 5 0 0.00 48569 322628 194590 0 0
t$perf-abend:sp_add_invoice_to_stock 164 164 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-norm:sp_add_invoice_to_stock 5302 5302 0 0.00 1 15918 977 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 1 6191 438 0 0
t$perf-norm:sp_cancel_client_order 1852 1852 0 0.00 0 1354 27 0 0
t$perf-norm:sp_cancel_customer_reserve 757 757 0 0.00 0 3736 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 1309 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 458 458 0 0.00 0 715 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 5222 60 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 130660 6896 0 0
t$perf-norm:sp_cancel_write_off 1098 1098 0 0.00 0 3793 22 0 0
t$perf-norm:sp_client_order 22455 22455 0 0.00 0 9081 39 0 0
t$perf-norm:sp_customer_reserve 1060 1060 0 0.00 0 791 14 0 0
t$perf-norm:sp_pay_from_customer 7650 7650 0 0.00 0 19271 22 0 0
t$perf-norm:sp_pay_to_supplier 6302 6302 0 0.00 0 12938 26 0 0
t$perf-norm:sp_reserve_write_off 10693 10693 0 0.00 0 16711 27 0 0
t$perf-norm:sp_supplier_invoice 5718 5718 0 0.00 0 4239 33 0 0
t$perf-norm:sp_supplier_order 5900 5900 0 0.00 0 3618 35 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 537 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 157 8 0 0
t$perf-norm:srv_make_invnt_saldo 534 534 0 0.00 0 3061 22 0 0
t$perf-norm:srv_make_money_saldo 935 935 0 0.00 0 1029 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 190 76 0 0
v_add_invoice_to_stock 10852 10524 328 3.02 0 10577 31 0 328
v_all_customers 40976 40976 0 0.00 0 103 4 0 0
v_all_suppliers 32466 32466 0 0.00 0 106 5 0 0
v_all_wares 44910 44910 0 0.00 0 105 4 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 3654 22 0 0
v_cancel_client_order 3704 3704 0 0.00 0 7505 13 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 6451 19 0 0
v_cancel_customer_reserve 1514 1514 0 0.00 0 556 10 0 0
v_cancel_payment_to_supplier 916 916 0 0.00 0 1301 14 0 0
v_cancel_supplier_invoice 902 874 28 3.10 0 2298 34 0 28
v_cancel_supplier_order 848 848 0 0.00 0 501 11 0 0
v_cancel_write_off 2196 2196 0 0.00 0 1502 25 0 0
v_max_id_clo_ord 5900 5900 0 0.00 0 255 5 0 0
v_max_id_clo_res 1060 1060 0 0.00 0 1816 20 0 0
v_max_id_ord_sup 5714 5714 0 0.00 0 9439 11 0 0
v_max_non_paid_invoice 1683 1683 0 0.00 0 806 9 0 0
v_max_non_paid_realizn 3970 3970 0 0.00 0 5405 9 0 0
v_min_id_clo_ord 5900 5900 0 0.00 0 1896 8 0 0
v_min_id_clo_res 1060 1060 0 0.00 0 1871 9 0 0
v_min_id_ord_sup 5714 5714 0 0.00 0 11994 16 0 0
v_min_non_paid_invoice 6302 6302 0 0.00 0 6225 13 0 0
v_min_non_paid_realizn 7650 7650 0 0.00 0 10031 19 0 0
v_our_firm 11294 11294 0 0.00 0 98 5 0 0
v_reserve_write_off 21384 21384 0 0.00 0 14602 15 0 0
x_lock_depdocs_on_canc_invoice 10647 10647 0 0.00 0 15649 127 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 8261 344 0 0
x_make_qstorno_1000_1200 5900 5900 0 0.00 3 55322 1250 0 0
x_make_qstorno_1200_2000 5718 5718 0 0.00 5 82259 2989 0 0
x_make_qstorno_2100_3300 494907 494907 0 0.00 0 90434 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-02-2023 04:38:15 to 01-02-2023 04:38:19.

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
02:07:30 7 8192000000 8806243232 8820416512 9275600 0.113 3 3 4 0 9463456 100912 86944
02:08:32 30070 8192000000 9729648128 10028105728 802129776 8.918 100 88 177 0 831450880 7604576 21716528
02:10:08 15188 8192000000 9769610560 10077384704 845953200 9.360 100 87 174 0 877580624 10950864 20676560
02:11:30 19718 8192000000 9812249536 10132254720 877676640 9.677 100 87 174 0 919317088 21298256 20342192
02:12:55 8931 8192000000 9874518880 10211729408 927279776 10.168 100 81 162 0 992841488 48372400 17189312
02:14:12 2450 8192000000 9937980288 10284765184 976920416 10.654 100 92 177 0 1056457984 60641888 18895680
02:15:19 1880 8192000000 9964859920 10320474112 998803808 10.867 100 80 158 1 1082990080 68403696 15782576
02:16:23 1247 8192000000 10012661248 10376323072 1017277008 11.046 100 90 177 1 1123250432 89089296 16884128
02:17:26 714 8192000000 10002748272 10372005888 1045201184 11.315 100 91 177 0 1124586368 60451616 18933568
02:18:28 1193 8192000000 10045298192 10419994624 1052788432 11.387 100 94 182 1 1160995184 89808176 18398576
02:19:31 1022 8192000000 10038214400 10414501888 1058522352 11.442 100 90 176 0 1159729152 83770496 17436304
02:20:32 1056 8192000000 10035596320 10414751744 1069282080 11.545 100 94 187 1 1155365696 66723056 19360560
02:21:34 1228 8192000000 10039358736 10422988800 1074478944 11.595 100 91 173 2 1159948720 68169968 17299808
02:22:36 1143 8192000000 10056118688 10441650176 1086769424 11.712 100 95 189 0 1179325280 73636896 18918960
02:23:38 5192 8192000000 10051159552 10448683008 1085581040 11.701 100 63 121 1 1171172160 73022544 12568576
02:24:45 891 8192000000 10074934000 10468667392 1091954960 11.761 100 88 162 1 1200565552 90951184 17659408
02:25:48 2098 8192000000 10058235328 10454589440 1093885232 11.780 100 74 141 0 1177042128 68936112 14220784
02:26:51 983 8192000000 10057681664 10448957440 1094282640 11.783 100 89 171 1 1178192848 66216992 17693216
02:27:55 1058 8192000000 10039008592 10428739584 1087810240 11.722 100 93 174 8 1162695264 56575664 18309360
02:28:57 1952 8192000000 10051957296 10445189120 1087122592 11.715 100 72 139 0 1178069008 76449216 14497200
02:29:59 1371 8192000000 10040634000 10428628992 1084708656 11.692 100 86 165 2 1163633856 61979984 16945216
02:31:02 1199 8192000000 10026228896 10403049472 1062234704 11.478 100 98 185 2 1149115840 67215360 19665776
02:32:04 932 8192000000 10047038800 10427342848 1062052736 11.476 99 94 188 1 1173182784 91974400 19155648
02:33:08 1047 8192000000 10004618192 10380050432 1052064704 11.380 100 90 173 3 1127510976 57848896 17597376
02:34:09 1226 8192000000 9951126176 10323238912 1022320160 11.094 99 75 144 1 1074942752 39085232 13537360
02:35:12 1685 8192000000 9998179136 10379096064 1044385232 11.307 100 76 146 1 1122491296 62306848 15799216
02:36:14 1160 8192000000 9985383616 10356805632 1030915008 11.177 100 86 161 5 1102684496 56169424 15600064
02:37:16 922 8192000000 9995690832 10370760704 1055461472 11.413 100 91 168 0 1115634800 43804272 16369056
02:38:26 15437 8192000000 10024612128 10408734720 1072172384 11.573 100 76 141 1 1142928912 57229808 13526720
02:39:54 27948 8192000000 10042960512 10438483968 1088697888 11.730 100 59 111 1 1146179296 45488576 11992832
02:41:27 1194 8192000000 10130026688 10515537920 1111047136 11.942 100 98 181 2 1247997376 116980800 19969440
02:42:32 1058 8192000000 10132000000 10522419200 1117993536 12.008 100 97 181 1 1246912016 109687200 19231280
02:43:37 3347 8192000000 10145574032 10543890432 1130355712 12.125 100 74 139 0 1261097808 116762096 13980000
02:44:46 1207 8192000000 10193328640 10592210944 1139464160 12.210 100 82 152 2 1306142448 150555104 16123184
02:45:50 1409 8192000000 10195407888 10597142528 1135042352 12.169 100 84 162 0 1314532656 161650720 17839584
02:46:55 2392 8192000000 10226490960 10638610432 1143435712 12.248 100 88 168 0 1341878736 177850560 20592464
02:48:02 1180 8192000000 10252668944 10655494144 1158558128 12.390 100 92 175 2 1364174512 186764080 18852304
02:49:06 3132 8192000000 10261871664 10668961792 1159835152 12.402 100 71 136 0 1377440000 202943568 14661280
02:50:12 1449 8192000000 10308298560 10712313856 1167408816 12.473 100 90 178 0 1421354240 232704448 21240976
02:51:16 1520 8192000000 10311455744 10711318528 1168465552 12.482 100 84 167 1 1418937024 231869504 18601968
02:52:20 1150 8192000000 10330456432 10752020480 1177013984 12.562 100 87 169 0 1443208464 246295248 19899232
02:53:24 1177 8192000000 10323047680 10752139264 1171434144 12.510 99 94 190 1 1431874784 239310352 21130288
02:54:35 1454 8192000000 10347028784 10766340096 1181668384 12.606 100 96 184 1 1455076448 252538544 20869520
02:55:43 3286 8192000000 10361674960 10773884928 1186064256 12.647 100 82 160 1 1472589440 267688112 18837072
02:56:51 1679 8192000000 10412311712 10829344768 1189742896 12.681 100 89 170 0 1524130896 314706272 19681728
02:57:56 839 8192000000 10390890688 10802450432 1164555472 12.446 98 95 184 0 1499080736 313755696 20769568
02:59:00 931 8192000000 10440650736 10873606144 1193022080 12.711 100 100 194 2 1545537232 330319552 22195600
03:00:05 991 8192000000 10472998512 10889998336 1192188528 12.704 100 96 187 0 1576879328 362356368 22334432
03:01:08 1566 8192000000 10519418800 10925113344 1189519072 12.679 100 92 186 0 1621969728 409566752 22883904
03:02:14 1107 8192000000 10544863472 10985308160 1198561424 12.763 100 95 181 1 1647258400 426038448 22658528
03:03:18 874 8192000000 10553559408 11012079616 1185885248 12.645 100 96 187 1 1656052848 446664352 23503248
03:04:22 2483 8192000000 10547963280 10985349120 1178232640 12.574 100 74 140 0 1652063680 456153392 17677648
03:05:34 6126 8192000000 10554356000 11038924800 1178502464 12.576 100 62 124 0 1653411440 458942384 15966592
03:06:45 17813 8192000000 10459568384 10908782592 1164995280 12.450 99 40 80 0 1550608512 375472912 10140320
03:08:10 15038 8192000000 10429191856 10950098944 1164949792 12.450 100 28 54 0 1549960672 377811264 7199616
03:09:53 38544 8192000000 10237350480 10698657792 1144705424 12.260 100 22 43 0 1362633360 213086768 4841168
03:11:40 55746 8192000000 10086917408 10542559232 1139249808 12.208 99 14 28 0 1236846048 93867312 3728928
03:13:38 35350 8192000000 10099590032 10529189888 1147313120 12.284 100 37 74 0 1187786160 32421904 8051136
03:15:52 30876 8192000000 10091901104 10521616384 1115425696 11.984 98 40 78 1 1153638352 29778704 8433952
03:17:25 47057 8192000000 10063865216 10510573568 1116194672 11.991 98 27 46 2 1154969264 33981344 4793248
03:19:15 19509 8192000000 10109444560 10552217600 1138393376 12.200 100 52 104 1 1209202320 59772224 11036720
03:20:43 3895 8192000000 10078242896 10516733952 1131262752 12.133 99 54 106 2 1182086880 39134144 11689984
03:21:48 15207 8192000000 10074697568 10514227200 1104025248 11.876 98 41 80 0 1167981536 54722912 9233376
03:23:28 4146 8192000000 10127291488 10551246848 1135892000 12.177 100 71 129 7 1220761328 70599040 14270288
03:24:35 51271 8192000000 10097060144 10570289152 1129900544 12.120 99 26 50 0 1213023472 77621456 5501472
03:26:32 22379 8192000000 10113193872 10555195392 1129631200 12.118 99 24 40 1 1190213184 56164448 4417536
03:27:59 59739 8192000000 10062334688 10546102272 1130266688 12.124 99 18 31 2 1206103184 72703984 3132512
03:30:04 21403 8192000000 10072054448 10503905280 1106382928 11.898 98 52 101 0 1148934992 31243776 11308288
03:31:49 17795 8192000000 10087898032 10507997184 1088491840 11.728 97 63 118 1 1164949152 63605184 12852128
03:33:11 41751 8192000000 10111456096 10540191744 1124969120 12.074 100 31 60 0 1191621344 60495392 6156832
03:34:53 46072 8192000000 10080848240 10551832576 1118386896 12.012 100 21 41 0 1201545232 79070400 4087936
03:36:56 76969 8192000000 10095658080 10558353408 1116287776 11.992 99 13 26 1 1187069744 68356992 2424976
03:39:16 33707 8192000000 10106130432 10536890368 1135973088 12.178 100 28 55 0 1182959856 41537328 5449440
03:40:51 47270 8192000000 10124513584 10616897536 1123487184 12.060 99 29 58 0 1242258688 112920608 5850896
03:42:43 45814 8192000000 10102306016 10540404736 1127711680 12.100 99 45 90 0 1192298000 54769936 9816384
03:44:33 107691 8192000000 10069622304 10508382208 1111712096 11.949 98 37 73 2 1151629968 33041504 6876368
03:47:21 80056 8192000000 10052340768 10514452480 1084344192 11.689 96 12 21 0 1127015488 41008000 1663296
03:49:41 80130 8192000000 10091797136 10570788864 1117707744 12.005 98 24 49 0 1217335952 94124048 5504160
03:52:02 69712 8192000000 10159517584 10624339968 1137046816 12.188 100 16 32 0 1247304144 106809552 3447776
03:54:31 85672 8192000000 10096564032 10535931904 1075268112 11.602 94 21 42 0 1115485840 35434864 4782864
03:56:58 23168 8192000000 10135680480 10586861568 1121332240 12.040 98 41 82 0 1222249520 92016928 8900352
03:58:22 59795 8192000000 10080105456 10548518912 1094507520 11.785 97 15 30 0 1169454288 71602016 3344752
04:00:27 15180 8192000000 10091724672 10518515712 1122402272 12.050 99 60 118 1 1176415536 41060000 12953264
04:01:48 54977 8192000000 10084061152 10536722432 1109960976 11.932 98 34 65 1 1191999968 75405792 6633200
04:03:46 12550 8192000000 10091261808 10506592256 1113815680 11.969 99 64 117 1 1167991056 43471024 10704352
04:04:59 74759 8192000000 10117364032 10573180928 1129826800 12.120 100 45 90 0 1201824464 62051600 9946064
04:07:23 37246 8192000000 10053702640 10524155904 1123251648 12.058 99 5 10 0 1148458080 24466272 740160
04:09:01 44871 8192000000 10080489680 10572967936 1121495824 12.041 98 6 12 0 1154758240 32329360 933056
04:10:46 60708 8192000000 10105860240 10579025920 1137621008 12.193 99 32 64 0 1187902016 43152480 7128528
04:12:47 33911 8192000000 10125401472 10583515136 1155122448 12.358 100 26 52 1 1214627424 54302336 5202640
04:14:21 36853 8192000000 10177996736 10617536512 1150781152 12.317 99 24 48 0 1244125296 88135152 5208992
04:16:05 33367 8192000000 10181690720 10632765440 1144358304 12.257 99 28 57 0 1264187488 113548416 6280768
04:17:38 43395 8192000000 10202602976 10681085952 1138361072 12.200 98 44 87 0 1277755712 129905072 9489568
04:19:28 38162 8192000000 10089459616 10517495808 1120329296 12.030 98 33 62 0 1168630048 42140288 6160464
04:21:08 32266 8192000000 10102501312 10562613248 1107555184 11.909 97 34 64 0 1186051760 71270384 7226192
04:22:47 14251 8192000000 10116181088 10547683328 1131390368 12.134 100 49 95 0 1201213824 60168624 9654832
04:24:01 134093 8192000000 10099855280 10576261120 1104933744 11.884 97 15 30 0 1149474480 41625408 2915328
04:27:15 116807 8192000000 10114903856 10594775040 1109711680 11.930 98 7 15 0 1199153312 87702976 1738656
04:30:26 104236 8192000000 10121092384 10563637248 1116184448 11.991 98 23 46 1 1197000176 75132240 5683488
04:33:11 35764 8192000000 10139963952 10592587776 1124859664 12.073 98 19 38 0 1227604832 98554480 4190688
04:34:59 24258 8192000000 10099939248 10545090560 1132752688 12.147 99 49 97 0 1198051248 54788128 10510432
04:36:26 53519 8192000000 10071741040 10526425088 1099042384 11.829 97 16 32 0 1158161200 55949600 3169216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 01-02-2023 04:38:21 to 01-02-2023 04:38:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 164 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 01-02-2023 04:38:39 to 01-02-2023 04:38:41.

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          231324
MON$OLDEST_ACTIVE               232241
MON$OLDEST_SNAPSHOT             232241
MON$NEXT_TRANSACTION            232241
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                       2063136
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             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2894 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2894 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2894 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 ---
:277: 
:278: mscs-test-161.budzdorov.ru    Wed Feb  1 04:37:29 2023
:279:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:280: 
:281: 
:282: mscs-test-161.budzdorov.ru    Wed Feb  1 04:37:33 2023
:283:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:284: 
--- end of diff output ---

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

01.02.23 04:38:42. 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-02-01 04:38:45 No severe PSQL-related problems occured
01.02.23 04:38:46. End of report.