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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15317796 kB
MemAvailable 30398904 kB
Buffers 0 kB
Cached 16419028 kB
SwapCached 4552 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-06 10:37:23
DTS_END                         2023-01-06 10:37:23.0730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45892
TRN_ID                          224723
ATT_ID                          677
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: 8708.38 21066 1045005
customer order: creation 173.33 470 20800
customer order: refuse 14.69 416 1763
order to supplier: creation 45.82 1391 5498
order to supplier: removal 3.12 152687 374
invoice (draft): creation 45.86 3330 5503
invoice (draft): removal 46.34 1065 5561
invoice accept: apply 42.51 25673 5101
invoice accept: cancel 41.73 1342 5007
customer reserve: creation 3837.64 252 460517
customer reserve: removal 4169.13 89 500295
realization accept: apply 85.11 281 10213
realization accept: cancel 73.99 166 8879
payment from customer: creation 58.79 171 7055
payment from customer: removal 5.58 142 670
payment to supplier: creation 48.62 197 5834
payment to supplier: removal 3.37 133 404
service: total inventory turnovers 4.20 3531 504
service: total monetary turnovers 7.66 359 919
service: aggregate perf. data 0.87 17759 104
service: refresh index statistics 0.03 211868 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-01-2023 10:38:54 to 06-01-2023 10:38:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6702
TEST_TIME 2 5248
TEST_TIME 3 6350
TEST_TIME 4 5940
TEST_TIME 5 5606
TEST_TIME 6 5581
TEST_TIME 7 5826
TEST_TIME 8 6203
TEST_TIME 9 6233
TEST_TIME 10 6394
TEST_TIME 11 6796
TEST_TIME 12 7066
TEST_TIME 13 7210
TEST_TIME 14 7119
TEST_TIME 15 7154
TEST_TIME 16 7361
TEST_TIME 17 7706
TEST_TIME 18 7916
TEST_TIME 19 7954
TEST_TIME 20 8018
TEST_TIME 21 8528
TEST_TIME 22 8798
TEST_TIME 23 8806
TEST_TIME 24 8976
TEST_TIME 25 9089
TEST_TIME 26 9851
TEST_TIME 27 10000
TEST_TIME 28 10043
TEST_TIME 29 11013
TEST_TIME 30 10894
TEST_TIME 31 10629
TEST_TIME 32 10586
TEST_TIME 33 10517
TEST_TIME 34 10483
TEST_TIME 35 10463
TEST_TIME 36 10363
TEST_TIME 37 10313
TEST_TIME 38 10200
TEST_TIME 39 10060
TEST_TIME 40 9911
TEST_TIME 41 9828
TEST_TIME 42 9692
TEST_TIME 43 9677
TEST_TIME 44 9730
TEST_TIME 45 9637
TEST_TIME 46 9498
TEST_TIME 47 9450
TEST_TIME 48 9371
TEST_TIME 49 9310
TEST_TIME 50 9260
TEST_TIME 51 9162
TEST_TIME 52 9006
TEST_TIME 53 9006
TEST_TIME 54 8961
TEST_TIME 55 9078
TEST_TIME 56 9167
TEST_TIME 57 9126
TEST_TIME 58 9097
TEST_TIME 59 9212
TEST_TIME 60 9253
TEST_TIME 61 9226
TEST_TIME 62 9242
TEST_TIME 63 9214
TEST_TIME 64 9229
TEST_TIME 65 9296
TEST_TIME 66 9402
TEST_TIME 67 9390
TEST_TIME 68 9441
TEST_TIME 69 9396
TEST_TIME 70 9293
TEST_TIME 71 9280
TEST_TIME 72 9193
TEST_TIME 73 9139
TEST_TIME 74 9146
TEST_TIME 75 9101
TEST_TIME 76 9110
TEST_TIME 77 9140
TEST_TIME 78 9059
TEST_TIME 79 9053
TEST_TIME 80 9011
TEST_TIME 81 9004
TEST_TIME 82 8983
TEST_TIME 83 8947
TEST_TIME 84 8953
TEST_TIME 85 8993
TEST_TIME 86 8957
TEST_TIME 87 8917
TEST_TIME 88 8906
TEST_TIME 89 8907
TEST_TIME 90 8995
TEST_TIME 91 9125
TEST_TIME 92 9112
TEST_TIME 93 9102
TEST_TIME 94 9026
TEST_TIME 95 9006
TEST_TIME 96 8982
TEST_TIME 97 8953
TEST_TIME 98 8885
TEST_TIME 99 8899
TEST_TIME 100 8855
TEST_TIME 101 8939
TEST_TIME 102 8911
TEST_TIME 103 8897
TEST_TIME 104 8915
TEST_TIME 105 8970
TEST_TIME 106 8965
TEST_TIME 107 9028
TEST_TIME 108 9013
TEST_TIME 109 9056
TEST_TIME 110 8991
TEST_TIME 111 8947
TEST_TIME 112 8900
TEST_TIME 113 8905
TEST_TIME 114 8886
TEST_TIME 115 8868
TEST_TIME 116 8834
TEST_TIME 117 8805
TEST_TIME 118 8766
TEST_TIME 119 8789
TEST_TIME 120 8733

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-01-2023 10:38:57 to 06-01-2023 10:39:01.

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 1470223 1470223 0 0.00 0 19696 13 0 0
doc_list_biud 1470210 1470210 0 0.00 0 49583 37 0 0
sp_add_invoice_to_stock 5210 5101 109 2.09 30 292422 25673 0 109
sp_cancel_adding_invoice 5007 5007 0 0.00 6 85407 1342 0 0
sp_cancel_client_order 1763 1763 0 0.00 5 8034 416 0 0
sp_cancel_customer_reserve 500295 500295 0 0.00 1 21069 90 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 8212 142 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 5742 133 0 0
sp_cancel_supplier_invoice 5576 5561 15 0.26 2 49592 1065 0 15
sp_cancel_supplier_order 374 374 0 0.00 8 1456688 152687 0 0
sp_cancel_write_off 8879 8879 0 0.00 2 12436 166 0 0
sp_client_order 20800 20800 0 0.00 5 40886 470 0 0
sp_customer_reserve 460517 460517 0 0.00 0 76344 252 0 0
sp_fill_shopping_cart 31791 31791 0 0.00 1 23855 85 0 0
sp_fill_shopping_cart_clo_res 460485 460485 0 0.00 0 15889 19 0 0
sp_get_clo_for_invoice 5062 5062 0 0.00 0 23149 317 0 0
sp_kill_cost_storno 14960 14960 0 0.00 0 9079 77 0 0
sp_kill_qstorno_ret_qs2qd 1015404 1015404 0 0.00 0 49582 41 0 0
sp_kill_qty_storno 537152 537152 0 0.00 0 49583 93 0 0
sp_lock_selected_doc 21004 21004 0 0.00 0 8165 8 0 0
sp_make_cost_storno 28164 28164 0 0.00 0 15426 80 0 0
sp_multiply_rows_for_pdistr 28162 28162 0 0.00 0 8912 39 0 0
sp_multiply_rows_for_qdistr 20800 20800 0 0.00 0 10015 176 0 0
sp_pay_from_customer 7055 7055 0 0.00 6 11977 171 0 0
sp_pay_to_supplier 5834 5834 0 0.00 7 19702 198 0 0
sp_payment_common 12888 12888 0 0.00 2 7631 45 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 1 8023 331 0 0
sp_qd_handle_on_invoice_upd_sts 10068 10068 0 0.00 0 40218 500 0 0
sp_qd_handle_on_reserve_upd_sts 10212 10212 0 0.00 0 12971 59 0 0
sp_reserve_write_off 10213 10213 0 0.00 8 16514 281 0 0
sp_supplier_invoice 5503 5503 0 0.00 11 116502 3331 0 0
sp_supplier_order 5498 5498 0 0.00 8 42336 1391 0 0
srv_aggregate_perf_data 104 104 0 0.00 2641 161833 17760 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 801 140122 38337 0 0
srv_find_qd_qs_mism 996715 996715 0 0.00 0 18249 9 0 0
srv_make_invnt_saldo 504 504 0 0.00 33 68489 3531 0 0
srv_make_money_saldo 919 919 0 0.00 1 11945 359 0 0
srv_recalc_idx_stat 4 4 0 0.00 64769 392509 211869 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5107 5107 0 0.00 0 18841 898 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 6382 525 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 2392 27 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 750 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 1777 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 5078 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 379 26 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 91367 5947 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 3711 21 0 0
t$perf-norm:sp_client_order 20801 20801 0 0.00 0 13738 37 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 1940 17 0 0
t$perf-norm:sp_pay_from_customer 7055 7055 0 0.00 0 7758 21 0 0
t$perf-norm:sp_pay_to_supplier 5834 5834 0 0.00 0 6074 18 0 0
t$perf-norm:sp_reserve_write_off 10213 10213 0 0.00 0 10863 23 0 0
t$perf-norm:sp_supplier_invoice 5503 5503 0 0.00 0 3723 30 0 0
t$perf-norm:sp_supplier_order 5498 5498 0 0.00 0 5673 33 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 296 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 1458 51 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 4408 25 0 0
t$perf-norm:srv_make_money_saldo 919 919 0 0.00 0 1850 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 133 46 0 0
v_add_invoice_to_stock 10328 10110 218 2.11 0 15568 32 0 218
v_all_customers 37594 37594 0 0.00 0 89 4 0 0
v_all_suppliers 30460 30460 0 0.00 0 116 4 0 0
v_all_wares 41600 41600 0 0.00 0 201 4 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 463 14 0 0
v_cancel_client_order 3526 3526 0 0.00 0 2848 14 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 2856 15 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 2996 16 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 2699 17 0 0
v_cancel_supplier_invoice 866 836 30 3.46 0 4172 30 0 30
v_cancel_supplier_order 748 748 0 0.00 0 2048 15 0 0
v_cancel_write_off 2036 2036 0 0.00 0 1047 24 0 0
v_max_id_clo_ord 5495 5495 0 0.00 0 367 5 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 2302 15 0 0
v_max_id_ord_sup 5494 5494 0 0.00 0 6132 8 0 0
v_max_non_paid_invoice 1592 1592 0 0.00 0 85 8 0 0
v_max_non_paid_realizn 3791 3791 0 0.00 0 2900 9 0 0
v_min_id_clo_ord 5495 5495 0 0.00 0 3156 9 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 5320 19 0 0
v_min_id_ord_sup 5494 5494 0 0.00 0 3900 10 0 0
v_min_non_paid_invoice 5833 5833 0 0.00 0 7615 18 0 0
v_min_non_paid_realizn 7054 7054 0 0.00 0 5956 15 0 0
v_our_firm 10530 10530 0 0.00 0 93 5 0 0
v_reserve_write_off 20424 20424 0 0.00 0 5525 12 0 0
x_lock_depdocs_on_canc_invoice 10150 10150 0 0.00 0 13550 142 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 21521 441 0 0
x_make_qstorno_1000_1200 5498 5498 0 0.00 2 42324 1228 0 0
x_make_qstorno_1200_2000 5503 5503 0 0.00 4 113113 3089 0 0
x_make_qstorno_2100_3300 460517 460517 0 0.00 0 76330 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2023 10:39:02 to 06-01-2023 10:39:06.

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:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 29757 8192000000 9721474160 10019930112 793988208 8.835 100 87 174 0 823129792 7570192 21571392
08:10:01 21800 8192000000 9762393312 10073042944 837092080 9.271 100 85 166 0 869302704 12239200 19971424
08:11:38 13881 8192000000 9798679328 10116767744 868238464 9.582 100 87 174 0 907656944 19676016 19742464
08:12:55 7009 8192000000 9872294432 10202177536 910795120 10.005 100 84 168 1 984969344 55731024 18443200
08:14:08 4032 8192000000 9923523088 10268336128 952400368 10.415 100 82 163 1 1031191024 61176512 17614144
08:15:15 2224 8192000000 9962521840 10318864384 998160304 10.861 100 87 168 1 1076356016 61462384 16733328
08:16:20 1812 8192000000 9981152688 10348310528 1027267056 11.142 100 86 167 2 1101796416 58046336 16483024
08:17:24 889 8192000000 10024698304 10394439680 1047610528 11.338 100 96 190 1 1145176304 78421456 19144320
08:18:26 957 8192000000 10041014864 10417762304 1060424608 11.461 100 95 191 1 1162456800 82731552 19300640
08:19:28 1528 8192000000 10040585696 10416951296 1073691744 11.587 100 94 183 2 1165439664 74122448 17625472
08:20:42 1036 8192000000 10033078704 10416005120 1078437488 11.633 100 90 161 0 1154175712 59272480 16465744
08:21:43 2484 8192000000 10062057616 10452287488 1083376224 11.680 100 73 134 0 1186163920 89869360 12918336
08:22:46 1062 8192000000 10034846096 10422411264 1088718784 11.730 100 94 183 0 1157117824 49881088 18517952
08:23:49 1052 8192000000 10055283184 10445803520 1096336496 11.803 100 95 173 1 1176376352 62168320 17871536
08:24:51 1040 8192000000 10063102960 10454167552 1097576112 11.815 100 97 187 1 1187576544 69608304 20392128
08:25:52 2012 8192000000 10063540224 10456862720 1093695808 11.778 100 77 153 0 1186838416 77977328 15165280
08:26:58 1034 8192000000 10047471776 10438778880 1095741056 11.797 100 84 166 2 1172155488 59435344 16979088
08:28:00 1217 8192000000 10055377536 10444001280 1087501856 11.719 100 98 181 2 1177765872 71307472 18956544
08:29:02 1344 8192000000 10014905696 10397892608 1082438896 11.671 100 98 186 1 1140250512 38045904 19765712
08:30:05 1788 8192000000 10048003664 10427543552 1073569728 11.586 100 95 170 3 1167566048 77087312 16909008
08:31:08 1200 8192000000 9995546064 10368458752 1041392448 11.278 100 97 182 0 1116860304 56573552 18894304
08:32:10 1165 8192000000 10008948624 10382540800 1043992000 11.303 100 94 176 5 1130967856 69238592 17737264
08:33:12 1424 8192000000 9980560208 10356465664 1034669536 11.213 100 79 154 5 1102332864 51530976 16132352
08:34:14 979 8192000000 9990703808 10362556416 1035592912 11.222 100 96 183 3 1111799968 58706512 17500544
08:35:16 6415 8192000000 10005386432 10388525056 1045853040 11.321 100 63 123 1 1130669376 71213440 13602896
08:36:23 4469 8192000000 9991424576 10374447104 1054161136 11.401 100 74 144 1 1120570656 51983152 14426368
08:37:31 39083 8192000000 10025309632 10411294720 1074093008 11.591 100 60 114 0 1141635296 56292576 11249712
08:39:26 3713 8192000000 10039454800 10432159744 1088625296 11.730 100 62 114 3 1162935344 61829488 12480560
08:40:33 1830 8192000000 10095645360 10481180672 1104572112 11.881 100 91 170 4 1216618128 95118336 16927680
08:41:38 7786 8192000000 10099559552 10492469248 1114529520 11.975 100 61 118 0 1208903280 82942320 11431440
08:42:50 2927 8192000000 10146173264 10540269568 1127233776 12.095 100 82 159 1 1272282496 127757824 17290896
08:43:55 2651 8192000000 10182394016 10579746816 1128314192 12.105 100 83 149 4 1294032224 150102384 15615648
08:45:04 2067 8192000000 10212112976 10606182400 1145511792 12.267 100 84 155 0 1327858880 165413808 16933280
08:46:10 6939 8192000000 10220116544 10617921536 1143762448 12.251 100 86 153 3 1336427744 176676992 15988304
08:47:22 1168 8192000000 10276482880 10679328768 1161181824 12.414 100 93 183 0 1388510784 206461792 20867168
08:48:26 1096 8192000000 10271961664 10670559232 1161128928 12.414 100 93 175 0 1382727088 202031232 19566928
08:49:29 1035 8192000000 10318155680 10719162368 1178652000 12.578 100 99 194 0 1431848640 232853824 20342816
08:50:32 2484 8192000000 10341345632 10747158528 1178785952 12.579 100 87 170 0 1457756080 260000016 18970112
08:51:39 1697 8192000000 10365679008 10773241856 1176329856 12.556 100 86 171 0 1474239584 278931536 18978192
08:52:44 1819 8192000000 10399389488 10823680000 1186275712 12.649 100 89 170 3 1504640800 298007040 20358048
08:53:50 1896 8192000000 10388603440 10820612096 1178055680 12.572 100 90 178 0 1496707536 297448400 21203456
08:54:54 786 8192000000 10408283840 10826723328 1166425072 12.463 99 94 184 0 1515778864 327076448 22277344
08:55:57 2182 8192000000 10404968080 10818347008 1155657808 12.363 100 75 143 0 1509740992 337533232 16549952
08:57:02 5423 8192000000 10432524192 10882871296 1165392576 12.454 100 64 126 1 1543673312 362906512 15374224
08:58:12 1902 8192000000 10439925440 10899435520 1176373600 12.556 100 90 170 1 1549918432 353969280 19575552
08:59:17 6145 8192000000 10433567296 10882895872 1168642496 12.484 100 61 120 0 1544470304 360640800 15187008
09:00:28 6548 8192000000 10403456320 10862161920 1160730256 12.410 99 53 105 1 1517624992 343496768 13397968
09:01:36 4999 8192000000 10400813216 10870571008 1169716608 12.494 100 51 96 1 1506153936 323732656 12704672
09:02:43 17613 8192000000 10373916880 10883055616 1176433648 12.557 100 42 84 0 1513164480 325681968 11048864
09:04:12 24995 8192000000 10279762944 10770399232 1163397520 12.435 100 23 45 1 1404923584 235677808 5848256
09:05:47 24879 8192000000 10239521888 10719019008 1135722608 12.175 98 23 46 0 1352092768 210414144 5956016
09:07:12 51473 8192000000 10117420848 10556092416 1158019456 12.385 100 30 56 1 1223339232 57621264 7698512
09:09:31 6388 8192000000 10110230720 10535923712 1136962240 12.187 99 71 130 2 1203934752 52024624 14947888
09:10:42 26845 8192000000 10104099872 10580406272 1123536832 12.060 99 18 36 0 1226638256 99112560 3988864
09:13:02 81540 8192000000 10059657296 10534625280 1132407088 12.144 99 16 33 0 1190775616 54540160 3828368
09:15:30 58310 8192000000 10091014640 10549587968 1134095856 12.160 99 20 40 0 1184100192 45766528 4237808
09:17:29 111091 8192000000 10159272752 10608586752 1132425280 12.144 98 44 88 0 1241707152 99690992 9590880
09:20:22 105661 8192000000 10067928336 10556542976 1122608320 12.052 98 26 51 0 1169090304 41171200 5310784
09:23:10 33880 8192000000 10039008160 10493194240 1105254560 11.887 97 24 42 0 1135912976 26305632 4352784
09:24:44 44493 8192000000 10092102848 10523676672 1107651920 11.910 98 37 74 0 1180744320 65362912 7729488
09:26:30 46730 8192000000 10110812464 10578350080 1131472976 12.135 99 34 68 0 1211921984 72373232 8075776
09:28:18 90323 8192000000 10148878560 10601553920 1139829024 12.214 100 13 26 0 1251551968 108666576 3056368
09:30:49 73316 8192000000 10177942608 10630754304 1143559200 12.249 99 18 36 0 1285413904 137437648 4417056
09:33:03 23665 8192000000 10062660896 10519474176 1146056880 12.272 100 32 61 1 1175313808 22253104 7003824
09:34:29 18816 8192000000 10103050656 10540097536 1149404784 12.304 100 52 96 4 1205785600 44177584 12203232
09:35:49 58745 8192000000 10128265744 10595168256 1114653808 11.976 97 20 39 0 1218563328 98910512 4999008
09:37:49 47828 8192000000 10078803040 10515451904 1118135216 12.009 98 26 50 1 1156197920 33157776 4904928
09:39:37 57305 8192000000 10127302032 10605895680 1128729936 12.109 98 23 46 1 1222778640 89363520 4685184
09:41:37 47028 8192000000 10133052272 10606014464 1119622704 12.023 98 48 93 1 1234400272 104384176 10393392
09:43:35 84357 8192000000 10086610448 10548027392 1083352448 11.679 95 9 18 0 1145679856 60477104 1850304
09:45:59 140120 8192000000 10091371632 10544062464 1120623696 12.033 99 30 56 2 1174986928 48521232 5842000
09:49:43 40263 8192000000 10121865616 10559250432 1114352896 11.974 98 47 90 0 1205131024 81619280 9158848
09:51:45 41334 8192000000 10119897872 10574987264 1104242496 11.878 98 20 40 0 1235574368 126970768 4361104
09:53:41 46173 8192000000 10050966752 10552729600 1113637296 11.967 99 13 26 0 1161349440 44901280 2810864
09:55:49 67095 8192000000 10022289408 10492256256 1088958096 11.733 97 13 24 0 1126565696 34613472 2994128
09:58:05 69508 8192000000 10054238352 10508713984 1093730864 11.778 98 14 28 0 1126224976 29915616 2578496
10:00:28 28004 8192000000 10075702112 10503524352 1094414960 11.785 98 31 63 0 1157122048 55648400 7058688
10:02:20 38305 8192000000 10095814496 10532069376 1123435504 12.059 100 17 28 0 1182148384 55942176 2770704
10:04:10 32846 8192000000 10152549824 10584231936 1115790512 11.987 98 25 49 0 1228014928 106775056 5449360
10:05:46 23254 8192000000 10205660880 10652778496 1132969104 12.149 100 33 64 0 1301036000 161037568 7029328
10:07:09 82721 8192000000 10085563200 10533134336 1115443456 11.984 99 19 38 0 1222807024 103150000 4213568
10:09:33 15165 8192000000 10066574400 10482036736 1095815616 11.798 97 40 78 0 1150388320 46986368 7586336
10:10:52 71555 8192000000 10119109248 10570256384 1132992272 12.150 100 46 90 0 1225080912 83357168 8731472
10:13:06 44339 8192000000 10087341664 10526101504 1119926352 12.026 98 28 56 0 1167173824 41670960 5576512
10:14:58 37200 8192000000 10110537984 10534772736 1135204432 12.170 100 37 70 0 1189499472 46984848 7310192
10:16:56 126986 8192000000 10082100048 10540511232 1082776096 11.674 95 14 28 0 1169185360 83380880 3028384
10:20:04 102517 8192000000 10140167408 10615275520 1108497600 11.918 98 17 34 0 1213750048 101377568 3874880
10:23:21 39266 8192000000 10128433456 10575687680 1119141648 12.019 99 25 50 0 1181168496 56753104 5273744
10:25:01 17044 8192000000 10085937840 10537451520 1108464112 11.918 98 32 63 0 1151583008 36997248 6121648
10:26:21 62874 8192000000 10096079680 10579709952 1116372064 11.993 99 37 74 0 1189666880 65603808 7691008
10:28:40 32925 8192000000 10082909328 10511470592 1131807232 12.138 100 42 82 0 1179337392 39409808 8120352
10:30:14 61451 8192000000 10084943344 10559488000 1113449120 11.965 98 21 42 0 1186546656 68533648 4563888
10:32:15 77823 8192000000 10051621248 10516144128 1102212592 11.859 98 8 15 0 1137759184 33996448 1550144
10:34:34 69944 8192000000 10093415424 10566107136 1123067232 12.056 100 10 20 1 1185531424 60005072 2459120
10:36:52 34826 8192000000 9113335328 9393037312 265694032 3.141 24 5 10 0 304271584 37840240 737312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-01-2023 10:39:08 to 06-01-2023 10:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-01-2023 10:39:25 to 06-01-2023 10:39:27.

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          224033
MON$OLDEST_ACTIVE               225000
MON$OLDEST_SNAPSHOT             225000
MON$NEXT_TRANSACTION            225000
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                       2033696
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.2886 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2886 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2886 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2886 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'

06.01.23 10:39:28. 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-06 10:39:30 No severe PSQL-related problems occured
06.01.23 10:39:32. End of report.