Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.01.2023 13: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 14975592 kB
MemAvailable 30392420 kB
Buffers 0 kB
Cached 16710336 kB
SwapCached 1588 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-01-25 13:37:24
DTS_END                         2023-01-25 13:37:24.4740
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/55770
TRN_ID                          224524
ATT_ID                          613
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: 9125.21 24367 1095025
customer order: creation 181.21 484 21745
customer order: refuse 15.23 463 1827
order to supplier: creation 46.83 1334 5620
order to supplier: removal 3.45 178136 414
invoice (draft): creation 49.02 3225 5882
invoice (draft): removal 48.58 1045 5829
invoice accept: apply 43.75 26941 5250
invoice accept: cancel 43.91 1398 5269
customer reserve: creation 4098.19 252 491783
customer reserve: removal 4300.05 114 516006
realization accept: apply 85.98 298 10318
realization accept: cancel 74.34 202 8921
payment from customer: creation 61.54 181 7385
payment from customer: removal 5.70 129 684
payment to supplier: creation 50.47 199 6056
payment to supplier: removal 3.40 150 408
service: total inventory turnovers 4.43 4114 532
service: total monetary turnovers 8.18 367 982
service: aggregate perf. data 0.92 15672 110
service: refresh index statistics 0.03 252631 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-01-2023 13:38:39 to 25-01-2023 13:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8288
TEST_TIME 2 6412
TEST_TIME 3 5470
TEST_TIME 4 6398
TEST_TIME 5 6610
TEST_TIME 6 6309
TEST_TIME 7 5852
TEST_TIME 8 5837
TEST_TIME 9 5852
TEST_TIME 10 6127
TEST_TIME 11 6224
TEST_TIME 12 6252
TEST_TIME 13 6305
TEST_TIME 14 6434
TEST_TIME 15 6789
TEST_TIME 16 6943
TEST_TIME 17 7001
TEST_TIME 18 7029
TEST_TIME 19 7194
TEST_TIME 20 7404
TEST_TIME 21 7662
TEST_TIME 22 7694
TEST_TIME 23 7807
TEST_TIME 24 7762
TEST_TIME 25 8227
TEST_TIME 26 8428
TEST_TIME 27 8687
TEST_TIME 28 8590
TEST_TIME 29 8618
TEST_TIME 30 8713
TEST_TIME 31 8617
TEST_TIME 32 8714
TEST_TIME 33 9081
TEST_TIME 34 9183
TEST_TIME 35 9693
TEST_TIME 36 10265
TEST_TIME 37 10649
TEST_TIME 38 10443
TEST_TIME 39 10370
TEST_TIME 40 10266
TEST_TIME 41 10072
TEST_TIME 42 9990
TEST_TIME 43 10008
TEST_TIME 44 9918
TEST_TIME 45 9757
TEST_TIME 46 9701
TEST_TIME 47 9734
TEST_TIME 48 9675
TEST_TIME 49 9688
TEST_TIME 50 9708
TEST_TIME 51 9636
TEST_TIME 52 9564
TEST_TIME 53 9579
TEST_TIME 54 9507
TEST_TIME 55 9403
TEST_TIME 56 9355
TEST_TIME 57 9384
TEST_TIME 58 9373
TEST_TIME 59 9402
TEST_TIME 60 9453
TEST_TIME 61 9468
TEST_TIME 62 9424
TEST_TIME 63 9492
TEST_TIME 64 9449
TEST_TIME 65 9576
TEST_TIME 66 9544
TEST_TIME 67 9514
TEST_TIME 68 9539
TEST_TIME 69 9576
TEST_TIME 70 9580
TEST_TIME 71 9455
TEST_TIME 72 9425
TEST_TIME 73 9349
TEST_TIME 74 9374
TEST_TIME 75 9337
TEST_TIME 76 9256
TEST_TIME 77 9178
TEST_TIME 78 9201
TEST_TIME 79 9226
TEST_TIME 80 9202
TEST_TIME 81 9205
TEST_TIME 82 9161
TEST_TIME 83 9152
TEST_TIME 84 9117
TEST_TIME 85 9260
TEST_TIME 86 9290
TEST_TIME 87 9319
TEST_TIME 88 9359
TEST_TIME 89 9318
TEST_TIME 90 9311
TEST_TIME 91 9266
TEST_TIME 92 9324
TEST_TIME 93 9263
TEST_TIME 94 9217
TEST_TIME 95 9188
TEST_TIME 96 9141
TEST_TIME 97 9149
TEST_TIME 98 9118
TEST_TIME 99 9098
TEST_TIME 100 9092
TEST_TIME 101 9066
TEST_TIME 102 9038
TEST_TIME 103 9065
TEST_TIME 104 9054
TEST_TIME 105 9040
TEST_TIME 106 9003
TEST_TIME 107 9054
TEST_TIME 108 9092
TEST_TIME 109 9122
TEST_TIME 110 9223
TEST_TIME 111 9189
TEST_TIME 112 9174
TEST_TIME 113 9301
TEST_TIME 114 9272
TEST_TIME 115 9311
TEST_TIME 116 9300
TEST_TIME 117 9261
TEST_TIME 118 9258
TEST_TIME 119 9217
TEST_TIME 120 9151

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-01-2023 13:38:43 to 25-01-2023 13:38:46.

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 1545583 1545583 0 0.00 0 25580 15 0 0
doc_list_biud 1545580 1545580 0 0.00 0 34429 44 0 0
sp_add_invoice_to_stock 5357 5250 107 1.99 66 189605 26941 0 107
sp_cancel_adding_invoice 5269 5269 0 0.00 7 114949 1399 0 0
sp_cancel_client_order 1827 1827 0 0.00 6 18859 463 0 0
sp_cancel_customer_reserve 516006 516006 0 0.00 1 34433 115 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 4 6591 129 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 8140 151 0 0
sp_cancel_supplier_invoice 5845 5829 16 0.27 3 26282 1045 0 16
sp_cancel_supplier_order 414 414 0 0.00 12 1376784 178136 0 0
sp_cancel_write_off 8921 8921 0 0.00 2 9363 202 0 0
sp_client_order 21745 21745 0 0.00 6 56093 484 0 0
sp_customer_reserve 491783 491783 0 0.00 0 117984 253 0 0
sp_fill_shopping_cart 33241 33241 0 0.00 1 25320 87 0 0
sp_fill_shopping_cart_clo_res 491758 491758 0 0.00 0 13252 17 0 0
sp_get_clo_for_invoice 5203 5203 0 0.00 0 42996 265 0 0
sp_kill_cost_storno 15282 15282 0 0.00 0 19192 80 0 0
sp_kill_qstorno_ret_qs2qd 1047176 1047176 0 0.00 0 34426 50 0 0
sp_kill_qty_storno 553786 553786 0 0.00 0 34429 114 0 0
sp_lock_selected_doc 21448 21448 0 0.00 0 6579 7 0 0
sp_make_cost_storno 28961 28961 0 0.00 0 25189 83 0 0
sp_multiply_rows_for_pdistr 28961 28961 0 0.00 0 18042 39 0 0
sp_multiply_rows_for_qdistr 21745 21745 0 0.00 0 12125 163 0 0
sp_pay_from_customer 7385 7385 0 0.00 5 12043 181 0 0
sp_pay_to_supplier 6056 6056 0 0.00 6 24712 199 0 0
sp_payment_common 13441 13441 0 0.00 2 8417 50 0 0
sp_qd_handle_on_cancel_clo 1827 1827 0 0.00 0 18834 388 0 0
sp_qd_handle_on_invoice_upd_sts 10471 10471 0 0.00 0 15182 563 0 0
sp_qd_handle_on_reserve_upd_sts 10318 10318 0 0.00 0 22706 71 0 0
sp_reserve_write_off 10318 10318 0 0.00 8 25787 299 0 0
sp_supplier_invoice 5882 5882 0 0.00 11 89157 3226 0 0
sp_supplier_order 5620 5620 0 0.00 7 30482 1335 0 0
srv_aggregate_perf_data 110 110 0 0.00 2585 162283 15673 0 0
srv_fill_mon_cache_memory 76 76 0 0.00 779 126560 28817 0 0
srv_find_qd_qs_mism 1042426 1042426 0 0.00 0 25578 12 0 0
srv_make_invnt_saldo 532 532 0 0.00 48 76039 4114 0 0
srv_make_money_saldo 982 982 0 0.00 1 12766 367 0 0
srv_recalc_idx_stat 4 4 0 0.00 51503 477288 252632 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5253 5253 0 0.00 0 39631 1032 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 1 7627 526 0 0
t$perf-norm:sp_cancel_client_order 1827 1827 0 0.00 0 2422 29 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 3725 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 3799 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 341 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 619 36 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 104254 7280 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 2175 22 0 0
t$perf-norm:sp_client_order 21745 21745 0 0.00 0 17948 43 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 5647 20 0 0
t$perf-norm:sp_pay_from_customer 7385 7385 0 0.00 0 25239 23 0 0
t$perf-norm:sp_pay_to_supplier 6056 6056 0 0.00 0 29594 27 0 0
t$perf-norm:sp_reserve_write_off 10318 10318 0 0.00 0 18559 26 0 0
t$perf-norm:sp_supplier_invoice 5882 5882 0 0.00 0 6309 37 0 0
t$perf-norm:sp_supplier_order 5620 5620 0 0.00 0 6112 35 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 360 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 515 17 0 0
t$perf-norm:srv_make_invnt_saldo 532 532 0 0.00 0 6117 29 0 0
t$perf-norm:srv_make_money_saldo 982 982 0 0.00 0 1805 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 187 62 0 0
v_add_invoice_to_stock 10618 10404 214 2.01 0 5555 24 0 214
v_all_customers 39420 39420 0 0.00 0 130 4 0 0
v_all_suppliers 31908 31908 0 0.00 0 85 5 0 0
v_all_wares 43490 43490 0 0.00 0 189 4 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 1785 18 0 0
v_cancel_client_order 3654 3654 0 0.00 0 5118 13 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 491 11 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 519 11 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 4000 18 0 0
v_cancel_supplier_invoice 860 828 32 3.72 0 5267 31 0 32
v_cancel_supplier_order 828 828 0 0.00 0 4793 17 0 0
v_cancel_write_off 2078 2078 0 0.00 0 1709 26 0 0
v_max_id_clo_ord 5620 5620 0 0.00 0 1374 6 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 2476 14 0 0
v_max_id_ord_sup 5876 5876 0 0.00 0 5940 8 0 0
v_max_non_paid_invoice 1598 1598 0 0.00 0 529 9 0 0
v_max_non_paid_realizn 3914 3914 0 0.00 0 920 8 0 0
v_min_id_clo_ord 5620 5620 0 0.00 0 4107 9 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 7025 19 0 0
v_min_id_ord_sup 5876 5876 0 0.00 0 9568 16 0 0
v_min_non_paid_invoice 6056 6056 0 0.00 0 7334 20 0 0
v_min_non_paid_realizn 7385 7385 0 0.00 0 8396 19 0 0
v_our_firm 11012 11012 0 0.00 0 126 5 0 0
v_reserve_write_off 20636 20636 0 0.00 0 25665 14 0 0
x_lock_depdocs_on_canc_invoice 10684 10684 0 0.00 0 9547 120 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 4186 340 0 0
x_make_qstorno_1000_1200 5620 5620 0 0.00 3 30440 1157 0 0
x_make_qstorno_1200_2000 5882 5882 0 0.00 5 88583 2987 0 0
x_make_qstorno_2100_3300 491783 491783 0 0.00 0 117970 223 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 25-01-2023 13:38:47 to 25-01-2023 13:38:52.

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
11:07:28 3 8192000000 8799910112 8811700224 5583376 0.068 2 2 2 0 5710928 91776 35776
11:08:29 29415 8192000000 9729616160 10026405888 799109440 8.887 100 88 176 0 828095696 7283824 21702432
11:10:05 17491 8192000000 9766482032 10074107904 843204176 9.332 100 92 184 0 876364528 11649952 21510400
11:11:31 17324 8192000000 9802701712 10125230080 875859760 9.658 100 77 154 0 914076368 21083872 17132736
11:13:00 10810 8192000000 9857505376 10190520320 923262496 10.128 100 83 164 0 970580032 29698928 17618608
11:14:15 7729 8192000000 9931614320 10273423360 961522272 10.504 100 88 172 0 1037573056 58811616 17239168
11:15:25 3327 8192000000 9955831088 10309332992 986295760 10.745 100 87 171 0 1080085440 75945568 17844112
11:16:32 1969 8192000000 9972964192 10331938816 1016417696 11.037 100 93 173 1 1100712560 66579648 17715216
11:17:36 1721 8192000000 10021578640 10385481728 1036141936 11.228 100 97 189 1 1145151184 90552576 18456672
11:18:40 942 8192000000 10021276528 10390855680 1043577280 11.299 100 97 185 0 1140974832 78173872 19223680
11:19:43 866 8192000000 10007008144 10381561856 1055298800 11.411 100 91 179 0 1128242720 55933248 17010672
11:20:46 1113 8192000000 10042023392 10416324608 1065132032 11.506 100 94 186 3 1163125776 79397744 18596000
11:21:49 1502 8192000000 10030113552 10412261376 1067414624 11.527 100 94 179 1 1158414736 71873072 19127040
11:22:53 1128 8192000000 10048622512 10432958464 1072007888 11.571 100 97 186 1 1171516480 80478256 19030336
11:23:56 1206 8192000000 10029693040 10415730688 1082685680 11.673 100 77 145 0 1157749056 60963728 14099648
11:24:59 1370 8192000000 10056492448 10448576512 1085491824 11.700 100 89 172 0 1180485072 77472624 17520624
11:26:03 1080 8192000000 10048038032 10437111808 1093551984 11.776 100 96 182 2 1170606960 57536496 19518480
11:27:05 1093 8192000000 10064446000 10452652032 1087858544 11.722 99 95 184 0 1186973568 80453472 18661552
11:28:07 3947 8192000000 10050420752 10443542528 1096885728 11.808 100 67 133 0 1176079552 66101552 13092272
11:29:13 4736 8192000000 10047712288 10433929216 1092157968 11.763 100 88 152 4 1164399264 58250480 13990816
11:30:19 1203 8192000000 10044336992 10434613248 1089347728 11.736 100 84 154 1 1167850816 63310560 15192528
11:31:23 9424 8192000000 10036430080 10428510208 1089103568 11.734 100 70 123 2 1170697456 69294256 12299632
11:32:33 988 8192000000 10033452032 10414166016 1068859056 11.541 100 92 179 2 1156247952 68455936 18932960
11:33:36 1200 8192000000 9999557088 10376589312 1045845136 11.321 100 89 167 4 1122413536 59947120 16621280
11:34:38 2828 8192000000 9987120496 10365829120 1042439200 11.288 99 77 145 0 1116617088 59753888 14424000
11:35:42 2453 8192000000 10005030496 10386210816 1048606496 11.347 100 73 141 0 1131387648 68718720 14062432
11:36:46 1215 8192000000 9985120624 10357805056 1022627008 11.097 100 87 168 3 1107083056 65878368 18577680
11:37:50 1510 8192000000 10003929840 10385260544 1051717600 11.377 100 96 177 0 1125469344 54947408 18804336
11:39:02 1205 8192000000 10032699568 10412924928 1067925808 11.532 100 92 172 2 1150973424 65131472 17916144
11:40:12 22837 8192000000 10051508288 10440273920 1073076528 11.581 100 62 122 0 1159116368 73680000 12359840
11:41:39 8431 8192000000 10055679936 10453905408 1090342352 11.746 100 70 137 1 1185197968 80749648 14105968
11:42:51 7888 8192000000 10059643664 10457620480 1098199680 11.821 100 59 110 1 1179622544 69703120 11719744
11:44:04 4845 8192000000 10090057408 10485424128 1117677744 12.005 100 69 134 0 1207095376 75825376 13592256
11:45:13 2145 8192000000 10162834464 10557202432 1123244464 12.058 100 77 141 1 1268814032 130192608 15376960
11:46:20 1306 8192000000 10168428336 10565173248 1130572880 12.127 100 89 175 1 1287575424 137239040 19763504
11:47:37 1019 8192000000 10209740560 10607763456 1130468208 12.126 99 94 186 1 1322680944 171560240 20652496
11:48:42 1627 8192000000 10227597568 10629677056 1155491680 12.361 100 82 161 0 1332482928 159879808 17111440
11:49:49 5003 8192000000 10278705552 10688937984 1160874816 12.411 100 78 152 0 1390238992 211178096 18186080
11:51:05 1636 8192000000 10273582656 10687504384 1174757888 12.541 100 92 172 1 1384923152 191449936 18715328
11:52:09 986 8192000000 10303724768 10717892608 1172120784 12.517 100 95 191 2 1415211056 220392944 22697328
11:53:14 1134 8192000000 10360041600 10763866112 1180620816 12.596 100 93 178 1 1468271088 267294272 20356000
11:54:19 1553 8192000000 10376055696 10782703616 1182064160 12.609 100 94 186 0 1485203648 281134864 22004624
11:55:22 1469 8192000000 10413755200 10821758976 1187665168 12.662 100 90 170 0 1518436944 311336432 19435344
11:56:27 961 8192000000 10412458448 10839814144 1190345664 12.687 100 97 184 0 1519598592 307944480 21308448
11:57:30 1090 8192000000 10425985856 10866835456 1188870528 12.673 100 92 180 1 1534097760 323367056 21860176
11:58:35 1142 8192000000 10436818016 10858156032 1187787904 12.663 100 91 177 0 1539943344 330309456 21845984
11:59:39 767 8192000000 10472892288 10888417280 1182799840 12.616 100 96 189 2 1584985072 378458240 23726992
12:00:41 1152 8192000000 10493707392 10913484800 1173584128 12.530 100 95 189 1 1598387680 401437488 23366064
12:01:45 940 8192000000 10451843120 10926034944 1156611120 12.372 99 99 187 0 1556410128 376672608 23126400
12:02:49 2320 8192000000 10432041424 10886434816 1181194464 12.601 100 94 188 0 1535370368 331650896 22525008
12:03:54 1120 8192000000 10474014560 10940026880 1191070848 12.693 100 85 168 0 1577409664 366763840 19574976
12:05:00 14393 8192000000 10452596480 10895114240 1170790032 12.504 99 61 112 0 1548155008 362660176 14704800
12:06:25 6766 8192000000 10487582112 10918219776 1172897568 12.524 100 46 90 0 1584041600 398912240 12231792
12:07:59 15512 8192000000 10492083472 10958913536 1178226112 12.574 100 39 77 0 1591942640 403704768 10011760
12:09:21 8721 8192000000 10528312016 10992914432 1172563664 12.521 100 46 92 1 1643496768 459201216 11731888
12:10:31 40694 8192000000 10433416784 10928852992 1170395616 12.501 100 28 56 0 1542220064 364354112 7470336
12:12:14 12666 8192000000 10373773936 10858688512 1160258464 12.406 100 29 55 0 1488117440 321028736 6830240
12:13:35 47029 8192000000 10081960896 10579378176 1086245152 11.707 95 10 18 0 1173580080 84348240 2986688
12:15:23 7631 8192000000 10039036064 10459693056 1111452768 11.946 99 52 93 3 1131330208 10799088 9078352
12:17:01 10486 8192000000 10029355264 10444320768 1097336864 11.812 99 76 142 3 1127112880 14391936 15384080
12:18:13 49807 8192000000 10036715744 10468429824 1111850656 11.950 100 31 58 0 1141118336 23046096 6221584
12:20:07 42262 8192000000 10047178400 10478956544 1076570064 11.615 95 37 66 2 1103504976 20882208 6052704
12:21:55 32792 8192000000 10058277072 10496110592 1108374800 11.917 99 16 30 0 1147680320 36019760 3285760
12:23:32 2402 8192000000 10107616816 10526556160 1119386176 12.021 100 75 150 0 1200194736 65264272 15544288
12:24:38 8241 8192000000 10093280304 10511650816 1108526496 11.918 99 64 124 0 1192594400 71122864 12945040
12:25:48 13801 8192000000 10122418288 10546630656 1116633344 11.995 100 57 106 0 1223137552 95370096 11134112
12:27:02 98647 8192000000 10081270400 10522206208 1112150592 11.953 99 19 37 0 1180571840 64428336 3992912
12:29:42 50908 8192000000 10091035472 10544979968 1131839040 12.139 100 18 33 0 1193898064 58450320 3608704
12:31:46 52035 8192000000 10052544016 10526396416 1134102880 12.160 100 28 53 0 1182184496 42065216 6016400
12:33:38 63629 8192000000 10119502320 10602692608 1145130016 12.264 100 20 40 0 1225853552 76359648 4363888
12:35:45 46917 8192000000 10138126656 10612654080 1117148416 12.000 98 17 32 0 1222772496 101911424 3712656
12:37:34 18497 8192000000 10092168848 10532003840 1150250896 12.312 100 32 64 0 1182172048 25523984 6397168
12:38:53 30961 8192000000 10087739840 10533654528 1145127744 12.264 100 20 40 0 1199329104 49984208 4217152
12:40:24 39258 8192000000 10118070768 10588241920 1126493104 12.088 98 29 58 0 1185870528 53363424 6014000
12:42:28 52659 8192000000 10083592976 10518188032 1101713344 11.854 96 31 57 0 1151145424 43710032 5722048
12:44:26 6474 8192000000 10097075920 10525294592 1135717408 12.175 100 52 104 0 1189413072 43535696 10159968
12:45:33 31292 8192000000 10140903104 10596642816 1112704000 11.958 98 50 94 0 1205307504 83579296 9024208
12:47:08 126559 8192000000 10109285088 10596843520 1140599312 12.221 100 37 73 0 1197043792 48661136 7783344
12:50:38 16563 8192000000 10120197152 10558832640 1140795552 12.223 100 43 83 0 1208772016 59698432 8278032
12:51:56 70509 8192000000 10069269872 10540244992 1114804416 11.978 98 9 18 0 1182149936 65541680 1803840
12:54:16 94824 8192000000 10096190720 10600054784 1120050848 12.027 99 12 24 0 1181960080 59688192 2221040
12:56:51 52352 8192000000 10052314544 10497495040 1055993904 11.418 94 17 34 0 1123838768 64113440 3731424
12:58:44 40287 8192000000 10095240944 10534080512 1130333232 12.125 100 25 49 0 1198045584 62388464 5323888
13:00:27 39101 8192000000 10162691840 10629574656 1123539696 12.060 98 24 48 1 1248406096 118940128 5926272
13:02:14 61197 8192000000 10125773568 10564202496 1111114048 11.943 98 27 54 0 1249916896 132991616 5811232
13:04:16 19451 8192000000 10100756464 10561761280 1119410480 12.021 99 28 56 0 1175850992 50721184 5719328
13:05:42 36653 8192000000 10073188384 10489655296 1095944704 11.799 97 41 79 0 1126401376 22753968 7702704
13:07:20 38945 8192000000 10158407920 10606071808 1074616368 11.596 95 38 76 0 1206264416 123988880 7659168
13:09:12 96290 8192000000 10045856864 10499108864 1101615552 11.853 98 25 50 0 1139900800 33215136 5070112
13:12:04 66850 8192000000 10071400688 10526199808 1116484160 11.994 99 27 52 0 1182653808 61441264 4728384
13:15:00 39850 8192000000 10091810752 10558316544 1127261088 12.096 99 26 52 0 1178086192 44920432 5904672
13:16:46 36496 8192000000 10143074192 10593583104 1147179024 12.283 100 34 66 1 1227029856 72294032 7556800
13:18:25 19168 8192000000 10198892736 10630389760 1155883840 12.365 100 51 100 1 1266157344 99477072 10796432
13:19:45 34731 8192000000 10208384384 10667642880 1165389056 12.454 100 39 78 1 1314481232 140389552 8702624
13:21:26 28421 8192000000 10222399584 10671329280 1149054208 12.301 98 32 62 0 1293753008 137480224 7218576
13:23:00 20975 8192000000 10272516592 10713149440 1168879968 12.486 99 49 96 1 1348928784 168990288 11058528
13:24:24 14283 8192000000 10282588480 10716270592 1169005584 12.488 100 42 84 1 1375606544 196174688 10426272
13:25:43 21613 8192000000 10285080528 10783952896 1164858176 12.449 100 29 56 0 1393670320 221570480 7241664
13:27:15 60157 8192000000 10207064800 10696704000 1116524192 11.994 97 9 18 0 1279966160 161291424 2150544
13:29:18 85859 8192000000 10117530368 10627874816 1133074880 12.150 99 28 56 0 1264594368 125072464 6447024
13:31:44 88093 8192000000 10088003376 10590408704 1134876928 12.167 99 10 20 0 1210952656 73894768 2180960
13:34:23 70821 8192000000 10119281904 10557833216 1142989488 12.244 99 39 72 0 1187775776 37759168 7027120
13:36:42 31171 8192000000 10091343808 10517958656 1120449680 12.031 98 55 105 0 1166110288 34891536 10769072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 25-01-2023 13:38:54 to 25-01-2023 13:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-01-2023 13:39:12 to 25-01-2023 13:39:14.

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          221569
MON$OLDEST_ACTIVE               224677
MON$OLDEST_SNAPSHOT             224677
MON$NEXT_TRANSACTION            224677
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                       2071712
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2891 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2891 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2891 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2891 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 ---
:100: 
:101: mscs-test-161.budzdorov.ru    Wed Jan 25 13:37:26 2023
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:103: 
--- end of diff output ---

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

25.01.23 13:39:15. 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-01-25 13:39:17 No severe PSQL-related problems occured
25.01.23 13:39:19. End of report.