Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.01.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 14554760 kB
MemAvailable 30401068 kB
Buffers 20 kB
Cached 17107736 kB
SwapCached 4548 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-13 04:37:24
DTS_END                         2023-01-13 04:37:24.3070
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/36892
TRN_ID                          217007
ATT_ID                          636
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: 9109.58 27511 1093149
customer order: creation 172.33 511 20680
customer order: refuse 14.11 510 1693
order to supplier: creation 45.03 1492 5403
order to supplier: removal 3.30 191763 396
invoice (draft): creation 45.68 3397 5482
invoice (draft): removal 49.58 1083 5950
invoice accept: apply 42.39 28840 5087
invoice accept: cancel 45.40 1591 5448
customer reserve: creation 3957.93 269 474952
customer reserve: removal 4439.73 112 532767
realization accept: apply 82.88 302 9945
realization accept: cancel 80.85 188 9702
payment from customer: creation 58.93 183 7071
payment from customer: removal 5.57 151 668
payment to supplier: creation 49.53 204 5943
payment to supplier: removal 3.43 154 412
service: total inventory turnovers 3.65 5168 438
service: total monetary turnovers 8.26 413 991
service: aggregate perf. data 0.98 17278 117
service: refresh index statistics 0.03 296603 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4743
TEST_TIME 2 4651
TEST_TIME 3 4668
TEST_TIME 4 5465
TEST_TIME 5 5571
TEST_TIME 6 5569
TEST_TIME 7 6234
TEST_TIME 8 6456
TEST_TIME 9 6774
TEST_TIME 10 6660
TEST_TIME 11 6877
TEST_TIME 12 6794
TEST_TIME 13 6794
TEST_TIME 14 6915
TEST_TIME 15 6949
TEST_TIME 16 6936
TEST_TIME 17 7092
TEST_TIME 18 7129
TEST_TIME 19 7193
TEST_TIME 20 7321
TEST_TIME 21 7759
TEST_TIME 22 7777
TEST_TIME 23 7815
TEST_TIME 24 7861
TEST_TIME 25 7873
TEST_TIME 26 8207
TEST_TIME 27 8244
TEST_TIME 28 8240
TEST_TIME 29 8066
TEST_TIME 30 8060
TEST_TIME 31 8469
TEST_TIME 32 8752
TEST_TIME 33 8886
TEST_TIME 34 9131
TEST_TIME 35 9481
TEST_TIME 36 9401
TEST_TIME 37 9691
TEST_TIME 38 9900
TEST_TIME 39 10112
TEST_TIME 40 10150
TEST_TIME 41 10442
TEST_TIME 42 10695
TEST_TIME 43 10792
TEST_TIME 44 10704
TEST_TIME 45 10650
TEST_TIME 46 10560
TEST_TIME 47 10387
TEST_TIME 48 10351
TEST_TIME 49 10155
TEST_TIME 50 10058
TEST_TIME 51 10047
TEST_TIME 52 9960
TEST_TIME 53 9883
TEST_TIME 54 10019
TEST_TIME 55 9982
TEST_TIME 56 9855
TEST_TIME 57 9784
TEST_TIME 58 9762
TEST_TIME 59 9751
TEST_TIME 60 9673
TEST_TIME 61 9881
TEST_TIME 62 9869
TEST_TIME 63 9866
TEST_TIME 64 9795
TEST_TIME 65 9858
TEST_TIME 66 9784
TEST_TIME 67 9748
TEST_TIME 68 9787
TEST_TIME 69 9820
TEST_TIME 70 9796
TEST_TIME 71 9768
TEST_TIME 72 9752
TEST_TIME 73 9731
TEST_TIME 74 9683
TEST_TIME 75 9682
TEST_TIME 76 9733
TEST_TIME 77 9733
TEST_TIME 78 9727
TEST_TIME 79 9686
TEST_TIME 80 9658
TEST_TIME 81 9728
TEST_TIME 82 9621
TEST_TIME 83 9627
TEST_TIME 84 9557
TEST_TIME 85 9503
TEST_TIME 86 9501
TEST_TIME 87 9464
TEST_TIME 88 9454
TEST_TIME 89 9438
TEST_TIME 90 9370
TEST_TIME 91 9346
TEST_TIME 92 9397
TEST_TIME 93 9391
TEST_TIME 94 9358
TEST_TIME 95 9384
TEST_TIME 96 9360
TEST_TIME 97 9375
TEST_TIME 98 9385
TEST_TIME 99 9389
TEST_TIME 100 9409
TEST_TIME 101 9462
TEST_TIME 102 9476
TEST_TIME 103 9453
TEST_TIME 104 9435
TEST_TIME 105 9517
TEST_TIME 106 9536
TEST_TIME 107 9462
TEST_TIME 108 9427
TEST_TIME 109 9397
TEST_TIME 110 9361
TEST_TIME 111 9337
TEST_TIME 112 9318
TEST_TIME 113 9276
TEST_TIME 114 9274
TEST_TIME 115 9242
TEST_TIME 116 9223
TEST_TIME 117 9191
TEST_TIME 118 9190
TEST_TIME 119 9159
TEST_TIME 120 9133

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-01-2023 04:39:02 to 13-01-2023 04:39:06.

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 1529143 1529143 0 0.00 0 24029 15 0 0
doc_list_biud 1529131 1529131 0 0.00 0 25262 45 0 0
sp_add_invoice_to_stock 5228 5087 141 2.69 48 209928 28841 0 141
sp_cancel_adding_invoice 5448 5448 0 0.00 7 91171 1591 0 0
sp_cancel_client_order 1693 1693 0 0.00 5 10637 510 0 0
sp_cancel_customer_reserve 532767 532767 0 0.00 1 25271 113 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 8262 152 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 13453 154 0 0
sp_cancel_supplier_invoice 5959 5950 9 0.15 3 14396 1083 0 9
sp_cancel_supplier_order 396 396 0 0.00 8 1365884 191764 0 0
sp_cancel_write_off 9702 9702 0 0.00 2 17108 188 0 0
sp_client_order 20680 20680 0 0.00 6 32505 512 0 0
sp_customer_reserve 474952 474952 0 0.00 1 73224 270 0 0
sp_fill_shopping_cart 31554 31554 0 0.00 1 14265 92 0 0
sp_fill_shopping_cart_clo_res 474919 474919 0 0.00 0 16896 19 0 0
sp_get_clo_for_invoice 5045 5045 0 0.00 0 22660 279 0 0
sp_kill_cost_storno 16230 16230 0 0.00 0 13447 88 0 0
sp_kill_qstorno_ret_qs2qd 1081582 1081582 0 0.00 0 23115 48 0 0
sp_kill_qty_storno 570945 570945 0 0.00 0 25261 112 0 0
sp_lock_selected_doc 20665 20665 0 0.00 0 7924 8 0 0
sp_make_cost_storno 28004 28004 0 0.00 0 24024 85 0 0
sp_multiply_rows_for_pdistr 28001 28001 0 0.00 0 13355 42 0 0
sp_multiply_rows_for_qdistr 20680 20680 0 0.00 0 14121 171 0 0
sp_pay_from_customer 7071 7071 0 0.00 5 7372 183 0 0
sp_pay_to_supplier 5943 5943 0 0.00 6 8873 205 0 0
sp_payment_common 13011 13011 0 0.00 2 4840 47 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 10624 427 0 0
sp_qd_handle_on_invoice_upd_sts 10492 10492 0 0.00 0 20917 627 0 0
sp_qd_handle_on_reserve_upd_sts 9945 9945 0 0.00 0 11969 71 0 0
sp_reserve_write_off 9945 9945 0 0.00 9 12025 303 0 0
sp_supplier_invoice 5482 5482 0 0.00 8 88139 3397 0 0
sp_supplier_order 5403 5403 0 0.00 7 37466 1492 0 0
srv_aggregate_perf_data 117 117 0 0.00 2846 163181 17278 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 798 112291 29548 0 0
srv_find_qd_qs_mism 1043008 1043008 0 0.00 0 12177 11 0 0
srv_make_invnt_saldo 438 438 0 0.00 76 86055 5169 0 0
srv_make_money_saldo 991 991 0 0.00 1 13150 413 0 0
srv_recalc_idx_stat 4 4 0 0.00 90234 404892 296604 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 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 5088 5088 0 0.00 0 10142 1111 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 1 7251 613 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 2977 39 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 1703 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 930 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1458 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 970 39 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 101828 8876 0 0
t$perf-norm:sp_cancel_write_off 925 925 0 0.00 0 2425 21 0 0
t$perf-norm:sp_client_order 20680 20680 0 0.00 0 8203 45 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 2326 21 0 0
t$perf-norm:sp_pay_from_customer 7071 7071 0 0.00 0 6295 22 0 0
t$perf-norm:sp_pay_to_supplier 5943 5943 0 0.00 0 6136 24 0 0
t$perf-norm:sp_reserve_write_off 9946 9946 0 0.00 0 14332 32 0 0
t$perf-norm:sp_supplier_invoice 5482 5482 0 0.00 0 1635 36 0 0
t$perf-norm:sp_supplier_order 5403 5403 0 0.00 0 3588 37 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 876 40 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 535 16 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 3070 34 0 0
t$perf-norm:srv_make_money_saldo 991 991 0 0.00 0 1269 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 149 51 0 0
v_add_invoice_to_stock 10358 10076 282 2.72 0 3235 26 0 282
v_all_customers 37784 37784 0 0.00 0 96 4 0 0
v_all_suppliers 30800 30800 0 0.00 0 71 5 0 0
v_all_wares 41348 41348 0 0.00 0 96 5 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 864 17 0 0
v_cancel_client_order 3386 3386 0 0.00 0 1733 13 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 1509 14 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 1 1148 13 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 497 12 0 0
v_cancel_supplier_invoice 850 832 18 2.11 0 1291 30 0 18
v_cancel_supplier_order 792 792 0 0.00 0 1071 18 0 0
v_cancel_write_off 1850 1850 0 0.00 0 2247 30 0 0
v_max_id_clo_ord 5400 5400 0 0.00 0 2374 6 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 1576 14 0 0
v_max_id_ord_sup 5478 5478 0 0.00 0 4261 9 0 0
v_max_non_paid_invoice 1420 1420 0 0.00 0 2438 12 0 0
v_max_non_paid_realizn 3605 3605 0 0.00 0 2436 9 0 0
v_min_id_clo_ord 5400 5400 0 0.00 0 1861 10 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 4868 16 0 0
v_min_id_ord_sup 5478 5478 0 0.00 0 10682 11 0 0
v_min_non_paid_invoice 5942 5942 0 0.00 0 4449 13 0 0
v_min_non_paid_realizn 7069 7069 0 0.00 0 4837 14 0 0
v_our_firm 10490 10490 0 0.00 0 97 6 0 0
v_reserve_write_off 19882 19882 0 0.00 0 2287 12 0 0
x_lock_depdocs_on_canc_invoice 10982 10982 0 0.00 0 23229 147 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 4058 406 0 0
x_make_qstorno_1000_1200 5403 5403 0 0.00 2 37437 1294 0 0
x_make_qstorno_1200_2000 5482 5482 0 0.00 4 77440 3153 0 0
x_make_qstorno_2100_3300 474951 474951 0 0.00 0 73223 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2023 04:39:07 to 13-01-2023 04:39:11.

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:28 923 8192000000 8931980080 8989704192 55454944 0.672 10 9 18 0 57182720 351072 1376704
02:08:30 39665 8192000000 9741326624 10042134528 811479856 9.012 100 84 168 0 839251328 7599360 20172112
02:10:17 22859 8192000000 9771887040 10080899072 845037840 9.350 100 92 183 0 880759312 13866800 21854672
02:11:43 17933 8192000000 9809650864 10130550784 872489568 9.625 100 81 162 0 914765264 24067008 18208688
02:13:06 5902 8192000000 9867362768 10197344256 916711504 10.064 100 85 170 0 977166176 41384352 19070320
02:14:17 4614 8192000000 9915200400 10255331328 947522016 10.367 100 85 169 1 1029902560 64789872 17590672
02:15:25 4160 8192000000 9959507328 10311720960 983829056 10.721 100 85 162 0 1072589600 72479952 16280592
02:16:33 1957 8192000000 9990732144 10349744128 1013227424 11.007 100 90 172 0 1100487680 71054112 16206144
02:17:36 2215 8192000000 10003606784 10368684032 1035665904 11.223 100 78 154 0 1118987936 67904192 15417840
02:18:41 1070 8192000000 10023124576 10395443200 1055739600 11.416 100 94 179 1 1143901504 70128736 18033168
02:19:44 1016 8192000000 10034116656 10410295296 1060683840 11.463 100 93 181 1 1155448272 76439552 18324880
02:20:48 1255 8192000000 10036021744 10410631168 1065422112 11.508 100 94 179 1 1153665936 71202784 17041040
02:21:52 1098 8192000000 10044563488 10424590336 1072011952 11.571 100 93 178 2 1163262000 72540960 18709088
02:22:56 1170 8192000000 10065187504 10452393984 1076111792 11.610 100 97 187 0 1188639152 93681424 18845936
02:24:00 1371 8192000000 10059758848 10445254656 1083542560 11.681 100 97 177 1 1178965632 79169056 16254016
02:25:02 905 8192000000 10049327984 10437480448 1087278432 11.717 100 93 174 2 1170547504 65238912 18030160
02:26:05 1055 8192000000 10041845632 10427822080 1093169664 11.773 100 93 176 1 1165622976 52636928 19816384
02:27:08 1131 8192000000 10080273936 10469081088 1098569744 11.824 100 97 183 2 1197585072 80848256 18167072
02:28:11 1241 8192000000 10070999648 10458472448 1098552000 11.824 100 99 188 3 1192548096 73858368 20137728
02:29:13 1384 8192000000 10055345920 10447511552 1097204320 11.811 100 93 178 4 1182364048 66494592 18665136
02:30:17 1085 8192000000 10077467904 10466209792 1092707536 11.768 100 97 188 0 1200973568 90084288 18181744
02:31:20 1337 8192000000 10025286064 10407190528 1074785616 11.598 99 84 158 2 1150795584 61044576 14965392
02:32:23 1062 8192000000 10037495968 10417033216 1061669312 11.472 100 94 183 1 1160138368 79500576 18968480
02:33:27 1272 8192000000 10019454688 10396909568 1063586720 11.491 100 95 188 2 1137004336 55064032 18353584
02:34:29 1184 8192000000 10008065520 10383204352 1054541888 11.404 100 98 187 1 1130089776 56995744 18552144
02:35:31 3288 8192000000 10014011472 10394791936 1057696736 11.434 100 74 125 1 1139001792 69104192 12200864
02:36:37 1421 8192000000 10003913520 10375733248 1037457376 11.240 99 82 154 0 1119425952 66769904 15198672
02:37:43 936 8192000000 9995102560 10368126976 1045844400 11.321 100 90 158 1 1113168144 53352464 13971280
02:38:55 4802 8192000000 10005084064 10389905408 1051680416 11.377 100 76 141 0 1126182032 60682544 13819072
02:40:07 2755 8192000000 10019005264 10399043584 1064753808 11.502 100 86 152 3 1132745456 52732176 15259472
02:41:17 7372 8192000000 10052883520 10435842048 1071629360 11.568 99 72 136 1 1163052464 77698608 13724496
02:42:29 19653 8192000000 10091698976 10495922176 1102598080 11.862 100 65 129 0 1207837792 91675904 13563808
02:43:52 8970 8192000000 10110914624 10505814016 1117337184 12.002 100 72 141 1 1228865808 97268688 14259936
02:45:03 911 8192000000 10148059488 10534596608 1119383504 12.021 100 95 181 1 1265547648 126372432 19791712
02:46:10 1373 8192000000 10176342336 10579070976 1131098080 12.132 100 96 181 0 1299893264 147346816 21448368
02:47:16 10466 8192000000 10179331728 10574880768 1134476288 12.164 100 74 132 1 1286844048 138485248 13882512
02:48:32 895 8192000000 10195499792 10601037824 1141075376 12.226 100 94 179 2 1306556896 145869712 19611808
02:49:39 1444 8192000000 10221879216 10616975360 1152444832 12.332 100 84 160 3 1333158576 163186224 17527520
02:50:45 2131 8192000000 10271683136 10672603136 1161925248 12.421 100 84 161 1 1378065280 198180448 17959584
02:51:53 2002 8192000000 10286272384 10693894144 1166292272 12.462 100 81 151 1 1410131808 225330992 18508544
02:52:58 1303 8192000000 10339801664 10738831360 1179930784 12.590 100 95 183 0 1448538752 248353312 20254656
02:54:03 923 8192000000 10370923696 10778116096 1173323808 12.528 100 98 191 1 1480695216 285276992 22094416
02:55:09 1345 8192000000 10414301632 10818514944 1184088032 12.628 100 92 174 0 1521694736 316472656 21134048
02:56:13 1761 8192000000 10418087680 10838470656 1177943760 12.571 100 90 180 0 1525258672 324747616 22567296
02:57:18 807 8192000000 10408369696 10869665792 1179133248 12.582 100 97 190 1 1517117504 315929952 22054304
02:58:25 2047 8192000000 10413339264 10828345344 1184543456 12.633 100 98 190 1 1528362848 319330144 24489248
02:59:32 929 8192000000 10443792880 10856058880 1178933648 12.580 100 97 192 2 1551561936 350335520 22292768
03:00:35 834 8192000000 10447273664 10879045632 1188747088 12.672 100 97 189 0 1556284032 345023360 22513584
03:01:40 925 8192000000 10476889520 10894172160 1188838576 12.673 100 96 190 1 1582964336 371146336 22979424
03:02:46 787 8192000000 10482757504 10941104128 1184480640 12.632 100 97 183 1 1585849504 378608944 22759920
03:03:52 1073 8192000000 10503725872 10918957056 1204955936 12.822 100 96 185 0 1605843536 378451600 22436000
03:04:55 9848 8192000000 10500498640 10970415104 1202740960 12.802 100 56 108 2 1600561440 383211088 14609392
03:06:09 3128 8192000000 10572230320 10995380224 1203571664 12.809 100 70 136 2 1679318448 457640656 18106128
03:07:16 845 8192000000 10610712592 11047694336 1192506720 12.707 100 95 185 3 1715526528 500263200 22756608
03:08:20 3455 8192000000 10618511968 11089670144 1199582592 12.772 100 73 145 1 1733404000 515215632 18605776
03:09:46 1831 8192000000 10496708512 11026980864 1189835472 12.682 100 64 123 0 1599282688 394209616 15237600
03:10:56 5547 8192000000 10526534416 10957430784 1189164704 12.676 100 64 128 1 1627580480 422293520 16122256
03:12:22 27229 8192000000 10413010864 10877128704 1182971600 12.618 99 33 64 0 1526116944 334596128 8549216
03:14:56 21758 8192000000 10340156512 10817802240 1173696720 12.531 100 32 61 0 1487412112 306334768 7380624
03:17:39 40961 8192000000 10199066272 10780463104 1154546112 12.352 99 16 32 0 1387061920 228295744 4220064
03:20:04 86104 8192000000 10118326512 10681630720 1147845536 12.289 100 12 25 1 1215267008 64861392 2560080
03:22:51 67484 8192000000 10067764384 10530742272 1127440304 12.097 99 21 38 0 1166109056 34854896 3813856
03:25:05 112290 8192000000 10086701168 10532782080 1132170992 12.142 99 40 76 0 1180169904 39576064 8422848
03:28:02 105269 8192000000 10150318544 10602962944 1113704944 11.967 97 27 54 0 1194452240 74644640 6102656
03:30:55 71075 8192000000 10096494432 10539950080 1094035344 11.781 96 30 60 0 1145949168 44217504 7696320
03:33:07 25780 8192000000 10115229648 10556715008 1125847856 12.082 99 37 72 1 1200879344 66812320 8219168
03:34:36 982 8192000000 10142394352 10552340480 1130935072 12.130 100 89 175 2 1231402224 82721712 17745440
03:35:39 3129 8192000000 10217341616 10637135872 1147873008 12.290 100 78 153 0 1307314496 143535568 15905920
03:36:46 11931 8192000000 10193574704 10616016896 1131816992 12.138 98 63 126 1 1276257472 128136528 16303952
03:38:03 34373 8192000000 10182260880 10700025856 1142536176 12.239 100 47 93 0 1309937328 157287440 10113712
03:39:41 42178 8192000000 10107298016 10577936384 1112078880 11.952 98 15 30 0 1220617344 105168480 3369984
03:42:29 6204 8192000000 10078631856 10572742656 1136331664 12.181 100 22 41 1 1174593520 34423424 3838432
03:43:51 45149 8192000000 10081908144 10573959168 1106734304 11.901 97 8 16 0 1155293456 46885664 1673488
03:45:37 76262 8192000000 10117294512 10581725184 1125502416 12.079 99 20 40 0 1209413392 79993728 3917248
03:47:59 74050 8192000000 10091915504 10547638272 1134928704 12.168 99 14 26 0 1172168480 34539792 2699984
03:50:13 12180 8192000000 10097456704 10541772800 1146615072 12.278 100 33 66 1 1198288528 45165248 6508208
03:51:28 53128 8192000000 10138974208 10583711744 1126239504 12.086 98 36 68 0 1208305024 75425936 6639584
03:53:21 32853 8192000000 10086721008 10530340864 1119898944 12.026 98 23 46 0 1156946864 32423232 4624688
03:54:55 59846 8192000000 10092060400 10531340288 1080651392 11.654 95 25 50 0 1138452896 53178080 4623424
03:56:56 55243 8192000000 10155875904 10603081728 1102362880 11.860 97 34 68 1 1214220496 105018256 6839360
03:58:55 75114 8192000000 10083466992 10519928832 1117658032 12.005 99 54 109 0 1176013520 46507248 11848240
04:01:29 58905 8192000000 10067977248 10506670080 1130348464 12.125 100 31 62 0 1162744208 26121648 6274096
04:03:29 38586 8192000000 10131218480 10591965184 1132729248 12.147 100 44 88 0 1231752816 89354384 9669184
04:05:11 55250 8192000000 10148163456 10615668736 1148176480 12.292 100 32 64 0 1261124784 105828592 7119712
04:07:28 66901 8192000000 10137099008 10626338816 1142148624 12.236 99 15 28 0 1258355184 112914544 3292016
04:09:36 70333 8192000000 10071079616 10550677504 1121075184 12.037 98 20 40 1 1176706160 51816784 3814192
04:11:52 47266 8192000000 10072702400 10520428544 1123034512 12.056 98 17 34 0 1162483520 35841360 3607648
04:13:41 7011 8192000000 10102095360 10538426368 1145001008 12.263 100 47 91 0 1202231104 48369824 8860272
04:14:53 42421 8192000000 10162225840 10607644672 1140753264 12.223 99 37 70 0 1234371920 86333616 7285040
04:16:39 49570 8192000000 10143516656 10576433152 1139235312 12.208 99 26 52 0 1234034272 88975840 5823120
04:18:39 54895 8192000000 10110322016 10586292224 1136299680 12.181 100 29 58 0 1204148272 62169392 5679200
04:20:42 10053 8192000000 10080559520 10515202048 1127022336 12.093 99 41 82 0 1167852864 32633680 8196848
04:21:52 16500 8192000000 10123793696 10545975296 1109733184 11.930 99 50 100 0 1207552000 87963760 9855056
04:23:10 92563 8192000000 10101453472 10599469056 1064561936 11.500 94 9 18 0 1132168512 65668944 1937632
04:26:28 43662 8192000000 10047731856 10535297024 1094159568 11.782 98 25 51 0 1143392880 44434672 4798640
04:28:12 61209 8192000000 10090701760 10548744192 1114371808 11.974 99 17 34 0 1158617344 41107552 3137984
04:30:14 50791 8192000000 10083892896 10541047808 1133580544 12.155 100 14 28 0 1196310144 59550528 3179072
04:32:05 65013 8192000000 10067486208 10520895488 1111773312 11.949 98 18 35 0 1171499104 55797664 3928128
04:34:10 19381 8192000000 10086641040 10526425088 1120614896 12.033 99 42 84 0 1169343952 40103392 8625664
04:35:32 64728 8192000000 10152265296 10603671552 1153541600 12.343 100 21 42 0 1250733552 92721104 4470848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 13-01-2023 04:39:13 to 13-01-2023 04:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 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 13-01-2023 04:39:31 to 13-01-2023 04:39:33.

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          213937
MON$OLDEST_ACTIVE               217511
MON$OLDEST_SNAPSHOT             217511
MON$NEXT_TRANSACTION            217511
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                       2120640
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 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 ---
:273: 
:274: mscs-test-161.budzdorov.ru    Fri Jan 13 04:37:33 2023
:275:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:276: 
:277: 
:278: mscs-test-161.budzdorov.ru    Fri Jan 13 04:37:34 2023
:279:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:280: 
:281: 
:282: mscs-test-161.budzdorov.ru    Fri Jan 13 04:37:35 2023
:283:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:284: 
--- end of diff output ---

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

13.01.23 04:39:34. 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-13 04:39:36 No severe PSQL-related problems occured
13.01.23 04:39:38. End of report.