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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-28 10:37:29
DTS_END                         2023-02-28 10:37:29.9630
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40340
TRN_ID                          228158
ATT_ID                          669
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: 9551.31 24214 1146157
customer order: creation 179.00 442 21480
customer order: refuse 15.09 391 1811
order to supplier: creation 46.53 1214 5584
order to supplier: removal 3.60 157125 432
invoice (draft): creation 47.25 2878 5670
invoice (draft): removal 54.53 883 6544
invoice accept: apply 43.91 26016 5269
invoice accept: cancel 48.85 1155 5862
customer reserve: creation 4035.23 253 484227
customer reserve: removal 4759.02 95 571083
realization accept: apply 88.64 258 10637
realization accept: cancel 94.94 160 11393
payment from customer: creation 60.69 157 7283
payment from customer: removal 5.78 131 694
payment to supplier: creation 50.94 172 6113
payment to supplier: removal 3.82 125 458
service: total inventory turnovers 4.46 3619 535
service: total monetary turnovers 8.00 310 960
service: aggregate perf. data 0.98 13025 118
service: refresh index statistics 0.03 275877 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-02-2023 10:40:34 to 28-02-2023 10:40:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7156
TEST_TIME 2 6278
TEST_TIME 3 5959
TEST_TIME 4 5522
TEST_TIME 5 6162
TEST_TIME 6 6260
TEST_TIME 7 6290
TEST_TIME 8 6526
TEST_TIME 9 6800
TEST_TIME 10 6969
TEST_TIME 11 7001
TEST_TIME 12 7257
TEST_TIME 13 7508
TEST_TIME 14 7445
TEST_TIME 15 7509
TEST_TIME 16 7390
TEST_TIME 17 7327
TEST_TIME 18 7583
TEST_TIME 19 7760
TEST_TIME 20 8272
TEST_TIME 21 8225
TEST_TIME 22 8468
TEST_TIME 23 8569
TEST_TIME 24 8572
TEST_TIME 25 8528
TEST_TIME 26 9007
TEST_TIME 27 9216
TEST_TIME 28 9222
TEST_TIME 29 9486
TEST_TIME 30 9729
TEST_TIME 31 10236
TEST_TIME 32 10602
TEST_TIME 33 11208
TEST_TIME 34 11217
TEST_TIME 35 11134
TEST_TIME 36 11269
TEST_TIME 37 11216
TEST_TIME 38 11103
TEST_TIME 39 11015
TEST_TIME 40 10943
TEST_TIME 41 10713
TEST_TIME 42 10555
TEST_TIME 43 10673
TEST_TIME 44 10636
TEST_TIME 45 10600
TEST_TIME 46 10462
TEST_TIME 47 10363
TEST_TIME 48 10341
TEST_TIME 49 10356
TEST_TIME 50 10403
TEST_TIME 51 10399
TEST_TIME 52 10277
TEST_TIME 53 10271
TEST_TIME 54 10306
TEST_TIME 55 10222
TEST_TIME 56 10083
TEST_TIME 57 10076
TEST_TIME 58 9998
TEST_TIME 59 9910
TEST_TIME 60 9927
TEST_TIME 61 9919
TEST_TIME 62 9899
TEST_TIME 63 9918
TEST_TIME 64 9890
TEST_TIME 65 9933
TEST_TIME 66 9974
TEST_TIME 67 10001
TEST_TIME 68 10012
TEST_TIME 69 10013
TEST_TIME 70 9972
TEST_TIME 71 10062
TEST_TIME 72 10039
TEST_TIME 73 10141
TEST_TIME 74 10157
TEST_TIME 75 10142
TEST_TIME 76 10051
TEST_TIME 77 9996
TEST_TIME 78 9936
TEST_TIME 79 9852
TEST_TIME 80 9808
TEST_TIME 81 9768
TEST_TIME 82 9818
TEST_TIME 83 9783
TEST_TIME 84 9794
TEST_TIME 85 9745
TEST_TIME 86 9737
TEST_TIME 87 9713
TEST_TIME 88 9718
TEST_TIME 89 9703
TEST_TIME 90 9689
TEST_TIME 91 9671
TEST_TIME 92 9663
TEST_TIME 93 9715
TEST_TIME 94 9733
TEST_TIME 95 9731
TEST_TIME 96 9769
TEST_TIME 97 9773
TEST_TIME 98 9796
TEST_TIME 99 9742
TEST_TIME 100 9661
TEST_TIME 101 9699
TEST_TIME 102 9724
TEST_TIME 103 9682
TEST_TIME 104 9645
TEST_TIME 105 9609
TEST_TIME 106 9559
TEST_TIME 107 9564
TEST_TIME 108 9548
TEST_TIME 109 9532
TEST_TIME 110 9518
TEST_TIME 111 9483
TEST_TIME 112 9484
TEST_TIME 113 9457
TEST_TIME 114 9467
TEST_TIME 115 9438
TEST_TIME 116 9476
TEST_TIME 117 9464
TEST_TIME 118 9496
TEST_TIME 119 9618
TEST_TIME 120 9572

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-02-2023 10:40:38 to 28-02-2023 10:40:41.

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 1592848 1592848 0 0.00 0 15332 13 0 0
doc_list_biud 1592842 1592842 0 0.00 0 39557 40 0 0
sp_add_invoice_to_stock 5376 5269 107 1.99 51 187213 26017 0 107
sp_cancel_adding_invoice 5862 5862 0 0.00 7 75815 1155 0 0
sp_cancel_client_order 1811 1811 0 0.00 6 11262 392 0 0
sp_cancel_customer_reserve 571083 571083 0 0.00 1 36510 96 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 4 4050 132 0 0
sp_cancel_pay_to_supplier 458 458 0 0.00 4 3792 126 0 0
sp_cancel_supplier_invoice 6557 6544 13 0.19 2 39569 883 0 13
sp_cancel_supplier_order 432 432 0 0.00 13 1426948 157125 0 0
sp_cancel_write_off 11393 11393 0 0.00 1 19338 160 0 0
sp_client_order 21480 21480 0 0.00 6 32578 443 0 0
sp_customer_reserve 484227 484227 0 0.00 0 50512 253 0 0
sp_fill_shopping_cart 32718 32718 0 0.00 1 10218 75 0 0
sp_fill_shopping_cart_clo_res 484205 484205 0 0.00 0 20215 18 0 0
sp_get_clo_for_invoice 5243 5243 0 0.00 0 18991 222 0 0
sp_kill_cost_storno 18407 18407 0 0.00 0 15216 64 0 0
sp_kill_qstorno_ret_qs2qd 1160535 1160535 0 0.00 0 39555 42 0 0
sp_kill_qty_storno 613001 613001 0 0.00 0 39557 95 0 0
sp_lock_selected_doc 21957 21957 0 0.00 0 7013 6 0 0
sp_make_cost_storno 29276 29276 0 0.00 0 15318 68 0 0
sp_multiply_rows_for_pdistr 29274 29274 0 0.00 0 9144 33 0 0
sp_multiply_rows_for_qdistr 21480 21480 0 0.00 0 12747 178 0 0
sp_pay_from_customer 7283 7283 0 0.00 5 8149 158 0 0
sp_pay_to_supplier 6113 6113 0 0.00 7 6900 173 0 0
sp_payment_common 13396 13396 0 0.00 1 7101 44 0 0
sp_qd_handle_on_cancel_clo 1811 1811 0 0.00 1 11252 321 0 0
sp_qd_handle_on_invoice_upd_sts 11102 11102 0 0.00 0 19703 468 0 0
sp_qd_handle_on_reserve_upd_sts 10636 10636 0 0.00 0 14685 58 0 0
sp_reserve_write_off 10637 10637 0 0.00 8 17050 259 0 0
sp_supplier_invoice 5670 5670 0 0.00 10 120177 2878 0 0
sp_supplier_order 5584 5584 0 0.00 8 31498 1215 0 0
srv_aggregate_perf_data 118 118 0 0.00 3079 138131 13025 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 773 129059 31112 0 0
srv_find_qd_qs_mism 1095659 1095659 0 0.00 0 8662 11 0 0
srv_make_invnt_saldo 535 535 0 0.00 67 69954 3620 0 0
srv_make_money_saldo 960 960 0 0.00 1 10153 310 0 0
srv_recalc_idx_stat 4 4 0 0.00 75089 482448 275878 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5274 5274 0 0.00 0 9469 866 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 5712 480 0 0
t$perf-norm:sp_cancel_client_order 1811 1811 0 0.00 0 1905 23 0 0
t$perf-norm:sp_cancel_customer_reserve 759 759 0 0.00 0 2541 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 576 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 458 458 0 0.00 0 553 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 450 450 0 0.00 0 880 32 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 64364 5664 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 2087 19 0 0
t$perf-norm:sp_client_order 21480 21480 0 0.00 0 14429 35 0 0
t$perf-norm:sp_customer_reserve 1103 1103 0 0.00 0 452 12 0 0
t$perf-norm:sp_pay_from_customer 7283 7283 0 0.00 0 8843 22 0 0
t$perf-norm:sp_pay_to_supplier 6113 6113 0 0.00 0 6998 19 0 0
t$perf-norm:sp_reserve_write_off 10637 10637 0 0.00 0 14534 24 0 0
t$perf-norm:sp_supplier_invoice 5670 5670 0 0.00 0 6110 28 0 0
t$perf-norm:sp_supplier_order 5584 5584 0 0.00 0 19911 36 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 238 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 344 7 0 0
t$perf-norm:srv_make_invnt_saldo 535 535 0 0.00 0 838 17 0 0
t$perf-norm:srv_make_money_saldo 960 960 0 0.00 0 3082 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 165 56 0 0
v_add_invoice_to_stock 10692 10478 214 2.00 0 4985 22 0 214
v_all_customers 39122 39122 0 0.00 0 240 4 0 0
v_all_suppliers 31204 31204 0 0.00 0 243 4 0 0
v_all_wares 42952 42952 0 0.00 0 242 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 385 15 0 0
v_cancel_client_order 3622 3622 0 0.00 0 3876 11 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 3924 20 0 0
v_cancel_customer_reserve 1518 1518 0 0.00 0 3887 15 0 0
v_cancel_payment_to_supplier 916 916 0 0.00 0 3784 17 0 0
v_cancel_supplier_invoice 926 900 26 2.80 0 370 20 0 26
v_cancel_supplier_order 864 864 0 0.00 0 1136 12 0 0
v_cancel_write_off 2118 2118 0 0.00 0 1274 23 0 0
v_max_id_clo_ord 5581 5581 0 0.00 0 1473 5 0 0
v_max_id_clo_res 1103 1103 0 0.00 0 1123 16 0 0
v_max_id_ord_sup 5661 5661 0 0.00 0 3940 6 0 0
v_max_non_paid_invoice 1753 1753 0 0.00 0 68 7 0 0
v_max_non_paid_realizn 3681 3681 0 0.00 0 1653 8 0 0
v_min_id_clo_ord 5581 5581 0 0.00 0 1794 8 0 0
v_min_id_clo_res 1103 1103 0 0.00 0 2672 12 0 0
v_min_id_ord_sup 5661 5661 0 0.00 0 8651 10 0 0
v_min_non_paid_invoice 6113 6113 0 0.00 0 5150 17 0 0
v_min_non_paid_realizn 7283 7283 0 0.00 0 7098 16 0 0
v_our_firm 11034 11034 0 0.00 0 70 5 0 0
v_reserve_write_off 21272 21272 0 0.00 0 4072 11 0 0
x_lock_depdocs_on_canc_invoice 11956 11956 0 0.00 0 13199 105 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 10321 324 0 0
x_make_qstorno_1000_1200 5584 5584 0 0.00 2 31406 1060 0 0
x_make_qstorno_1200_2000 5670 5670 0 0.00 5 120124 2684 0 0
x_make_qstorno_2100_3300 484227 484227 0 0.00 0 44839 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-02-2023 10:40:42 to 28-02-2023 10:40:46.

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:31 922 8192000000 8937976704 8995422208 19264288 0.234 5 3 7 0 20008272 157552 586432
08:08:35 22593 8192000000 9711531456 10010320896 785870224 8.753 100 88 175 0 814877264 7791856 21215184
08:10:07 15954 8192000000 9766689904 10075881472 832568928 9.225 100 88 176 0 865774720 11723344 21482448
08:11:27 13567 8192000000 9786153952 10106146816 854702096 9.447 100 84 168 0 892221408 18123504 19395808
08:12:52 9035 8192000000 9864923728 10198335488 905855632 9.956 100 80 160 0 965331552 41225648 18250272
08:14:07 4049 8192000000 9918261648 10265305088 953876112 10.429 100 80 159 0 1025984768 55542304 16566352
08:15:16 1624 8192000000 9966270320 10316738560 982538160 10.709 100 94 180 0 1064713392 64643664 17531568
08:16:21 1396 8192000000 9993846752 10352889856 1003275696 10.910 100 95 181 1 1104223840 82723008 18225136
08:17:25 1003 8192000000 10021442720 10389843968 1024092192 11.112 100 92 179 0 1126901632 85053408 17756032
08:18:27 1181 8192000000 10001900640 10374307840 1041039744 11.275 100 91 169 0 1114255360 56471776 16743840
08:19:29 1027 8192000000 10034219136 10411032576 1056412800 11.422 100 93 180 1 1140731552 65576480 18742272
08:20:32 1125 8192000000 10035058384 10418954240 1060701456 11.463 100 94 176 0 1144479776 65266816 18511504
08:21:34 973 8192000000 10049859840 10432483328 1069524896 11.548 100 89 170 0 1158223952 71305744 17393312
08:22:36 1122 8192000000 10054287072 10443198464 1073514768 11.586 100 98 179 2 1165802752 74170880 18117104
08:23:37 1295 8192000000 10051609648 10434904064 1079572512 11.643 100 95 179 2 1161231600 63772176 17886912
08:24:39 1206 8192000000 10067340688 10457239552 1082213024 11.669 100 95 184 1 1173565984 72406960 18946000
08:25:41 1236 8192000000 10041502144 10433511424 1085360672 11.699 100 88 166 2 1152726208 49738368 17627168
08:26:43 960 8192000000 10072367312 10464256000 1082485088 11.671 100 84 167 2 1180491040 80820400 17185552
08:27:45 4852 8192000000 10053904128 10448949248 1084126512 11.687 100 64 122 3 1171004080 75560064 11317504
08:28:52 4597 8192000000 10033816704 10426540032 1065722704 11.511 99 64 122 0 1133971360 56788496 11460160
08:29:57 3664 8192000000 10045868384 10429771776 1068709824 11.540 99 86 163 1 1155877744 71528064 15639856
08:31:02 1155 8192000000 10050548368 10432806912 1071183936 11.563 100 96 183 1 1162641248 72098800 19358512
08:32:05 1255 8192000000 10018536320 10395848704 1045269648 11.315 99 87 153 1 1123209536 62582160 15357728
08:33:07 952 8192000000 10016245952 10400595968 1055337856 11.412 100 82 154 3 1134317632 62094944 16884832
08:34:09 1928 8192000000 10032866368 10413649920 1060392480 11.460 100 77 147 1 1149990896 74238448 15359968
08:35:12 2818 8192000000 9971316608 10351693824 1034127424 11.208 99 68 127 4 1086394688 39256880 13010384
08:36:16 926 8192000000 9988710912 10362638336 1030310048 11.171 100 93 180 1 1108217840 59684896 18222896
08:37:18 5607 8192000000 9990321888 10373287936 1025762880 11.128 98 54 95 4 1102288736 67396960 9128896
08:38:27 6168 8192000000 10021849216 10403598336 1070453440 11.556 100 78 139 1 1145835632 61606432 13775760
08:39:37 19236 8192000000 10029087312 10413522944 1064999472 11.504 99 74 144 1 1147548448 67765936 14783040
08:41:02 13246 8192000000 10062712880 10448773120 1091302032 11.755 100 70 131 1 1176964720 72435600 13227088
08:42:19 2410 8192000000 10108231168 10496253952 1102008592 11.857 100 82 152 0 1226777776 107949504 16819680
08:43:23 1036 8192000000 10086928256 10469781504 1091808496 11.760 99 92 176 0 1198254544 88514544 17931504
08:44:28 4741 8192000000 10143024448 10550943744 1119125792 12.019 100 80 149 3 1255917616 121369792 15422032
08:45:36 1401 8192000000 10173910256 10570670080 1128128800 12.104 100 90 169 1 1286752976 140606944 18017232
08:46:43 1085 8192000000 10194206880 10587152384 1140248032 12.218 100 92 179 3 1304263376 143189936 20825408
08:47:46 2914 8192000000 10200026656 10604802048 1131854976 12.139 99 71 139 0 1311634688 163768432 16011280
08:48:54 3056 8192000000 10242710464 10647105536 1148980448 12.300 100 74 143 0 1363798224 198226576 16591200
08:50:00 1540 8192000000 10278911840 10683080704 1158191152 12.386 100 80 160 0 1393082800 216421440 18470208
08:51:05 892 8192000000 10295435328 10697170944 1167151168 12.470 100 88 170 0 1403877472 217258400 19467904
08:52:09 838 8192000000 10354688144 10757263360 1169376912 12.491 100 94 180 1 1458373312 268659200 20337200
08:53:14 758 8192000000 10373040032 10780995584 1168916672 12.487 100 90 179 1 1481161840 291351568 20893600
08:54:19 855 8192000000 10417096720 10835656704 1184631456 12.633 100 98 185 1 1524716176 316846016 23238704
08:55:22 1077 8192000000 10465332304 10893639680 1186464752 12.650 100 95 185 1 1571768480 362965920 22337808
08:56:26 1751 8192000000 10462338608 10888151040 1177636048 12.568 100 84 166 2 1561565808 364091520 19838240
08:57:29 951 8192000000 10452557424 10883776512 1183719424 12.625 100 100 192 0 1555148832 349262672 22166736
08:58:35 1417 8192000000 10482174000 10892398592 1172355184 12.519 100 78 154 0 1591815296 401229200 18230912
08:59:41 3848 8192000000 10467184960 10903576576 1167736608 12.476 100 64 128 0 1570397392 386458880 16201904
09:00:51 1663 8192000000 10473473040 10911944704 1167151392 12.470 100 70 142 0 1580913072 396580000 17181680
09:01:56 5946 8192000000 10519885120 10962444288 1179361232 12.584 100 54 103 1 1630803840 438591824 12850784
09:03:10 7302 8192000000 10498591024 10971754496 1165342096 12.453 100 51 100 1 1607710672 428302816 14065760
09:04:21 17173 8192000000 10468279296 10932019200 1167103152 12.470 100 34 68 0 1568038416 392037040 8898224
09:05:41 29329 8192000000 10486347056 10957373440 1140220560 12.218 98 40 80 0 1569729664 419123072 10386032
09:07:23 38925 8192000000 10390599552 10841407488 1127279856 12.096 99 24 48 0 1470720368 337402400 6038112
09:09:12 44887 8192000000 10255916976 10778951680 1139249456 12.208 99 19 38 0 1363664288 220064432 4350400
09:11:04 28483 8192000000 10065852896 10519990272 1096536944 11.805 96 16 30 1 1173983888 74042608 3404336
09:12:33 95078 8192000000 10055264288 10524708864 1088881120 11.732 96 14 26 0 1131024288 39807552 2335616
09:15:09 14774 8192000000 10063343840 10472112128 1094724656 11.788 98 50 88 1 1141006720 35722608 10559456
09:16:25 22089 8192000000 10079576528 10495459328 1100503728 11.842 98 53 102 1 1163386320 51394208 11488384
09:17:49 129059 8192000000 10119113584 10562732032 1097940320 11.818 98 23 46 1 1199265216 96371744 4953152
09:21:03 66721 8192000000 10111320320 10563747840 1131635504 12.137 100 34 65 0 1228113408 89196336 7281568
09:23:15 21952 8192000000 10095823440 10544844800 1138663888 12.203 100 21 38 0 1195636032 53103504 3868640
09:24:41 1652 8192000000 10122195120 10548363264 1150306640 12.312 100 61 117 0 1219394080 56406544 12680896
09:25:43 29309 8192000000 10153619136 10592993280 1155844656 12.364 100 36 67 2 1261512816 97684880 7983280
09:27:13 24173 8192000000 10165190432 10618753024 1153226512 12.340 99 68 130 1 1265970880 98792016 13952352
09:28:40 32993 8192000000 10119825008 10575163392 1154053968 12.348 100 15 29 0 1214310032 57349760 2906304
09:30:14 43467 8192000000 10111026256 10607742976 1151962864 12.328 99 14 26 0 1230753936 75113696 3677376
09:32:04 83742 8192000000 10107629760 10565050368 1147912160 12.290 99 27 52 1 1186257216 32783328 5561728
09:34:36 56733 8192000000 10081450176 10541846528 1113645280 11.967 96 24 48 0 1159107792 40321856 5140656
09:37:03 74434 8192000000 10180505200 10687717376 1159897120 12.402 100 16 31 0 1292053472 128549952 3606400
09:39:18 64835 8192000000 10090068624 10581852160 1134333856 12.162 98 14 28 0 1181631824 44314832 2983136
09:41:24 59407 8192000000 10151009312 10655465472 1173367760 12.528 100 28 56 0 1256134320 77251456 5515104
09:43:24 40680 8192000000 10114770016 10589941760 1114970448 11.979 96 25 48 0 1173138256 53545056 4622752
09:45:07 54431 8192000000 10121159312 10566688768 1093525728 11.776 95 39 76 1 1155719504 54694592 7499184
09:47:03 29101 8192000000 10118648064 10565160960 1115233792 11.982 98 30 60 0 1198362256 76410992 6717472
09:48:34 35284 8192000000 10150624176 10636828672 1137763824 12.194 99 23 46 0 1230967600 88395200 4808576
09:50:16 32951 8192000000 10109033776 10571411456 1133823072 12.157 99 29 55 0 1201380512 61766928 5790512
09:51:52 39150 8192000000 10098870112 10531287040 1119429184 12.022 98 35 70 0 1177239008 50550240 7259584
09:53:31 101053 8192000000 10039822272 10499354624 1097674000 11.816 98 29 58 0 1155744560 52297536 5773024
09:56:20 29933 8192000000 10116083008 10551738368 1131370688 12.134 100 36 70 0 1208057824 69266624 7420512
09:57:50 65479 8192000000 10159173808 10627653632 1136811264 12.186 100 15 30 0 1251024992 110806464 3407264
10:01:17 54532 8192000000 10045197008 10491858944 1093799072 11.779 98 17 32 1 1157318080 59922336 3596672
10:03:32 58806 8192000000 10076789888 10532945920 1091009008 11.752 98 19 38 0 1155747312 60319712 4418592
10:05:31 24432 8192000000 10034534112 10472042496 1083043872 11.676 98 38 72 1 1120141392 28931888 8165632
10:06:59 20890 8192000000 10069071888 10500554752 1116023056 11.989 100 37 73 0 1172655664 49727936 6904672
10:08:23 11051 8192000000 10088539024 10510319616 1125724096 12.081 100 53 104 0 1183183248 47093104 10366048
10:09:34 54025 8192000000 10109134928 10593181696 1121178656 12.038 99 33 64 0 1220978688 93334912 6465120
10:11:37 11890 8192000000 10082357488 10511065088 1136297552 12.181 100 48 94 1 1192079088 46090080 9691456
10:12:52 17608 8192000000 10118110544 10537435136 1106365888 11.898 98 57 113 0 1190286544 72901472 11019184
10:14:10 44568 8192000000 10112923744 10575708160 1133735472 12.157 100 27 50 1 1189831184 51465872 4629840
10:15:59 41883 8192000000 10166796272 10611470336 1136464464 12.182 99 48 94 1 1230215520 84137216 9613840
10:17:47 91800 8192000000 10108471440 10665816064 1138476480 12.201 100 20 40 0 1289919120 146802464 4640176
10:20:23 55763 8192000000 10092267232 10562932736 1145193216 12.264 100 9 18 0 1216363120 69327136 1842768
10:22:19 69724 8192000000 10075192256 10559819776 1119421264 12.022 98 4 8 0 1169420752 49390288 609200
10:24:29 28804 8192000000 10087120896 10524491776 1134053232 12.160 100 32 62 0 1172392864 31491488 6848144
10:25:59 18895 8192000000 10125656608 10566995968 1142023472 12.235 99 31 61 0 1221289232 72387104 6878656
10:27:20 23735 8192000000 10163635872 10607792128 1142307456 12.237 99 30 60 0 1243979584 94263376 7408752
10:28:54 44376 8192000000 10175814592 10613841920 1136455056 12.182 98 26 52 1 1248849216 106337296 6056864
10:30:45 38520 8192000000 10238759136 10700906496 1126801008 12.091 97 32 63 0 1287026016 152748432 7476576
10:32:26 20372 8192000000 10255762512 10691252224 1149002704 12.300 100 38 74 0 1338929392 181614528 8312160
10:33:47 20401 8192000000 10248781376 10684174336 1156228176 12.368 100 38 76 0 1344057536 179454720 8374640
10:35:09 53792 8192000000 10264720560 10740424704 1108248032 11.916 96 33 66 0 1306556592 191179536 7129024
10:37:24 35856 8192000000 9035703504 9326907392 178066112 2.127 16 4 8 0 220344352 41368704 909536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 28-02-2023 10:40:48 to 28-02-2023 10:41:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-02-2023 10:41:06 to 28-02-2023 10:41:08.

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          228436
MON$OLDEST_ACTIVE               228437
MON$OLDEST_SNAPSHOT             228437
MON$NEXT_TRANSACTION            228437
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                       2081056
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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