Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.12.2022 04:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14682872 kB
MemAvailable 30362200 kB
Buffers 20 kB
Cached 16936016 kB
SwapCached 11576 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-12-30 04:37:23
DTS_END                         2022-12-30 04:37:23.6180
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/46826
TRN_ID                          221434
ATT_ID                          648
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: 9273.67 25928 1112841
customer order: creation 171.52 444 20582
customer order: refuse 14.51 411 1741
order to supplier: creation 46.03 1374 5523
order to supplier: removal 3.49 167933 419
invoice (draft): creation 45.35 3052 5442
invoice (draft): removal 52.88 955 6345
invoice accept: apply 41.68 23266 5002
invoice accept: cancel 48.02 1198 5762
customer reserve: creation 3908.33 217 468999
customer reserve: removal 4645.25 99 557430
realization accept: apply 81.40 265 9768
realization accept: cancel 87.78 151 10533
payment from customer: creation 58.51 177 7021
payment from customer: removal 5.45 128 654
payment to supplier: creation 47.86 204 5743
payment to supplier: removal 3.38 157 406
service: total inventory turnovers 3.82 4466 458
service: total monetary turnovers 7.44 375 893
service: aggregate perf. data 0.97 15959 116
service: refresh index statistics 0.03 297728 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-12-2022 04:38:47 to 30-12-2022 04:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11636
TEST_TIME 2 9511
TEST_TIME 3 7463
TEST_TIME 4 6565
TEST_TIME 5 6194
TEST_TIME 6 5989
TEST_TIME 7 5753
TEST_TIME 8 5846
TEST_TIME 9 6134
TEST_TIME 10 6175
TEST_TIME 11 6431
TEST_TIME 12 6686
TEST_TIME 13 6900
TEST_TIME 14 7076
TEST_TIME 15 7386
TEST_TIME 16 7795
TEST_TIME 17 7791
TEST_TIME 18 7646
TEST_TIME 19 7616
TEST_TIME 20 7561
TEST_TIME 21 7979
TEST_TIME 22 7988
TEST_TIME 23 8288
TEST_TIME 24 8220
TEST_TIME 25 8391
TEST_TIME 26 8589
TEST_TIME 27 8814
TEST_TIME 28 9002
TEST_TIME 29 9174
TEST_TIME 30 9440
TEST_TIME 31 9833
TEST_TIME 32 9636
TEST_TIME 33 10434
TEST_TIME 34 11209
TEST_TIME 35 11161
TEST_TIME 36 11135
TEST_TIME 37 11278
TEST_TIME 38 11055
TEST_TIME 39 10882
TEST_TIME 40 10900
TEST_TIME 41 10745
TEST_TIME 42 10800
TEST_TIME 43 10740
TEST_TIME 44 10592
TEST_TIME 45 10581
TEST_TIME 46 10594
TEST_TIME 47 10539
TEST_TIME 48 10532
TEST_TIME 49 10580
TEST_TIME 50 10536
TEST_TIME 51 10406
TEST_TIME 52 10266
TEST_TIME 53 10145
TEST_TIME 54 10184
TEST_TIME 55 10138
TEST_TIME 56 10073
TEST_TIME 57 10040
TEST_TIME 58 9961
TEST_TIME 59 9865
TEST_TIME 60 9783
TEST_TIME 61 9730
TEST_TIME 62 9739
TEST_TIME 63 9709
TEST_TIME 64 9800
TEST_TIME 65 9746
TEST_TIME 66 9724
TEST_TIME 67 9641
TEST_TIME 68 9646
TEST_TIME 69 9607
TEST_TIME 70 9553
TEST_TIME 71 9536
TEST_TIME 72 9498
TEST_TIME 73 9449
TEST_TIME 74 9532
TEST_TIME 75 9547
TEST_TIME 76 9536
TEST_TIME 77 9584
TEST_TIME 78 9591
TEST_TIME 79 9631
TEST_TIME 80 9630
TEST_TIME 81 9694
TEST_TIME 82 9647
TEST_TIME 83 9699
TEST_TIME 84 9676
TEST_TIME 85 9604
TEST_TIME 86 9574
TEST_TIME 87 9503
TEST_TIME 88 9512
TEST_TIME 89 9474
TEST_TIME 90 9493
TEST_TIME 91 9444
TEST_TIME 92 9389
TEST_TIME 93 9371
TEST_TIME 94 9358
TEST_TIME 95 9339
TEST_TIME 96 9393
TEST_TIME 97 9398
TEST_TIME 98 9410
TEST_TIME 99 9394
TEST_TIME 100 9362
TEST_TIME 101 9398
TEST_TIME 102 9393
TEST_TIME 103 9398
TEST_TIME 104 9495
TEST_TIME 105 9606
TEST_TIME 106 9588
TEST_TIME 107 9577
TEST_TIME 108 9545
TEST_TIME 109 9519
TEST_TIME 110 9483
TEST_TIME 111 9448
TEST_TIME 112 9450
TEST_TIME 113 9441
TEST_TIME 114 9411
TEST_TIME 115 9366
TEST_TIME 116 9306
TEST_TIME 117 9297
TEST_TIME 118 9379
TEST_TIME 119 9354
TEST_TIME 120 9301

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-12-2022 04:38:51 to 30-12-2022 04:38:54.

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 1544680 1544680 0 0.00 0 11830 12 0 0
doc_list_biud 1544669 1544669 0 0.00 0 20104 41 0 0
sp_add_invoice_to_stock 5090 5002 88 1.72 46 159447 23266 0 88
sp_cancel_adding_invoice 5762 5762 0 0.00 5 108599 1199 0 0
sp_cancel_client_order 1741 1741 0 0.00 6 12745 411 0 0
sp_cancel_customer_reserve 557430 557430 0 0.00 1 18663 100 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 7853 128 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 9549 158 0 0
sp_cancel_supplier_invoice 6352 6345 7 0.11 2 14078 956 0 7
sp_cancel_supplier_order 419 419 0 0.00 8 1389827 167933 0 0
sp_cancel_write_off 10533 10533 0 0.00 2 6959 151 0 0
sp_client_order 20582 20582 0 0.00 5 17498 445 0 0
sp_customer_reserve 468999 468999 0 0.00 1 92048 217 0 0
sp_fill_shopping_cart 31529 31529 0 0.00 1 8889 80 0 0
sp_fill_shopping_cart_clo_res 468960 468960 0 0.00 0 14365 18 0 0
sp_get_clo_for_invoice 4958 4958 0 0.00 0 21436 269 0 0
sp_kill_cost_storno 17355 17355 0 0.00 0 11621 70 0 0
sp_kill_qstorno_ret_qs2qd 1132157 1132157 0 0.00 0 18633 43 0 0
sp_kill_qty_storno 596955 596955 0 0.00 0 20099 98 0 0
sp_lock_selected_doc 20361 20361 0 0.00 0 10277 7 0 0
sp_make_cost_storno 27490 27490 0 0.00 0 11744 77 0 0
sp_multiply_rows_for_pdistr 27489 27489 0 0.00 0 8548 37 0 0
sp_multiply_rows_for_qdistr 20582 20582 0 0.00 0 10774 181 0 0
sp_pay_from_customer 7021 7021 0 0.00 6 10726 178 0 0
sp_pay_to_supplier 5743 5743 0 0.00 6 14012 205 0 0
sp_payment_common 12763 12763 0 0.00 2 11797 50 0 0
sp_qd_handle_on_cancel_clo 1741 1741 0 0.00 1 12713 330 0 0
sp_qd_handle_on_invoice_upd_sts 10720 10720 0 0.00 0 10244 528 0 0
sp_qd_handle_on_reserve_upd_sts 9767 9767 0 0.00 0 20094 59 0 0
sp_reserve_write_off 9768 9768 0 0.00 8 20161 265 0 0
sp_supplier_invoice 5442 5442 0 0.00 10 95458 3052 0 0
sp_supplier_order 5523 5523 0 0.00 9 39167 1374 0 0
srv_aggregate_perf_data 116 116 0 0.00 2515 212032 15960 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 820 143150 37909 0 0
srv_find_qd_qs_mism 1063926 1063926 0 0.00 0 11349 9 0 0
srv_make_invnt_saldo 458 458 0 0.00 65 100957 4467 0 0
srv_make_money_saldo 893 893 0 0.00 1 10366 376 0 0
srv_recalc_idx_stat 4 4 0 0.00 33728 450562 297729 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5009 5009 0 0.00 1 13399 913 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 8086 515 0 0
t$perf-norm:sp_cancel_client_order 1741 1741 0 0.00 0 1669 27 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 772 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 1942 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 2677 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 1113 26 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 90852 7620 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 2060 24 0 0
t$perf-norm:sp_client_order 20584 20584 0 0.00 0 7140 36 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 1678 17 0 0
t$perf-norm:sp_pay_from_customer 7021 7021 0 0.00 0 7177 19 0 0
t$perf-norm:sp_pay_to_supplier 5743 5743 0 0.00 0 5392 22 0 0
t$perf-norm:sp_reserve_write_off 9768 9768 0 0.00 0 12731 24 0 0
t$perf-norm:sp_supplier_invoice 5442 5442 0 0.00 0 10234 30 0 0
t$perf-norm:sp_supplier_order 5523 5523 0 0.00 0 2533 27 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 1147 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 252 10 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 622 13 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 605 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 202 71 0 0
v_add_invoice_to_stock 10084 9908 176 1.74 0 9445 28 0 176
v_all_customers 37622 37622 0 0.00 0 222 4 0 0
v_all_suppliers 30506 30506 0 0.00 0 74 4 0 0
v_all_wares 41154 41154 0 0.00 0 221 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 1603 21 0 0
v_cancel_client_order 3482 3482 0 0.00 0 4362 13 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 5802 15 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 861 11 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 2771 14 0 0
v_cancel_supplier_invoice 744 730 14 1.88 0 1642 23 0 14
v_cancel_supplier_order 838 838 0 0.00 0 3483 21 0 0
v_cancel_write_off 1968 1968 0 0.00 0 1654 21 0 0
v_max_id_clo_ord 5516 5516 0 0.00 0 711 5 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 2250 13 0 0
v_max_id_ord_sup 5435 5435 0 0.00 0 2218 6 0 0
v_max_non_paid_invoice 1438 1438 0 0.00 0 536 8 0 0
v_max_non_paid_realizn 3559 3559 0 0.00 0 638 7 0 0
v_min_id_clo_ord 5516 5516 0 0.00 0 2650 9 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 1116 9 0 0
v_min_id_ord_sup 5435 5435 0 0.00 0 3040 7 0 0
v_min_non_paid_invoice 5743 5743 0 0.00 0 11773 25 0 0
v_min_non_paid_realizn 7020 7020 0 0.00 0 7403 20 0 0
v_our_firm 10448 10448 0 0.00 0 52 4 0 0
v_reserve_write_off 19534 19534 0 0.00 0 8478 13 0 0
x_lock_depdocs_on_canc_invoice 11742 11742 0 0.00 0 19126 115 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 21438 403 0 0
x_make_qstorno_1000_1200 5523 5523 0 0.00 3 39157 1209 0 0
x_make_qstorno_1200_2000 5442 5442 0 0.00 4 95213 2845 0 0
x_make_qstorno_2100_3300 468999 468999 0 0.00 0 92035 188 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-12-2022 04:38:55 to 30-12-2022 04:38:59.

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:27 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:28 26841 8192000000 9740120016 10041999360 817038688 9.069 100 92 184 0 846921872 7984064 21899120
02:09:57 16424 8192000000 9769333568 10078752768 843394064 9.334 100 85 171 0 876244592 12690608 20159920
02:11:20 10115 8192000000 9805879408 10124607488 870552352 9.606 100 86 172 0 916501040 25691648 20257040
02:12:47 6894 8192000000 9859185248 10193268736 910465712 10.002 100 76 148 0 972692976 45986416 16240848
02:13:57 3930 8192000000 9923536176 10267070464 959684496 10.486 100 86 168 1 1033850624 56377696 17788432
02:15:04 1891 8192000000 9959024608 10312544256 998577152 10.865 100 84 159 1 1075695280 61320400 15797728
02:16:08 1329 8192000000 10001835648 10362437632 1024698640 11.117 100 95 185 2 1114711248 71790512 18222096
02:17:12 1243 8192000000 10011498112 10379149312 1054550464 11.404 100 90 172 0 1136973360 66200896 16222000
02:18:16 1230 8192000000 10051471008 10430025728 1058029824 11.438 100 96 187 0 1173893184 96604864 19258496
02:19:19 2192 8192000000 10022437760 10404327424 1067117632 11.525 100 96 177 4 1140730416 54547488 19065296
02:20:23 994 8192000000 10046666016 10431066112 1075626640 11.606 100 95 180 1 1170096720 75573456 18896624
02:21:25 2420 8192000000 10069025424 10449494016 1083044432 11.676 100 89 167 3 1188661424 89263696 16353296
02:22:29 1059 8192000000 10051574720 10440949760 1090151664 11.744 100 94 178 0 1171208800 63416256 17640880
02:23:32 2209 8192000000 10048623920 10437697536 1091569552 11.758 100 71 134 3 1167210192 61529280 14111360
02:24:35 1253 8192000000 10071361984 10461405184 1095986336 11.800 100 95 184 4 1196702144 82212512 18503296
02:25:37 1124 8192000000 10078014720 10465738752 1108195152 11.915 100 96 183 2 1195605280 69119712 18290416
02:26:39 1137 8192000000 10082661472 10471981056 1106946992 11.904 100 94 180 0 1202341888 76715616 18679280
02:27:40 1086 8192000000 10072152816 10463703040 1114161392 11.972 100 93 179 0 1199552880 66865648 18525840
02:28:42 1192 8192000000 10075144416 10465615872 1104619408 11.881 100 89 171 0 1199286384 76917824 17749152
02:29:45 1124 8192000000 10017195536 10399408128 1084273344 11.688 100 90 175 3 1140527424 38098368 18155712
02:30:47 1128 8192000000 10022026544 10404392960 1070289008 11.555 99 92 180 1 1145940400 58083760 17567632
02:31:49 1629 8192000000 10007580352 10384052224 1055140768 11.410 99 91 169 2 1133509584 61738208 16630608
02:32:52 3004 8192000000 9996376176 10378010624 1043642944 11.300 99 66 123 4 1120442160 64611424 12187792
02:33:56 1070 8192000000 9999907232 10376556544 1046303808 11.325 100 90 160 4 1122809024 59068048 17437168
02:34:58 1179 8192000000 9977501200 10348949504 1026793808 11.138 99 88 168 1 1096551696 53449984 16307904
02:36:01 1635 8192000000 9978517744 10360000512 1041392480 11.278 100 75 136 1 1100825568 46633696 12799392
02:37:03 1050 8192000000 9995935056 10370777088 1040476848 11.269 100 94 173 1 1116351024 59112656 16761520
02:38:11 5445 8192000000 10014098720 10397663232 1053754000 11.397 100 68 128 0 1142599072 75755088 13089984
02:39:20 3993 8192000000 10028961168 10411270144 1075887520 11.608 100 79 151 1 1146558688 57069824 13601344
02:40:31 11983 8192000000 10036020576 10429050880 1093980560 11.780 100 54 105 0 1152162560 47677312 10504688
02:41:46 6268 8192000000 10099035120 10491047936 1106166704 11.896 100 78 135 4 1213976784 93358704 14451376
02:42:58 8747 8192000000 10141834912 10541490176 1118856288 12.016 100 71 135 1 1248660464 115534864 14269312
02:44:09 10693 8192000000 10169285696 10575069184 1128485520 12.107 100 53 104 0 1289501488 149233056 11782912
02:45:24 5188 8192000000 10200453456 10612924416 1131096128 12.132 100 62 122 0 1316765744 171808832 13860784
02:46:34 1482 8192000000 10221786768 10627518464 1145828736 12.270 100 90 178 1 1340284944 171961360 22494848
02:47:38 1088 8192000000 10244620752 10638540800 1146285296 12.275 99 91 167 2 1358414896 193624704 18504896
02:48:40 818 8192000000 10287165984 10692358144 1159935920 12.403 100 94 181 0 1399693008 218974512 20782576
02:49:44 1221 8192000000 10327260992 10742693888 1169161744 12.489 100 92 181 0 1436809328 245870704 21776880
02:50:49 1928 8192000000 10322679040 10746773504 1165890416 12.458 100 84 162 1 1430179456 245728864 18560176
02:51:55 1604 8192000000 10343473696 10779910144 1175458112 12.548 100 88 172 0 1456659344 261019536 20181696
02:53:01 2588 8192000000 10344529888 10781511680 1190301008 12.686 100 94 184 0 1451174176 239901216 20971952
02:54:10 3728 8192000000 10360845552 10774417408 1187775952 12.663 100 74 148 1 1474866528 269563024 17527552
02:55:26 1900 8192000000 10413122048 10821804032 1189214512 12.676 100 94 179 1 1522764096 312981248 20568336
02:56:30 1339 8192000000 10473482016 10885402624 1192119488 12.703 100 83 156 1 1582465472 370520912 19825072
02:57:36 1281 8192000000 10495072656 10934128640 1195890400 12.738 99 95 175 2 1602541120 386999984 19650736
02:58:40 813 8192000000 10520228608 10927620096 1195640880 12.736 100 95 185 0 1626268752 407359392 23268480
02:59:45 887 8192000000 10563048976 10993836032 1206869616 12.840 100 99 192 1 1669723520 438337696 24516208
03:00:48 2551 8192000000 10576909728 11016314880 1194673728 12.727 99 82 162 2 1676134672 461292560 20168384
03:01:53 885 8192000000 10620030368 11053662208 1200951872 12.785 100 95 186 1 1724985328 500945152 23088304
03:02:56 12230 8192000000 10592480352 11064766464 1194197264 12.722 100 70 138 2 1745423872 533478784 17747824
03:04:09 12716 8192000000 10587191728 11050946560 1181924544 12.608 100 49 98 0 1711831152 516767984 13138624
03:05:24 9176 8192000000 10568924032 11030761472 1185212080 12.639 100 42 85 0 1682219616 486005168 11002368
03:06:34 13884 8192000000 10565377520 11054022656 1170469600 12.501 99 40 80 0 1675814464 494166640 11178224
03:08:32 21481 8192000000 10452776032 10933501952 1157562144 12.380 99 28 56 0 1568187536 403028752 7596640
03:10:07 17303 8192000000 10383364384 10939047936 1159697440 12.400 100 50 98 1 1542734848 371083136 11954272
03:11:32 4966 8192000000 10188332048 10644676608 1155647760 12.362 100 54 107 2 1303306112 136295920 11362432
03:12:37 47536 8192000000 10137610352 10600681472 1142146928 12.236 100 27 52 0 1239065648 91137904 5780816
03:14:29 96339 8192000000 10082049552 10525962240 1083942272 11.685 95 11 21 0 1114895664 29105392 1848000
03:17:10 63965 8192000000 10078338336 10542198784 1126468976 12.088 100 20 40 0 1179841392 49563232 3809184
03:19:17 111519 8192000000 10076496640 10566537216 1093538384 11.776 97 9 18 0 1134206208 39231456 1436368
03:22:09 119203 8192000000 10101535120 10584645632 1102225312 11.859 97 10 18 1 1171483968 67598128 1660528
03:25:09 62912 8192000000 10084479472 10597617664 1090852704 11.751 96 19 39 0 1162127248 67129104 4145440
03:27:13 143150 8192000000 10068011632 10521477120 1089241424 11.735 96 22 43 1 1145875664 51689216 4945024
03:30:38 45657 8192000000 10154647808 10585313280 1099231264 11.830 97 50 99 1 1186971232 77145008 10594960
03:32:25 108519 8192000000 10069619712 10505109504 1103304816 11.869 98 31 58 1 1204240320 94266128 6669376
03:35:21 60859 8192000000 10135778752 10585542656 1130607920 12.127 100 23 46 0 1195252400 59038416 5606064
03:37:29 17750 8192000000 10148211728 10576265216 1137642368 12.193 99 54 97 3 1236759264 88296144 10820752
03:38:57 90071 8192000000 10188655088 10642731008 1138216400 12.199 99 20 40 0 1260652384 117750320 4685664
03:41:27 54784 8192000000 10112239648 10605027328 1138409792 12.201 100 16 32 0 1275079504 133186848 3482864
03:43:26 45138 8192000000 10082093952 10527002624 1145050512 12.263 100 37 74 3 1180309776 26590640 8668624
03:45:18 90191 8192000000 10124080848 10615816192 1148752096 12.298 100 17 34 0 1198938976 46612624 3574256
03:48:53 44718 8192000000 10119676528 10584207360 1147875120 12.290 99 22 43 1 1217227120 64731328 4620672
03:50:38 23595 8192000000 10186020352 10634100736 1158916320 12.393 100 40 79 0 1281517024 114452656 8148048
03:52:05 33451 8192000000 10168700624 10621079552 1151174112 12.321 100 30 60 0 1260169584 102570128 6425344
03:53:41 67285 8192000000 10125626864 10588848128 1107629984 11.910 96 27 52 0 1209304912 96172400 5502528
03:55:48 65565 8192000000 10120363840 10553909248 1103746144 11.873 96 24 48 0 1166806576 58247248 4813184
03:57:54 27316 8192000000 10107431840 10570539008 1125610368 12.080 99 59 102 1 1188899392 52828224 10460800
03:59:22 39876 8192000000 10083601152 10531160064 1093770496 11.778 96 33 64 1 1151637328 51204512 6662320
04:01:07 85980 8192000000 10079909984 10574528512 1118663792 12.014 99 16 32 1 1180205312 58284672 3256848
04:04:04 53137 8192000000 10094310624 10524213248 1099456560 11.832 97 47 90 0 1171344112 63368368 8519184
04:05:58 64359 8192000000 10079766192 10553131008 1108628736 11.919 99 14 28 0 1180555664 68953184 2973744
04:08:08 64096 8192000000 10070699616 10533969920 1124702864 12.071 99 20 38 0 1162922576 33881296 4338416
04:10:17 79151 8192000000 10094161792 10576691200 1110129856 11.934 97 9 18 0 1157731776 46013296 1588624
04:12:37 33505 8192000000 10121807296 10581733376 1117044624 11.999 98 43 87 0 1208420016 82181216 9194176
04:14:13 47173 8192000000 10089149264 10528575488 1114286992 11.973 99 23 45 0 1186289728 67606896 4395840
04:16:01 14587 8192000000 10108220464 10545737728 1126051424 12.084 99 29 58 1 1198646144 66486480 6108240
04:17:20 14672 8192000000 10177589008 10604548096 1147280576 12.284 100 52 103 0 1261710240 103032608 11397056
04:18:36 16787 8192000000 10184912240 10629492736 1142295952 12.237 99 50 100 2 1261674640 108059008 11319680
04:19:55 80385 8192000000 10192135648 10665619456 1140738528 12.222 99 35 70 0 1296884816 148812912 7333376
04:22:16 47733 8192000000 10094038000 10598465536 1105711776 11.892 97 15 28 0 1163791776 55466816 2613184
04:24:05 105132 8192000000 10071695552 10537086976 1091923312 11.761 96 32 62 0 1173413376 74977120 6512944
04:26:51 45679 8192000000 10113132592 10553634816 1131559680 12.136 100 41 82 0 1190001360 50064416 8377264
04:30:02 54199 8192000000 10094389040 10564145152 1140128160 12.217 100 14 28 0 1216624800 73294784 3201856
04:32:08 77327 8192000000 10146457024 10604888064 1128888912 12.111 98 18 36 0 1221408320 88441744 4077664
04:34:43 79272 8192000000 10076073920 10549059584 1129606256 12.118 99 12 22 0 1211825616 79587296 2632064
04:37:03 24693 8192000000 9532808560 9901629440 622538640 7.062 54 13 25 0 674971984 49726976 2706368

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-12-2022 04:39:01 to 30-12-2022 04:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-12-2022 04:39:18 to 30-12-2022 04:39:20.

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          222284
MON$OLDEST_ACTIVE               222285
MON$OLDEST_SNAPSHOT             222285
MON$NEXT_TRANSACTION            222285
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                       2098944
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

30.12.22 04:39:21. 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-12-30 04:39:24 No severe PSQL-related problems occured
30.12.22 04:39:25. End of report.