Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2022 10: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 15133296 kB
MemAvailable 30461356 kB
Buffers 0 kB
Cached 16562652 kB
SwapCached 1940 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 2022-10-21 10:37:24
DTS_END                         2022-10-21 10:37:24.6610
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/46386
TRN_ID                          223323
ATT_ID                          629
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: 8954.42 27236 1074531
customer order: creation 173.09 501 20771
customer order: refuse 14.86 387 1783
order to supplier: creation 45.63 1557 5476
order to supplier: removal 3.38 165577 405
invoice (draft): creation 46.97 3458 5636
invoice (draft): removal 49.08 968 5890
invoice accept: apply 42.06 25135 5047
invoice accept: cancel 43.89 1316 5267
customer reserve: creation 3891.22 239 466946
customer reserve: removal 4353.88 103 522465
realization accept: apply 83.42 280 10010
realization accept: cancel 76.33 170 9160
payment from customer: creation 59.67 165 7160
payment from customer: removal 5.63 114 675
payment to supplier: creation 49.08 200 5890
payment to supplier: removal 3.44 145 413
service: total inventory turnovers 4.00 4405 480
service: total monetary turnovers 7.81 346 937
service: aggregate perf. data 0.97 14457 116
service: refresh index statistics 0.03 325203 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2022 10:38:28 to 21-10-2022 10:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7175
TEST_TIME 2 5949
TEST_TIME 3 5846
TEST_TIME 4 6170
TEST_TIME 5 6716
TEST_TIME 6 7283
TEST_TIME 7 6970
TEST_TIME 8 6875
TEST_TIME 9 6834
TEST_TIME 10 6562
TEST_TIME 11 6500
TEST_TIME 12 6914
TEST_TIME 13 6971
TEST_TIME 14 7318
TEST_TIME 15 7349
TEST_TIME 16 7568
TEST_TIME 17 7667
TEST_TIME 18 7629
TEST_TIME 19 7780
TEST_TIME 20 7802
TEST_TIME 21 8005
TEST_TIME 22 8092
TEST_TIME 23 8273
TEST_TIME 24 8599
TEST_TIME 25 8499
TEST_TIME 26 8951
TEST_TIME 27 9225
TEST_TIME 28 9681
TEST_TIME 29 9918
TEST_TIME 30 10076
TEST_TIME 31 10333
TEST_TIME 32 10801
TEST_TIME 33 11193
TEST_TIME 34 11042
TEST_TIME 35 10773
TEST_TIME 36 10708
TEST_TIME 37 10602
TEST_TIME 38 10419
TEST_TIME 39 10460
TEST_TIME 40 10450
TEST_TIME 41 10291
TEST_TIME 42 10260
TEST_TIME 43 10109
TEST_TIME 44 9992
TEST_TIME 45 9861
TEST_TIME 46 9980
TEST_TIME 47 10043
TEST_TIME 48 10030
TEST_TIME 49 9967
TEST_TIME 50 10000
TEST_TIME 51 9895
TEST_TIME 52 9843
TEST_TIME 53 9671
TEST_TIME 54 9585
TEST_TIME 55 9573
TEST_TIME 56 9465
TEST_TIME 57 9412
TEST_TIME 58 9368
TEST_TIME 59 9390
TEST_TIME 60 9349
TEST_TIME 61 9299
TEST_TIME 62 9309
TEST_TIME 63 9248
TEST_TIME 64 9256
TEST_TIME 65 9238
TEST_TIME 66 9284
TEST_TIME 67 9233
TEST_TIME 68 9298
TEST_TIME 69 9348
TEST_TIME 70 9374
TEST_TIME 71 9359
TEST_TIME 72 9373
TEST_TIME 73 9337
TEST_TIME 74 9430
TEST_TIME 75 9419
TEST_TIME 76 9458
TEST_TIME 77 9435
TEST_TIME 78 9427
TEST_TIME 79 9385
TEST_TIME 80 9335
TEST_TIME 81 9267
TEST_TIME 82 9244
TEST_TIME 83 9173
TEST_TIME 84 9114
TEST_TIME 85 9097
TEST_TIME 86 9123
TEST_TIME 87 9076
TEST_TIME 88 9039
TEST_TIME 89 9023
TEST_TIME 90 8996
TEST_TIME 91 8998
TEST_TIME 92 9005
TEST_TIME 93 8967
TEST_TIME 94 9081
TEST_TIME 95 9085
TEST_TIME 96 9170
TEST_TIME 97 9237
TEST_TIME 98 9241
TEST_TIME 99 9273
TEST_TIME 100 9280
TEST_TIME 101 9253
TEST_TIME 102 9175
TEST_TIME 103 9148
TEST_TIME 104 9151
TEST_TIME 105 9152
TEST_TIME 106 9110
TEST_TIME 107 9100
TEST_TIME 108 9089
TEST_TIME 109 9073
TEST_TIME 110 9055
TEST_TIME 111 9021
TEST_TIME 112 9004
TEST_TIME 113 8987
TEST_TIME 114 8960
TEST_TIME 115 8948
TEST_TIME 116 8944
TEST_TIME 117 8960
TEST_TIME 118 8999
TEST_TIME 119 8998
TEST_TIME 120 8975

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-10-2022 10:38:31 to 21-10-2022 10:38:35.

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 1504872 1504872 0 0.00 0 24053 13 0 0
doc_list_biud 1504864 1504864 0 0.00 0 36689 41 0 0
sp_add_invoice_to_stock 5140 5047 93 1.80 76 230467 25135 0 93
sp_cancel_adding_invoice 5267 5267 0 0.00 6 82309 1316 0 0
sp_cancel_client_order 1783 1783 0 0.00 6 11089 388 0 0
sp_cancel_customer_reserve 522465 522465 0 0.00 1 34420 103 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 4542 114 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 4 4327 145 0 0
sp_cancel_supplier_invoice 5908 5890 18 0.30 2 36696 969 0 18
sp_cancel_supplier_order 405 405 0 0.00 17 1446111 165578 0 0
sp_cancel_write_off 9160 9160 0 0.00 2 10755 171 0 0
sp_client_order 20771 20771 0 0.00 5 27219 501 0 0
sp_customer_reserve 466946 466946 0 0.00 0 100188 240 0 0
sp_fill_shopping_cart 31869 31869 0 0.00 1 15882 89 0 0
sp_fill_shopping_cart_clo_res 466922 466922 0 0.00 0 14487 17 0 0
sp_get_clo_for_invoice 5016 5016 0 0.00 0 36239 324 0 0
sp_kill_cost_storno 15515 15515 0 0.00 0 8257 70 0 0
sp_kill_qstorno_ret_qs2qd 1060385 1060385 0 0.00 0 36687 45 0 0
sp_kill_qty_storno 559996 559996 0 0.00 0 36687 103 0 0
sp_lock_selected_doc 20899 20899 0 0.00 0 10379 8 0 0
sp_make_cost_storno 28076 28076 0 0.00 0 22837 82 0 0
sp_multiply_rows_for_pdistr 28075 28075 0 0.00 0 9737 40 0 0
sp_multiply_rows_for_qdistr 20771 20771 0 0.00 0 9811 180 0 0
sp_pay_from_customer 7160 7160 0 0.00 5 12221 166 0 0
sp_pay_to_supplier 5890 5890 0 0.00 6 16093 200 0 0
sp_payment_common 13049 13049 0 0.00 2 11214 47 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 10997 311 0 0
sp_qd_handle_on_invoice_upd_sts 10283 10283 0 0.00 0 17741 518 0 0
sp_qd_handle_on_reserve_upd_sts 10010 10010 0 0.00 0 10856 64 0 0
sp_reserve_write_off 10010 10010 0 0.00 8 21671 280 0 0
sp_supplier_invoice 5636 5636 0 0.00 10 91782 3458 0 0
sp_supplier_order 5476 5476 0 0.00 7 59833 1557 0 0
srv_aggregate_perf_data 116 116 0 0.00 2231 174707 14458 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 747 146253 34705 0 0
srv_find_qd_qs_mism 1025376 1025376 0 0.00 0 24052 10 0 0
srv_make_invnt_saldo 480 480 0 0.00 53 68193 4405 0 0
srv_make_money_saldo 937 937 0 0.00 1 12340 346 0 0
srv_recalc_idx_stat 4 4 0 0.00 58539 676950 325203 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5049 5049 0 0.00 1 25678 909 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 2 9198 422 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 1879 27 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 1837 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 1271 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 654 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 784 31 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 78128 5980 0 0
t$perf-norm:sp_cancel_write_off 1040 1040 0 0.00 0 1476 21 0 0
t$perf-norm:sp_client_order 20771 20771 0 0.00 0 39978 39 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 14322 26 0 0
t$perf-norm:sp_pay_from_customer 7160 7160 0 0.00 0 27140 25 0 0
t$perf-norm:sp_pay_to_supplier 5890 5890 0 0.00 0 8939 18 0 0
t$perf-norm:sp_reserve_write_off 10010 10010 0 0.00 0 5910 23 0 0
t$perf-norm:sp_supplier_invoice 5636 5636 0 0.00 0 23573 34 0 0
t$perf-norm:sp_supplier_order 5476 5476 0 0.00 0 4300 31 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 1307 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 144 4 0 0
t$perf-norm:srv_make_invnt_saldo 480 480 0 0.00 0 5780 23 0 0
t$perf-norm:srv_make_money_saldo 938 938 0 0.00 0 2921 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 174 99 0 0
v_add_invoice_to_stock 10210 10024 186 1.82 0 4795 26 0 186
v_all_customers 38078 38078 0 0.00 0 158 4 0 0
v_all_suppliers 31150 31150 0 0.00 0 171 4 0 0
v_all_wares 41542 41542 0 0.00 0 212 4 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 1283 15 0 0
v_cancel_client_order 3566 3566 0 0.00 0 1888 9 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 4508 13 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 5004 17 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 4317 24 0 0
v_cancel_supplier_invoice 836 800 36 4.30 0 347 22 0 36
v_cancel_supplier_order 810 810 0 0.00 0 4980 20 0 0
v_cancel_write_off 2080 2080 0 0.00 0 3197 25 0 0
v_max_id_clo_ord 5471 5471 0 0.00 0 261 5 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 3671 18 0 0
v_max_id_ord_sup 5624 5624 0 0.00 0 4163 9 0 0
v_max_non_paid_invoice 1409 1409 0 0.00 0 1255 8 0 0
v_max_non_paid_realizn 3663 3663 0 0.00 0 1197 8 0 0
v_min_id_clo_ord 5471 5471 0 0.00 0 2547 9 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 4366 12 0 0
v_min_id_ord_sup 5624 5624 0 0.00 0 5270 15 0 0
v_min_non_paid_invoice 5889 5889 0 0.00 0 11208 17 0 0
v_min_non_paid_realizn 7160 7160 0 0.00 0 5807 17 0 0
v_our_firm 10455 10455 0 0.00 0 52 5 0 0
v_reserve_write_off 20020 20020 0 0.00 0 4979 10 0 0
x_lock_depdocs_on_canc_invoice 10757 10757 0 0.00 0 24572 138 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 2903 324 0 0
x_make_qstorno_1000_1200 5476 5476 0 0.00 2 59817 1387 0 0
x_make_qstorno_1200_2000 5636 5636 0 0.00 4 81731 3211 0 0
x_make_qstorno_2100_3300 466945 466945 0 0.00 0 100182 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2022 10:38:36 to 21-10-2022 10:38:40.

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
08:07:28 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:29 7608 8192000000 9707788160 10005471232 794741728 8.843 100 84 168 0 823122816 8391840 19989248
08:09:46 14964 8192000000 9765787936 10077163520 840921056 9.309 100 85 170 0 872587680 11803888 19862736
08:11:16 14857 8192000000 9799375312 10117931008 866706896 9.567 100 82 164 0 904902752 19446000 18749856
08:12:43 4804 8192000000 9862589456 10193326080 910757664 10.005 100 90 180 0 977516608 47187744 19571200
08:13:52 4841 8192000000 9901498864 10240774144 938179728 10.275 100 85 166 0 1026106784 70783136 17143920
08:15:02 2051 8192000000 9966028032 10315997184 983250112 10.716 100 89 172 0 1074705312 74743680 16711520
08:16:05 1351 8192000000 9995145200 10356908032 1018680832 11.059 100 95 174 0 1110087776 74917872 16489072
08:17:09 1004 8192000000 10009252000 10380173312 1038157568 11.247 100 92 170 3 1128903744 73258000 17488176
08:18:11 992 8192000000 10034880944 10411323392 1061438512 11.470 100 94 180 1 1152650144 73371024 17840608
08:19:14 1254 8192000000 10045568400 10425905152 1071526864 11.567 100 97 185 1 1164341520 74705552 18109104
08:20:16 952 8192000000 10051042528 10436497408 1079222672 11.640 100 96 186 3 1173172960 74404800 19545488
08:21:18 8048 8192000000 10042134528 10436202496 1083580384 11.682 100 64 123 3 1165757920 69606848 12570688
08:22:26 1112 8192000000 10044786192 10433740800 1091753232 11.759 100 90 175 0 1163072576 52783024 18536320
08:23:28 1312 8192000000 10059111312 10455150592 1095413264 11.794 100 71 142 2 1183974368 74476032 14085072
08:24:31 1052 8192000000 10054597008 10443415552 1098086656 11.819 100 97 188 0 1178805504 59397520 21321328
08:25:33 1019 8192000000 10046272784 10433802240 1098122624 11.820 100 94 187 0 1163952384 45866704 19963056
08:26:34 1796 8192000000 10067416912 10462756864 1098667600 11.825 100 83 160 0 1184796608 69354128 16774880
08:27:37 1017 8192000000 10060218048 10450665472 1092733792 11.769 100 88 163 2 1181664784 71765248 17165744
08:28:39 1002 8192000000 10060946176 10457128960 1097754992 11.816 100 85 163 0 1183779984 69403328 16621664
08:29:41 1039 8192000000 10042476608 10431123456 1084818400 11.693 100 89 165 2 1161024368 59264032 16941936
08:30:44 1137 8192000000 10004677488 10389311488 1060444960 11.461 100 90 169 2 1128167360 50686624 17035776
08:31:46 1122 8192000000 10024087296 10399694848 1069995696 11.552 100 94 174 1 1146953296 59203808 17753792
08:32:48 1144 8192000000 9973226224 10349514752 1036179632 11.228 98 80 155 2 1097914144 46399664 15334848
08:33:50 1189 8192000000 9987881472 10359771136 1018676912 11.059 99 91 164 3 1111776032 76282432 16816688
08:34:52 1345 8192000000 9995983616 10367819776 1025746304 11.127 100 88 169 0 1117907728 75190656 16970768
08:35:54 1040 8192000000 9964609968 10334892032 1022573888 11.097 100 87 163 1 1088975904 49605824 16796192
08:36:56 1205 8192000000 9985139744 10358763520 1035908816 11.225 99 84 161 0 1109614784 56641328 17064640
08:38:03 2858 8192000000 10013640064 10392064000 1075734640 11.607 100 88 161 0 1132473184 39886832 16851712
08:39:16 11354 8192000000 10055789312 10449235968 1086195152 11.706 100 71 137 0 1183100880 84100304 12805424
08:40:56 5931 8192000000 10072637872 10463133696 1104325040 11.879 100 79 153 3 1189755136 70195312 15234784
08:42:07 2421 8192000000 10124536016 10521776128 1120067216 12.028 100 88 164 1 1244341392 105490432 18783744
08:43:13 1455 8192000000 10149696256 10539769856 1126659632 12.090 100 86 167 1 1265983040 122316992 17006416
08:44:17 2110 8192000000 10156726064 10557652992 1136599552 12.184 100 73 130 0 1279717952 129445120 13673280
08:45:22 1190 8192000000 10194206736 10598060032 1146541296 12.277 100 90 171 2 1306468432 142560800 17366336
08:46:28 1135 8192000000 10220972288 10619289600 1151974848 12.328 100 91 167 0 1338303728 166016096 20312784
08:47:37 3701 8192000000 10262920720 10672644096 1163201360 12.433 100 85 162 1 1374002656 192563456 18237840
08:48:47 1187 8192000000 10273576208 10689585152 1168896048 12.487 100 86 163 1 1387960768 200763840 18300880
08:49:51 1279 8192000000 10316930448 10731069440 1167516448 12.474 100 95 186 0 1430633328 240974912 22141968
08:50:55 1078 8192000000 10359122192 10777640960 1189872496 12.682 100 94 176 0 1469390416 258351328 21166592
08:51:59 897 8192000000 10368446448 10782142464 1195209312 12.732 100 93 180 1 1480082192 264027952 20844928
08:53:02 841 8192000000 10387059648 10809446400 1196949552 12.748 100 97 187 1 1494692144 276233968 21508624
08:54:05 1148 8192000000 10412306656 10830237696 1208015536 12.851 100 93 178 0 1523950304 295083232 20851536
08:55:09 970 8192000000 10431170160 10856267776 1207065408 12.842 100 98 195 2 1539579872 310962528 21551936
08:56:13 1093 8192000000 10476856736 10891550720 1203498640 12.809 100 93 180 0 1583289904 358120528 21670736
08:57:18 863 8192000000 10501964624 10945064960 1205477376 12.827 100 97 195 0 1607318736 379083728 22757632
08:58:21 1821 8192000000 10547561488 10971992064 1201699744 12.792 100 92 181 1 1653747744 429957760 22090240
08:59:28 3738 8192000000 10529279776 10968031232 1175871040 12.552 99 88 169 1 1631595088 435308560 20415488
09:00:34 2763 8192000000 10517781504 10957463552 1181326480 12.603 100 74 145 0 1622064848 422112768 18625600
09:01:40 2800 8192000000 10511420768 10937909248 1172197776 12.517 99 71 139 1 1607237360 418858976 16180608
09:02:47 744 8192000000 10504107360 10949124096 1161472256 12.417 100 86 168 3 1609016768 426533984 21010528
09:03:51 12193 8192000000 10452182080 10964938752 1161435920 12.417 100 47 90 0 1602665888 430008128 11221840
09:05:06 11588 8192000000 10425584496 10910236672 1154072352 12.348 100 32 62 0 1546866592 384280128 8514112
09:06:33 34776 8192000000 10314658368 10754498560 1125568464 12.080 98 32 64 0 1416409568 283255504 7585600
09:08:46 32462 8192000000 10220945344 10723233792 1113413232 11.965 98 14 28 0 1355787296 238939504 3434560
09:10:47 10670 8192000000 10062722928 10482352128 1133405392 12.153 100 63 106 5 1159750544 17174496 9170656
09:11:59 42447 8192000000 10100003584 10533081088 1139621840 12.212 100 61 99 3 1195688816 46194720 9872256
09:13:44 19713 8192000000 10110286864 10582605824 1120631040 12.033 98 17 31 0 1220644336 96633312 3379984
09:15:10 85693 8192000000 10123686096 10629201920 1127697296 12.100 99 19 39 0 1236819328 105209808 3912224
09:19:13 61503 8192000000 10092418608 10558922752 1135822304 12.176 100 17 32 1 1180842944 41926048 3094592
09:21:22 48486 8192000000 10141100448 10568790016 1132941728 12.149 99 37 67 1 1207618960 67159072 7518160
09:23:13 56225 8192000000 10107299376 10592612352 1105427776 11.889 97 13 27 0 1197995904 89597584 2970544
09:25:23 130265 8192000000 10111643024 10596712448 1121714896 12.043 99 14 27 0 1165744272 41385168 2644208
09:28:34 10918 8192000000 10060893296 10499055616 1130451968 12.126 100 58 111 2 1151485504 8013328 13020208
09:30:22 108293 8192000000 10088031024 10532274176 1115418688 11.984 98 44 85 0 1143658304 19686288 8553328
09:33:12 88813 8192000000 10094404288 10532151296 1142138832 12.236 100 43 78 2 1183955200 33610192 8206176
09:35:43 4333 8192000000 10122009728 10547552256 1142967216 12.243 100 70 131 3 1217763776 61816096 12980464
09:36:48 38752 8192000000 10153346528 10597851136 1148912608 12.299 99 32 63 0 1209627472 53061440 7653424
09:38:29 72819 8192000000 10123020832 10611699712 1140975872 12.225 99 21 42 0 1232242720 86616464 4650384
09:40:42 70724 8192000000 10125847344 10602123264 1118709296 12.015 97 13 26 0 1229627344 107713072 3204976
09:43:35 15156 8192000000 10085123584 10510237696 1123119920 12.056 98 65 122 2 1156594912 19064480 14410512
09:45:07 40742 8192000000 10113539088 10577723392 1143383328 12.247 100 20 40 0 1245133328 97508000 4242000
09:46:48 35020 8192000000 10051969664 10482094080 1073378032 11.584 95 19 37 0 1108973744 32076992 3518720
09:48:26 27898 8192000000 10106795792 10549673984 1130112576 12.122 100 35 70 1 1208572560 71525344 6934640
09:49:59 54095 8192000000 10125938384 10561224704 1087723952 11.721 96 37 72 0 1177980640 82660480 7596208
09:51:57 66898 8192000000 10113802096 10575171584 1116813280 11.997 99 25 50 2 1159307120 37633472 4860368
09:54:13 53040 8192000000 10141769360 10595491840 1141980928 12.234 100 27 50 0 1209221168 62418784 4821456
09:56:06 135648 8192000000 10074440480 10521735168 1098444928 11.823 98 36 70 0 1151371520 45933792 6992800
09:59:23 47062 8192000000 10073406256 10527457280 1099384192 11.832 98 21 42 0 1161845376 58195712 4265472
10:01:11 82554 8192000000 10068392720 10534117376 1091540848 11.757 97 17 34 0 1179412944 83978752 3893344
10:03:38 69513 8192000000 10064137408 10511171584 1084020704 11.686 97 13 22 0 1145997168 59524144 2452320
10:05:47 30886 8192000000 10115860400 10552455168 1120232864 12.029 99 34 66 0 1209228448 81774384 7221200
10:07:23 18436 8192000000 10168021856 10612961280 1149354272 12.303 100 41 82 0 1267667184 108954976 9357936
10:08:48 7679 8192000000 10201329696 10632720384 1161711680 12.419 100 75 137 4 1303618992 125008432 16898880
10:10:12 9717 8192000000 10211830160 10647539712 1163346032 12.435 100 42 80 1 1305256672 132997776 8912864
10:11:25 25961 8192000000 10252800304 10727841792 1153635984 12.344 100 39 76 0 1345178992 182864032 8678976
10:13:35 53141 8192000000 10144746016 10590257152 1120398240 12.031 98 27 54 0 1201255104 75301872 5554992
10:15:33 32188 8192000000 10137881280 10576080896 1144685776 12.260 100 47 89 1 1240479456 86614736 9178944
10:17:05 47848 8192000000 10115436672 10582056960 1140466560 12.220 99 36 71 0 1208963072 61387696 7108816
10:19:01 146252 8192000000 10122716752 10603442176 1146285392 12.275 100 34 68 0 1239512432 85869776 7357264
10:22:28 41876 8192000000 10092076640 10568044544 1141360880 12.228 99 26 52 0 1213640608 67052432 5227296
10:24:16 58411 8192000000 10101016672 10554773504 1125221792 12.076 98 16 30 0 1169060640 40224368 3614480
10:26:20 52991 8192000000 10100472304 10580074496 1150333872 12.313 100 18 36 1 1209463248 55367072 3762304
10:28:35 49734 8192000000 10092187552 10547331072 1146431664 12.276 100 22 42 0 1184117984 33097072 4589248
10:30:34 107374 8192000000 10104798192 10572427264 1111908624 11.950 97 7 13 0 1147826976 34824528 1093824
10:33:34 65413 8192000000 10122842096 10597429248 1123075600 12.056 98 19 36 0 1227275776 100237728 3962448
10:35:41 23419 8192000000 10109451152 10562781184 1145711792 12.269 100 29 58 0 1216261728 64071584 6478352
10:37:04 36080 8192000000 8984546720 9212297216 137545008 1.651 13 2 4 0 153899904 16042048 312848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-10-2022 10:38:42 to 21-10-2022 10:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2022 10:38:59 to 21-10-2022 10:39:01.

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          224506
MON$OLDEST_ACTIVE               224507
MON$OLDEST_SNAPSHOT             224507
MON$NEXT_TRANSACTION            224507
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                       2067808
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2861 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2861 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

21.10.22 10:39:02. 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
2022-10-21 10:39:05 No severe PSQL-related problems occured
21.10.22 10:39:06. End of report.