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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15091012 kB
MemAvailable 30425276 kB
Buffers 20 kB
Cached 16568388 kB
SwapCached 3568 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-08 04:37:27
DTS_END                         2023-02-08 04:37:27.4820
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/39044
TRN_ID                          218334
ATT_ID                          600
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: 9115.43 27699 1093852
customer order: creation 167.22 447 20066
customer order: refuse 14.18 378 1701
order to supplier: creation 44.28 1278 5313
order to supplier: removal 3.33 155326 400
invoice (draft): creation 44.06 2652 5287
invoice (draft): removal 49.89 882 5987
invoice accept: apply 41.65 25115 4998
invoice accept: cancel 45.29 1034 5435
customer reserve: creation 3935.28 237 472233
customer reserve: removal 4485.38 92 538245
realization accept: apply 78.60 238 9432
realization accept: cancel 81.01 148 9721
payment from customer: creation 56.64 154 6797
payment from customer: removal 5.49 109 659
payment to supplier: creation 48.39 170 5807
payment to supplier: removal 3.28 121 393
service: total inventory turnovers 3.81 3735 457
service: total monetary turnovers 6.83 358 819
service: aggregate perf. data 0.81 15549 97
service: refresh index statistics 0.04 345949 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-02-2023 04:38:50 to 08-02-2023 04:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6760
TEST_TIME 2 5727
TEST_TIME 3 5200
TEST_TIME 4 5157
TEST_TIME 5 5197
TEST_TIME 6 5388
TEST_TIME 7 5451
TEST_TIME 8 5677
TEST_TIME 9 5968
TEST_TIME 10 6200
TEST_TIME 11 6117
TEST_TIME 12 6225
TEST_TIME 13 6552
TEST_TIME 14 6757
TEST_TIME 15 7365
TEST_TIME 16 7591
TEST_TIME 17 7442
TEST_TIME 18 7686
TEST_TIME 19 7912
TEST_TIME 20 8084
TEST_TIME 21 8355
TEST_TIME 22 8865
TEST_TIME 23 9085
TEST_TIME 24 8852
TEST_TIME 25 9271
TEST_TIME 26 9457
TEST_TIME 27 9686
TEST_TIME 28 9847
TEST_TIME 29 10242
TEST_TIME 30 10533
TEST_TIME 31 10791
TEST_TIME 32 10950
TEST_TIME 33 10971
TEST_TIME 34 10891
TEST_TIME 35 10733
TEST_TIME 36 10661
TEST_TIME 37 10592
TEST_TIME 38 10454
TEST_TIME 39 10592
TEST_TIME 40 10343
TEST_TIME 41 10187
TEST_TIME 42 10171
TEST_TIME 43 10131
TEST_TIME 44 10047
TEST_TIME 45 10058
TEST_TIME 46 9974
TEST_TIME 47 9906
TEST_TIME 48 9866
TEST_TIME 49 9820
TEST_TIME 50 9844
TEST_TIME 51 9762
TEST_TIME 52 9716
TEST_TIME 53 9877
TEST_TIME 54 9915
TEST_TIME 55 9867
TEST_TIME 56 9934
TEST_TIME 57 9880
TEST_TIME 58 9879
TEST_TIME 59 9955
TEST_TIME 60 9935
TEST_TIME 61 9935
TEST_TIME 62 10021
TEST_TIME 63 10056
TEST_TIME 64 10130
TEST_TIME 65 10121
TEST_TIME 66 10107
TEST_TIME 67 10150
TEST_TIME 68 10165
TEST_TIME 69 10019
TEST_TIME 70 9950
TEST_TIME 71 9855
TEST_TIME 72 9851
TEST_TIME 73 9767
TEST_TIME 74 9726
TEST_TIME 75 9645
TEST_TIME 76 9688
TEST_TIME 77 9619
TEST_TIME 78 9584
TEST_TIME 79 9560
TEST_TIME 80 9532
TEST_TIME 81 9490
TEST_TIME 82 9643
TEST_TIME 83 9645
TEST_TIME 84 9592
TEST_TIME 85 9551
TEST_TIME 86 9687
TEST_TIME 87 9705
TEST_TIME 88 9680
TEST_TIME 89 9592
TEST_TIME 90 9550
TEST_TIME 91 9534
TEST_TIME 92 9506
TEST_TIME 93 9483
TEST_TIME 94 9447
TEST_TIME 95 9423
TEST_TIME 96 9364
TEST_TIME 97 9308
TEST_TIME 98 9285
TEST_TIME 99 9312
TEST_TIME 100 9287
TEST_TIME 101 9304
TEST_TIME 102 9316
TEST_TIME 103 9331
TEST_TIME 104 9299
TEST_TIME 105 9360
TEST_TIME 106 9322
TEST_TIME 107 9296
TEST_TIME 108 9371
TEST_TIME 109 9408
TEST_TIME 110 9410
TEST_TIME 111 9410
TEST_TIME 112 9384
TEST_TIME 113 9324
TEST_TIME 114 9291
TEST_TIME 115 9295
TEST_TIME 116 9263
TEST_TIME 117 9224
TEST_TIME 118 9185
TEST_TIME 119 9195
TEST_TIME 120 9138

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-02-2023 04:38:54 to 08-02-2023 04:38:58.

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 1527855 1527855 0 0.00 0 30770 11 0 0
doc_list_biud 1527838 1527838 0 0.00 0 41205 38 0 0
sp_add_invoice_to_stock 5094 4998 96 1.88 60 244513 25116 0 96
sp_cancel_adding_invoice 5435 5435 0 0.00 5 66901 1034 0 0
sp_cancel_client_order 1701 1701 0 0.00 6 10423 379 0 0
sp_cancel_customer_reserve 538245 538245 0 0.00 1 41219 92 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 4 7555 109 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 4 6602 121 0 0
sp_cancel_supplier_invoice 5993 5987 6 0.10 2 31362 882 0 6
sp_cancel_supplier_order 400 400 0 0.00 21 1338348 155326 0 0
sp_cancel_write_off 9721 9721 0 0.00 2 11469 149 0 0
sp_client_order 20066 20066 0 0.00 5 37934 447 0 0
sp_customer_reserve 472233 472233 0 0.00 0 172200 238 0 0
sp_fill_shopping_cart 30650 30650 0 0.00 1 12207 68 0 0
sp_fill_shopping_cart_clo_res 472202 472202 0 0.00 0 15992 19 0 0
sp_get_clo_for_invoice 4956 4956 0 0.00 0 24562 272 0 0
sp_kill_cost_storno 16208 16208 0 0.00 0 9578 63 0 0
sp_kill_qstorno_ret_qs2qd 1092598 1092598 0 0.00 0 41136 41 0 0
sp_kill_qty_storno 575872 575872 0 0.00 0 41200 93 0 0
sp_lock_selected_doc 19952 19952 0 0.00 0 9188 6 0 0
sp_make_cost_storno 26991 26991 0 0.00 0 15045 72 0 0
sp_multiply_rows_for_pdistr 26988 26988 0 0.00 0 10379 32 0 0
sp_multiply_rows_for_qdistr 20066 20066 0 0.00 0 10872 183 0 0
sp_pay_from_customer 6797 6797 0 0.00 5 11803 155 0 0
sp_pay_to_supplier 5807 5807 0 0.00 7 27847 171 0 0
sp_payment_common 12604 12604 0 0.00 2 8499 39 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 1 10408 310 0 0
sp_qd_handle_on_invoice_upd_sts 10389 10389 0 0.00 0 20951 437 0 0
sp_qd_handle_on_reserve_upd_sts 9429 9429 0 0.00 0 22363 59 0 0
sp_reserve_write_off 9432 9432 0 0.00 9 22392 238 0 0
sp_supplier_invoice 5287 5287 0 0.00 12 83321 2653 0 0
sp_supplier_order 5313 5313 0 0.00 7 56026 1278 0 0
srv_aggregate_perf_data 97 97 0 0.00 2662 161017 15549 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 1020 164156 38214 0 0
srv_find_qd_qs_mism 1045048 1045048 0 0.00 0 30768 9 0 0
srv_make_invnt_saldo 457 457 0 0.00 60 74996 3736 0 0
srv_make_money_saldo 819 819 0 0.00 1 20257 359 0 0
srv_recalc_idx_stat 5 5 0 0.00 88863 549272 345949 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5001 5001 0 0.00 1 13065 769 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 2 6405 386 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 9306 26 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 672 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 1752 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 1438 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 465 23 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 109949 6012 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 20423 39 0 0
t$perf-norm:sp_client_order 20066 20066 0 0.00 0 3736 31 0 0
t$perf-norm:sp_customer_reserve 946 946 0 0.00 0 3226 14 0 0
t$perf-norm:sp_pay_from_customer 6797 6797 0 0.00 0 8839 15 0 0
t$perf-norm:sp_pay_to_supplier 5807 5807 0 0.00 0 4690 17 0 0
t$perf-norm:sp_reserve_write_off 9432 9432 0 0.00 0 7223 21 0 0
t$perf-norm:sp_supplier_invoice 5287 5287 0 0.00 0 7109 31 0 0
t$perf-norm:sp_supplier_order 5314 5314 0 0.00 0 4872 29 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 219 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 85 5 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 1209 14 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 829 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 192 52 0 0
v_add_invoice_to_stock 10088 9896 192 1.90 0 4835 26 0 192
v_all_customers 37222 37222 0 0.00 0 77 3 0 0
v_all_suppliers 30080 30080 0 0.00 0 89 4 0 0
v_all_wares 40130 40130 0 0.00 0 94 3 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 396 11 0 0
v_cancel_client_order 3402 3402 0 0.00 0 5831 12 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 3443 12 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 1 4929 16 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 3827 15 0 0
v_cancel_supplier_invoice 780 768 12 1.53 0 5281 34 0 12
v_cancel_supplier_order 800 800 0 0.00 0 2230 14 0 0
v_cancel_write_off 1964 1964 0 0.00 0 4502 27 0 0
v_max_id_clo_ord 5306 5306 0 0.00 0 1181 4 0 0
v_max_id_clo_res 946 946 0 0.00 0 1351 8 0 0
v_max_id_ord_sup 5279 5279 0 0.00 0 5517 5 0 0
v_max_non_paid_invoice 1351 1351 0 0.00 0 85 6 0 0
v_max_non_paid_realizn 3362 3362 0 0.00 0 1162 7 0 0
v_min_id_clo_ord 5306 5306 0 0.00 0 1957 7 0 0
v_min_id_clo_res 946 946 0 0.00 0 1111 8 0 0
v_min_id_ord_sup 5279 5279 0 0.00 0 7267 9 0 0
v_min_non_paid_invoice 5806 5806 0 0.00 0 7364 13 0 0
v_min_non_paid_realizn 6797 6797 0 0.00 0 4814 16 0 0
v_our_firm 9778 9778 0 0.00 0 91 4 0 0
v_reserve_write_off 18858 18858 0 0.00 0 5788 11 0 0
x_lock_depdocs_on_canc_invoice 11038 11038 0 0.00 0 15296 124 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 17813 401 0 0
x_make_qstorno_1000_1200 5313 5313 0 0.00 2 56013 1144 0 0
x_make_qstorno_1200_2000 5287 5287 0 0.00 5 83307 2462 0 0
x_make_qstorno_2100_3300 472233 472233 0 0.00 0 172159 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-02-2023 04:38:59 to 08-02-2023 04:39:03.

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:31 923 8192000000 8915957264 8970461184 22772736 0.277 5 3 6 0 23529616 274880 482000
02:08:33 19812 8192000000 9722136880 10022166528 802742352 8.924 100 89 178 0 832858208 7748000 22367856
02:09:59 27098 8192000000 9769258560 10078289920 839859104 9.298 100 86 172 0 872838848 12378816 20600928
02:11:34 18170 8192000000 9796255152 10117345280 863667856 9.537 100 84 168 0 908113744 24701472 19744416
02:12:58 10461 8192000000 9857004880 10192384000 913739760 10.034 100 73 146 0 970443488 40588672 16115056
02:14:12 3451 8192000000 9917315216 10258616320 957475136 10.464 100 87 171 1 1025606736 50083504 18048096
02:15:19 1776 8192000000 9963068576 10312130560 987629664 10.758 100 93 184 1 1081246912 74599008 19018240
02:16:22 1204 8192000000 9997138272 10358431744 1019859440 11.071 100 88 172 1 1112434880 75706064 16869376
02:17:26 1295 8192000000 10014126736 10384543744 1046347776 11.326 100 84 162 0 1136321888 73926848 16047264
02:18:28 1017 8192000000 10036339232 10411433984 1061989744 11.476 100 97 186 3 1156770112 76829360 17951008
02:19:30 1066 8192000000 10038301328 10417946624 1066351104 11.517 100 91 180 1 1160292384 76486624 17454656
02:20:33 1085 8192000000 10038561936 10423619584 1074870592 11.599 100 93 174 2 1162662352 70053408 17738352
02:21:35 1127 8192000000 10045440544 10425565184 1084944224 11.695 100 88 165 1 1168172176 67279968 15947984
02:22:37 1566 8192000000 10066408528 10452643840 1084417520 11.690 100 84 163 1 1184604512 83694816 16492176
02:23:40 1036 8192000000 10057356736 10442838016 1090854688 11.751 100 88 176 0 1179363792 70380000 18129104
02:24:43 1214 8192000000 10052969808 10443563008 1095859152 11.798 100 88 164 3 1177812448 66771584 15181712
02:25:46 2089 8192000000 10063550288 10457526272 1098743232 11.826 100 81 145 3 1194113920 81157584 14213104
02:26:50 2102 8192000000 10057607744 10450092032 1099044624 11.829 100 76 144 2 1184275504 69470448 15760432
02:27:53 1071 8192000000 10064564160 10457518080 1095949168 11.799 100 89 167 0 1189867024 76026368 17891488
02:28:55 1023 8192000000 10070692416 10460614656 1100413856 11.842 100 94 179 1 1192861280 75409888 17037536
02:29:57 1182 8192000000 10057691024 10443870208 1092175872 11.763 100 97 182 0 1183265728 71767312 19322544
02:30:59 1014 8192000000 10011543392 10393112576 1073483600 11.585 99 92 171 1 1137688112 45720096 18484416
02:32:00 1196 8192000000 10023142336 10405773312 1067425520 11.527 100 94 180 2 1143116480 58067616 17623344
02:33:02 927 8192000000 10001380960 10383036416 1053084320 11.390 100 87 167 2 1125831872 54654096 18093456
02:34:04 1269 8192000000 9982254832 10354991104 1021973344 11.091 99 79 151 0 1102903424 66088896 14841184
02:35:06 1446 8192000000 9962098016 10341728256 1013311632 11.007 99 64 125 0 1088141376 61822224 13007520
02:36:09 1969 8192000000 9967528176 10344538112 1040847776 11.273 100 70 140 1 1094054304 38475120 14731408
02:37:13 8521 8192000000 9982333296 10355458048 1040799072 11.272 100 66 121 4 1103979584 50179440 13001072
02:38:32 1055 8192000000 10018186992 10393628672 1070559312 11.557 100 92 174 0 1135333536 47936208 16838016
02:39:39 6041 8192000000 10055195744 10446307328 1084776768 11.693 100 63 114 3 1175187840 78980448 11430624
02:40:49 12676 8192000000 10078391632 10471047168 1100436160 11.842 100 71 140 1 1200712480 86096448 14179872
02:42:08 4531 8192000000 10149468816 10544160768 1115720912 11.987 100 78 153 1 1267307472 136002896 15583664
02:43:17 4058 8192000000 10187754240 10579738624 1127315488 12.096 100 82 160 2 1300892256 156737056 16839712
02:44:23 1462 8192000000 10210321584 10599084032 1134531600 12.164 100 87 166 0 1324879952 172267152 18081200
02:45:30 2938 8192000000 10271452992 10675036160 1147209120 12.283 100 74 139 0 1391846432 229566336 15070976
02:46:37 1289 8192000000 10281495744 10688327680 1163710544 12.438 100 92 184 0 1392980720 208707120 20563056
02:47:42 1161 8192000000 10317935344 10727444480 1177904112 12.571 100 91 181 3 1435174400 236516672 20753616
02:48:48 996 8192000000 10368435744 10767585280 1185166160 12.638 100 99 194 0 1480785552 274513008 21106384
02:49:54 1204 8192000000 10386534336 10807349248 1186660640 12.652 100 96 188 0 1500725696 292129648 21935408
02:50:58 1428 8192000000 10401093840 10817060864 1175948112 12.552 100 93 183 4 1509237104 311343024 21945968
02:52:02 1305 8192000000 10398408192 10848813056 1189046208 12.674 100 94 180 1 1506160272 295191488 21922576
02:53:05 13920 8192000000 10412149312 10852139008 1185660432 12.643 100 47 94 0 1539451744 341683744 12107568
02:54:25 5426 8192000000 10403360864 10838859776 1176763088 12.560 100 63 123 2 1510602976 318538944 15300944
02:55:40 6274 8192000000 10418588912 10875314176 1187889744 12.664 100 52 99 1 1542475664 341361824 13224096
02:56:49 5206 8192000000 10386316224 10836967424 1170780784 12.504 100 71 140 0 1502535248 313465616 18288848
02:58:03 1522 8192000000 10393986432 10859864064 1187621136 12.661 100 94 180 3 1497332704 287667552 22044016
02:59:06 6397 8192000000 10422088672 10863132672 1195244464 12.732 100 64 128 1 1529708656 318604064 15860128
03:00:20 9035 8192000000 10390853264 10828697600 1186396272 12.650 99 40 80 0 1495527920 298771296 10360352
03:01:30 15033 8192000000 10401678544 10868191232 1165253440 12.452 99 36 72 0 1505833568 331498864 9081264
03:02:46 34056 8192000000 10345645584 10841841664 1165563216 12.455 99 26 52 0 1468398848 296346368 6489264
03:04:25 17404 8192000000 10325599328 10790346752 1160980832 12.412 99 46 87 0 1436629344 265685280 9963232
03:05:48 49799 8192000000 10232373680 10726555648 1098718704 11.825 95 25 49 0 1346253536 241711984 5822848
03:07:43 77986 8192000000 10137994816 10626207744 1130955168 12.130 99 11 22 0 1277997360 143980512 3061680
03:10:08 48880 8192000000 10072190240 10548498432 1126247792 12.086 97 12 26 0 1184209712 55575920 2386000
03:12:07 27264 8192000000 10085144416 10526916608 1129141744 12.113 98 39 69 0 1169529536 33557728 6830064
03:13:35 56065 8192000000 10063305184 10517049344 1082882592 11.675 94 14 28 0 1127923184 42611280 2429312
03:15:32 76242 8192000000 10095080736 10595696640 1132744272 12.147 99 22 42 1 1221819104 84747520 4327312
03:17:49 59565 8192000000 10122487376 10568052736 1128705664 12.109 99 50 93 0 1193189264 54148176 10335424
03:20:00 66236 8192000000 10102529376 10543759360 1123828272 12.063 99 40 74 0 1212405104 81616992 6959840
03:22:09 17852 8192000000 10096038032 10530213888 1104911888 11.884 97 34 67 0 1173707376 61596288 7199200
03:23:28 30495 8192000000 10152109968 10592911360 1130275104 12.124 99 49 92 2 1234905264 93704256 10925904
03:25:00 15765 8192000000 10181839392 10627801088 1150169344 12.311 100 34 66 0 1273783616 116250528 7363744
03:26:16 73786 8192000000 10190745760 10654531584 1129505456 12.117 98 23 46 0 1264529360 129869216 5154688
03:28:45 164155 8192000000 10086281584 10583027712 1106713600 11.901 97 20 41 0 1282907888 170920928 5273360
03:32:30 84965 8192000000 10077918528 10526670848 1100958528 11.847 97 24 47 0 1141466928 36084720 4423680
03:34:55 37335 8192000000 10116205920 10568400896 1107602896 11.910 98 42 80 1 1189926816 72876896 9447024
03:36:35 58785 8192000000 10090057312 10535280640 1118352608 12.011 99 31 56 0 1168463072 44421600 5688864
03:38:36 58580 8192000000 10140948512 10636308480 1126338640 12.087 98 21 42 0 1238533040 107953328 4241072
03:40:36 106580 8192000000 10096083664 10544861184 1120010896 12.027 97 15 30 0 1230470112 106907360 3551856
03:43:29 63087 8192000000 10161634160 10668220416 1155706000 12.363 100 31 62 0 1244463744 82530736 6227008
03:45:33 107415 8192000000 10116452608 10572570624 1139021488 12.206 99 23 46 0 1199084592 55611696 4451408
03:49:09 58719 8192000000 10075022128 10565513216 1134052480 12.160 99 17 34 0 1175168432 38035680 3080272
03:51:08 68036 8192000000 10072945424 10533945344 1103809456 11.874 97 6 12 0 1135092816 30265136 1018224
03:53:16 5645 8192000000 10080656704 10533769216 1129793904 12.119 100 53 102 0 1179271504 38944032 10533568
03:54:29 17630 8192000000 10092094480 10529083392 1138498864 12.201 100 39 79 0 1189915952 43684304 7732784
03:55:52 41652 8192000000 10119318560 10561515520 1111016608 11.942 97 28 56 1 1177387264 60471344 5899312
03:57:34 71863 8192000000 10175188720 10618314752 1129981600 12.121 98 24 48 0 1243832896 108314800 5536496
03:59:50 115451 8192000000 10094593632 10527768576 1067862272 11.532 94 18 35 0 1111179328 39884656 3432400
04:02:46 58177 8192000000 10134669488 10610540544 1121991888 12.046 99 27 54 0 1251717008 123389808 6335312
04:04:54 28601 8192000000 10088422672 10518818816 1102267536 11.859 98 39 78 1 1160209504 50333392 7608576
04:06:24 74163 8192000000 10086031792 10539384832 1096881600 11.808 98 39 68 1 1165164272 62257360 6025312
04:08:39 8823 8192000000 10095245840 10561699840 1123003088 12.055 100 26 49 0 1194970208 66907344 5059776
04:09:49 22980 8192000000 10044742160 10499039232 1095912480 11.799 98 39 77 1 1162892864 58478432 8501952
04:11:13 59960 8192000000 10067472288 10524041216 1126694992 12.090 100 13 26 0 1162776560 33188240 2893328
04:13:13 60080 8192000000 10104762480 10552295424 1122995328 12.055 99 15 30 0 1183066192 57127392 2943472
04:16:26 9572 8192000000 10058701312 10509357056 1129616032 12.118 100 38 78 0 1157421248 19770048 8035168
04:17:36 48340 8192000000 10059730208 10522779648 1112674128 11.958 99 15 28 1 1139350576 23674672 3001776
04:19:42 30118 8192000000 10090140080 10544119808 1121359680 12.040 99 20 40 0 1183190576 57432080 4398816
04:21:18 13790 8192000000 10107182976 10530459648 1126902528 12.092 100 62 111 1 1198937056 60500096 11534432
04:22:34 33495 8192000000 10067886768 10500194304 1104631072 11.882 98 29 58 0 1169680928 59176000 5873856
04:24:19 25665 8192000000 10137233184 10567311360 1122857312 12.054 100 45 90 0 1219807808 87763232 9187264
04:25:55 68215 8192000000 10076931520 10529443840 1103998560 11.876 98 27 54 0 1191300096 81702432 5599104
04:28:03 32410 8192000000 10071398400 10522169344 1119054704 12.018 100 34 68 0 1169695328 44124320 6516304
04:29:38 72682 8192000000 10140790752 10588815360 1126082816 12.084 100 63 126 0 1217860336 79001312 12776208
04:31:53 48065 8192000000 10069377168 10517364736 1133006624 12.150 100 30 60 1 1174336208 35154848 6174736
04:33:42 64889 8192000000 10050125200 10528849920 1125420656 12.078 99 9 18 0 1160536032 33315888 1799488
04:35:48 62285 8192000000 10056935344 10523238400 1090876256 11.751 96 29 59 0 1139066624 41927888 6262480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-02-2023 04:39:05 to 08-02-2023 04:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-02-2023 04:39:22 to 08-02-2023 04:39:24.

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          217142
MON$OLDEST_ACTIVE               218779
MON$OLDEST_SNAPSHOT             218779
MON$NEXT_TRANSACTION            218779
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                       2056224
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.2895 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2895 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2895 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 ---
:253: 
:254: mscs-test-161.budzdorov.ru    Wed Feb  8 04:37:31 2023
:255:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:256: 
:257: 
:258: mscs-test-161.budzdorov.ru    Wed Feb  8 04:37:31 2023
:259:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:260: 
:261: 
:262: mscs-test-161.budzdorov.ru    Wed Feb  8 04:37:33 2023
:263:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:264: 
:265: 
:266: mscs-test-161.budzdorov.ru    Wed Feb  8 04:37:34 2023
:267:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:268: 
--- end of diff output ---

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

08.02.23 04:39:25. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-08 04:39:28 No severe PSQL-related problems occured
08.02.23 04:39:29. End of report.