Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.03.2023 13:37
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 15256624 kB
MemAvailable 30404400 kB
Buffers 0 kB
Cached 16429116 kB
SwapCached 3224 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-13 13:37:27
DTS_END                         2023-03-13 13:37:27.2700
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/44326
TRN_ID                          225521
ATT_ID                          675
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: 8963.63 19145 1075635
customer order: creation 177.07 504 21248
customer order: refuse 14.38 383 1726
order to supplier: creation 46.52 1363 5582
order to supplier: removal 3.42 152030 410
invoice (draft): creation 47.10 3161 5652
invoice (draft): removal 48.25 953 5790
invoice accept: apply 42.83 25923 5139
invoice accept: cancel 43.29 1184 5195
customer reserve: creation 3994.58 247 479349
customer reserve: removal 4251.30 96 510156
realization accept: apply 85.40 263 10248
realization accept: cancel 75.74 179 9089
payment from customer: creation 60.58 198 7270
payment from customer: removal 5.82 116 698
payment to supplier: creation 50.95 193 6114
payment to supplier: removal 3.73 106 448
service: total inventory turnovers 4.33 3523 520
service: total monetary turnovers 7.29 385 875
service: aggregate perf. data 1.02 12694 122
service: refresh index statistics 0.03 179406 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-03-2023 13:38:17 to 13-03-2023 13:38:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8264
TEST_TIME 2 7087
TEST_TIME 3 5930
TEST_TIME 4 6353
TEST_TIME 5 6283
TEST_TIME 6 6200
TEST_TIME 7 6154
TEST_TIME 8 6125
TEST_TIME 9 6392
TEST_TIME 10 6325
TEST_TIME 11 6497
TEST_TIME 12 6684
TEST_TIME 13 6656
TEST_TIME 14 6873
TEST_TIME 15 7137
TEST_TIME 16 7182
TEST_TIME 17 7382
TEST_TIME 18 7707
TEST_TIME 19 7873
TEST_TIME 20 7937
TEST_TIME 21 8110
TEST_TIME 22 8373
TEST_TIME 23 8794
TEST_TIME 24 8882
TEST_TIME 25 9125
TEST_TIME 26 9561
TEST_TIME 27 9963
TEST_TIME 28 9994
TEST_TIME 29 9945
TEST_TIME 30 10139
TEST_TIME 31 10821
TEST_TIME 32 10608
TEST_TIME 33 10838
TEST_TIME 34 10719
TEST_TIME 35 10443
TEST_TIME 36 10344
TEST_TIME 37 10427
TEST_TIME 38 10239
TEST_TIME 39 10146
TEST_TIME 40 10020
TEST_TIME 41 9976
TEST_TIME 42 9869
TEST_TIME 43 9919
TEST_TIME 44 9882
TEST_TIME 45 9764
TEST_TIME 46 9761
TEST_TIME 47 9621
TEST_TIME 48 9483
TEST_TIME 49 9498
TEST_TIME 50 9447
TEST_TIME 51 9404
TEST_TIME 52 9379
TEST_TIME 53 9512
TEST_TIME 54 9426
TEST_TIME 55 9458
TEST_TIME 56 9420
TEST_TIME 57 9399
TEST_TIME 58 9510
TEST_TIME 59 9477
TEST_TIME 60 9452
TEST_TIME 61 9446
TEST_TIME 62 9427
TEST_TIME 63 9477
TEST_TIME 64 9418
TEST_TIME 65 9404
TEST_TIME 66 9325
TEST_TIME 67 9325
TEST_TIME 68 9237
TEST_TIME 69 9175
TEST_TIME 70 9140
TEST_TIME 71 9144
TEST_TIME 72 9174
TEST_TIME 73 9163
TEST_TIME 74 9126
TEST_TIME 75 9094
TEST_TIME 76 9083
TEST_TIME 77 9140
TEST_TIME 78 9214
TEST_TIME 79 9220
TEST_TIME 80 9292
TEST_TIME 81 9298
TEST_TIME 82 9269
TEST_TIME 83 9218
TEST_TIME 84 9186
TEST_TIME 85 9153
TEST_TIME 86 9124
TEST_TIME 87 9126
TEST_TIME 88 9093
TEST_TIME 89 9095
TEST_TIME 90 9069
TEST_TIME 91 9046
TEST_TIME 92 9047
TEST_TIME 93 9052
TEST_TIME 94 9063
TEST_TIME 95 9038
TEST_TIME 96 9026
TEST_TIME 97 9030
TEST_TIME 98 9043
TEST_TIME 99 9087
TEST_TIME 100 9065
TEST_TIME 101 9214
TEST_TIME 102 9359
TEST_TIME 103 9316
TEST_TIME 104 9238
TEST_TIME 105 9185
TEST_TIME 106 9183
TEST_TIME 107 9125
TEST_TIME 108 9079
TEST_TIME 109 9054
TEST_TIME 110 9068
TEST_TIME 111 9036
TEST_TIME 112 9032
TEST_TIME 113 9040
TEST_TIME 114 9013
TEST_TIME 115 8998
TEST_TIME 116 9026
TEST_TIME 117 9031
TEST_TIME 118 9038
TEST_TIME 119 9021
TEST_TIME 120 8996

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-03-2023 13:38:21 to 13-03-2023 13:38:24.

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 1515448 1515448 0 0.00 0 42940 13 0 0
doc_list_biud 1515440 1515440 0 0.00 0 28442 38 0 0
sp_add_invoice_to_stock 5289 5139 150 2.83 36 208114 25924 0 150
sp_cancel_adding_invoice 5195 5195 0 0.00 7 86010 1184 0 0
sp_cancel_client_order 1726 1726 0 0.00 6 11873 384 0 0
sp_cancel_customer_reserve 510156 510156 0 0.00 1 25994 97 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 8593 117 0 0
sp_cancel_pay_to_supplier 448 448 0 0.00 3 3477 106 0 0
sp_cancel_supplier_invoice 5799 5790 9 0.15 3 23198 953 0 9
sp_cancel_supplier_order 410 410 0 0.00 9 1289879 152030 0 0
sp_cancel_write_off 9089 9089 0 0.00 2 11333 179 0 0
sp_client_order 21248 21248 0 0.00 5 43290 504 0 0
sp_customer_reserve 479349 479349 0 0.00 0 54009 248 0 0
sp_fill_shopping_cart 32473 32473 0 0.00 1 28595 81 0 0
sp_fill_shopping_cart_clo_res 479323 479323 0 0.00 0 15270 18 0 0
sp_get_clo_for_invoice 5106 5106 0 0.00 0 26540 329 0 0
sp_kill_cost_storno 15430 15430 0 0.00 0 19618 71 0 0
sp_kill_qstorno_ret_qs2qd 1035601 1035601 0 0.00 0 25986 43 0 0
sp_kill_qty_storno 547720 547720 0 0.00 0 28442 98 0 0
sp_lock_selected_doc 21203 21203 0 0.00 0 7719 6 0 0
sp_make_cost_storno 28738 28738 0 0.00 0 42928 80 0 0
sp_multiply_rows_for_pdistr 28738 28738 0 0.00 0 9279 38 0 0
sp_multiply_rows_for_qdistr 21246 21246 0 0.00 0 14817 190 0 0
sp_pay_from_customer 7270 7270 0 0.00 6 21619 198 0 0
sp_pay_to_supplier 6114 6114 0 0.00 6 11985 194 0 0
sp_payment_common 13383 13383 0 0.00 2 8489 46 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 1 11813 314 0 0
sp_qd_handle_on_invoice_upd_sts 10301 10301 0 0.00 0 28441 481 0 0
sp_qd_handle_on_reserve_upd_sts 10248 10248 0 0.00 0 13153 60 0 0
sp_reserve_write_off 10248 10248 0 0.00 8 19456 264 0 0
sp_supplier_invoice 5652 5652 0 0.00 9 91072 3162 0 0
sp_supplier_order 5582 5582 0 0.00 7 48556 1364 0 0
srv_aggregate_perf_data 122 122 0 0.00 2474 83293 12694 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 686 111211 35254 0 0
srv_find_qd_qs_mism 1024328 1024328 0 0.00 0 9704 11 0 0
srv_make_invnt_saldo 520 520 0 0.00 46 76427 3524 0 0
srv_make_money_saldo 875 875 0 0.00 1 9478 385 0 0
srv_recalc_idx_stat 4 4 0 0.00 70834 297128 179407 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 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 5148 5148 0 0.00 0 16222 792 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 1 11281 463 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 6667 32 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 1149 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 3667 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 448 448 0 0.00 0 3483 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 2597 34 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 80390 6115 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 2131 21 0 0
t$perf-norm:sp_client_order 21248 21248 0 0.00 0 24166 33 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 2371 14 0 0
t$perf-norm:sp_pay_from_customer 7270 7270 0 0.00 0 5326 16 0 0
t$perf-norm:sp_pay_to_supplier 6114 6114 0 0.00 0 7323 19 0 0
t$perf-norm:sp_reserve_write_off 10248 10248 0 0.00 0 6521 20 0 0
t$perf-norm:sp_supplier_invoice 5652 5652 0 0.00 0 7559 27 0 0
t$perf-norm:sp_supplier_order 5582 5582 0 0.00 0 4586 28 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 426 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 295 13 0 0
t$perf-norm:srv_make_invnt_saldo 520 520 0 0.00 0 2559 21 0 0
t$perf-norm:srv_make_money_saldo 875 875 0 0.00 0 860 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 165 60 0 0
v_add_invoice_to_stock 10508 10208 300 2.85 0 15786 29 0 300
v_all_customers 38644 38644 0 0.00 0 98 3 0 0
v_all_suppliers 31790 31790 0 0.00 0 72 4 0 0
v_all_wares 42492 42492 0 0.00 0 203 4 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 9365 33 0 0
v_cancel_client_order 3452 3452 0 0.00 0 4331 11 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 8569 15 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 4392 15 0 0
v_cancel_payment_to_supplier 896 896 0 0.00 0 406 10 0 0
v_cancel_supplier_invoice 886 868 18 2.03 0 16121 44 0 18
v_cancel_supplier_order 820 820 0 0.00 0 2090 11 0 0
v_cancel_write_off 2028 2028 0 0.00 0 3992 25 0 0
v_max_id_clo_ord 5581 5581 0 0.00 0 2859 5 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 8778 23 0 0
v_max_id_ord_sup 5646 5646 0 0.00 0 6479 9 0 0
v_max_non_paid_invoice 1445 1445 0 0.00 0 2519 9 0 0
v_max_non_paid_realizn 3854 3854 0 0.00 0 7199 11 0 0
v_min_id_clo_ord 5581 5581 0 0.00 0 1343 7 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 2880 12 0 0
v_min_id_ord_sup 5646 5646 0 0.00 0 4298 11 0 0
v_min_non_paid_invoice 6113 6113 0 0.00 0 4966 16 0 0
v_min_non_paid_realizn 7269 7269 0 0.00 0 5167 19 0 0
v_our_firm 10678 10678 0 0.00 0 93 4 0 0
v_reserve_write_off 20493 20493 0 0.00 0 19064 13 0 0
x_lock_depdocs_on_canc_invoice 10551 10551 0 0.00 0 26724 123 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 2962 284 0 0
x_make_qstorno_1000_1200 5582 5582 0 0.00 2 48539 1219 0 0
x_make_qstorno_1200_2000 5652 5652 0 0.00 5 73517 2917 0 0
x_make_qstorno_2100_3300 479348 479348 0 0.00 0 51575 219 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 13-03-2023 13:38:25 to 13-03-2023 13:38:30.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:31 940 8192000000 8932584224 8988987392 15916240 0.193 6 3 6 0 16427296 161568 349488
11:08:48 32724 8192000000 9729967296 10031796224 805930800 8.956 100 84 166 1 833920576 8095616 19894160
11:10:30 18824 8192000000 9771923840 10081361920 846296672 9.363 100 89 178 0 881042320 13818032 20927616
11:12:01 12634 8192000000 9801988048 10123706368 871379200 9.614 100 80 160 0 916237552 26306096 18552256
11:13:22 9878 8192000000 9885467920 10220425216 922680368 10.123 100 85 169 0 995412144 55024528 17707248
11:14:35 4268 8192000000 9941479872 10286804992 959361584 10.483 100 92 178 0 1054752064 76679184 18711296
11:15:45 1520 8192000000 9975860288 10329645056 995342672 10.833 100 91 183 0 1095439072 81373120 18723280
11:16:49 2008 8192000000 9989128960 10356871168 1026536896 11.135 100 89 173 1 1100158528 55706480 17915152
11:17:53 1163 8192000000 10028651328 10398482432 1046458192 11.327 100 95 180 0 1149448496 85174512 17815792
11:18:56 964 8192000000 10036077008 10412204032 1057798464 11.435 100 94 186 2 1156665936 80325968 18541504
11:19:59 1090 8192000000 10013924464 10392350720 1062073472 11.476 100 86 171 1 1133438224 53004480 18360272
11:21:01 4788 8192000000 10032549216 10413907968 1066819648 11.522 100 78 148 1 1168535680 86688224 15027808
11:22:07 1277 8192000000 10040634512 10420371456 1070357888 11.555 100 99 188 1 1159742816 69237600 20147328
11:23:10 1197 8192000000 10049856448 10434867200 1075575200 11.605 100 90 173 2 1171346352 78652608 17118544
11:24:12 1200 8192000000 10049608928 10433921024 1087996688 11.724 100 96 184 1 1170426448 64606432 17823328
11:25:14 1607 8192000000 10071096160 10458988544 1092478896 11.766 100 85 165 1 1188782176 79378112 16925168
11:26:18 2869 8192000000 10056858080 10439544832 1093042128 11.772 100 86 155 1 1186631520 78765520 14823872
11:27:21 963 8192000000 10044878176 10433044480 1095827504 11.798 100 89 174 1 1162527792 47962736 18737552
11:28:23 7796 8192000000 10014913472 10403340288 1083939360 11.685 100 72 133 1 1138059024 40578368 13541296
11:29:31 7429 8192000000 10048835808 10443722752 1089524304 11.738 100 64 122 1 1174447072 73107984 11814784
11:30:40 2492 8192000000 10002368496 10385367040 1065630816 11.510 100 71 136 0 1122333904 43286688 13416400
11:31:44 1426 8192000000 10021991152 10394357760 1053570576 11.395 99 96 187 2 1145091024 72454864 19065584
11:32:46 1146 8192000000 10042561984 10423349248 1074390912 11.594 100 92 171 3 1165567728 74657296 16519520
11:33:48 1131 8192000000 10002723472 10384465920 1047379792 11.336 99 80 148 0 1124394256 61855072 15159392
11:34:49 12227 8192000000 9983584720 10389970944 1057437200 11.432 100 55 108 1 1120509920 51389040 11683680
11:36:03 3674 8192000000 9954689328 10337026048 1039406080 11.259 100 58 110 2 1086768624 36070160 11292384
11:37:08 1244 8192000000 10003242240 10375565312 1049435856 11.355 100 99 188 2 1122808288 54160160 19212272
11:38:17 900 8192000000 10008556160 10386997248 1068304704 11.536 100 97 180 3 1129982080 43021392 18655984
11:39:29 18398 8192000000 10017896080 10409611264 1076211312 11.611 99 63 117 2 1148128160 59738144 12178704
11:40:51 2300 8192000000 10089856064 10476122112 1099874320 11.836 100 85 168 2 1215954048 97592624 18487104
11:41:58 924 8192000000 10117401744 10505187328 1121828640 12.044 100 96 182 1 1236405264 93126768 21449856
11:43:07 20716 8192000000 10140283312 10541015040 1135534048 12.174 100 67 124 0 1264848928 115568752 13746128
11:44:31 9893 8192000000 10171242912 10589040640 1147214656 12.283 100 52 104 0 1296215344 137091696 11908992
11:45:47 1481 8192000000 10217274080 10612776960 1151105184 12.320 100 91 178 0 1327561024 157063504 19392336
11:46:54 11830 8192000000 10233969264 10646151168 1157526576 12.380 100 71 135 0 1347849520 175469488 14853456
11:48:12 1500 8192000000 10260085120 10670469120 1160436752 12.407 100 96 186 0 1375683200 194447840 20798608
11:49:18 2627 8192000000 10287891376 10689159168 1177256880 12.565 100 90 176 3 1397960832 199225904 21478048
11:50:23 725 8192000000 10327192912 10728833024 1181808784 12.607 100 95 182 5 1433976192 231569664 20597744
11:51:27 1062 8192000000 10353757520 10772918272 1180067744 12.591 100 93 184 0 1467931696 267462272 20401680
11:52:31 1416 8192000000 10364325728 10774667264 1187677504 12.662 100 91 176 2 1471095344 264053024 19364816
11:53:42 1744 8192000000 10415163200 10827583488 1187437552 12.660 100 96 176 2 1523825648 315504464 20883632
11:54:49 1890 8192000000 10382491152 10823233536 1186849024 12.654 100 84 169 0 1494312672 286494560 20969088
11:55:54 681 8192000000 10430004368 10853900288 1197927856 12.757 100 98 194 1 1533722752 313702352 22092544
11:56:56 2658 8192000000 10439099024 10867838976 1200742144 12.783 100 78 157 0 1543152928 324053536 18357248
11:58:04 1164 8192000000 10469941200 10899484672 1201700928 12.792 100 89 178 0 1580485024 357879488 20904608
11:59:07 924 8192000000 10451423312 10902048768 1182922208 12.617 99 87 171 0 1558063472 354083504 21057760
12:00:13 8262 8192000000 10441468288 10924097536 1193032736 12.712 100 58 114 0 1548768256 342101264 13634256
12:01:24 14177 8192000000 10424102544 10899841024 1169138784 12.489 99 51 102 0 1519289616 336795344 13355488
12:02:47 10546 8192000000 10347441952 10866548736 1159509664 12.399 99 40 78 1 1474013328 303741984 10761680
12:03:59 9684 8192000000 10319634880 10806272000 1161811648 12.420 100 38 72 0 1440382928 269072800 9498480
12:05:20 33472 8192000000 10261436832 10737127424 1134776896 12.166 98 20 38 0 1344851728 205014816 5060016
12:06:58 38668 8192000000 10263920304 10777600000 1118750800 12.015 98 20 38 0 1410013232 286319792 4942640
12:08:44 34677 8192000000 10121076832 10570735616 1120138368 12.028 98 17 34 0 1224932304 100909424 3884512
12:10:31 20468 8192000000 10111460848 10573971456 1119816880 12.025 99 41 78 1 1203894432 75975504 8102048
12:11:51 85530 8192000000 10099918512 10553032704 1115173296 11.981 98 49 98 0 1197929968 71683088 11073584
12:14:21 101236 8192000000 10059165824 10481086464 1073827216 11.589 95 30 59 0 1143369104 63121664 6420224
12:17:03 28941 8192000000 10092250688 10515689472 1117194848 12.000 99 29 57 0 1180371744 57233328 5943568
12:18:38 36610 8192000000 10104469360 10561720320 1103588560 11.872 97 27 54 0 1198436512 88933568 5914384
12:20:17 48539 8192000000 10084491040 10570948608 1134363920 12.162 100 15 31 0 1193635904 56060320 3211664
12:22:12 35981 8192000000 10081051664 10526593024 1117825584 12.006 98 17 32 0 1163281744 41255184 4200976
12:23:50 27082 8192000000 10079053040 10545668096 1121719264 12.043 100 15 29 0 1183953984 58984992 3249728
12:25:17 65949 8192000000 10087332352 10546315264 1131470144 12.135 100 23 46 0 1180603792 44689184 4444464
12:27:25 85617 8192000000 10097299856 10550861824 1119466480 12.022 98 19 37 0 1190280928 66964848 3849600
12:29:51 62754 8192000000 10059705328 10547978240 1099623152 11.834 97 18 36 0 1163009632 59562880 3823600
12:31:56 18648 8192000000 10097579552 10524991488 1108450928 11.918 98 52 104 0 1167054400 47885712 10717760
12:33:15 47991 8192000000 10093433520 10528763904 1131101088 12.132 100 41 84 0 1185110320 45702512 8306720
12:35:05 45693 8192000000 10091864416 10578558976 1112983008 11.961 98 32 64 0 1199127904 79406736 6738160
12:36:52 60896 8192000000 10110516576 10563395584 1132223184 12.142 99 34 64 0 1187537200 48887952 6426064
12:38:56 40135 8192000000 10158524944 10610278400 1126918288 12.092 97 32 62 0 1227310288 93996160 6395840
12:40:40 95634 8192000000 10104998224 10572218368 1121652464 12.043 98 27 54 0 1229339248 101525472 6161312
12:43:27 2613 8192000000 10097529344 10543067136 1138869504 12.205 100 52 93 2 1205584352 56322464 10392384
12:44:31 61802 8192000000 10094983504 10561445888 1133500000 12.154 99 15 30 0 1209614864 73153216 2961648
12:46:42 34166 8192000000 10086902656 10529452032 1125468448 12.079 99 17 34 0 1187394768 58201136 3725184
12:48:37 66067 8192000000 10067211456 10565103616 1133115152 12.151 99 11 20 1 1210735776 74706160 2914464
12:50:44 2906 8192000000 10047788928 10480128000 1133279136 12.152 100 59 112 3 1154610800 9230416 12101248
12:51:48 19529 8192000000 10110911520 10550161408 1130613504 12.127 99 38 75 0 1204339744 65975616 7750624
12:53:16 62764 8192000000 10114696608 10546479104 1099820000 11.836 97 33 64 0 1211468560 105031072 6617488
12:55:20 89278 8192000000 10116814240 10588602368 1116337504 11.992 98 23 46 0 1193874960 72207952 5329504
12:57:52 40549 8192000000 10062034160 10512023552 1090766656 11.750 97 29 56 1 1157446816 60533808 6146352
12:59:33 6180 8192000000 10052140480 10472906752 1100509440 11.842 99 51 102 0 1146887056 36088448 10289168
13:00:40 111211 8192000000 10041442848 10473566208 1088791488 11.731 98 44 88 0 1198733824 100196384 9745952
13:03:46 46132 8192000000 10062224112 10521722880 1091522768 11.757 98 36 71 0 1192351712 93895904 6933040
13:05:33 53931 8192000000 10062333792 10529406976 1102814800 11.864 99 11 19 0 1156741072 52123248 1803024
13:07:28 44717 8192000000 10051151904 10496225280 1110118464 11.934 99 16 29 0 1149371776 36608320 2644992
13:09:19 77366 8192000000 10115501808 10572034048 1111242960 11.944 98 29 58 0 1199592016 82416960 5932096
13:11:41 22704 8192000000 10134996688 10566823936 1130926480 12.130 99 36 72 1 1243698320 104620160 8151680
13:13:12 22935 8192000000 10206444816 10638270464 1128293888 12.105 98 46 90 1 1303812176 165517312 10000976
13:14:36 36483 8192000000 10218023056 10687561728 1151284464 12.322 100 33 65 1 1339202960 180282208 7636288
13:16:29 68183 8192000000 10259464400 10732576768 1149770848 12.307 100 27 54 0 1340525280 184255248 6499184
13:18:50 67749 8192000000 10068746064 10567856128 1111690384 11.948 98 8 16 0 1208798080 95408496 1699200
13:20:57 96502 8192000000 10092134432 10524540928 1109742768 11.930 98 61 116 0 1152679104 30641440 12294896
13:23:37 43794 8192000000 10080643264 10541031424 1142128288 12.236 100 16 32 0 1196464992 51010672 3326032
13:25:21 89620 8192000000 10133865280 10601037824 1128499312 12.107 98 17 34 0 1216228752 83031904 4697536
13:27:58 62453 8192000000 10103981584 10569367552 1146301152 12.275 100 17 27 0 1219746624 70846320 2599152
13:30:01 55664 8192000000 10061284576 10508623872 1095004496 11.790 96 23 43 2 1131262480 31798896 4459088
13:32:26 42687 8192000000 10121010784 10567786496 1144380512 12.257 100 39 78 0 1235488368 82924176 8183680
13:34:14 34085 8192000000 10118728240 10580738048 1138176208 12.198 99 35 66 0 1190399024 45801968 6420848
13:35:53 14418 8192000000 10105176608 10533920768 1106235120 11.897 97 54 107 0 1161232576 44368736 10628720
13:37:13 21587 8192000000 9294600656 9601216512 409605184 4.761 37 20 40 0 461135840 47593536 3937120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-03-2023 13:38:32 to 13-03-2023 13:38:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 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-03-2023 13:38:49 to 13-03-2023 13:38:51.

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          226294
MON$OLDEST_ACTIVE               226295
MON$OLDEST_SNAPSHOT             226295
MON$NEXT_TRANSACTION            226295
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                       2037792
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2908 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2908 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2908 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2908 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 ---
:104: 
:105: mscs-test-161.budzdorov.ru    Mon Mar 13 13:37:40 2023
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:107: 
:108: 
:109: mscs-test-161.budzdorov.ru    Mon Mar 13 13:37:43 2023
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:111: 
:112: 
:113: mscs-test-161.budzdorov.ru    Mon Mar 13 13:37:45 2023
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:115: 
--- end of diff output ---

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

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