Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.03.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 14804188 kB
MemAvailable 30341376 kB
Buffers 20 kB
Cached 16802268 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-07 04:37:28
DTS_END                         2023-03-07 04:37:28.2450
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/59484
TRN_ID                          226075
ATT_ID                          652
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: 9245.23 24357 1109428
customer order: creation 176.13 448 21136
customer order: refuse 15.23 357 1828
order to supplier: creation 47.28 1288 5673
order to supplier: removal 3.65 146884 438
invoice (draft): creation 46.99 3113 5639
invoice (draft): removal 51.65 958 6198
invoice accept: apply 42.52 24846 5102
invoice accept: cancel 46.03 1146 5524
customer reserve: creation 3986.05 236 478326
customer reserve: removal 4531.23 94 543748
realization accept: apply 85.80 247 10296
realization accept: cancel 82.12 164 9854
payment from customer: creation 59.37 166 7124
payment from customer: removal 5.81 113 697
payment to supplier: creation 48.45 166 5814
payment to supplier: removal 3.95 92 474
service: total inventory turnovers 4.20 3391 504
service: total monetary turnovers 7.78 362 933
service: aggregate perf. data 0.97 15794 116
service: refresh index statistics 0.03 287273 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-03-2023 04:38:05 to 07-03-2023 04:38:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8504
TEST_TIME 2 6477
TEST_TIME 3 6064
TEST_TIME 4 5786
TEST_TIME 5 5776
TEST_TIME 6 6197
TEST_TIME 7 6169
TEST_TIME 8 6768
TEST_TIME 9 7092
TEST_TIME 10 7293
TEST_TIME 11 7145
TEST_TIME 12 7189
TEST_TIME 13 7317
TEST_TIME 14 7285
TEST_TIME 15 7437
TEST_TIME 16 7557
TEST_TIME 17 7535
TEST_TIME 18 7724
TEST_TIME 19 8047
TEST_TIME 20 8458
TEST_TIME 21 8573
TEST_TIME 22 8468
TEST_TIME 23 8497
TEST_TIME 24 8973
TEST_TIME 25 9132
TEST_TIME 26 9488
TEST_TIME 27 9928
TEST_TIME 28 9968
TEST_TIME 29 10109
TEST_TIME 30 10433
TEST_TIME 31 10701
TEST_TIME 32 10480
TEST_TIME 33 10998
TEST_TIME 34 10989
TEST_TIME 35 10797
TEST_TIME 36 10681
TEST_TIME 37 10532
TEST_TIME 38 10526
TEST_TIME 39 10387
TEST_TIME 40 10264
TEST_TIME 41 10205
TEST_TIME 42 10340
TEST_TIME 43 10195
TEST_TIME 44 10289
TEST_TIME 45 10146
TEST_TIME 46 10145
TEST_TIME 47 10285
TEST_TIME 48 10134
TEST_TIME 49 10006
TEST_TIME 50 9846
TEST_TIME 51 9681
TEST_TIME 52 9759
TEST_TIME 53 9843
TEST_TIME 54 9826
TEST_TIME 55 9792
TEST_TIME 56 9749
TEST_TIME 57 9767
TEST_TIME 58 9691
TEST_TIME 59 9698
TEST_TIME 60 9686
TEST_TIME 61 9619
TEST_TIME 62 9527
TEST_TIME 63 9461
TEST_TIME 64 9516
TEST_TIME 65 9461
TEST_TIME 66 9507
TEST_TIME 67 9460
TEST_TIME 68 9434
TEST_TIME 69 9408
TEST_TIME 70 9431
TEST_TIME 71 9363
TEST_TIME 72 9485
TEST_TIME 73 9559
TEST_TIME 74 9663
TEST_TIME 75 9690
TEST_TIME 76 9656
TEST_TIME 77 9586
TEST_TIME 78 9526
TEST_TIME 79 9509
TEST_TIME 80 9532
TEST_TIME 81 9508
TEST_TIME 82 9480
TEST_TIME 83 9419
TEST_TIME 84 9367
TEST_TIME 85 9378
TEST_TIME 86 9310
TEST_TIME 87 9236
TEST_TIME 88 9311
TEST_TIME 89 9252
TEST_TIME 90 9237
TEST_TIME 91 9214
TEST_TIME 92 9186
TEST_TIME 93 9292
TEST_TIME 94 9372
TEST_TIME 95 9365
TEST_TIME 96 9397
TEST_TIME 97 9381
TEST_TIME 98 9418
TEST_TIME 99 9432
TEST_TIME 100 9397
TEST_TIME 101 9392
TEST_TIME 102 9370
TEST_TIME 103 9313
TEST_TIME 104 9264
TEST_TIME 105 9244
TEST_TIME 106 9254
TEST_TIME 107 9216
TEST_TIME 108 9166
TEST_TIME 109 9220
TEST_TIME 110 9210
TEST_TIME 111 9183
TEST_TIME 112 9178
TEST_TIME 113 9185
TEST_TIME 114 9191
TEST_TIME 115 9204
TEST_TIME 116 9174
TEST_TIME 117 9186
TEST_TIME 118 9199
TEST_TIME 119 9249
TEST_TIME 120 9268

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-03-2023 04:38:09 to 07-03-2023 04:38:13.

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 1549752 1549752 0 0.00 0 15602 12 0 0
doc_list_biud 1549751 1549751 0 0.00 0 42807 39 0 0
sp_add_invoice_to_stock 5205 5102 103 1.97 47 253296 24846 0 103
sp_cancel_adding_invoice 5524 5524 0 0.00 7 95772 1146 0 0
sp_cancel_client_order 1828 1828 0 0.00 6 10950 357 0 0
sp_cancel_customer_reserve 543748 543748 0 0.00 1 42820 94 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 3574 114 0 0
sp_cancel_pay_to_supplier 474 474 0 0.00 3 2512 93 0 0
sp_cancel_supplier_invoice 6207 6198 9 0.14 2 33172 958 0 9
sp_cancel_supplier_order 438 438 0 0.00 17 1294754 146884 0 0
sp_cancel_write_off 9854 9854 0 0.00 2 13940 165 0 0
sp_client_order 21136 21136 0 0.00 5 20404 448 0 0
sp_customer_reserve 478326 478326 0 0.00 1 137293 237 0 0
sp_fill_shopping_cart 32446 32446 0 0.00 1 12905 76 0 0
sp_fill_shopping_cart_clo_res 478296 478296 0 0.00 0 22102 19 0 0
sp_get_clo_for_invoice 5061 5061 0 0.00 0 26352 246 0 0
sp_kill_cost_storno 16549 16549 0 0.00 0 8968 68 0 0
sp_kill_qstorno_ret_qs2qd 1103986 1103986 0 0.00 0 42648 43 0 0
sp_kill_qty_storno 582947 582947 0 0.00 0 42806 96 0 0
sp_lock_selected_doc 21375 21375 0 0.00 0 8275 7 0 0
sp_make_cost_storno 28295 28295 0 0.00 0 15599 69 0 0
sp_multiply_rows_for_pdistr 28294 28294 0 0.00 0 9171 33 0 0
sp_multiply_rows_for_qdistr 21136 21136 0 0.00 0 10520 166 0 0
sp_pay_from_customer 7124 7124 0 0.00 5 17629 166 0 0
sp_pay_to_supplier 5814 5814 0 0.00 6 16619 167 0 0
sp_payment_common 12937 12937 0 0.00 2 17593 43 0 0
sp_qd_handle_on_cancel_clo 1828 1828 0 0.00 0 10925 288 0 0
sp_qd_handle_on_invoice_upd_sts 10585 10585 0 0.00 0 23066 469 0 0
sp_qd_handle_on_reserve_upd_sts 10296 10296 0 0.00 0 10657 51 0 0
sp_reserve_write_off 10296 10296 0 0.00 8 18029 248 0 0
sp_supplier_invoice 5639 5639 0 0.00 10 129212 3114 0 0
sp_supplier_order 5673 5673 0 0.00 8 29951 1288 0 0
srv_aggregate_perf_data 116 116 0 0.00 2550 195159 15795 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 792 125273 32017 0 0
srv_find_qd_qs_mism 1059302 1059302 0 0.00 0 13594 9 0 0
srv_make_invnt_saldo 504 504 0 0.00 61 45566 3391 0 0
srv_make_money_saldo 933 933 0 0.00 1 10545 363 0 0
srv_recalc_idx_stat 4 4 0 0.00 78884 547752 287274 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5104 5104 0 0.00 1 12448 752 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 2 6729 376 0 0
t$perf-norm:sp_cancel_client_order 1828 1828 0 0.00 0 2948 26 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 2161 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 2219 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 474 474 0 0.00 0 2669 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1276 29 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 103828 4928 0 0
t$perf-norm:sp_cancel_write_off 1064 1064 0 0.00 0 1382 17 0 0
t$perf-norm:sp_client_order 21136 21136 0 0.00 0 5538 31 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 4118 17 0 0
t$perf-norm:sp_pay_from_customer 7124 7124 0 0.00 0 11988 16 0 0
t$perf-norm:sp_pay_to_supplier 5814 5814 0 0.00 0 20533 21 0 0
t$perf-norm:sp_reserve_write_off 10296 10296 0 0.00 0 6086 21 0 0
t$perf-norm:sp_supplier_invoice 5639 5639 0 0.00 0 9196 28 0 0
t$perf-norm:sp_supplier_order 5673 5673 0 0.00 0 2111 27 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 154 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 209 6 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 441 10 0 0
t$perf-norm:srv_make_money_saldo 933 933 0 0.00 0 9340 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 190 58 0 0
v_add_invoice_to_stock 10328 10122 206 1.99 0 4826 25 0 206
v_all_customers 38918 38918 0 0.00 0 97 3 0 0
v_all_suppliers 31084 31084 0 0.00 0 171 4 0 0
v_all_wares 42268 42268 0 0.00 0 75 4 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 1766 14 0 0
v_cancel_client_order 3656 3656 0 0.00 0 4856 10 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 3484 19 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 0 2427 13 0 0
v_cancel_payment_to_supplier 948 948 0 0.00 0 623 9 0 0
v_cancel_supplier_invoice 816 798 18 2.20 0 1712 29 0 18
v_cancel_supplier_order 876 876 0 0.00 0 1299 14 0 0
v_cancel_write_off 2128 2128 0 0.00 0 1466 23 0 0
v_max_id_clo_ord 5673 5673 0 0.00 0 578 5 0 0
v_max_id_clo_res 990 990 0 0.00 0 3042 20 0 0
v_max_id_ord_sup 5639 5639 0 0.00 0 3798 8 0 0
v_max_non_paid_invoice 1583 1583 0 0.00 0 6200 11 0 0
v_max_non_paid_realizn 3630 3630 0 0.00 0 452 7 0 0
v_min_id_clo_ord 5673 5673 0 0.00 0 6543 9 0 0
v_min_id_clo_res 990 990 0 0.00 0 1488 8 0 0
v_min_id_ord_sup 5639 5639 0 0.00 0 4546 10 0 0
v_min_non_paid_invoice 5813 5813 0 0.00 0 10603 17 0 0
v_min_non_paid_realizn 7124 7124 0 0.00 0 17580 18 0 0
v_our_firm 10338 10338 0 0.00 0 65 4 0 0
v_reserve_write_off 20592 20592 0 0.00 0 6919 11 0 0
x_lock_depdocs_on_canc_invoice 11323 11323 0 0.00 0 18476 111 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 32015 353 0 0
x_make_qstorno_1000_1200 5673 5673 0 0.00 3 25498 1140 0 0
x_make_qstorno_1200_2000 5639 5639 0 0.00 5 128661 2910 0 0
x_make_qstorno_2100_3300 478326 478326 0 0.00 0 137291 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-03-2023 04:38:14 to 07-03-2023 04:38:18.

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:32 3 8192000000 8799257840 8810713088 5118400 0.062 3 1 2 0 5219568 65392 35776
02:08:33 32809 8192000000 9736908560 10036420608 814506768 9.043 100 87 174 0 843738752 7919184 21312800
02:10:10 24377 8192000000 9762057424 10070241280 835434032 9.254 100 89 176 1 869212576 12998240 20780304
02:11:39 18563 8192000000 9808600928 10130710528 875462096 9.654 100 77 152 0 920348416 27644016 17242304
02:13:06 8772 8192000000 9879103296 10218110976 926211936 10.157 100 76 152 0 992889328 50417936 16259456
02:14:19 2137 8192000000 9923393088 10270597120 961465792 10.503 100 84 161 1 1031390432 52257728 17666912
02:15:23 1380 8192000000 9970117632 10325950464 1005248272 10.929 100 88 163 1 1090916688 69410688 16257728
02:16:27 1112 8192000000 10006255744 10370387968 1029621440 11.165 100 92 177 1 1121873504 74733952 17518112
02:17:30 1042 8192000000 10023838240 10395594752 1046931664 11.331 100 92 175 0 1149132368 83205744 18994960
02:18:32 967 8192000000 10042744400 10418511872 1066592992 11.520 100 95 188 0 1163174896 77926816 18655088
02:19:34 5894 8192000000 10038544512 10419712000 1075073024 11.600 100 90 173 2 1163072992 69016672 18983296
02:20:42 2876 8192000000 10051048576 10439745536 1083116016 11.677 100 76 145 2 1176052128 79485920 13450192
02:21:46 1074 8192000000 10048672656 10432176128 1088184720 11.725 100 96 180 0 1171576608 64793792 18598096
02:22:47 944 8192000000 10071502944 10460291072 1092961360 11.771 100 94 171 0 1195388576 85638128 16789088
02:23:49 825 8192000000 10050290048 10442387456 1094113520 11.782 100 86 155 0 1169971504 60101664 15756320
02:24:51 896 8192000000 10062643584 10454548480 1096446320 11.804 100 90 177 2 1183858144 69959536 17452288
02:25:54 1179 8192000000 10067482448 10451746816 1087566496 11.720 99 95 182 0 1193331904 87257472 18507936
02:26:56 1075 8192000000 10054893232 10445037568 1098343360 11.822 100 96 185 1 1178879520 61374880 19161280
02:27:58 1085 8192000000 10064866752 10457903104 1098472240 11.823 100 96 184 1 1188994256 71319728 19202288
02:29:00 4545 8192000000 10007795616 10390712320 1072645280 11.577 99 73 119 5 1124853344 40939600 11268464
02:30:05 5277 8192000000 9991989072 10376052736 1048439776 11.346 98 63 119 0 1103816464 43856880 11519808
02:31:11 1073 8192000000 9997218208 10377269248 1047314768 11.335 100 77 145 2 1120557184 59306400 13936016
02:32:14 1273 8192000000 9986916432 10364190720 1044861696 11.311 100 87 158 2 1107770976 46624928 16284352
02:33:16 933 8192000000 10008948592 10385600512 1036503856 11.231 100 93 175 2 1131280768 76158416 18618496
02:34:18 1147 8192000000 9985387472 10357669888 1028645456 11.155 99 95 176 1 1105319968 59363328 17311184
02:35:19 974 8192000000 9986593088 10358599680 1040252032 11.267 100 96 188 0 1107667648 47515568 19900048
02:36:21 1107 8192000000 9966154704 10339987456 1029072272 11.160 99 82 156 1 1089853152 44750416 16030464
02:37:24 15479 8192000000 10010167888 10406096896 1064845264 11.503 100 54 105 1 1135758400 60726032 10187104
02:39:18 8311 8192000000 10048477680 10435178496 1083885712 11.684 100 75 143 2 1167217696 69168528 14163456
02:40:34 1737 8192000000 10050866800 10441207808 1094105808 11.782 100 73 135 1 1172026400 64157456 13763136
02:41:39 1527 8192000000 10104211408 10488717312 1105850336 11.893 100 87 166 0 1219733104 96706512 17176256
02:42:42 4840 8192000000 10132288224 10537582592 1113402096 11.965 100 64 122 0 1253896016 127942640 12551280
02:43:59 2375 8192000000 10154175296 10554245120 1116717712 11.996 100 84 162 1 1272817328 139714800 16384816
02:45:06 4195 8192000000 10162662880 10574450688 1128038832 12.103 100 80 156 0 1287041632 142773664 16229136
02:46:12 1382 8192000000 10216043872 10621022208 1149349488 12.303 100 95 186 2 1321848608 153195664 19303456
02:47:16 1239 8192000000 10223815232 10618044416 1145487056 12.267 100 84 167 0 1343084272 178979120 18618096
02:48:21 1854 8192000000 10243567744 10635333632 1152075648 12.329 100 88 171 0 1348208448 176738128 19394672
02:49:26 1289 8192000000 10292786704 10688974848 1159827552 12.402 100 92 180 1 1405691776 225562224 20302000
02:50:32 1071 8192000000 10336858688 10743730176 1166003088 12.459 100 98 186 2 1451266640 264100432 21163120
02:51:39 889 8192000000 10349703344 10756825088 1177013056 12.562 100 94 182 2 1456408448 258821552 20573840
02:52:47 846 8192000000 10389640528 10804510720 1185941280 12.646 100 94 187 0 1500708096 292838752 21928064
02:53:51 1544 8192000000 10409694336 10815303680 1183168416 12.620 100 93 180 0 1518613920 314725904 20719600
02:54:56 1887 8192000000 10426058976 10875953152 1178458864 12.576 100 87 171 0 1534473664 335210768 20804032
02:56:00 786 8192000000 10439880256 10872537088 1179369968 12.584 100 96 187 1 1546525904 345518768 21637168
02:57:03 8016 8192000000 10452928368 10903244800 1189550448 12.679 100 55 110 0 1558668192 354926912 14190832
02:58:13 14290 8192000000 10431041568 10881875968 1181845968 12.607 100 49 96 0 1538126048 343558176 12721904
02:59:30 28160 8192000000 10471171120 10915516416 1179872960 12.589 100 46 90 0 1588600368 396577200 12150208
03:01:03 8835 8192000000 10468092320 10916880384 1167993200 12.478 99 49 98 0 1583561856 402807440 12761216
03:02:18 25614 8192000000 10388456160 10841661440 1156803280 12.373 100 29 57 1 1478644400 314083104 7758016
03:03:45 21278 8192000000 10264446272 10782629888 1146691248 12.278 99 35 70 0 1380574864 225519472 8364144
03:05:10 16393 8192000000 10297106816 10758152192 1150106640 12.310 99 39 76 2 1383798192 224855504 8836048
03:06:38 30066 8192000000 10281512448 10758873088 1138416832 12.201 99 30 61 0 1398836352 252676880 7742640
03:08:38 41333 8192000000 10215573184 10675441664 1150681296 12.316 99 37 68 0 1299150768 140529104 7940368
03:10:21 40881 8192000000 10131441216 10698907648 1148481472 12.295 100 10 20 0 1268494624 117649328 2363824
03:13:24 87898 8192000000 10070419904 10516791296 1110155120 11.934 96 16 32 0 1152543120 39220016 3167984
03:16:45 45541 8192000000 10131454000 10572611584 1151591328 12.324 100 47 82 1 1231659568 69794192 10274048
03:18:34 15662 8192000000 10145852288 10585325568 1157163296 12.377 100 36 72 0 1252792240 88869568 6759376
03:20:44 60898 8192000000 10116689920 10576519168 1119062608 12.018 97 18 36 1 1211579056 88579536 3936912
03:22:48 54509 8192000000 10122610704 10581770240 1141942624 12.234 99 41 77 1 1198955024 48528048 8484352
03:24:48 33943 8192000000 10072670976 10511327232 1129703536 12.119 98 29 58 2 1173013552 35829792 7480224
03:26:27 125271 8192000000 10197133920 10641551360 1133296336 12.152 99 34 64 0 1257135520 115679488 8159696
03:29:43 47002 8192000000 10081304576 10522275840 1134158400 12.161 100 24 46 0 1253628160 114281088 5188672
03:31:30 56275 8192000000 10044546400 10513162240 1084339568 11.689 96 14 28 0 1132154816 44798096 3017152
03:33:27 20038 8192000000 10061738464 10507976704 1120224496 12.029 99 41 77 0 1167386848 39357296 7805056
03:34:49 122453 8192000000 10059436672 10532675584 1105276976 11.888 97 10 18 1 1139221072 32168288 1775808
03:37:52 29760 8192000000 10063634192 10543919104 1134888128 12.167 99 8 16 0 1164437408 28074048 1475232
03:39:22 67587 8192000000 10101706064 10563883008 1136609424 12.184 99 22 44 0 1195356672 54305744 4441504
03:41:43 54769 8192000000 10108740496 10569277440 1122347440 12.049 98 16 32 1 1168722224 42994896 3379888
03:43:39 66480 8192000000 10135623712 10620731392 1118818240 12.016 98 15 28 0 1221203408 99107552 3277616
03:45:48 21163 8192000000 10192080288 10636795904 1149623136 12.306 100 31 62 0 1285008528 128274064 7111328
03:47:13 32248 8192000000 10218600736 10673598464 1158279040 12.387 100 30 58 1 1319524640 154272624 6972976
03:48:46 37205 8192000000 10191800544 10677522432 1134948240 12.168 99 26 52 0 1301779808 160745120 6086448
03:50:32 44329 8192000000 10131821408 10606374912 1147892608 12.290 100 16 32 0 1268898592 117262704 3743280
03:52:28 40046 8192000000 10085436032 10525286400 1103943104 11.875 97 33 66 0 1151161872 40460928 6757840
03:54:17 10595 8192000000 10135155584 10560057344 1138748416 12.204 100 69 135 0 1217814096 65543760 13521920
03:55:30 75811 8192000000 10146234800 10646425600 1140107696 12.217 100 47 94 0 1265214704 115135824 9971184
03:57:53 11772 8192000000 10066724544 10496274432 1132403824 12.144 99 48 93 0 1168636112 27536848 8695440
03:59:06 48581 8192000000 10085496720 10550956032 1120470288 12.031 99 27 54 0 1187325520 61428128 5427104
04:00:56 68267 8192000000 10087274624 10534293504 1064876784 11.503 94 18 36 0 1143158608 74447184 3834640
04:03:05 79172 8192000000 10091148720 10550931456 1122967280 12.055 100 21 42 0 1201680736 74135136 4578320
04:05:24 42892 8192000000 10040488608 10484129792 1101684928 11.854 98 29 50 1 1133073136 26324112 5064096
04:07:09 25207 8192000000 10108525760 10558271488 1115887584 11.988 98 36 71 0 1205602800 82026656 7688560
04:08:49 16193 8192000000 10145764208 10579439616 1127910128 12.102 99 35 70 1 1223107008 87494048 7702832
04:10:16 33414 8192000000 10148796192 10632691712 1122679824 12.052 98 23 45 0 1257227424 129976656 4570944
04:11:57 32338 8192000000 10091439536 10524438528 1140668352 12.222 100 36 64 1 1178773264 31275200 6829712
04:13:32 38653 8192000000 10114381392 10565648384 1119322880 12.021 98 35 68 0 1190858096 65305952 6229264
04:15:14 50382 8192000000 10119329104 10568798208 1135510816 12.173 100 35 68 1 1191893472 49390512 6992144
04:17:05 4471 8192000000 10095384176 10516389888 1141870080 12.233 100 63 116 0 1182872816 28905072 12097664
04:18:12 36055 8192000000 10116255856 10562064384 1107622976 11.910 97 52 104 3 1207379360 89801776 9954608
04:19:59 42252 8192000000 10054463648 10489143296 1113491392 11.965 98 39 78 0 1145249520 23397088 8361040
04:21:46 19591 8192000000 10111525472 10533445632 1129681616 12.118 99 72 144 2 1207780672 63315776 14783280
04:23:09 45182 8192000000 10112102976 10591203328 1138777008 12.204 100 21 42 0 1242403264 98990336 4635920
04:24:55 42930 8192000000 10098221344 10559565824 1117261648 12.001 98 10 20 0 1212319920 92772928 2285344
04:26:39 30713 8192000000 10081706704 10562207744 1140819360 12.223 100 12 24 0 1196175984 52878416 2478208
04:28:10 32174 8192000000 10083533312 10525237248 1113996080 11.970 97 29 58 0 1159900816 39869456 6035280
04:29:47 38733 8192000000 10124980176 10572005376 1138607968 12.202 99 31 60 0 1221767248 76370464 6788816
04:31:31 36181 8192000000 10111887536 10565984256 1117352528 12.002 98 43 84 0 1221219888 95169072 8698288
04:33:20 56471 8192000000 10145406048 10586173440 1116063296 11.990 98 23 44 0 1209364000 88357984 4942720
04:35:18 56339 8192000000 10126740512 10598633472 1119562960 12.023 98 22 44 0 1228559680 103504240 5492480
04:37:24 13423 8192000000 8880681136 9089974272 38843184 0.471 4 1 2 0 41367168 2488576 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 07-03-2023 04:38:20 to 07-03-2023 04:38:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-03-2023 04:38:38 to 07-03-2023 04:38:40.

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          227147
MON$OLDEST_ACTIVE               227148
MON$OLDEST_SNAPSHOT             227148
MON$NEXT_TRANSACTION            227148
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                       2073760
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             784
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'

07.03.23 04:38:41. 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-07 04:38:43 No severe PSQL-related problems occured
07.03.23 04:38:45. End of report.