Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.01.2023 13:41
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 15000744 kB
MemAvailable 30422636 kB
Buffers 0 kB
Cached 16735352 kB
SwapCached 2552 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-18 13:37:23
DTS_END                         2023-01-18 13:37:23.2160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33526
TRN_ID                          236015
ATT_ID                          629
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9137.08 24752 1096450
customer order: creation 189.75 433 22770
customer order: refuse 15.65 378 1878
order to supplier: creation 48.60 1361 5832
order to supplier: removal 3.32 152056 398
invoice (draft): creation 49.29 2957 5915
invoice (draft): removal 48.02 922 5762
invoice accept: apply 44.48 23879 5337
invoice accept: cancel 43.58 1299 5230
customer reserve: creation 4069.73 228 488367
customer reserve: removal 4316.46 93 517975
realization accept: apply 90.33 262 10839
realization accept: cancel 75.75 173 9090
payment from customer: creation 64.83 166 7780
payment from customer: removal 6.33 141 760
payment to supplier: creation 53.80 187 6456
payment to supplier: removal 3.59 124 431
service: total inventory turnovers 4.42 3736 530
service: total monetary turnovers 8.16 332 979
service: aggregate perf. data 0.98 15992 117
service: refresh index statistics 0.03 290321 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-01-2023 13:41:27 to 18-01-2023 13:41:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6001
TEST_TIME 2 5848
TEST_TIME 3 6229
TEST_TIME 4 6613
TEST_TIME 5 6729
TEST_TIME 6 6529
TEST_TIME 7 6670
TEST_TIME 8 6610
TEST_TIME 9 6903
TEST_TIME 10 6988
TEST_TIME 11 7006
TEST_TIME 12 7119
TEST_TIME 13 7201
TEST_TIME 14 7405
TEST_TIME 15 7671
TEST_TIME 16 7789
TEST_TIME 17 7865
TEST_TIME 18 7857
TEST_TIME 19 7856
TEST_TIME 20 7812
TEST_TIME 21 8026
TEST_TIME 22 8145
TEST_TIME 23 8277
TEST_TIME 24 8365
TEST_TIME 25 8865
TEST_TIME 26 9187
TEST_TIME 27 9100
TEST_TIME 28 9532
TEST_TIME 29 9859
TEST_TIME 30 10339
TEST_TIME 31 10811
TEST_TIME 32 11341
TEST_TIME 33 11235
TEST_TIME 34 11229
TEST_TIME 35 11097
TEST_TIME 36 11032
TEST_TIME 37 11047
TEST_TIME 38 10845
TEST_TIME 39 10686
TEST_TIME 40 10511
TEST_TIME 41 10602
TEST_TIME 42 10492
TEST_TIME 43 10448
TEST_TIME 44 10323
TEST_TIME 45 10252
TEST_TIME 46 10202
TEST_TIME 47 10099
TEST_TIME 48 10054
TEST_TIME 49 10082
TEST_TIME 50 10178
TEST_TIME 51 10106
TEST_TIME 52 10104
TEST_TIME 53 9990
TEST_TIME 54 9864
TEST_TIME 55 9842
TEST_TIME 56 9801
TEST_TIME 57 9687
TEST_TIME 58 9668
TEST_TIME 59 9640
TEST_TIME 60 9603
TEST_TIME 61 9616
TEST_TIME 62 9588
TEST_TIME 63 9566
TEST_TIME 64 9536
TEST_TIME 65 9493
TEST_TIME 66 9497
TEST_TIME 67 9587
TEST_TIME 68 9581
TEST_TIME 69 9579
TEST_TIME 70 9647
TEST_TIME 71 9598
TEST_TIME 72 9601
TEST_TIME 73 9616
TEST_TIME 74 9605
TEST_TIME 75 9566
TEST_TIME 76 9601
TEST_TIME 77 9659
TEST_TIME 78 9666
TEST_TIME 79 9567
TEST_TIME 80 9546
TEST_TIME 81 9463
TEST_TIME 82 9433
TEST_TIME 83 9389
TEST_TIME 84 9325
TEST_TIME 85 9304
TEST_TIME 86 9289
TEST_TIME 87 9305
TEST_TIME 88 9260
TEST_TIME 89 9225
TEST_TIME 90 9269
TEST_TIME 91 9242
TEST_TIME 92 9302
TEST_TIME 93 9324
TEST_TIME 94 9319
TEST_TIME 95 9345
TEST_TIME 96 9363
TEST_TIME 97 9406
TEST_TIME 98 9407
TEST_TIME 99 9397
TEST_TIME 100 9395
TEST_TIME 101 9338
TEST_TIME 102 9294
TEST_TIME 103 9247
TEST_TIME 104 9205
TEST_TIME 105 9167
TEST_TIME 106 9153
TEST_TIME 107 9143
TEST_TIME 108 9130
TEST_TIME 109 9158
TEST_TIME 110 9133
TEST_TIME 111 9168
TEST_TIME 112 9183
TEST_TIME 113 9187
TEST_TIME 114 9193
TEST_TIME 115 9220
TEST_TIME 116 9258
TEST_TIME 117 9231
TEST_TIME 118 9250
TEST_TIME 119 9229
TEST_TIME 120 9160

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-01-2023 13:41:31 to 18-01-2023 13:41:34.

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 1545880 1545880 0 0.00 0 21645 13 0 0
doc_list_biud 1545867 1545867 0 0.00 0 29951 37 0 0
sp_add_invoice_to_stock 5514 5337 177 3.21 36 192751 23879 0 177
sp_cancel_adding_invoice 5230 5230 0 0.00 6 86902 1299 0 0
sp_cancel_client_order 1878 1878 0 0.00 6 11595 379 0 0
sp_cancel_customer_reserve 517975 517975 0 0.00 1 26954 93 0 0
sp_cancel_pay_from_customer 760 760 0 0.00 3 6474 142 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 4 4212 125 0 0
sp_cancel_supplier_invoice 5778 5762 16 0.27 3 29956 922 0 16
sp_cancel_supplier_order 398 398 0 0.00 9 1619099 152056 0 0
sp_cancel_write_off 9090 9090 0 0.00 2 8557 173 0 0
sp_client_order 22770 22770 0 0.00 5 44526 433 0 0
sp_customer_reserve 488367 488367 0 0.00 0 45838 228 0 0
sp_fill_shopping_cart 34505 34505 0 0.00 1 11619 84 0 0
sp_fill_shopping_cart_clo_res 488331 488331 0 0.00 0 18119 18 0 0
sp_get_clo_for_invoice 5294 5294 0 0.00 0 30491 277 0 0
sp_kill_cost_storno 15511 15511 0 0.00 0 6670 69 0 0
sp_kill_qstorno_ret_qs2qd 1051200 1051200 0 0.00 0 29950 40 0 0
sp_kill_qty_storno 556464 556464 0 0.00 0 29951 93 0 0
sp_lock_selected_doc 22246 22246 0 0.00 0 6328 7 0 0
sp_make_cost_storno 30369 30369 0 0.00 0 13303 75 0 0
sp_multiply_rows_for_pdistr 30366 30366 0 0.00 0 11048 36 0 0
sp_multiply_rows_for_qdistr 22768 22768 0 0.00 0 9946 161 0 0
sp_pay_from_customer 7780 7780 0 0.00 5 14169 166 0 0
sp_pay_to_supplier 6456 6456 0 0.00 6 18076 187 0 0
sp_payment_common 14234 14234 0 0.00 2 7469 45 0 0
sp_qd_handle_on_cancel_clo 1878 1878 0 0.00 0 11577 307 0 0
sp_qd_handle_on_invoice_upd_sts 10523 10523 0 0.00 0 14628 497 0 0
sp_qd_handle_on_reserve_upd_sts 10838 10838 0 0.00 0 11401 55 0 0
sp_reserve_write_off 10839 10839 0 0.00 8 13151 262 0 0
sp_supplier_invoice 5915 5915 0 0.00 9 65116 2957 0 0
sp_supplier_order 5832 5832 0 0.00 7 31709 1362 0 0
srv_aggregate_perf_data 117 117 0 0.00 2735 193727 15992 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 782 114692 36781 0 0
srv_find_qd_qs_mism 1043467 1043467 0 0.00 0 14864 10 0 0
srv_make_invnt_saldo 530 530 0 0.00 49 162243 3737 0 0
srv_make_money_saldo 979 979 0 0.00 1 12961 333 0 0
srv_recalc_idx_stat 4 4 0 0.00 72309 438795 290321 0 0
t$perf-abend:sp_add_invoice_to_stock 177 177 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5343 5343 0 0.00 1 32347 889 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 2 7932 508 0 0
t$perf-norm:sp_cancel_client_order 1878 1878 0 0.00 0 2332 27 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 2218 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 760 760 0 0.00 0 373 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 2341 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 3305 44 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 98117 6497 0 0
t$perf-norm:sp_cancel_write_off 1100 1100 0 0.00 0 1916 20 0 0
t$perf-norm:sp_client_order 22770 22770 0 0.00 0 29103 36 0 0
t$perf-norm:sp_customer_reserve 1083 1083 0 0.00 0 3802 23 0 0
t$perf-norm:sp_pay_from_customer 7780 7780 0 0.00 0 8036 19 0 0
t$perf-norm:sp_pay_to_supplier 6456 6456 0 0.00 0 3080 16 0 0
t$perf-norm:sp_reserve_write_off 10839 10839 0 0.00 0 13587 22 0 0
t$perf-norm:sp_supplier_invoice 5915 5915 0 0.00 0 7187 31 0 0
t$perf-norm:sp_supplier_order 5832 5832 0 0.00 0 2556 30 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 268 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 91 5 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 807 15 0 0
t$perf-norm:srv_make_money_saldo 980 980 0 0.00 0 730 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 79 27 0 0
v_add_invoice_to_stock 10932 10578 354 3.23 0 13689 29 0 354
v_all_customers 41475 41475 0 0.00 0 71 4 0 0
v_all_suppliers 32880 32880 0 0.00 0 174 4 0 0
v_all_wares 45534 45534 0 0.00 0 171 4 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 815 14 0 0
v_cancel_client_order 3756 3756 0 0.00 0 3616 12 0 0
v_cancel_customer_prepayment 1520 1520 0 0.00 0 6455 27 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 1 4381 13 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 1392 13 0 0
v_cancel_supplier_invoice 838 806 32 3.81 0 2774 32 0 32
v_cancel_supplier_order 796 796 0 0.00 0 575 12 0 0
v_cancel_write_off 2200 2200 0 0.00 0 6485 34 0 0
v_max_id_clo_ord 5824 5824 0 0.00 0 781 5 0 0
v_max_id_clo_res 1083 1083 0 0.00 0 1728 13 0 0
v_max_id_ord_sup 5910 5910 0 0.00 0 5497 9 0 0
v_max_non_paid_invoice 1749 1749 0 0.00 0 4048 10 0 0
v_max_non_paid_realizn 4243 4243 0 0.00 0 695 7 0 0
v_min_id_clo_ord 5824 5824 0 0.00 0 2057 9 0 0
v_min_id_clo_res 1083 1083 0 0.00 0 9738 18 0 0
v_min_id_ord_sup 5910 5910 0 0.00 0 3130 8 0 0
v_min_non_paid_invoice 6455 6455 0 0.00 0 7461 17 0 0
v_min_non_paid_realizn 7778 7778 0 0.00 0 5737 17 0 0
v_our_firm 11130 11130 0 0.00 0 62 5 0 0
v_reserve_write_off 21673 21673 0 0.00 0 7186 13 0 0
x_lock_depdocs_on_canc_invoice 10589 10589 0 0.00 0 23573 120 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 6578 333 0 0
x_make_qstorno_1000_1200 5832 5832 0 0.00 3 31682 1201 0 0
x_make_qstorno_1200_2000 5915 5915 0 0.00 5 65086 2722 0 0
x_make_qstorno_2100_3300 488366 488366 0 0.00 0 45829 198 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-01-2023 13:41:35 to 18-01-2023 13:41:39.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:27 940 8192000000 8934484768 8991817728 22682864 0.276 4 3 6 0 23211552 239472 289216
11:08:29 24749 8192000000 9718643696 10017705984 794803440 8.844 100 87 174 0 823379776 7654896 20921440
11:09:59 22513 8192000000 9757781056 10065899520 834576720 9.245 100 93 184 0 869384944 12584960 22223264
11:11:34 10957 8192000000 9808288272 10129051648 875768736 9.658 100 86 172 0 923111232 27189456 20153040
11:12:47 6964 8192000000 9867312752 10201014272 914910816 10.046 100 81 162 0 976253840 43921328 17421696
11:13:57 2802 8192000000 9916348192 10257420288 952308432 10.414 100 86 167 0 1027180608 57184912 17687264
11:15:03 2153 8192000000 9955884592 10306867200 981937504 10.703 100 91 174 1 1073700304 73738160 18024640
11:16:08 3560 8192000000 9987536352 10354987008 1019741056 11.070 100 90 177 0 1115631488 78403040 17487392
11:17:14 2418 8192000000 10018952528 10383646720 1034625440 11.213 100 89 170 3 1131754000 80424368 16704192
11:18:18 988 8192000000 10032566624 10410774528 1053349632 11.393 100 95 187 2 1155773888 83484800 18939456
11:19:20 1015 8192000000 10024134704 10400403456 1059717168 11.454 100 92 166 2 1145574768 69508240 16349360
11:20:22 1333 8192000000 10025493088 10404917248 1065791296 11.512 100 88 174 0 1144775216 61850400 17133520
11:21:24 1082 8192000000 10023212112 10405863424 1067328992 11.527 100 96 185 0 1146475088 60849936 18296160
11:22:26 1238 8192000000 10038430992 10432225280 1074836416 11.598 100 81 155 0 1163658688 71612128 17210144
11:23:30 1157 8192000000 10035596160 10419994624 1077027680 11.619 100 96 186 2 1158993424 62784064 19181680
11:24:32 983 8192000000 10045012736 10431815680 1084436944 11.690 100 94 173 0 1165160016 63017008 17706064
11:25:34 1178 8192000000 10055969920 10447114240 1088377552 11.727 100 92 170 1 1179596960 74290832 16928576
11:26:37 1175 8192000000 10055887248 10442522624 1094940176 11.790 100 96 181 0 1178754192 63974064 19839952
11:27:39 1242 8192000000 10061986416 10450325504 1098542096 11.824 100 95 184 3 1183312688 66163808 18606784
11:28:41 1344 8192000000 10040023088 10424532992 1074133456 11.592 100 92 176 2 1163129872 70652480 18343936
11:29:45 1169 8192000000 10029893616 10409750528 1069083072 11.543 100 95 176 1 1152904320 67051504 16769744
11:30:47 1257 8192000000 10046985872 10430611456 1081665792 11.663 100 97 189 0 1168584640 67875872 19042976
11:31:50 1159 8192000000 10013310160 10392547328 1057939200 11.437 100 93 179 0 1137658384 61388704 18330480
11:32:53 1077 8192000000 9992828272 10369544192 1032971664 11.197 100 86 166 0 1115567120 66139184 16456272
11:33:55 1194 8192000000 9956471136 10324107264 1007037024 10.947 100 85 155 2 1077515776 55113232 15365520
11:34:57 877 8192000000 9959169600 10333585408 1025626128 11.126 100 85 159 1 1082658624 40983984 16048512
11:35:59 2060 8192000000 9998677024 10382602240 1033166688 11.199 100 61 120 1 1119223248 73589280 12467280
11:37:02 3848 8192000000 9981884256 10364305408 1038267088 11.248 100 54 102 1 1105661216 57620144 9773984
11:38:10 9801 8192000000 10026064912 10416934912 1058597840 11.443 100 60 110 2 1140589808 70445856 11546112
11:39:29 1602 8192000000 10036882128 10426474496 1078175232 11.630 100 75 136 3 1156776336 64183504 14417600
11:40:34 2104 8192000000 10083058640 10466349056 1100819792 11.845 100 81 153 2 1201161248 84908576 15432880
11:41:39 869 8192000000 10129124880 10518224896 1115848272 11.988 100 85 162 1 1248568336 114998496 17721568
11:42:43 2236 8192000000 10154789792 10550661120 1126525088 12.089 100 84 158 1 1274838304 130622624 17690592
11:44:04 1583 8192000000 10195493328 10585534464 1133567040 12.155 100 93 177 3 1305192512 153487072 18138400
11:45:10 1031 8192000000 10219435088 10617262080 1147187408 12.283 100 97 186 2 1337117056 169929088 20000560
11:46:14 1868 8192000000 10244959456 10645704704 1155876880 12.365 100 88 163 1 1362472064 188074608 18520576
11:47:20 1003 8192000000 10270275376 10675630080 1168221632 12.480 100 96 187 0 1386727728 196523568 21982528
11:48:27 871 8192000000 10279178800 10682671104 1162444736 12.426 100 95 181 3 1390164656 207801232 19918688
11:49:31 1080 8192000000 10306664784 10706292736 1165771632 12.457 100 97 190 0 1414053520 227940336 20341552
11:50:35 1132 8192000000 10329351856 10747125760 1178090224 12.572 100 96 173 2 1440249136 242761088 19397824
11:51:39 1141 8192000000 10365007712 10770964480 1185327776 12.640 100 96 187 0 1474425264 268682800 20414688
11:52:44 1533 8192000000 10369330224 10789294080 1191710528 12.699 100 92 181 2 1479990560 267975760 20304272
11:53:50 773 8192000000 10404391280 10819629056 1198500496 12.762 100 97 189 1 1512539296 292584816 21453984
11:54:53 1740 8192000000 10431815072 10851782656 1198126768 12.759 100 88 172 1 1538299472 320271072 19901632
11:55:59 1459 8192000000 10483031776 10893123584 1206929296 12.841 100 99 192 2 1593018064 363510224 22578544
11:57:04 941 8192000000 10502278592 10915536896 1191123600 12.694 100 99 193 0 1609221632 394341728 23756304
11:58:07 898 8192000000 10533411408 10983366656 1202443712 12.799 100 96 190 2 1639693696 413541440 23708544
11:59:09 983 8192000000 10544664912 10959376384 1193408192 12.715 100 95 174 1 1648544736 434583664 20552880
12:00:14 1266 8192000000 10564594768 10994802688 1192868096 12.710 100 87 168 1 1665281392 451428608 20984688
12:01:20 863 8192000000 10540071120 10995494912 1179276752 12.583 100 95 186 1 1642461568 440954672 22230144
12:02:22 5988 8192000000 10486665888 10960977920 1173345904 12.528 100 63 127 0 1589169360 400193856 15629600
12:03:41 22260 8192000000 10482112944 10954964992 1165174768 12.452 100 43 86 0 1586327200 409957488 11194944
12:05:06 7792 8192000000 10415605888 10899419136 1143283440 12.246 99 40 71 0 1517455728 364994368 9177920
12:06:15 14538 8192000000 10371534624 10847903744 1143382288 12.247 100 44 88 1 1483453296 329631872 10439136
12:07:31 27237 8192000000 10284650976 10736910336 1136056080 12.178 100 24 47 1 1411785936 270155952 5573904
12:08:59 64921 8192000000 10078995040 10541133824 1114990432 11.980 98 17 33 1 1200080496 82310512 2779552
12:11:04 72885 8192000000 10075970832 10548232192 1104565184 11.881 97 24 47 0 1159203680 49141760 5496736
12:13:18 59375 8192000000 10101313920 10574344192 1137558224 12.193 100 15 30 0 1198763216 58108800 3096192
12:15:20 63200 8192000000 10104391552 10546573312 1144003536 12.253 100 26 45 0 1170579936 21491408 5084992
12:17:25 92748 8192000000 10084706192 10574708736 1113374144 11.964 97 15 31 0 1172423072 55608400 3440528
12:20:07 81653 8192000000 10063246544 10502344704 1096532784 11.805 96 12 24 1 1124494240 24992256 2969200
12:22:45 34444 8192000000 10067746592 10498220032 1125827216 12.082 99 34 61 2 1146502576 14284816 6390544
12:24:20 54959 8192000000 10115007600 10549067776 1129978336 12.121 99 15 29 1 1176039648 43332944 2728368
12:26:38 113990 8192000000 10087062192 10532540416 1123583232 12.061 98 15 24 0 1162802800 37284144 1935424
12:29:34 114692 8192000000 10053584816 10500599808 1073345424 11.584 94 16 32 1 1099327424 21981792 4000208
12:32:37 70608 8192000000 10079939536 10538352640 1083019424 11.676 95 43 82 0 1128321600 36506432 8795744
12:35:24 1023 8192000000 10087104416 10499629056 1130783168 12.129 100 95 186 4 1184556304 33696816 20076320
12:36:25 38622 8192000000 10099997728 10534060032 1110992832 11.942 98 29 58 1 1183301488 65874816 6433840
12:38:07 32268 8192000000 10119106336 10575388672 1133265120 12.152 99 39 77 0 1204769072 63626128 7877824
12:39:41 56217 8192000000 10133766000 10603425792 1130406624 12.125 99 19 38 0 1224557616 89853776 4297216
12:42:55 81434 8192000000 10112590112 10607398912 1116710096 11.996 98 16 31 0 1212368784 91802432 3856256
12:45:23 41399 8192000000 10092681328 10527711232 1099745088 11.835 97 37 74 0 1160648880 52837392 8066400
12:47:19 43416 8192000000 10062077952 10542350336 1087620080 11.720 96 39 75 0 1149795696 54102880 8072736
12:49:07 30618 8192000000 10084215568 10530439168 1114680928 11.977 100 36 72 1 1178722240 56605488 7435824
12:50:39 43683 8192000000 10066908240 10532007936 1107761888 11.911 99 26 52 0 1180241200 67399216 5080096
12:52:26 11036 8192000000 10111812992 10533236736 1128427104 12.107 100 45 84 0 1199457648 62511744 8518800
12:54:19 73579 8192000000 10056295408 10525839360 1090243136 11.745 97 28 54 0 1183851728 87887264 5721328
12:56:32 82425 8192000000 10027513536 10472329216 1094720608 11.788 98 29 58 0 1146284944 45646512 5917824
12:58:58 16759 8192000000 10067310192 10496143360 1130079408 12.122 100 48 92 0 1175856768 35134832 10642528
13:00:16 83496 8192000000 10085236000 10546368512 1100332112 11.841 97 14 23 0 1170001568 67249664 2419792
13:02:39 90260 8192000000 10078382304 10556485632 1121400688 12.040 99 12 24 0 1198967584 75096912 2469984
13:05:32 51882 8192000000 10112632880 10553712640 1132817040 12.148 99 24 46 1 1205617552 67495296 5305216
13:07:27 54864 8192000000 10118207984 10564091904 1118576000 12.014 97 24 45 0 1184102912 60702048 4824864
13:09:32 77376 8192000000 10118841104 10601349120 1145021040 12.263 100 22 44 0 1222647872 73465264 4161568
13:11:50 50851 8192000000 10118916368 10606129152 1118815264 12.016 97 25 50 0 1237000672 112671984 5513424
13:13:41 47138 8192000000 10111876784 10571444224 1097796432 11.817 96 38 76 0 1160545712 55525680 7223600
13:15:29 23242 8192000000 10120720768 10576683008 1139624416 12.212 100 44 86 1 1205381120 56446176 9310528
13:16:55 11701 8192000000 10117577408 10553319424 1129704592 12.119 100 63 126 1 1215453312 73515136 12233584
13:18:13 83295 8192000000 10065239584 10518552576 1137885600 12.196 100 33 62 2 1182702944 38219056 6598288
13:20:42 81529 8192000000 10091587136 10560970752 1132468784 12.145 100 11 22 0 1208573040 73741408 2362848
13:23:03 112591 8192000000 10109735648 10577059840 1106322576 11.898 97 22 46 1 1191237184 79906848 5007760
13:25:58 60113 8192000000 10103863376 10578079744 1106604000 11.900 97 23 45 0 1184835456 73026048 5205408
13:27:58 29646 8192000000 10149697904 10590318592 1116286384 11.992 99 36 71 0 1232958384 109079424 7592576
13:29:34 57253 8192000000 10109278688 10556370944 1111453632 11.946 98 30 60 0 1170115392 51643136 7018624
13:31:43 52400 8192000000 10119684144 10589462528 1114674144 11.977 99 21 40 0 1194309440 75766192 3869104
13:33:35 20454 8192000000 10043103136 10480848896 1064570048 11.500 96 36 70 0 1103293920 31796192 6927680
13:34:59 25775 8192000000 10085917360 10512728064 1080681232 11.654 98 53 105 0 1136534880 45541600 10312048
13:37:07 69974 8192000000 9301079632 9643458560 423852896 4.919 38 6 12 0 494629824 69485984 1290944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 18-01-2023 13:41:41 to 18-01-2023 13:41:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 177 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-01-2023 13:41:59 to 18-01-2023 13:42:01.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237282
MON$OLDEST_ACTIVE               237283
MON$OLDEST_SNAPSHOT             237283
MON$NEXT_TRANSACTION            237283
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                       2072352
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             809
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'

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