Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.07.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 15549892 kB
MemAvailable 31031056 kB
Buffers 20 kB
Cached 16067340 kB
SwapCached 2532 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-07-15 04:37:18
DTS_END                         2022-07-15 04:37:18.4870
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/50818
TRN_ID                          213872
ATT_ID                          635
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: 8574.27 24188 1028912
customer order: creation 165.44 526 19853
customer order: refuse 13.37 519 1604
order to supplier: creation 43.93 1468 5271
order to supplier: removal 3.28 206075 394
invoice (draft): creation 44.68 3181 5361
invoice (draft): removal 48.69 1181 5843
invoice accept: apply 38.89 27068 4667
invoice accept: cancel 43.95 1454 5274
customer reserve: creation 3596.53 256 431583
customer reserve: removal 4292.55 123 515106
realization accept: apply 80.20 326 9624
realization accept: cancel 78.43 192 9412
payment from customer: creation 56.38 215 6766
payment from customer: removal 5.62 148 674
payment to supplier: creation 46.34 198 5561
payment to supplier: removal 3.51 161 421
service: total inventory turnovers 3.63 5153 435
service: total monetary turnovers 7.90 453 948
service: aggregate perf. data 0.92 19105 110
service: refresh index statistics 0.04 215954 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-07-2022 04:38:17 to 15-07-2022 04:38:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9777
TEST_TIME 2 8865
TEST_TIME 3 7020
TEST_TIME 4 6825
TEST_TIME 5 6597
TEST_TIME 6 6344
TEST_TIME 7 6380
TEST_TIME 8 6771
TEST_TIME 9 6602
TEST_TIME 10 6704
TEST_TIME 11 6713
TEST_TIME 12 6701
TEST_TIME 13 6823
TEST_TIME 14 6918
TEST_TIME 15 6962
TEST_TIME 16 7092
TEST_TIME 17 7503
TEST_TIME 18 7665
TEST_TIME 19 7572
TEST_TIME 20 7575
TEST_TIME 21 7619
TEST_TIME 22 7499
TEST_TIME 23 7627
TEST_TIME 24 7679
TEST_TIME 25 7779
TEST_TIME 26 7805
TEST_TIME 27 7730
TEST_TIME 28 7966
TEST_TIME 29 8070
TEST_TIME 30 8259
TEST_TIME 31 8314
TEST_TIME 32 8718
TEST_TIME 33 9079
TEST_TIME 34 9157
TEST_TIME 35 9879
TEST_TIME 36 10021
TEST_TIME 37 10811
TEST_TIME 38 10753
TEST_TIME 39 10620
TEST_TIME 40 10620
TEST_TIME 41 10453
TEST_TIME 42 10330
TEST_TIME 43 10322
TEST_TIME 44 10117
TEST_TIME 45 9992
TEST_TIME 46 10026
TEST_TIME 47 9965
TEST_TIME 48 9877
TEST_TIME 49 9798
TEST_TIME 50 9698
TEST_TIME 51 9634
TEST_TIME 52 9538
TEST_TIME 53 9431
TEST_TIME 54 9433
TEST_TIME 55 9385
TEST_TIME 56 9403
TEST_TIME 57 9384
TEST_TIME 58 9341
TEST_TIME 59 9228
TEST_TIME 60 9205
TEST_TIME 61 9107
TEST_TIME 62 9071
TEST_TIME 63 9028
TEST_TIME 64 8963
TEST_TIME 65 8922
TEST_TIME 66 8864
TEST_TIME 67 8837
TEST_TIME 68 8790
TEST_TIME 69 8761
TEST_TIME 70 8772
TEST_TIME 71 8744
TEST_TIME 72 8744
TEST_TIME 73 8764
TEST_TIME 74 8900
TEST_TIME 75 8929
TEST_TIME 76 8861
TEST_TIME 77 8973
TEST_TIME 78 8923
TEST_TIME 79 9071
TEST_TIME 80 9052
TEST_TIME 81 9050
TEST_TIME 82 8963
TEST_TIME 83 8872
TEST_TIME 84 8843
TEST_TIME 85 8819
TEST_TIME 86 8743
TEST_TIME 87 8685
TEST_TIME 88 8667
TEST_TIME 89 8621
TEST_TIME 90 8628
TEST_TIME 91 8615
TEST_TIME 92 8589
TEST_TIME 93 8579
TEST_TIME 94 8562
TEST_TIME 95 8547
TEST_TIME 96 8687
TEST_TIME 97 8763
TEST_TIME 98 8785
TEST_TIME 99 8776
TEST_TIME 100 8777
TEST_TIME 101 8869
TEST_TIME 102 8825
TEST_TIME 103 8905
TEST_TIME 104 8910
TEST_TIME 105 8863
TEST_TIME 106 8782
TEST_TIME 107 8739
TEST_TIME 108 8738
TEST_TIME 109 8726
TEST_TIME 110 8691
TEST_TIME 111 8667
TEST_TIME 112 8657
TEST_TIME 113 8605
TEST_TIME 114 8597
TEST_TIME 115 8617
TEST_TIME 116 8575
TEST_TIME 117 8576
TEST_TIME 118 8625
TEST_TIME 119 8638
TEST_TIME 120 8600

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-07-2022 04:38:20 to 15-07-2022 04:38:24.

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 1428781 1428781 0 0.00 0 18375 14 0 0
doc_list_biud 1428771 1428771 0 0.00 0 28974 51 0 0
sp_add_invoice_to_stock 4828 4667 161 3.33 48 210525 27068 0 161
sp_cancel_adding_invoice 5274 5274 0 0.00 5 90100 1455 0 0
sp_cancel_client_order 1604 1604 0 0.00 6 18653 520 0 0
sp_cancel_customer_reserve 515106 515106 0 0.00 1 24858 124 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 5214 149 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 4 5031 162 0 0
sp_cancel_supplier_invoice 5854 5843 11 0.18 2 28908 1181 0 11
sp_cancel_supplier_order 394 394 0 0.00 9 1477948 206076 0 0
sp_cancel_write_off 9412 9412 0 0.00 2 9702 192 0 0
sp_client_order 19853 19853 0 0.00 5 42104 527 0 0
sp_customer_reserve 431583 431583 0 0.00 0 107561 257 0 0
sp_fill_shopping_cart 30473 30473 0 0.00 1 23963 91 0 0
sp_fill_shopping_cart_clo_res 431566 431566 0 0.00 0 19652 21 0 0
sp_get_clo_for_invoice 4633 4633 0 0.00 0 26942 318 0 0
sp_kill_cost_storno 15781 15781 0 0.00 0 15120 86 0 0
sp_kill_qstorno_ret_qs2qd 1045861 1045861 0 0.00 0 28905 53 0 0
sp_kill_qty_storno 551888 551888 0 0.00 0 28974 122 0 0
sp_lock_selected_doc 19759 19759 0 0.00 0 7376 7 0 0
sp_make_cost_storno 26584 26584 0 0.00 0 18362 83 0 0
sp_multiply_rows_for_pdistr 26584 26584 0 0.00 0 16262 43 0 0
sp_multiply_rows_for_qdistr 19853 19853 0 0.00 0 16394 195 0 0
sp_pay_from_customer 6766 6766 0 0.00 5 16416 215 0 0
sp_pay_to_supplier 5561 5561 0 0.00 7 10695 198 0 0
sp_payment_common 12327 12327 0 0.00 2 11523 49 0 0
sp_qd_handle_on_cancel_clo 1604 1604 0 0.00 0 18635 431 0 0
sp_qd_handle_on_invoice_upd_sts 9905 9905 0 0.00 0 28974 621 0 0
sp_qd_handle_on_reserve_upd_sts 9624 9624 0 0.00 0 23223 96 0 0
sp_reserve_write_off 9624 9624 0 0.00 8 23342 327 0 0
sp_supplier_invoice 5361 5361 0 0.00 7 68719 3181 0 0
sp_supplier_order 5271 5271 0 0.00 7 59751 1469 0 0
srv_aggregate_perf_data 110 110 0 0.00 2494 553320 19106 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 844 118537 33752 0 0
srv_find_qd_qs_mism 983632 983632 0 0.00 0 16990 10 0 0
srv_make_invnt_saldo 435 435 0 0.00 52 193356 5153 0 0
srv_make_money_saldo 948 948 0 0.00 1 14019 454 0 0
srv_recalc_idx_stat 5 5 0 0.00 45107 404598 215955 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4675 4675 0 0.00 1 12661 1055 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 7191 511 0 0
t$perf-norm:sp_cancel_client_order 1604 1604 0 0.00 0 4007 30 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 3342 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 3052 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 2407 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 986 38 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 89962 8431 0 0
t$perf-norm:sp_cancel_write_off 916 916 0 0.00 0 2602 21 0 0
t$perf-norm:sp_client_order 19853 19853 0 0.00 0 14058 44 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 4004 25 0 0
t$perf-norm:sp_pay_from_customer 6766 6766 0 0.00 0 3529 19 0 0
t$perf-norm:sp_pay_to_supplier 5561 5561 0 0.00 0 14116 24 0 0
t$perf-norm:sp_reserve_write_off 9624 9624 0 0.00 0 15452 24 0 0
t$perf-norm:sp_supplier_invoice 5361 5361 0 0.00 0 11454 36 0 0
t$perf-norm:sp_supplier_order 5271 5271 0 0.00 0 7015 40 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 520 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 175 8 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 2001 18 0 0
t$perf-norm:srv_make_money_saldo 948 948 0 0.00 0 8885 16 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 214 100 0 0
v_add_invoice_to_stock 9568 9246 322 3.36 0 12139 29 0 322
v_all_customers 36376 36376 0 0.00 0 76 4 0 0
v_all_suppliers 29638 29638 0 0.00 0 81 5 0 0
v_all_wares 39704 39704 0 0.00 0 59 4 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 1024 16 0 0
v_cancel_client_order 3208 3208 0 0.00 0 4810 13 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 4478 17 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 3280 15 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 4786 18 0 0
v_cancel_supplier_invoice 776 754 22 2.83 0 2598 30 0 22
v_cancel_supplier_order 788 788 0 0.00 0 2558 18 0 0
v_cancel_write_off 1832 1832 0 0.00 0 8960 27 0 0
v_max_id_clo_ord 5268 5268 0 0.00 0 3330 6 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 6773 26 0 0
v_max_id_ord_sup 5353 5353 0 0.00 0 7823 13 0 0
v_max_non_paid_invoice 1363 1363 0 0.00 0 307 9 0 0
v_max_non_paid_realizn 3434 3434 0 0.00 0 253 7 0 0
v_min_id_clo_ord 5268 5268 0 0.00 0 3301 12 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 4091 18 0 0
v_min_id_ord_sup 5353 5353 0 0.00 0 8731 9 0 0
v_min_non_paid_invoice 5561 5561 0 0.00 0 6400 16 0 0
v_min_non_paid_realizn 6766 6766 0 0.00 0 11506 21 0 0
v_our_firm 9992 9992 0 0.00 0 66 5 0 0
v_reserve_write_off 19248 19248 0 0.00 0 12259 13 0 0
x_lock_depdocs_on_canc_invoice 10740 10740 0 0.00 0 30060 152 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 7318 403 0 0
x_make_qstorno_1000_1200 5271 5271 0 0.00 2 59733 1281 0 0
x_make_qstorno_1200_2000 5361 5361 0 0.00 3 68707 2940 0 0
x_make_qstorno_2100_3300 431580 431580 0 0.00 0 107558 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-07-2022 04:38:25 to 15-07-2022 04:38:29.

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:22 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 31285 8192000000 9715948864 10012545024 781815648 8.712 100 85 170 0 810596880 7568992 21212240
02:10:06 22570 8192000000 9759926912 10067603456 833471136 9.234 100 87 174 0 867322224 12290640 21560448
02:11:32 14773 8192000000 9803346832 10124472320 872215936 9.622 100 81 162 0 911264704 20344400 18704368
02:12:51 7870 8192000000 9870682512 10204020736 923064832 10.126 100 80 159 1 982746464 42416576 17265056
02:14:03 5181 8192000000 9926005280 10270023680 964096480 10.529 100 90 172 3 1037012720 54952112 17964128
02:15:11 2965 8192000000 9971350320 10323750912 996750624 10.847 100 86 167 0 1089064256 75072944 17240688
02:16:16 792 8192000000 10012791264 10371465216 1019381360 11.066 100 96 183 0 1129431952 91867136 18183456
02:17:18 1298 8192000000 10010832160 10379010048 1034023472 11.207 100 91 175 2 1127585360 76711424 16850464
02:18:21 930 8192000000 10021603024 10395041792 1060406256 11.460 100 95 186 1 1142956432 63282816 19267360
02:19:23 1169 8192000000 10028823936 10406428672 1070886960 11.561 100 91 178 2 1147023792 59238704 16898128
02:20:26 960 8192000000 10064023248 10445250560 1079117312 11.639 100 96 180 1 1184455008 87336096 18001600
02:21:28 1107 8192000000 10055484464 10437050368 1087338752 11.717 100 96 176 1 1173943568 69971648 16633168
02:22:30 1235 8192000000 10059579632 10444308480 1088169872 11.725 100 94 176 0 1181730176 74489920 19070384
02:23:32 1473 8192000000 10052744992 10447085568 1094179456 11.782 100 75 139 0 1172585680 64090720 14315504
02:24:34 3377 8192000000 10051607296 10445021184 1096838320 11.808 100 62 124 2 1163679952 53885488 12956144
02:25:39 1166 8192000000 10052499952 10447310848 1100311216 11.841 100 76 143 3 1175418240 60870336 14236688
02:26:43 886 8192000000 10075232816 10466865152 1106786576 11.902 100 90 173 2 1198767888 74237888 17743424
02:27:44 1328 8192000000 10040113104 10437992448 1102490144 11.861 100 85 152 1 1163400048 45058720 15851184
02:28:46 1060 8192000000 10072937408 10464436224 1095935360 11.799 100 95 187 2 1196106752 80936416 19234976
02:29:49 945 8192000000 10054833040 10442272768 1100143040 11.839 100 91 169 2 1175264656 58225168 16896448
02:30:50 1053 8192000000 10054118464 10436698112 1086670992 11.711 100 94 182 2 1171691024 66475392 18544640
02:31:52 1316 8192000000 10028615152 10408415232 1063562032 11.491 99 96 174 3 1145468080 62043296 19862752
02:32:54 1195 8192000000 10005343504 10380869632 1035948192 11.226 99 94 172 0 1123019888 69557312 17514384
02:33:56 1081 8192000000 9997701312 10373681152 1029632592 11.165 100 92 177 3 1120252256 71883008 18736656
02:34:58 1230 8192000000 9990629808 10367332352 1044826128 11.311 100 74 144 1 1113424848 53473600 15125120
02:36:01 1305 8192000000 9989678384 10366525440 1027956672 11.149 99 80 156 1 1106628896 61862592 16809632
02:37:07 6019 8192000000 9978066224 10353721344 1035571936 11.222 100 69 125 4 1099579728 50591152 13416640
02:38:20 2080 8192000000 10009247696 10387939328 1053025008 11.390 100 70 131 1 1127636832 62141232 12470592
02:39:26 3306 8192000000 10036067520 10424373248 1074305920 11.593 100 77 148 3 1162545504 73225712 15013872
02:40:42 37233 8192000000 10058022096 10450677760 1086582096 11.710 100 52 101 0 1168443296 70908560 10952640
02:42:27 2717 8192000000 10077999568 10464845824 1104927344 11.884 100 82 145 2 1195966544 75584752 15454448
02:43:33 1082 8192000000 10144020064 10529435648 1115141648 11.981 100 96 179 2 1255499328 122469376 17888304
02:44:38 2648 8192000000 10156074048 10543001600 1121452112 12.041 100 93 174 1 1272997456 133749536 17795808
02:45:44 1638 8192000000 10180654960 10578460672 1135465152 12.173 100 88 167 2 1290761344 137599248 17696944
02:46:51 6810 8192000000 10202075008 10606829568 1145922912 12.271 100 79 151 0 1316868944 154896160 16049872
02:48:17 992 8192000000 10259177856 10650693632 1163103712 12.432 100 90 174 3 1367204800 185380832 18720256
02:49:21 1727 8192000000 10274897232 10672529408 1168249072 12.480 100 87 163 0 1385058192 198457824 18351296
02:50:26 1198 8192000000 10324083392 10723115008 1177312496 12.565 100 98 192 2 1437853520 237980864 22560160
02:51:30 1539 8192000000 10337719680 10753540096 1186578304 12.652 100 85 168 0 1451291648 243978576 20734768
02:52:36 915 8192000000 10352170464 10769338368 1178907696 12.580 99 89 169 0 1461713680 262827584 19978400
02:53:40 1634 8192000000 10399282592 10834624512 1195660736 12.736 100 97 191 1 1509712080 291225088 22826256
02:54:44 942 8192000000 10397770704 10848849920 1191929776 12.701 100 98 193 0 1506516912 291526096 23061040
02:55:49 1430 8192000000 10417152384 10843734016 1200811904 12.784 100 90 175 1 1525962464 304657024 20493536
02:56:57 858 8192000000 10442266272 10855460864 1205071520 12.823 100 99 193 1 1544939760 318681216 21187024
02:58:02 980 8192000000 10495520448 10902806528 1198718896 12.764 100 96 188 3 1599822864 378571056 22532912
02:59:06 830 8192000000 10522634848 10926120960 1196141888 12.740 100 97 192 0 1627287040 408575104 22570048
03:00:14 1038 8192000000 10553824416 10989920256 1203832064 12.812 99 95 177 0 1655905424 429655424 22417936
03:01:18 959 8192000000 10618646480 11035664384 1223808400 12.997 100 96 188 3 1723331632 475481600 24041632
03:02:23 1342 8192000000 10637921040 11084804096 1213676960 12.903 100 90 179 0 1737211552 501242176 22292416
03:03:32 991 8192000000 10679618912 11105132544 1197385200 12.752 99 97 190 0 1781824144 559740416 24698528
03:04:37 1107 8192000000 10710836624 11141861376 1207073792 12.842 100 98 193 0 1820691120 589921472 23695856
03:05:40 1791 8192000000 10694904672 11162542080 1210443296 12.873 100 77 150 0 1798907584 569015392 19448896
03:06:53 1475 8192000000 10723740400 11151028224 1199239552 12.769 100 74 148 0 1830895024 611132592 20522880
03:08:17 7053 8192000000 10691551856 11144658944 1202385568 12.798 100 52 104 1 1787932960 571932240 13615152
03:09:27 4638 8192000000 10628745024 11127083008 1166606064 12.465 100 50 100 0 1751544976 571954928 12983984
03:10:46 12570 8192000000 10520405328 11049750528 1167749168 12.476 100 35 70 0 1618743280 441540352 9453760
03:12:01 10583 8192000000 10478694752 10977509376 1158511840 12.389 100 44 86 0 1589229472 419721568 10996064
03:13:14 19414 8192000000 10341627472 10879774720 1142739392 12.241 99 32 64 0 1460784128 309576000 8468736
03:14:36 41663 8192000000 10171507824 10614345728 1151429040 12.323 100 19 39 1 1261674464 105708096 4537328
03:16:51 20520 8192000000 10128387536 10570485760 1155233776 12.359 100 47 92 0 1218965520 54092096 9639648
03:18:15 47564 8192000000 10144234704 10603651072 1130659952 12.128 98 44 78 0 1211074496 71037280 9377264
03:20:29 118537 8192000000 10091020928 10541883392 1075760144 11.607 94 28 53 0 1123371520 41475168 6136208
03:23:42 86869 8192000000 10036163984 10475954176 1065022160 11.505 94 51 94 3 1091754192 15927824 10804208
03:26:16 27962 8192000000 10064613024 10504216576 1117416736 12.003 100 57 108 2 1160741680 32833424 10491520
03:27:48 12335 8192000000 10082487120 10530140160 1123485088 12.060 99 36 64 1 1169095888 39420192 6190608
03:29:05 104886 8192000000 10105731024 10531594240 1088891552 11.732 96 28 53 0 1153823408 58256160 6675696
03:31:51 19564 8192000000 10125308032 10572185600 1138871568 12.205 100 53 105 1 1239319456 90075984 10371904
03:33:15 8503 8192000000 10125986016 10546753536 1132990352 12.150 100 68 128 6 1220685136 74357248 13337536
03:34:25 44245 8192000000 10087011424 10564542464 1127883632 12.101 99 27 54 0 1186877744 53021600 5972512
03:36:10 109473 8192000000 10077946656 10547150848 1105459200 11.889 97 26 52 0 1155349408 44296656 5593552
03:39:26 87902 8192000000 10050342576 10503016448 1114226608 11.972 98 23 46 0 1155621216 36796992 4597616
03:41:55 9714 8192000000 10080665536 10537607168 1141697328 12.231 100 36 62 1 1191836256 43563664 6575264
03:43:05 71767 8192000000 10067652544 10558877696 1137757600 12.194 99 10 20 0 1194628560 54922816 1948144
03:45:17 60968 8192000000 10107435360 10574970880 1136284224 12.181 98 20 36 0 1216738688 76352480 4101984
03:47:40 51836 8192000000 10129934832 10582409216 1133312608 12.153 98 20 39 0 1233971456 96370544 4288304
03:49:32 31782 8192000000 10146436736 10596429824 1120491696 12.032 98 28 56 0 1231839072 104314912 7032464
03:51:17 59393 8192000000 10090087984 10535948288 1099444448 11.832 97 22 44 0 1199751616 94948560 5358608
03:53:21 42382 8192000000 10160583920 10594439168 1132859728 12.148 99 47 94 0 1228511264 85996368 9655168
03:55:04 72408 8192000000 10148952544 10593075200 1109264528 11.925 98 24 45 0 1209327936 95588032 4475376
03:57:17 75368 8192000000 10098340384 10555633664 1095237488 11.792 97 30 56 0 1161166064 58881392 7047184
03:59:38 118072 8192000000 10049256496 10507046912 1128275888 12.105 100 31 61 0 1160980160 26527216 6177056
04:02:36 69091 8192000000 10055480656 10527076352 1103371952 11.870 98 6 12 0 1144099872 39594784 1133136
04:04:46 65613 8192000000 10089537008 10570158080 1126277744 12.086 100 17 32 0 1196489616 66537712 3674160
04:07:35 39649 8192000000 10074103968 10517196800 1099763008 11.835 97 29 55 0 1153128352 47269536 6095808
04:09:18 46720 8192000000 10111742304 10571997184 1140473376 12.220 100 24 44 0 1217329376 71993312 4862688
04:11:13 21870 8192000000 10170656512 10612633600 1153692512 12.344 100 38 76 0 1274599792 112717280 8190000
04:12:47 53186 8192000000 10117624608 10621079552 1138472960 12.201 99 27 54 0 1298274208 153203040 6598208
04:14:45 64806 8192000000 10065884320 10503593984 1140339696 12.219 100 9 16 1 1177351232 35260736 1750800
04:17:00 42236 8192000000 10126262304 10599084032 1108587680 11.919 97 25 50 0 1180145008 66722032 4835296
04:18:58 114350 8192000000 10072483760 10595016704 1098000944 11.819 96 15 30 0 1155040448 53900640 3138864
04:22:07 95360 8192000000 10078167104 10503688192 1083180176 11.678 96 21 42 0 1143144592 55741488 4222928
04:24:49 53575 8192000000 10063028560 10556379136 1111996656 11.951 99 11 22 0 1173147152 59061248 2089248
04:26:43 44104 8192000000 10047018080 10526932992 1126974096 12.093 100 8 15 0 1161750400 33196032 1580272
04:29:14 64146 8192000000 10076199952 10532593664 1121852848 12.044 99 28 48 0 1180477648 53390224 5234576
04:31:54 57736 8192000000 10048307088 10506174464 1123575488 12.061 99 7 14 0 1156816624 32362768 878368
04:34:02 10700 8192000000 10124226992 10554388480 1147797392 12.289 100 66 117 1 1217612176 57239952 12574832
04:35:16 61917 8192000000 10090204656 10538553344 1133280224 12.152 99 23 46 0 1210968432 72320688 5367520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-07-2022 04:38:31 to 15-07-2022 04:38:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-07-2022 04:38:48 to 15-07-2022 04:38:50.

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          212103
MON$OLDEST_ACTIVE               214268
MON$OLDEST_SNAPSHOT             214268
MON$NEXT_TRANSACTION            214268
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                       2092864
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 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 ---
:257: 
:258: mscs-test-161.budzdorov.ru    Fri Jul 15 04:37:23 2022
:259:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:260: 
:261: 
:262: mscs-test-161.budzdorov.ru    Fri Jul 15 04:37:25 2022
:263:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:264: 
--- end of diff output ---

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

15.07.22 04:38:51. 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-07-15 04:38:53 No severe PSQL-related problems occured
15.07.22 04:38:55. End of report.