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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15017684 kB
MemAvailable 30445652 kB
Buffers 0 kB
Cached 16708436 kB
SwapCached 1644 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-22 10:37:24
DTS_END                         2023-01-22 10:37:24.4380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37372
TRN_ID                          224934
ATT_ID                          660
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: 8856.08 25139 1062729
customer order: creation 177.07 473 21248
customer order: refuse 14.96 486 1795
order to supplier: creation 46.88 1310 5626
order to supplier: removal 3.15 164783 378
invoice (draft): creation 46.94 3294 5633
invoice (draft): removal 46.78 1028 5614
invoice accept: apply 43.34 28827 5201
invoice accept: cancel 42.08 1319 5050
customer reserve: creation 4002.00 275 480240
customer reserve: removal 4134.94 97 496193
realization accept: apply 86.97 290 10436
realization accept: cancel 78.33 170 9399
payment from customer: creation 59.68 200 7161
payment from customer: removal 5.86 143 703
payment to supplier: creation 50.38 220 6045
payment to supplier: removal 3.50 182 420
service: total inventory turnovers 4.10 4271 492
service: total monetary turnovers 8.17 431 980
service: aggregate perf. data 0.91 17887 109
service: refresh index statistics 0.05 277095 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-01-2023 10:39:40 to 22-01-2023 10:39:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6276
TEST_TIME 2 5475
TEST_TIME 3 6008
TEST_TIME 4 6378
TEST_TIME 5 6655
TEST_TIME 6 6298
TEST_TIME 7 6006
TEST_TIME 8 6388
TEST_TIME 9 6365
TEST_TIME 10 6325
TEST_TIME 11 6535
TEST_TIME 12 6686
TEST_TIME 13 6986
TEST_TIME 14 7078
TEST_TIME 15 7130
TEST_TIME 16 7180
TEST_TIME 17 7541
TEST_TIME 18 7599
TEST_TIME 19 7757
TEST_TIME 20 7805
TEST_TIME 21 7857
TEST_TIME 22 8107
TEST_TIME 23 8110
TEST_TIME 24 8162
TEST_TIME 25 8261
TEST_TIME 26 8279
TEST_TIME 27 8369
TEST_TIME 28 8527
TEST_TIME 29 8633
TEST_TIME 30 9047
TEST_TIME 31 9049
TEST_TIME 32 9378
TEST_TIME 33 9508
TEST_TIME 34 10030
TEST_TIME 35 9997
TEST_TIME 36 10215
TEST_TIME 37 10430
TEST_TIME 38 10676
TEST_TIME 39 10539
TEST_TIME 40 10354
TEST_TIME 41 10288
TEST_TIME 42 10145
TEST_TIME 43 9991
TEST_TIME 44 9907
TEST_TIME 45 9830
TEST_TIME 46 9748
TEST_TIME 47 9831
TEST_TIME 48 9708
TEST_TIME 49 9620
TEST_TIME 50 9644
TEST_TIME 51 9606
TEST_TIME 52 9491
TEST_TIME 53 9401
TEST_TIME 54 9385
TEST_TIME 55 9259
TEST_TIME 56 9242
TEST_TIME 57 9183
TEST_TIME 58 9145
TEST_TIME 59 9205
TEST_TIME 60 9089
TEST_TIME 61 9093
TEST_TIME 62 9020
TEST_TIME 63 9035
TEST_TIME 64 9069
TEST_TIME 65 9010
TEST_TIME 66 8948
TEST_TIME 67 9124
TEST_TIME 68 9117
TEST_TIME 69 9068
TEST_TIME 70 9092
TEST_TIME 71 9084
TEST_TIME 72 9057
TEST_TIME 73 9064
TEST_TIME 74 9107
TEST_TIME 75 9178
TEST_TIME 76 9114
TEST_TIME 77 9080
TEST_TIME 78 9012
TEST_TIME 79 8940
TEST_TIME 80 8946
TEST_TIME 81 8903
TEST_TIME 82 8914
TEST_TIME 83 8863
TEST_TIME 84 8822
TEST_TIME 85 8789
TEST_TIME 86 8810
TEST_TIME 87 8796
TEST_TIME 88 8809
TEST_TIME 89 8815
TEST_TIME 90 8820
TEST_TIME 91 8787
TEST_TIME 92 8794
TEST_TIME 93 8809
TEST_TIME 94 8887
TEST_TIME 95 8908
TEST_TIME 96 8902
TEST_TIME 97 8942
TEST_TIME 98 9019
TEST_TIME 99 9021
TEST_TIME 100 8974
TEST_TIME 101 8958
TEST_TIME 102 8926
TEST_TIME 103 8876
TEST_TIME 104 8889
TEST_TIME 105 8980
TEST_TIME 106 8956
TEST_TIME 107 8930
TEST_TIME 108 8904
TEST_TIME 109 8908
TEST_TIME 110 8913
TEST_TIME 111 8904
TEST_TIME 112 8901
TEST_TIME 113 8927
TEST_TIME 114 9035
TEST_TIME 115 9004
TEST_TIME 116 8960
TEST_TIME 117 9008
TEST_TIME 118 9011
TEST_TIME 119 8943
TEST_TIME 120 8883

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-01-2023 10:39:44 to 22-01-2023 10:39:47.

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 1503181 1503181 0 0.00 0 18575 15 0 0
doc_list_biud 1503166 1503166 0 0.00 0 73266 39 0 0
sp_add_invoice_to_stock 5317 5201 116 2.18 79 200881 28828 0 116
sp_cancel_adding_invoice 5050 5050 0 0.00 6 108904 1319 0 0
sp_cancel_client_order 1795 1795 0 0.00 6 12566 487 0 0
sp_cancel_customer_reserve 496193 496193 0 0.00 1 17168 97 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 5033 143 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 4 13781 182 0 0
sp_cancel_supplier_invoice 5623 5614 9 0.16 2 73270 1029 0 9
sp_cancel_supplier_order 378 378 0 0.00 8 1513866 164784 0 0
sp_cancel_write_off 9399 9399 0 0.00 1 16351 170 0 0
sp_client_order 21248 21248 0 0.00 5 17827 473 0 0
sp_customer_reserve 480240 480240 0 0.00 1 90791 275 0 0
sp_fill_shopping_cart 32495 32495 0 0.00 1 13470 93 0 0
sp_fill_shopping_cart_clo_res 480207 480207 0 0.00 0 15009 19 0 0
sp_get_clo_for_invoice 5155 5155 0 0.00 0 35931 283 0 0
sp_kill_cost_storno 15572 15572 0 0.00 0 10358 80 0 0
sp_kill_qstorno_ret_qs2qd 1007777 1007777 0 0.00 0 73264 42 0 0
sp_kill_qty_storno 534020 534020 0 0.00 0 73266 99 0 0
sp_lock_selected_doc 21472 21472 0 0.00 0 7249 8 0 0
sp_make_cost_storno 28796 28796 0 0.00 0 18569 83 0 0
sp_multiply_rows_for_pdistr 28794 28794 0 0.00 0 7526 41 0 0
sp_multiply_rows_for_qdistr 21247 21247 0 0.00 0 13570 157 0 0
sp_pay_from_customer 7161 7161 0 0.00 5 13880 201 0 0
sp_pay_to_supplier 6045 6045 0 0.00 6 13828 221 0 0
sp_payment_common 13202 13202 0 0.00 2 9262 54 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 12528 406 0 0
sp_qd_handle_on_invoice_upd_sts 10205 10205 0 0.00 0 20775 583 0 0
sp_qd_handle_on_reserve_upd_sts 10436 10436 0 0.00 0 13604 61 0 0
sp_reserve_write_off 10436 10436 0 0.00 8 18663 291 0 0
sp_supplier_invoice 5633 5633 0 0.00 9 91553 3294 0 0
sp_supplier_order 5626 5626 0 0.00 7 20672 1310 0 0
srv_aggregate_perf_data 109 109 0 0.00 2560 183895 17888 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 869 114583 34916 0 0
srv_find_qd_qs_mism 1011444 1011444 0 0.00 0 11447 12 0 0
srv_make_invnt_saldo 492 492 0 0.00 53 69977 4271 0 0
srv_make_money_saldo 980 980 0 0.00 1 13810 431 0 0
srv_recalc_idx_stat 6 6 0 0.00 62627 599375 277096 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 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 5206 5206 0 0.00 1 10734 1087 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 2 8351 482 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 2232 33 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 532 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 951 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 2537 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1293 43 0 0
t$perf-norm:sp_cancel_supplier_order 378 378 0 0.00 0 120555 8101 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 3680 24 0 0
t$perf-norm:sp_client_order 21248 21248 0 0.00 0 11307 40 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 1935 15 0 0
t$perf-norm:sp_pay_from_customer 7162 7162 0 0.00 0 9229 24 0 0
t$perf-norm:sp_pay_to_supplier 6045 6045 0 0.00 0 4614 22 0 0
t$perf-norm:sp_reserve_write_off 10436 10436 0 0.00 0 14360 26 0 0
t$perf-norm:sp_supplier_invoice 5633 5633 0 0.00 0 6105 31 0 0
t$perf-norm:sp_supplier_order 5627 5627 0 0.00 0 5925 35 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 577 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 540 14 0 0
t$perf-norm:srv_make_invnt_saldo 492 492 0 0.00 0 680 11 0 0
t$perf-norm:srv_make_money_saldo 980 980 0 0.00 0 6333 11 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 2 177 74 0 0
v_add_invoice_to_stock 10538 10306 232 2.20 0 7324 29 0 232
v_all_customers 38660 38660 0 0.00 0 77 4 0 0
v_all_suppliers 31271 31271 0 0.00 0 209 5 0 0
v_all_wares 42478 42478 0 0.00 0 54 5 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 2431 24 0 0
v_cancel_client_order 3590 3590 0 0.00 0 3911 13 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 2828 16 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 1 4020 15 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 3413 23 0 0
v_cancel_supplier_invoice 816 798 18 2.20 0 1698 29 0 18
v_cancel_supplier_order 756 756 0 0.00 0 2286 18 0 0
v_cancel_write_off 2070 2070 0 0.00 0 2298 27 0 0
v_max_id_clo_ord 5621 5621 0 0.00 0 1123 6 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 4321 17 0 0
v_max_id_ord_sup 5626 5626 0 0.00 0 13195 10 0 0
v_max_non_paid_invoice 1653 1653 0 0.00 0 72 8 0 0
v_max_non_paid_realizn 3812 3812 0 0.00 0 2839 9 0 0
v_min_id_clo_ord 5621 5621 0 0.00 0 863 8 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 5345 27 0 0
v_min_id_ord_sup 5625 5625 0 0.00 0 6576 11 0 0
v_min_non_paid_invoice 6042 6042 0 0.00 0 8935 19 0 0
v_min_non_paid_realizn 7159 7159 0 0.00 0 7264 22 0 0
v_our_firm 10512 10512 0 0.00 0 62 5 0 0
v_reserve_write_off 20870 20870 0 0.00 0 4872 13 0 0
x_lock_depdocs_on_canc_invoice 10265 10265 0 0.00 0 27525 139 0 0
x_lock_depdocs_on_canc_sup_ord 378 378 0 0.00 0 14282 435 0 0
x_make_qstorno_1000_1200 5626 5626 0 0.00 2 20662 1115 0 0
x_make_qstorno_1200_2000 5633 5633 0 0.00 4 87399 3045 0 0
x_make_qstorno_2100_3300 480239 480239 0 0.00 0 90749 241 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-01-2023 10:39:48 to 22-01-2023 10:39:52.

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 931 8192000000 8926724240 8983322624 18374128 0.223 4 3 6 0 19140560 144528 621904
08:08:30 26537 8192000000 9715914256 10012581888 791692800 8.812 100 90 180 0 821893440 7826976 22373664
08:10:02 16935 8192000000 9752652880 10057502720 828088064 9.180 100 91 180 0 861469712 11542944 21838704
08:11:22 15831 8192000000 9788523168 10104307712 857632224 9.476 100 84 166 0 894655024 17977296 19045504
08:12:43 10826 8192000000 9848210240 10183471104 911717552 10.014 100 78 155 1 958703248 30241392 16744304
08:14:03 7259 8192000000 9926560832 10270261248 966917808 10.557 100 87 172 0 1040868160 56209056 17741296
08:15:13 2254 8192000000 9972417488 10324168704 990589632 10.787 100 92 171 3 1084881824 76288288 18003904
08:16:18 1126 8192000000 9999626336 10360324096 1014356560 11.018 100 92 180 0 1123010176 90760640 17892976
08:17:21 2737 8192000000 9986665104 10350182400 1030191472 11.170 100 82 161 1 1109936752 64543808 15201472
08:18:26 888 8192000000 10021013472 10392559616 1052050256 11.380 100 98 180 5 1135614688 65370432 18194000
08:19:29 10889 8192000000 10019045168 10402783232 1064665552 11.501 100 78 147 1 1150102016 70592000 14844464
08:20:41 5745 8192000000 10044272656 10425139200 1068823344 11.541 100 72 129 2 1165201424 83870912 12507168
08:21:48 1156 8192000000 10052209632 10429898752 1074947328 11.599 100 89 170 0 1172510704 79441824 18121552
08:22:51 1173 8192000000 10054194432 10438324224 1086081776 11.705 100 98 190 0 1178852128 73832592 18937760
08:23:53 7468 8192000000 10038321680 10425331712 1082553280 11.672 100 77 147 0 1160953456 64299424 14100752
08:25:01 1360 8192000000 10048203840 10440826880 1089680928 11.740 100 68 128 2 1160556960 58588480 12287552
08:26:03 999 8192000000 10058528768 10446581760 1094705088 11.787 100 97 182 1 1177133376 65630288 16798000
08:27:05 1859 8192000000 10067875200 10456801280 1096625056 11.806 100 85 157 5 1190359728 76444256 17290416
08:28:08 1265 8192000000 10060524432 10449301504 1095073552 11.791 100 93 178 0 1183351072 70554608 17722912
08:29:10 1117 8192000000 10062533552 10452643840 1086012320 11.705 99 91 171 2 1179991632 76394800 17584512
08:30:12 1901 8192000000 10022041072 10412888064 1084846672 11.694 100 75 142 0 1152102496 52538544 14717280
08:31:15 9673 8192000000 10015616944 10409029632 1070170464 11.554 100 55 110 0 1132989984 52241824 10577696
08:32:26 1200 8192000000 10026145504 10414092288 1064957664 11.504 99 72 140 1 1150303520 71409904 13935952
08:33:28 1426 8192000000 10011459872 10393788416 1057185552 11.430 100 84 157 1 1134003392 60414736 16403104
08:34:31 3849 8192000000 10003570800 10380435456 1045941472 11.322 99 72 128 2 1114464608 55279584 13243552
08:35:37 1116 8192000000 9976999360 10349314048 1023120128 11.102 98 85 153 2 1098872704 60098720 15653856
08:36:39 1098 8192000000 10002274624 10375626752 1039058672 11.256 100 95 182 0 1120195648 61239136 19897840
08:37:44 3198 8192000000 10004026624 10386087936 1058366496 11.441 99 73 138 3 1126262528 54352352 13543680
08:38:57 3466 8192000000 10045255456 10435440640 1079614944 11.644 100 81 153 1 1164127536 68837792 15674800
08:40:04 11877 8192000000 10073750368 10460213248 1090535504 11.748 100 64 126 0 1193927296 90180720 13211072
08:41:18 1081 8192000000 10089130496 10471428096 1098145664 11.820 100 98 192 1 1208164208 90271760 19746784
08:42:23 14684 8192000000 10103779616 10507431936 1111919520 11.951 100 59 114 1 1227094912 103278208 11897184
08:43:42 2770 8192000000 10142089776 10537152512 1126621280 12.089 100 90 171 2 1263727696 118318416 18788000
08:44:48 4931 8192000000 10188411840 10598830080 1130759152 12.129 100 61 118 0 1301199456 157577056 12863248
08:45:57 1042 8192000000 10184299088 10580914176 1148177936 12.292 100 86 158 2 1301499504 137062544 16259024
08:47:01 1121 8192000000 10232859680 10628820992 1152130672 12.329 100 88 167 1 1349295296 179024944 18139680
08:48:09 1190 8192000000 10255330800 10655285248 1163966496 12.440 100 96 178 2 1368603040 183529056 21107488
08:49:14 929 8192000000 10299287488 10713616384 1175907232 12.552 100 93 182 0 1411078656 214283920 20887504
08:50:19 2172 8192000000 10321706464 10723405824 1182770656 12.616 100 85 161 0 1427999488 228173584 17055248
08:51:24 1165 8192000000 10330741664 10734387200 1193138240 12.713 100 92 179 2 1445114528 231960992 20015296
08:52:29 1239 8192000000 10364865872 10771804160 1188543568 12.670 100 94 186 1 1475568448 265601520 21423360
08:53:33 1378 8192000000 10361288448 10798469120 1181471232 12.604 100 93 174 2 1466363536 264611904 20280400
08:54:38 856 8192000000 10378182512 10800259072 1180266576 12.593 100 97 182 0 1485567520 283927168 21373776
08:55:44 1444 8192000000 10387387152 10805297152 1173393488 12.529 100 83 160 0 1501863184 310334912 18134784
08:56:47 1199 8192000000 10415904048 10830680064 1176944816 12.562 100 92 175 1 1522944160 325784784 20214560
08:57:52 887 8192000000 10413803104 10819457024 1162956864 12.431 100 96 192 1 1521437280 336519536 21960880
08:58:57 973 8192000000 10424001216 10861682688 1162362816 12.425 100 97 194 0 1531833728 347555392 21915520
09:00:02 1089 8192000000 10422460080 10835914752 1159179552 12.396 100 100 194 1 1526704496 344384384 23140560
09:01:07 1716 8192000000 10444256928 10864529408 1173971424 12.534 100 95 182 1 1550017680 354560352 21485904
09:02:13 1155 8192000000 10490380272 10889236480 1166966736 12.468 100 98 192 0 1591670416 401592384 23111296
09:03:16 2273 8192000000 10492215472 10909454336 1186306240 12.649 100 92 179 1 1593650848 386050624 21293984
09:04:22 977 8192000000 10493169936 10951536640 1186156992 12.648 100 96 187 2 1600635968 392590096 21888880
09:05:25 6098 8192000000 10490071616 10959458304 1177911312 12.571 100 71 138 2 1593302000 398259936 17130752
09:06:38 1127 8192000000 10523646160 10964529152 1171764256 12.513 100 86 163 1 1626577056 434890496 19922304
09:07:42 9992 8192000000 10475175424 10923081728 1167598352 12.474 100 46 88 1 1577945664 399054736 11292576
09:09:00 10016 8192000000 10417253392 10897129472 1180951744 12.599 100 43 86 1 1547857520 356306656 10599120
09:10:12 14924 8192000000 10373272576 10824462336 1166871008 12.468 100 40 81 0 1488754640 311403072 10480560
09:11:38 34525 8192000000 10266524272 10723590144 1143756576 12.251 99 22 44 0 1356090304 207455472 4878256
09:13:23 48597 8192000000 10176488976 10652876800 1149745728 12.307 100 15 30 0 1324549392 171146208 3657456
09:15:13 52146 8192000000 10085480576 10527006720 1102446432 11.861 96 24 44 2 1166280672 59145856 4688384
09:17:08 95719 8192000000 10108092592 10570162176 1143770944 12.251 100 39 74 1 1219863376 68321184 7771248
09:19:57 48458 8192000000 10123555392 10560151552 1154562592 12.352 100 49 94 2 1210824768 46065776 10196400
09:21:47 42291 8192000000 10154865248 10603941888 1158172160 12.386 100 22 42 0 1243301584 80024336 5105088
09:24:08 87158 8192000000 10106450208 10594136064 1142244432 12.237 98 14 27 0 1201092592 55775632 3072528
09:26:37 40859 8192000000 10142840224 10592067584 1162245056 12.424 100 24 42 2 1240722736 73462144 5015536
09:28:23 56752 8192000000 10096957344 10571624448 1119685568 12.024 97 21 42 0 1198308608 73751440 4871600
09:30:21 70283 8192000000 10088276272 10584707072 1138662496 12.203 99 17 33 1 1215604688 73289408 3652784
09:33:26 54125 8192000000 10104581504 10573750272 1129991936 12.121 98 24 48 0 1209919648 74777120 5150592
09:35:21 103898 8192000000 10080608976 10611388416 1123760480 12.063 98 14 29 0 1208431040 81484832 3185728
09:38:05 20965 8192000000 10091718160 10556628992 1143695104 12.250 100 29 56 0 1204948208 55900336 5352768
09:40:01 44651 8192000000 10077707808 10548944896 1098489904 11.823 96 19 38 0 1166681488 64181936 4009648
09:41:46 69350 8192000000 10097944224 10541912064 1095411056 11.794 96 27 54 0 1156285936 55372080 5502800
09:44:03 28853 8192000000 10169264208 10634125312 1136245504 12.180 100 34 66 1 1269519728 126500336 6773888
09:45:35 54093 8192000000 10093440784 10529128448 1097712640 11.816 96 38 75 1 1153882720 48516512 7653568
09:47:30 18176 8192000000 10058529808 10484703232 1110309520 11.935 99 49 97 0 1157810656 38143712 9357424
09:48:49 9416 8192000000 10086478192 10514305024 1120363104 12.030 99 54 108 1 1186872816 55997264 10512448
09:49:59 8826 8192000000 10114361584 10535731200 1120529424 12.032 100 58 114 0 1204358288 72590512 11238352
09:51:08 26065 8192000000 10107020368 10545786880 1096118848 11.801 99 43 84 0 1190785616 86040016 8626752
09:52:36 79979 8192000000 10090911184 10570686464 1101823264 11.855 99 38 76 1 1222294000 112915296 7555440
09:55:51 39161 8192000000 10058543152 10491035648 1096853072 11.808 98 33 64 0 1155166576 51690160 6623344
09:57:32 63865 8192000000 10043320128 10506235904 1103809536 11.874 99 12 24 0 1177302880 71123440 2369904
09:59:37 58334 8192000000 10042910208 10488610816 1109157360 11.924 99 18 32 0 1154366208 41899200 3309648
10:01:36 103534 8192000000 10036426128 10513145856 1091276896 11.755 97 7 14 0 1131246688 38348240 1621552
10:04:26 35987 8192000000 10080821536 10528059392 1129464592 12.116 100 35 69 0 1166767712 30350368 6952752
10:06:08 33918 8192000000 10064010544 10503671808 1115098304 11.981 99 30 60 0 1167165248 46194464 5872480
10:07:46 60771 8192000000 10107529888 10550198272 1107508496 11.909 98 33 64 2 1169944096 55968800 6466800
10:09:51 85433 8192000000 10094018688 10596306944 1130725184 12.128 99 19 36 0 1256606288 122204224 3676880
10:12:19 92272 8192000000 10096140416 10546515968 1071838592 11.570 94 20 40 0 1119304544 43070592 4395360
10:14:52 59556 8192000000 10079361456 10562007040 1103064960 11.867 97 15 30 0 1170812240 64536832 3210448
10:16:54 72982 8192000000 10086254752 10572468224 1138522976 12.202 100 21 42 1 1189078560 45973632 4581952
10:19:55 75753 8192000000 10112647936 10564431872 1110464816 11.937 97 13 26 0 1163498368 49985168 3048384
10:22:11 80529 8192000000 10084178640 10584469504 1117870768 12.007 98 12 24 0 1164815232 44506256 2438208
10:24:33 37021 8192000000 10093398672 10530521088 1139353104 12.209 99 40 74 0 1182923088 35606768 7963216
10:26:16 93516 8192000000 10087279632 10524078080 1097614880 11.815 96 28 56 0 1145037088 41560752 5861456
10:28:53 47155 8192000000 10130709248 10594664448 1103345680 11.869 97 45 84 0 1198508528 86495632 8667216
10:30:41 114582 8192000000 10049839520 10549321728 1060418208 11.460 94 13 26 1 1118567392 55560048 2589136
10:33:45 13475 8192000000 10112243552 10539532288 1108355072 11.917 99 39 77 0 1182246720 66099440 7792208
10:34:58 50598 8192000000 10054778176 10520793088 1079609360 11.644 97 27 51 1 1136547504 51260368 5677776
10:36:49 93027 8192000000 8984943712 9217433600 138114608 1.658 13 5 9 0 150840464 11832640 893216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 22-01-2023 10:39:54 to 22-01-2023 10:40:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 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 22-01-2023 10:40:12 to 22-01-2023 10:40:14.

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          224933
MON$OLDEST_ACTIVE               225216
MON$OLDEST_SNAPSHOT             225216
MON$NEXT_TRANSACTION            225216
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                       2076384
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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'

22.01.23 10:40:15. 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-22 10:40:17 No severe PSQL-related problems occured
22.01.23 10:40:19. End of report.