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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16026188 kB
MemAvailable 31271372 kB
Buffers 20 kB
Cached 15583604 kB
SwapCached 2448 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.2
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 2022-06-04 04:37:17
DTS_END                         2022-06-04 04:37:17.2900
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/46676
TRN_ID                          227907
ATT_ID                          618
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: 9255.30 19795 1110636
customer order: creation 179.81 443 21577
customer order: refuse 15.24 412 1829
order to supplier: creation 48.15 1398 5778
order to supplier: removal 3.50 164454 420
invoice (draft): creation 46.84 2977 5621
invoice (draft): removal 50.92 1023 6110
invoice accept: apply 43.59 24746 5231
invoice accept: cancel 45.64 1129 5477
customer reserve: creation 3978.89 242 477467
customer reserve: removal 4537.82 100 544538
realization accept: apply 88.84 261 10661
realization accept: cancel 81.53 156 9783
payment from customer: creation 61.50 166 7380
payment from customer: removal 6.18 121 741
payment to supplier: creation 50.56 177 6067
payment to supplier: removal 3.32 94 398
service: total inventory turnovers 4.17 3983 500
service: total monetary turnovers 7.75 314 930
service: aggregate perf. data 1.03 14322 124
service: refresh index statistics 0.03 179378 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-06-2022 04:38:04 to 04-06-2022 04:38:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9995
TEST_TIME 2 8577
TEST_TIME 3 7328
TEST_TIME 4 6831
TEST_TIME 5 6494
TEST_TIME 6 6268
TEST_TIME 7 6179
TEST_TIME 8 5920
TEST_TIME 9 6203
TEST_TIME 10 6218
TEST_TIME 11 6439
TEST_TIME 12 6465
TEST_TIME 13 6560
TEST_TIME 14 6783
TEST_TIME 15 7040
TEST_TIME 16 7215
TEST_TIME 17 7126
TEST_TIME 18 7192
TEST_TIME 19 7296
TEST_TIME 20 7582
TEST_TIME 21 7663
TEST_TIME 22 7840
TEST_TIME 23 8326
TEST_TIME 24 8321
TEST_TIME 25 8568
TEST_TIME 26 8958
TEST_TIME 27 9201
TEST_TIME 28 9994
TEST_TIME 29 10602
TEST_TIME 30 10739
TEST_TIME 31 10738
TEST_TIME 32 10822
TEST_TIME 33 11191
TEST_TIME 34 10972
TEST_TIME 35 10921
TEST_TIME 36 10820
TEST_TIME 37 10659
TEST_TIME 38 10547
TEST_TIME 39 10643
TEST_TIME 40 10554
TEST_TIME 41 10513
TEST_TIME 42 10510
TEST_TIME 43 10604
TEST_TIME 44 10461
TEST_TIME 45 10466
TEST_TIME 46 10385
TEST_TIME 47 10198
TEST_TIME 48 10161
TEST_TIME 49 10169
TEST_TIME 50 10026
TEST_TIME 51 9942
TEST_TIME 52 9956
TEST_TIME 53 9877
TEST_TIME 54 9848
TEST_TIME 55 9915
TEST_TIME 56 9790
TEST_TIME 57 9840
TEST_TIME 58 9839
TEST_TIME 59 9753
TEST_TIME 60 9910
TEST_TIME 61 9899
TEST_TIME 62 9892
TEST_TIME 63 9873
TEST_TIME 64 9880
TEST_TIME 65 9906
TEST_TIME 66 9937
TEST_TIME 67 9945
TEST_TIME 68 9939
TEST_TIME 69 9924
TEST_TIME 70 9974
TEST_TIME 71 10042
TEST_TIME 72 9974
TEST_TIME 73 9917
TEST_TIME 74 9902
TEST_TIME 75 9781
TEST_TIME 76 9708
TEST_TIME 77 9724
TEST_TIME 78 9629
TEST_TIME 79 9626
TEST_TIME 80 9592
TEST_TIME 81 9538
TEST_TIME 82 9504
TEST_TIME 83 9441
TEST_TIME 84 9390
TEST_TIME 85 9382
TEST_TIME 86 9349
TEST_TIME 87 9389
TEST_TIME 88 9410
TEST_TIME 89 9486
TEST_TIME 90 9475
TEST_TIME 91 9541
TEST_TIME 92 9505
TEST_TIME 93 9486
TEST_TIME 94 9476
TEST_TIME 95 9565
TEST_TIME 96 9521
TEST_TIME 97 9598
TEST_TIME 98 9577
TEST_TIME 99 9493
TEST_TIME 100 9452
TEST_TIME 101 9408
TEST_TIME 102 9451
TEST_TIME 103 9402
TEST_TIME 104 9373
TEST_TIME 105 9351
TEST_TIME 106 9289
TEST_TIME 107 9240
TEST_TIME 108 9206
TEST_TIME 109 9234
TEST_TIME 110 9293
TEST_TIME 111 9291
TEST_TIME 112 9265
TEST_TIME 113 9253
TEST_TIME 114 9290
TEST_TIME 115 9347
TEST_TIME 116 9329
TEST_TIME 117 9382
TEST_TIME 118 9368
TEST_TIME 119 9345
TEST_TIME 120 9285

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-06-2022 04:38:08 to 04-06-2022 04:38:11.

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 1552178 1552178 0 0.00 0 21688 13 0 0
doc_list_biud 1552159 1552159 0 0.00 0 26873 41 0 0
sp_add_invoice_to_stock 5365 5231 134 2.49 54 274069 24747 0 134
sp_cancel_adding_invoice 5477 5477 0 0.00 6 98876 1130 0 0
sp_cancel_client_order 1829 1829 0 0.00 6 18482 413 0 0
sp_cancel_customer_reserve 544538 544538 0 0.00 1 26228 100 0 0
sp_cancel_pay_from_customer 741 741 0 0.00 3 5905 121 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 4 1793 95 0 0
sp_cancel_supplier_invoice 6126 6110 16 0.26 3 19269 1023 0 16
sp_cancel_supplier_order 420 420 0 0.00 15 1436898 164454 0 0
sp_cancel_write_off 9783 9783 0 0.00 2 7395 157 0 0
sp_client_order 21577 21577 0 0.00 5 32395 444 0 0
sp_customer_reserve 477467 477467 0 0.00 1 151084 243 0 0
sp_fill_shopping_cart 32966 32966 0 0.00 1 15255 73 0 0
sp_fill_shopping_cart_clo_res 477432 477432 0 0.00 0 20719 17 0 0
sp_get_clo_for_invoice 5183 5183 0 0.00 0 29390 240 0 0
sp_kill_cost_storno 16399 16399 0 0.00 0 5388 67 0 0
sp_kill_qstorno_ret_qs2qd 1105389 1105389 0 0.00 0 19260 44 0 0
sp_kill_qty_storno 584000 584000 0 0.00 0 26871 100 0 0
sp_lock_selected_doc 21783 21783 0 0.00 0 11475 9 0 0
sp_make_cost_storno 29290 29290 0 0.00 0 13792 76 0 0
sp_multiply_rows_for_pdistr 29287 29287 0 0.00 0 13171 35 0 0
sp_multiply_rows_for_qdistr 21577 21577 0 0.00 0 16024 174 0 0
sp_pay_from_customer 7380 7380 0 0.00 6 16480 166 0 0
sp_pay_to_supplier 6067 6067 0 0.00 6 16155 178 0 0
sp_payment_common 13444 13444 0 0.00 2 8885 43 0 0
sp_qd_handle_on_cancel_clo 1829 1829 0 0.00 1 18461 325 0 0
sp_qd_handle_on_invoice_upd_sts 10659 10659 0 0.00 0 26871 462 0 0
sp_qd_handle_on_reserve_upd_sts 10661 10661 0 0.00 0 14572 65 0 0
sp_reserve_write_off 10661 10661 0 0.00 8 21288 261 0 0
sp_supplier_invoice 5621 5621 0 0.00 9 82794 2977 0 0
sp_supplier_order 5778 5778 0 0.00 7 28627 1398 0 0
srv_aggregate_perf_data 124 124 0 0.00 2914 224678 14323 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 807 167473 38134 0 0
srv_find_qd_qs_mism 1060808 1060808 0 0.00 0 21687 11 0 0
srv_make_invnt_saldo 500 500 0 0.00 72 76251 3984 0 0
srv_make_money_saldo 930 930 0 0.00 1 10090 314 0 0
srv_recalc_idx_stat 4 4 0 0.00 49205 265944 179379 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 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 5236 5236 0 0.00 0 9877 767 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 1 15018 428 0 0
t$perf-norm:sp_cancel_client_order 1829 1829 0 0.00 0 4193 23 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 3077 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 741 741 0 0.00 0 284 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 3462 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 4896 38 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 100303 6302 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 2335 21 0 0
t$perf-norm:sp_client_order 21577 21577 0 0.00 0 13486 34 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 1294 14 0 0
t$perf-norm:sp_pay_from_customer 7380 7380 0 0.00 0 4349 18 0 0
t$perf-norm:sp_pay_to_supplier 6069 6069 0 0.00 0 3832 18 0 0
t$perf-norm:sp_reserve_write_off 10661 10661 0 0.00 0 13548 23 0 0
t$perf-norm:sp_supplier_invoice 5621 5621 0 0.00 0 6665 28 0 0
t$perf-norm:sp_supplier_order 5778 5778 0 0.00 0 5728 30 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 320 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 371 12 0 0
t$perf-norm:srv_make_invnt_saldo 500 500 0 0.00 0 381 7 0 0
t$perf-norm:srv_make_money_saldo 930 930 0 0.00 0 1192 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 48 31 0 0
v_add_invoice_to_stock 10627 10359 268 2.52 0 6606 27 0 268
v_all_customers 39379 39379 0 0.00 0 67 3 0 0
v_all_suppliers 32120 32120 0 0.00 0 52 4 0 0
v_all_wares 43146 43146 0 0.00 0 55 3 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 4612 22 0 0
v_cancel_client_order 3658 3658 0 0.00 0 3426 14 0 0
v_cancel_customer_prepayment 1482 1482 0 0.00 0 2390 15 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 3106 15 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 796 10 0 0
v_cancel_supplier_invoice 874 842 32 3.66 0 2386 30 0 32
v_cancel_supplier_order 840 840 0 0.00 0 5661 17 0 0
v_cancel_write_off 2076 2076 0 0.00 0 2747 21 0 0
v_max_id_clo_ord 5776 5776 0 0.00 0 587 4 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 3558 17 0 0
v_max_id_ord_sup 5615 5615 0 0.00 0 6545 7 0 0
v_max_non_paid_invoice 1396 1396 0 0.00 0 496 7 0 0
v_max_non_paid_realizn 3928 3928 0 0.00 0 5904 11 0 0
v_min_id_clo_ord 5775 5775 0 0.00 0 2541 8 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 4498 13 0 0
v_min_id_ord_sup 5615 5615 0 0.00 0 9747 11 0 0
v_min_non_paid_invoice 6067 6067 0 0.00 0 5211 12 0 0
v_min_non_paid_realizn 7376 7376 0 0.00 0 8879 17 0 0
v_our_firm 10662 10662 0 0.00 0 44 4 0 0
v_reserve_write_off 21320 21320 0 0.00 0 3861 11 0 0
x_lock_depdocs_on_canc_invoice 11166 11166 0 0.00 0 17513 131 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 6834 331 0 0
x_make_qstorno_1000_1200 5778 5778 0 0.00 2 28615 1252 0 0
x_make_qstorno_1200_2000 5621 5621 0 0.00 5 82754 2777 0 0
x_make_qstorno_2100_3300 477464 477464 0 0.00 0 151019 215 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-06-2022 04:38:12 to 04-06-2022 04:38:16.

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
02:07:21 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:22 29977 8192000000 9733584288 10037645312 812912704 9.027 100 92 185 1 843991152 8697440 22381008
02:09:58 16384 8192000000 9774579088 10087686144 850710704 9.407 100 94 188 0 887362080 14524048 22127328
02:11:18 15303 8192000000 9817111168 10136264704 879027984 9.690 100 87 174 0 919480048 20497920 19954144
02:12:36 11892 8192000000 9870081328 10203959296 913274016 10.030 100 84 166 3 978895104 47568464 18052624
02:13:52 5178 8192000000 9897597136 10241224704 950380224 10.395 100 84 161 1 1014785936 47805792 16599920
02:14:59 2584 8192000000 9962940592 10314321920 994254512 10.823 100 86 170 2 1079864768 68145536 17464720
02:16:04 1149 8192000000 9993438608 10354434048 1015154640 11.025 100 97 186 1 1105714432 72353520 18206272
02:17:07 1416 8192000000 10024879584 10392293376 1034414720 11.211 100 96 186 2 1147117840 93955952 18747168
02:18:10 1073 8192000000 10016613600 10388918272 1051821728 11.378 100 97 187 1 1139992192 69662448 18508016
02:19:13 1066 8192000000 10015355696 10387251200 1058732512 11.444 100 95 189 1 1135970016 58216256 19021248
02:20:15 1023 8192000000 10050600112 10430545920 1065086304 11.505 100 98 192 0 1168137984 84020496 19031184
02:21:17 1514 8192000000 10056570752 10441977856 1071095744 11.563 100 97 187 0 1175689792 85941872 18652176
02:22:20 2826 8192000000 10042198016 10428846080 1076850416 11.617 100 84 154 2 1159279216 67313792 15115008
02:23:24 1297 8192000000 10065216464 10449879040 1082790288 11.674 100 98 187 2 1189055648 85851344 20414016
02:24:27 1168 8192000000 10072561952 10458218496 1089975600 11.742 100 95 186 1 1190235424 81405232 18854592
02:25:30 1227 8192000000 10046497232 10432135168 1090957344 11.752 100 89 170 2 1171519616 62118752 18443520
02:26:32 3444 8192000000 10054479504 10448052224 1094783968 11.788 100 85 157 0 1173492384 62831056 15877360
02:27:37 1106 8192000000 10069128928 10455785472 1103161296 11.868 100 95 186 1 1188336640 65223344 19952000
02:28:40 1109 8192000000 10060122048 10451386368 1096639280 11.806 100 90 173 0 1180965888 65540032 18786576
02:29:42 1275 8192000000 10028870800 10413146112 1084211392 11.688 100 94 173 1 1151655072 49382432 18061248
02:30:44 1273 8192000000 10038762992 10415951872 1060047328 11.457 100 95 176 3 1158818928 80608160 18163440
02:31:46 1072 8192000000 10025054992 10407923712 1067113680 11.525 100 86 151 0 1142755664 61736576 13905408
02:32:48 1954 8192000000 9983120480 10360872960 1044518816 11.308 100 74 142 0 1104879056 44892512 15467728
02:33:50 1419 8192000000 9997237120 10372145152 1034637328 11.213 100 82 155 1 1123815584 73524800 15653456
02:34:52 1090 8192000000 10000814992 10378280960 1034531296 11.212 100 88 167 1 1125991584 74805776 16654512
02:35:54 5075 8192000000 10000576320 10384322560 1047393312 11.336 100 71 135 3 1122914272 62309152 13211808
02:37:01 5578 8192000000 10008409856 10383970304 1049388704 11.355 99 66 119 3 1122269840 60781216 12099920
02:38:12 16675 8192000000 10029128992 10415235072 1075143344 11.601 100 59 115 1 1143935008 57511552 11280112
02:39:34 942 8192000000 10064737232 10448764928 1096830128 11.808 100 93 172 2 1184129872 70263312 17036432
02:40:41 9707 8192000000 10067968976 10463375360 1106949984 11.904 100 74 136 2 1188393808 67327184 14116640
02:41:54 3088 8192000000 10098867568 10487889920 1117854512 12.007 100 75 143 3 1215160576 82237584 15068480
02:43:00 4675 8192000000 10160933936 10562969600 1129019488 12.112 100 79 151 2 1272733488 128283744 15430256
02:44:14 3573 8192000000 10150380848 10566311936 1142400480 12.238 100 64 120 1 1272644992 115258128 14986384
02:45:21 6148 8192000000 10231801440 10636156928 1163431280 12.435 100 69 124 3 1346179792 169420640 13327872
02:46:31 1525 8192000000 10279734720 10682732544 1175120496 12.545 100 86 161 1 1398241824 204681232 18440096
02:47:36 892 8192000000 10332050352 10732912640 1185373824 12.640 100 90 175 0 1443520208 238244240 19902144
02:48:41 1051 8192000000 10375033600 10788696064 1195614848 12.736 100 96 190 0 1487665488 268891648 23158992
02:49:45 1171 8192000000 10393465152 10810732544 1187392288 12.659 100 79 160 0 1511015504 304396544 19226672
02:50:49 928 8192000000 10425219280 10853363712 1188714096 12.671 99 94 179 0 1547237360 336543296 21979968
02:51:54 1953 8192000000 10458503120 10879606784 1197056592 12.749 100 94 180 0 1564088944 345656432 21375920
02:52:59 2120 8192000000 10486803920 10894966784 1205089056 12.824 100 86 167 2 1597314048 372618128 19606864
02:54:03 4206 8192000000 10494014832 10919751680 1197638752 12.754 100 61 120 1 1603009776 389933968 15437056
02:55:12 1052 8192000000 10564625184 10982887424 1202311392 12.798 100 97 191 1 1671170416 445560848 23298176
02:56:16 985 8192000000 10552889584 10999881728 1198935552 12.766 100 97 189 2 1658964592 438027680 22001360
02:57:20 986 8192000000 10602850032 11041427456 1205242624 12.825 100 94 188 0 1704253904 476276352 22734928
02:58:25 800 8192000000 10588344896 11039338496 1200528384 12.781 100 94 184 1 1692260848 467671568 24060896
02:59:29 1949 8192000000 10601958704 11034669056 1193013792 12.711 100 87 170 1 1704553616 491143664 20396160
03:00:32 9098 8192000000 10585883408 11051241472 1194152384 12.722 100 54 101 1 1691954368 484934128 12867856
03:01:56 13620 8192000000 10578153424 11035930624 1171905440 12.515 99 41 80 1 1685987408 503006992 11074976
03:03:24 12186 8192000000 10527675184 11035824128 1168858960 12.486 100 39 76 0 1647273408 468612192 9802256
03:04:46 15661 8192000000 10384277072 10921644032 1160211920 12.405 100 32 64 2 1523128128 354057552 8858656
03:06:03 25698 8192000000 10254101040 10752323584 1110790752 11.940 97 24 48 0 1379539088 262337776 6410560
03:07:31 22370 8192000000 10183201584 10652803072 1149884832 12.308 99 29 58 1 1272585248 116435280 6265136
03:08:55 55477 8192000000 10169640624 10632351744 1145335328 12.266 99 18 36 0 1324236736 175050800 3850608
03:10:54 30168 8192000000 10066334144 10493321216 1134056384 12.160 99 59 106 3 1155538960 11224688 10257888
03:12:26 86760 8192000000 10062674256 10514239488 1094986176 11.790 95 11 22 0 1128075392 30942928 2146288
03:15:06 138353 8192000000 10052315536 10512678912 1051790224 11.378 92 15 28 0 1106937536 52204848 2942464
03:18:25 167471 8192000000 10072733392 10514485248 1088827728 11.732 95 10 20 0 1146592304 55878144 1886432
03:22:15 49608 8192000000 10086771744 10535854080 1114066720 11.971 98 16 30 0 1188705072 71547184 3091168
03:24:06 102022 8192000000 10085945936 10533724160 1133031280 12.150 100 38 71 2 1173217360 32015984 8170096
03:26:53 60776 8192000000 10080923712 10527232000 1120350528 12.030 98 20 40 1 1169426048 44796960 4278560
03:29:03 34153 8192000000 10077466208 10511151104 1078182160 11.630 95 30 60 2 1142261568 57756848 6322560
03:30:39 12632 8192000000 10147554080 10563899392 1132353008 12.144 100 63 118 0 1234958144 89768720 12836416
03:31:52 19058 8192000000 10207062016 10656194560 1148373984 12.294 100 41 80 0 1313142880 156306976 8461920
03:33:13 58552 8192000000 10183415872 10643910656 1109193952 11.925 98 16 32 0 1256784832 143868496 3722384
03:35:12 24426 8192000000 10154032064 10630287360 1112740592 11.958 99 27 54 0 1267833232 148970688 6121952
03:37:28 72382 8192000000 10074622336 10575278080 1103474080 11.871 98 7 14 0 1163096032 58063248 1558704
03:39:42 69767 8192000000 10110954400 10543587328 1123148912 12.057 98 18 36 0 1184827424 58318464 3360048
03:42:00 44773 8192000000 10111937648 10569609216 1108012160 11.914 97 45 86 0 1189552048 72386112 9153776
03:43:45 63368 8192000000 10087867584 10533339136 1066234736 11.516 93 29 58 1 1111684656 39449232 6000688
03:45:49 25403 8192000000 10131768288 10579451904 1137849664 12.195 100 37 73 0 1197945264 53204736 6890864
03:47:16 99512 8192000000 10095029680 10602217472 1119972128 12.027 98 15 30 0 1186844960 63461904 3410928
03:49:57 41956 8192000000 10127591296 10577612800 1147234208 12.284 100 40 80 1 1203770416 48504320 8031888
03:51:42 139011 8192000000 10106952144 10545442816 1126570944 12.089 99 30 60 3 1185577216 52766304 6239968
03:55:02 49380 8192000000 10086984624 10564636672 1120127056 12.028 99 32 63 1 1176290528 49413584 6749888
03:56:51 28055 8192000000 10095291952 10534424576 1126018928 12.084 99 27 52 0 1181777728 50171536 5587264
03:59:16 72475 8192000000 10124852688 10589118464 1120834496 12.035 98 22 44 1 1208897312 82726384 5336432
04:01:34 73300 8192000000 10163440768 10632462336 1148967536 12.300 100 15 30 1 1262874208 110567856 3338816
04:03:47 41359 8192000000 10172758816 10649858048 1145233360 12.265 99 26 52 0 1288045648 136410064 6402224
04:05:30 64988 8192000000 10164406432 10639720448 1128973200 12.112 98 21 42 0 1256906176 122563456 5369520
04:07:42 73603 8192000000 10067943584 10501214208 1087122352 11.715 95 17 34 1 1168062416 77326960 3613104
04:09:57 10837 8192000000 10113119520 10535751680 1136761872 12.185 99 66 125 1 1189785632 40474832 12548928
04:11:11 38274 8192000000 10158874752 10597830656 1149950592 12.309 100 40 80 1 1234219504 76292336 7976576
04:12:50 30066 8192000000 10116598016 10548224000 1128971744 12.112 98 33 63 2 1199785312 64486528 6327040
04:14:24 83358 8192000000 10080881840 10546401280 1104218480 11.878 96 22 43 2 1162792896 53378672 5195744
04:17:34 33787 8192000000 10134411408 10580762624 1125956960 12.083 98 54 108 1 1201091408 64799040 10335408
04:19:09 39229 8192000000 10090867712 10564558848 1146661360 12.278 100 27 55 4 1215213456 62496544 6055552
04:20:48 52984 8192000000 10068235584 10527719424 1115668736 11.986 97 24 48 1 1153062800 31799968 5594096
04:22:42 83184 8192000000 10124881616 10586734592 1143290096 12.246 99 21 42 0 1208710784 60058064 5362624
04:25:21 80852 8192000000 10140002096 10624303104 1133884304 12.158 98 14 28 0 1249612720 112823568 2904848
04:27:52 47764 8192000000 10071220528 10536767488 1095218784 11.792 96 25 48 0 1166065168 65523120 5323264
04:29:40 14682 8192000000 10135238560 10565562368 1139390448 12.210 100 57 110 1 1205359136 54456656 11512032
04:30:58 53335 8192000000 10135556928 10620997632 1139706816 12.213 100 33 66 0 1258234064 111488640 7038608
04:32:52 37863 8192000000 10103214464 10539282432 1102973152 11.866 96 50 100 0 1165563968 52330496 10260320
04:34:36 56446 8192000000 10104421360 10574299136 1107178256 11.906 98 29 58 1 1164548080 51994784 5375040
04:36:42 6494 8192000000 10105928608 10528952320 1126012416 12.084 100 61 122 1 1181371504 43357664 12001424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-06-2022 04:38:18 to 04-06-2022 04:38:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 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 04-06-2022 04:38:35 to 04-06-2022 04:38:37.

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          223071
MON$OLDEST_ACTIVE               228981
MON$OLDEST_SNAPSHOT             228981
MON$NEXT_TRANSACTION            228981
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                       2064096
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
:89: 
:90: mscs-test-161.budzdorov.ru    Sat Jun  4 04:37:28 2022
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:92: 
--- end of diff output ---

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

04.06.22 04:38:38. 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
2022-06-04 04:38:41 No severe PSQL-related problems occured
04.06.22 04:38:42. End of report.