Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.03.2023 13:39
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 14880112 kB
MemAvailable 30444840 kB
Buffers 0 kB
Cached 16843324 kB
SwapCached 3172 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-17 13:37:28
DTS_END                         2023-03-17 13:37:28.2400
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/49370
TRN_ID                          216931
ATT_ID                          637
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: 9257.01 29611 1110841
customer order: creation 166.09 461 19931
customer order: refuse 14.72 471 1766
order to supplier: creation 45.03 1376 5404
order to supplier: removal 3.69 173998 443
invoice (draft): creation 45.85 3097 5502
invoice (draft): removal 51.63 1109 6196
invoice accept: apply 41.82 29389 5018
invoice accept: cancel 46.68 1394 5602
customer reserve: creation 3977.07 274 477248
customer reserve: removal 4573.79 110 548855
realization accept: apply 81.51 291 9781
realization accept: cancel 81.58 190 9789
payment from customer: creation 56.95 174 6834
payment from customer: removal 5.67 150 680
payment to supplier: creation 48.73 204 5848
payment to supplier: removal 3.63 130 435
service: total inventory turnovers 3.89 4946 467
service: total monetary turnovers 7.69 434 923
service: aggregate perf. data 0.95 16551 114
service: refresh index statistics 0.04 357472 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-03-2023 13:39:48 to 17-03-2023 13:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5492
TEST_TIME 2 4669
TEST_TIME 3 4733
TEST_TIME 4 5127
TEST_TIME 5 5500
TEST_TIME 6 5600
TEST_TIME 7 5437
TEST_TIME 8 5276
TEST_TIME 9 5522
TEST_TIME 10 5948
TEST_TIME 11 6330
TEST_TIME 12 6516
TEST_TIME 13 6642
TEST_TIME 14 6858
TEST_TIME 15 6828
TEST_TIME 16 6814
TEST_TIME 17 6923
TEST_TIME 18 6902
TEST_TIME 19 6808
TEST_TIME 20 6917
TEST_TIME 21 7006
TEST_TIME 22 7175
TEST_TIME 23 7394
TEST_TIME 24 7507
TEST_TIME 25 7510
TEST_TIME 26 7777
TEST_TIME 27 7943
TEST_TIME 28 8105
TEST_TIME 29 8273
TEST_TIME 30 9071
TEST_TIME 31 9150
TEST_TIME 32 9137
TEST_TIME 33 9170
TEST_TIME 34 9478
TEST_TIME 35 9613
TEST_TIME 36 9961
TEST_TIME 37 10302
TEST_TIME 38 10585
TEST_TIME 39 10812
TEST_TIME 40 10654
TEST_TIME 41 10602
TEST_TIME 42 10516
TEST_TIME 43 10399
TEST_TIME 44 10545
TEST_TIME 45 10543
TEST_TIME 46 10378
TEST_TIME 47 10296
TEST_TIME 48 10179
TEST_TIME 49 10074
TEST_TIME 50 10110
TEST_TIME 51 10045
TEST_TIME 52 9926
TEST_TIME 53 9871
TEST_TIME 54 9863
TEST_TIME 55 9823
TEST_TIME 56 9739
TEST_TIME 57 9740
TEST_TIME 58 9663
TEST_TIME 59 9602
TEST_TIME 60 9685
TEST_TIME 61 9570
TEST_TIME 62 9763
TEST_TIME 63 9870
TEST_TIME 64 9796
TEST_TIME 65 9853
TEST_TIME 66 9760
TEST_TIME 67 9915
TEST_TIME 68 9861
TEST_TIME 69 9868
TEST_TIME 70 9834
TEST_TIME 71 9807
TEST_TIME 72 9831
TEST_TIME 73 9845
TEST_TIME 74 9837
TEST_TIME 75 9777
TEST_TIME 76 9680
TEST_TIME 77 9647
TEST_TIME 78 9567
TEST_TIME 79 9528
TEST_TIME 80 9546
TEST_TIME 81 9533
TEST_TIME 82 9527
TEST_TIME 83 9492
TEST_TIME 84 9425
TEST_TIME 85 9412
TEST_TIME 86 9487
TEST_TIME 87 9478
TEST_TIME 88 9483
TEST_TIME 89 9492
TEST_TIME 90 9480
TEST_TIME 91 9496
TEST_TIME 92 9529
TEST_TIME 93 9569
TEST_TIME 94 9549
TEST_TIME 95 9598
TEST_TIME 96 9577
TEST_TIME 97 9566
TEST_TIME 98 9499
TEST_TIME 99 9480
TEST_TIME 100 9473
TEST_TIME 101 9441
TEST_TIME 102 9396
TEST_TIME 103 9353
TEST_TIME 104 9307
TEST_TIME 105 9338
TEST_TIME 106 9307
TEST_TIME 107 9284
TEST_TIME 108 9273
TEST_TIME 109 9307
TEST_TIME 110 9293
TEST_TIME 111 9268
TEST_TIME 112 9266
TEST_TIME 113 9260
TEST_TIME 114 9211
TEST_TIME 115 9365
TEST_TIME 116 9419
TEST_TIME 117 9396
TEST_TIME 118 9388
TEST_TIME 119 9361
TEST_TIME 120 9288

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-03-2023 13:39:52 to 17-03-2023 13:39:55.

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 1547166 1547166 0 0.00 0 19562 15 0 0
doc_list_biud 1547161 1547161 0 0.00 0 45136 45 0 0
sp_add_invoice_to_stock 5128 5018 110 2.14 55 220555 29389 0 110
sp_cancel_adding_invoice 5602 5602 0 0.00 7 92501 1394 0 0
sp_cancel_client_order 1766 1766 0 0.00 7 26167 472 0 0
sp_cancel_customer_reserve 548855 548855 0 0.00 1 21395 110 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 4 19046 151 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 4 2345 131 0 0
sp_cancel_supplier_invoice 6205 6196 9 0.14 4 13559 1109 0 9
sp_cancel_supplier_order 443 443 0 0.00 9 1560387 173999 0 0
sp_cancel_write_off 9789 9789 0 0.00 2 10625 190 0 0
sp_client_order 19931 19931 0 0.00 5 26749 462 0 0
sp_customer_reserve 477248 477248 0 0.00 1 138463 274 0 0
sp_fill_shopping_cart 30832 30832 0 0.00 1 9022 84 0 0
sp_fill_shopping_cart_clo_res 477216 477216 0 0.00 0 17664 19 0 0
sp_get_clo_for_invoice 4976 4976 0 0.00 0 40586 276 0 0
sp_kill_cost_storno 16506 16506 0 0.00 0 13004 86 0 0
sp_kill_qstorno_ret_qs2qd 1114138 1114138 0 0.00 0 21354 48 0 0
sp_kill_qty_storno 587407 587407 0 0.00 0 45135 110 0 0
sp_lock_selected_doc 20559 20559 0 0.00 0 11835 9 0 0
sp_make_cost_storno 27438 27438 0 0.00 0 19558 79 0 0
sp_multiply_rows_for_pdistr 27437 27437 0 0.00 0 7997 40 0 0
sp_multiply_rows_for_qdistr 19931 19931 0 0.00 0 14409 178 0 0
sp_pay_from_customer 6834 6834 0 0.00 5 26473 175 0 0
sp_pay_to_supplier 5848 5848 0 0.00 7 25835 204 0 0
sp_payment_common 12681 12681 0 0.00 2 7181 44 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 0 11679 369 0 0
sp_qd_handle_on_invoice_upd_sts 10577 10577 0 0.00 0 45133 578 0 0
sp_qd_handle_on_reserve_upd_sts 9781 9781 0 0.00 0 13152 67 0 0
sp_reserve_write_off 9781 9781 0 0.00 8 26596 291 0 0
sp_supplier_invoice 5502 5502 0 0.00 11 107679 3098 0 0
sp_supplier_order 5404 5404 0 0.00 7 41718 1376 0 0
srv_aggregate_perf_data 114 114 0 0.00 2971 209962 16552 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 695 114218 31036 0 0
srv_find_qd_qs_mism 1060397 1060397 0 0.00 0 18439 12 0 0
srv_make_invnt_saldo 467 467 0 0.00 50 184065 4946 0 0
srv_make_money_saldo 923 923 0 0.00 1 12109 434 0 0
srv_recalc_idx_stat 5 5 0 0.00 215924 512155 357472 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 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 5020 5020 0 0.00 0 15438 1023 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 1 6866 471 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 3149 37 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 1439 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 3647 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1218 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 4748 49 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 101974 7620 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 3943 27 0 0
t$perf-norm:sp_client_order 19931 19931 0 0.00 0 4096 37 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 4487 26 0 0
t$perf-norm:sp_pay_from_customer 6834 6834 0 0.00 0 10679 23 0 0
t$perf-norm:sp_pay_to_supplier 5848 5848 0 0.00 0 6787 20 0 0
t$perf-norm:sp_reserve_write_off 9781 9781 0 0.00 0 4656 24 0 0
t$perf-norm:sp_supplier_invoice 5502 5502 0 0.00 0 5885 32 0 0
t$perf-norm:sp_supplier_order 5404 5404 0 0.00 0 5054 31 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 361 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 98 5 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 957 27 0 0
t$perf-norm:srv_make_money_saldo 923 923 0 0.00 0 3238 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 217 105 0 0
v_add_invoice_to_stock 10164 9944 220 2.16 0 25758 30 0 220
v_all_customers 36632 36632 0 0.00 0 102 4 0 0
v_all_suppliers 30528 30528 0 0.00 0 113 5 0 0
v_all_wares 39858 39858 0 0.00 0 111 4 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 1492 17 0 0
v_cancel_client_order 3532 3532 0 0.00 0 26031 18 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 5292 18 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 1 2026 14 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 2303 16 0 0
v_cancel_supplier_invoice 848 830 18 2.12 0 6869 39 0 18
v_cancel_supplier_order 886 886 0 0.00 0 1204 12 0 0
v_cancel_write_off 1880 1880 0 0.00 0 4565 28 0 0
v_max_id_clo_ord 5404 5404 0 0.00 0 414 5 0 0
v_max_id_clo_res 997 997 0 0.00 0 1871 12 0 0
v_max_id_ord_sup 5498 5498 0 0.00 0 3381 8 0 0
v_max_non_paid_invoice 1485 1485 0 0.00 0 881 9 0 0
v_max_non_paid_realizn 3550 3550 0 0.00 0 1647 8 0 0
v_min_id_clo_ord 5404 5404 0 0.00 0 4359 10 0 0
v_min_id_clo_res 997 997 0 0.00 0 2373 12 0 0
v_min_id_ord_sup 5498 5498 0 0.00 0 6362 13 0 0
v_min_non_paid_invoice 5848 5848 0 0.00 0 7168 16 0 0
v_min_non_paid_realizn 6833 6833 0 0.00 0 2645 14 0 0
v_our_firm 9792 9792 0 0.00 0 214 5 0 0
v_reserve_write_off 19562 19562 0 0.00 0 26029 13 0 0
x_lock_depdocs_on_canc_invoice 11383 11383 0 0.00 0 15520 150 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 10072 375 0 0
x_make_qstorno_1000_1200 5404 5404 0 0.00 2 41697 1186 0 0
x_make_qstorno_1200_2000 5502 5502 0 0.00 4 107529 2885 0 0
x_make_qstorno_2100_3300 477246 477246 0 0.00 0 138459 242 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-03-2023 13:39:56 to 17-03-2023 13:40:00.

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:31 935 8192000000 8927168944 8982474752 11980848 0.146 4 2 5 0 12423360 98768 343744
11:08:34 30747 8192000000 9716739216 10013061120 791975760 8.815 100 87 174 0 820938896 7471472 21491664
11:10:07 23896 8192000000 9765763792 10073149440 833746624 9.237 100 88 176 0 866460176 11742192 20971360
11:11:37 15022 8192000000 9795748784 10112294912 863955600 9.540 100 90 180 0 903941072 19349840 20635632
11:12:59 7082 8192000000 9861820928 10194411520 910623184 10.003 100 81 160 0 970010432 42069856 17317392
11:14:16 3073 8192000000 9916579584 10257342464 949214928 10.383 100 84 162 0 1030428992 63727040 17487024
11:15:26 2207 8192000000 9951229680 10307194880 987396688 10.756 100 86 165 1 1069874400 65459744 17017968
11:16:31 1197 8192000000 9985113968 10344222720 1010593664 10.981 100 91 170 1 1099884656 72354048 16936944
11:17:34 1279 8192000000 10014050992 10375450624 1025624512 11.126 100 96 188 0 1128718864 84224768 18869584
11:18:37 1854 8192000000 9985034544 10364502016 1049481936 11.356 100 84 159 0 1112091328 46082544 16526848
11:19:41 1250 8192000000 10036425104 10412650496 1061217904 11.468 100 87 170 0 1156728000 77703824 17806272
11:20:44 969 8192000000 10036842576 10414276608 1074902448 11.599 100 97 187 0 1159449760 65843696 18703616
11:21:48 721 8192000000 10047959184 10432692224 1077077088 11.620 100 87 170 1 1166925776 72591088 17257600
11:22:49 1140 8192000000 10032717552 10420895744 1081522480 11.662 100 88 165 3 1155498224 56456304 17519440
11:23:51 1489 8192000000 10047985568 10438295552 1087456352 11.718 100 89 171 0 1172360160 67558832 17344976
11:25:00 1297 8192000000 10064706848 10454413312 1091864768 11.760 100 96 187 0 1189133584 79296432 17972384
11:26:02 1041 8192000000 10069942912 10457202688 1096631232 11.806 100 96 188 2 1186107584 70454736 19021616
11:27:05 1199 8192000000 10052223088 10441007104 1092570096 11.767 100 97 190 1 1172283696 60785904 18927696
11:28:06 7566 8192000000 10044280880 10437627904 1096136352 11.801 100 70 122 1 1159585968 51448096 12001520
11:29:15 989 8192000000 10073945312 10466050048 1102193264 11.858 100 90 169 2 1195683072 75470928 18018880
11:30:17 1042 8192000000 10069353344 10465296384 1105314080 11.888 100 94 181 2 1193004928 69511696 18179152
11:31:19 1227 8192000000 10050022816 10435940352 1087506848 11.719 99 95 173 3 1173156368 68439328 17210192
11:32:22 1142 8192000000 10063733024 10453688320 1092325344 11.765 100 85 163 2 1184747680 74830096 17592240
11:33:24 1142 8192000000 10026479616 10408579072 1065847984 11.512 99 94 182 2 1147868672 63027920 18992768
11:34:26 869 8192000000 10022886992 10408202240 1079962768 11.647 100 88 172 2 1147849200 49285552 18600880
11:35:29 1180 8192000000 9979857632 10345574400 1022632096 11.097 99 93 175 2 1106572176 66268016 17672064
11:36:31 947 8192000000 9971166304 10341924864 1031976032 11.187 100 92 168 0 1092250992 42851760 17423200
11:37:35 13599 8192000000 9978678832 10348027904 1013540256 11.010 98 77 134 1 1099274944 73218592 12516096
11:39:07 3774 8192000000 9983977536 10372796416 1050054560 11.361 100 56 110 0 1110785472 49095264 11635648
11:40:17 25891 8192000000 10048231808 10446041088 1066100432 11.515 100 52 100 0 1157086880 80412912 10573536
11:41:59 12701 8192000000 10062233664 10447757312 1076482368 11.614 100 71 136 0 1182959440 92613360 13863712
11:43:18 777 8192000000 10080242752 10462081024 1085477248 11.700 100 94 186 1 1199521840 94486960 19557632
11:44:23 5033 8192000000 10093170128 10485706752 1093453136 11.775 100 76 133 0 1210215648 102835088 13927424
11:45:34 5671 8192000000 10125840656 10519601152 1094931696 11.790 99 60 119 0 1234050880 126367200 12751984
11:46:47 3266 8192000000 10166620608 10561855488 1108602176 11.919 100 72 142 0 1280009056 155923664 15483216
11:47:56 1020 8192000000 10194767104 10587574272 1128807360 12.110 100 97 185 0 1303416048 153987040 20621648
11:48:59 2076 8192000000 10217893808 10626760704 1152558976 12.334 100 88 169 0 1329922640 159774368 17589296
11:50:05 2264 8192000000 10256702144 10653564928 1167621552 12.475 100 89 175 0 1369235424 182582928 19030944
11:51:10 912 8192000000 10283200000 10683150336 1177387280 12.566 100 91 174 1 1392880864 196417616 19075968
11:52:13 1630 8192000000 10324504016 10725068800 1175977888 12.553 99 86 170 1 1434740960 240060880 18702192
11:53:17 803 8192000000 10378818352 10777595904 1188529424 12.670 100 95 185 0 1487184016 276809600 21844992
11:54:21 1305 8192000000 10432958768 10838589440 1210898224 12.877 100 92 179 0 1541276192 310084512 20293456
11:55:26 718 8192000000 10474465616 10871336960 1196777216 12.746 100 99 196 0 1579556672 360386608 22392848
11:56:31 1450 8192000000 10536060832 10943586304 1217904864 12.942 100 93 182 3 1638481120 398884656 21691600
11:57:37 686 8192000000 10564458240 10991001600 1215877664 12.924 100 97 192 0 1670980896 432023904 23079328
11:58:40 856 8192000000 10554999392 10985865216 1195339520 12.733 100 95 184 0 1661291968 444092592 21859856
11:59:43 880 8192000000 10597530032 11026845696 1213208480 12.899 100 95 186 1 1703931984 468851968 21871536
12:00:48 809 8192000000 10571092048 11008110592 1188608272 12.670 100 94 185 2 1677115968 465640720 22866976
12:01:55 1950 8192000000 10597743616 11017383936 1197307056 12.751 100 88 164 2 1697220256 480414864 19498336
12:02:59 2111 8192000000 10595057536 11060023296 1201754432 12.793 100 76 149 0 1706880576 486057296 19068848
12:04:04 3839 8192000000 10539248160 11028942848 1172884464 12.524 100 69 136 0 1638796640 448157408 17754768
12:05:13 988 8192000000 10571303392 10997706752 1169220576 12.490 100 93 184 1 1671731728 480631424 21879728
12:06:17 819 8192000000 10529290544 11018866688 1168768848 12.485 100 84 166 0 1631377120 441767136 20841136
12:07:23 2518 8192000000 10475431520 10985328640 1176059104 12.553 100 63 124 0 1581919856 390847824 15012928
12:08:28 21291 8192000000 10460437840 10906234880 1189125120 12.675 100 47 94 0 1564610096 363899568 11585408
12:09:53 35915 8192000000 10451964032 10915643392 1168984400 12.487 100 28 56 0 1561113952 384605216 7524336
12:11:31 17424 8192000000 10405044864 10940141568 1156928672 12.374 100 29 58 1 1557874400 392619952 8325776
12:13:01 35760 8192000000 10286493216 10754859008 1106362112 11.898 97 24 48 0 1397015440 284178992 6474336
12:14:55 39320 8192000000 10167766752 10635194368 1106200848 11.896 98 11 22 0 1269290256 160452048 2637360
12:17:44 66134 8192000000 10083490464 10534387712 1117035808 11.999 99 22 45 1 1168868912 47606720 4226384
12:19:55 59490 8192000000 10129633952 10573901824 1096674464 11.806 96 40 78 0 1182477536 77021680 8781392
12:22:54 76789 8192000000 10065040288 10499301376 1110210608 11.934 98 28 55 2 1155979888 40032000 5737280
12:25:26 50308 8192000000 10071621968 10503741440 1125910128 12.083 99 38 72 1 1163108528 29913520 7284880
12:27:21 39530 8192000000 10057748992 10507321344 1102594208 11.862 98 51 99 0 1194393520 81884512 9914800
12:29:02 39732 8192000000 10080095456 10514436096 1133602176 12.155 100 60 106 5 1176102368 30834800 11665392
12:31:26 13087 8192000000 10158470256 10586456064 1143405904 12.248 100 67 130 1 1258386320 100824560 14155856
12:32:42 4911 8192000000 10161645408 10587811840 1151170000 12.320 99 59 116 0 1253206416 89167744 12868672
12:33:50 6389 8192000000 10240367600 10667323392 1161014464 12.413 100 79 156 1 1332656064 154547440 17094160
12:35:01 114217 8192000000 10264239696 10732777472 1156065184 12.366 99 26 50 0 1334792528 172058112 6669232
12:38:07 50641 8192000000 10225483744 10676084736 1137261344 12.190 99 19 38 0 1315864608 173724624 4878640
12:40:11 39603 8192000000 10097795392 10568749056 1152738336 12.335 100 12 22 0 1214576752 59764928 2073488
12:41:56 88721 8192000000 10127063200 10593357824 1114574224 11.976 97 14 28 0 1170160816 52766960 2819632
12:44:26 74452 8192000000 10102284864 10572361728 1116730768 11.996 97 25 50 0 1170527648 48636704 5160176
12:46:47 57578 8192000000 10090444512 10545291264 1101049200 11.848 96 29 56 0 1147207520 40729040 5429280
12:48:48 80772 8192000000 10067112256 10568253440 1101942816 11.856 97 16 32 0 1169564320 64302960 3318544
12:51:10 24195 8192000000 10070895584 10529226752 1098398640 11.822 97 33 63 1 1143758944 39125392 6234912
12:52:43 80509 8192000000 10073555520 10522099712 1124992400 12.074 99 36 72 1 1172652288 40294816 7365072
12:55:04 22086 8192000000 10127309664 10563706880 1135029728 12.169 100 65 123 0 1200049744 52730928 12289088
12:56:28 58805 8192000000 10132942208 10596982784 1148057616 12.291 100 32 60 0 1245628560 91102224 6468720
12:58:30 64923 8192000000 10085593344 10562973696 1105207488 11.887 96 16 32 0 1176859520 68294192 3357840
13:00:35 51417 8192000000 10078374720 10537959424 1119080784 12.018 98 12 22 0 1169096592 47832544 2183264
13:02:29 47926 8192000000 10117323680 10585776128 1105936768 11.894 98 23 44 0 1220164480 109672816 4554896
13:04:19 28695 8192000000 10088957632 10534215680 1134092512 12.160 100 29 59 0 1192176672 51942128 6142032
13:05:49 39357 8192000000 10095589360 10525736960 1115595520 11.985 99 29 56 0 1170775440 49423104 5756816
13:07:31 4592 8192000000 10135472064 10570723328 1136644800 12.184 100 75 137 1 1226566624 75251856 14669968
13:08:43 44214 8192000000 10100392448 10568089600 1091530336 11.757 96 31 62 0 1190308464 92472272 6305856
13:10:31 41858 8192000000 10100309056 10536091648 1091172064 11.754 97 27 55 0 1134633248 38065776 5395408
13:12:18 30637 8192000000 10065782864 10528784384 1096556544 11.805 98 23 46 0 1145861616 44255792 5049280
13:14:11 4469 8192000000 10126567552 10550714368 1132154176 12.142 100 59 117 1 1226048384 82701920 11192288
13:15:20 111390 8192000000 10075531072 10544697344 1114108544 11.971 99 35 67 1 1192532144 71471280 6952320
13:18:13 71031 8192000000 10063922368 10535956480 1132403680 12.144 100 9 18 0 1172590944 38765632 1421632
13:20:24 75746 8192000000 10102819632 10563497984 1124269280 12.067 99 12 22 1 1194082512 67272048 2541184
13:22:40 66026 8192000000 10068268192 10539765760 1111549632 11.947 98 15 26 1 1140085920 25532016 3004272
13:24:47 22572 8192000000 10126777408 10565971968 1136214560 12.180 100 39 78 1 1209617696 65155472 8247664
13:26:18 39362 8192000000 10093681824 10550468608 1120903424 12.036 98 26 52 0 1191937088 65888608 5145056
13:27:57 23519 8192000000 10093983808 10526593024 1106047360 11.895 97 33 63 0 1170217040 58020992 6148688
13:29:23 21764 8192000000 10180075088 10628771840 1133792448 12.157 99 36 70 0 1268201008 127128560 7280000
13:30:52 60278 8192000000 10244277568 10697359360 1128613424 12.108 98 33 66 0 1297316896 161504048 7199424
13:33:13 41971 8192000000 10053323488 10495361024 1102324176 11.860 98 23 39 0 1130538624 23626704 4587744
13:35:01 13700 8192000000 10096964464 10529091584 1123115008 12.056 100 53 106 0 1175655824 42175696 10365120
13:36:16 94849 8192000000 9320735360 9654132736 449674448 5.203 41 15 30 1 482101904 29129472 3297984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 17-03-2023 13:40:02 to 17-03-2023 13:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 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 17-03-2023 13:40:20 to 17-03-2023 13:40:22.

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          217169
MON$OLDEST_ACTIVE               217170
MON$OLDEST_SNAPSHOT             217170
MON$NEXT_TRANSACTION            217170
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                       2079456
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2909 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2909 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2909 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2909 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 ---
:152: 
:153: mscs-test-161.budzdorov.ru    Fri Mar 17 13:38:03 2023
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:155: 
--- end of diff output ---

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

17.03.23 13:40:23. 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-17 13:40:26 No severe PSQL-related problems occured
17.03.23 13:40:27. End of report.