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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-17 04:37:26
DTS_END                         2023-02-17 04:37:26.8010
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/36994
TRN_ID                          223020
ATT_ID                          598
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: 8775.47 29018 1053056
customer order: creation 167.49 527 20099
customer order: refuse 14.49 380 1739
order to supplier: creation 44.92 1426 5390
order to supplier: removal 3.32 164274 398
invoice (draft): creation 46.79 2973 5615
invoice (draft): removal 50.04 980 6005
invoice accept: apply 40.52 24841 4862
invoice accept: cancel 44.78 1237 5373
customer reserve: creation 3708.24 241 444989
customer reserve: removal 4368.65 100 524238
realization accept: apply 82.73 258 9927
realization accept: cancel 76.54 175 9185
payment from customer: creation 57.68 177 6922
payment from customer: removal 5.49 115 659
payment to supplier: creation 48.13 186 5776
payment to supplier: removal 3.30 121 396
service: total inventory turnovers 3.89 3688 467
service: total monetary turnovers 7.39 351 887
service: aggregate perf. data 1.04 12848 125
service: refresh index statistics 0.03 365469 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-02-2023 04:39:55 to 17-02-2023 04:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5459
TEST_TIME 2 5305
TEST_TIME 3 6296
TEST_TIME 4 6235
TEST_TIME 5 6055
TEST_TIME 6 6621
TEST_TIME 7 6355
TEST_TIME 8 6237
TEST_TIME 9 6534
TEST_TIME 10 6897
TEST_TIME 11 7020
TEST_TIME 12 7116
TEST_TIME 13 7217
TEST_TIME 14 7260
TEST_TIME 15 7643
TEST_TIME 16 7486
TEST_TIME 17 7445
TEST_TIME 18 7901
TEST_TIME 19 8113
TEST_TIME 20 8370
TEST_TIME 21 8459
TEST_TIME 22 8725
TEST_TIME 23 8844
TEST_TIME 24 8836
TEST_TIME 25 8934
TEST_TIME 26 9202
TEST_TIME 27 9494
TEST_TIME 28 10209
TEST_TIME 29 10456
TEST_TIME 30 10920
TEST_TIME 31 10808
TEST_TIME 32 10670
TEST_TIME 33 10532
TEST_TIME 34 10257
TEST_TIME 35 10289
TEST_TIME 36 10172
TEST_TIME 37 10007
TEST_TIME 38 10024
TEST_TIME 39 9895
TEST_TIME 40 9828
TEST_TIME 41 9715
TEST_TIME 42 9623
TEST_TIME 43 9456
TEST_TIME 44 9404
TEST_TIME 45 9479
TEST_TIME 46 9489
TEST_TIME 47 9557
TEST_TIME 48 9750
TEST_TIME 49 9894
TEST_TIME 50 9931
TEST_TIME 51 9920
TEST_TIME 52 10013
TEST_TIME 53 9989
TEST_TIME 54 9927
TEST_TIME 55 9906
TEST_TIME 56 9863
TEST_TIME 57 9732
TEST_TIME 58 9658
TEST_TIME 59 9658
TEST_TIME 60 9608
TEST_TIME 61 9573
TEST_TIME 62 9489
TEST_TIME 63 9440
TEST_TIME 64 9377
TEST_TIME 65 9253
TEST_TIME 66 9208
TEST_TIME 67 9205
TEST_TIME 68 9182
TEST_TIME 69 9140
TEST_TIME 70 9090
TEST_TIME 71 9107
TEST_TIME 72 9094
TEST_TIME 73 9084
TEST_TIME 74 9197
TEST_TIME 75 9181
TEST_TIME 76 9172
TEST_TIME 77 9208
TEST_TIME 78 9222
TEST_TIME 79 9250
TEST_TIME 80 9251
TEST_TIME 81 9227
TEST_TIME 82 9142
TEST_TIME 83 9108
TEST_TIME 84 9101
TEST_TIME 85 9036
TEST_TIME 86 8977
TEST_TIME 87 8977
TEST_TIME 88 8968
TEST_TIME 89 8929
TEST_TIME 90 8881
TEST_TIME 91 8862
TEST_TIME 92 8838
TEST_TIME 93 8891
TEST_TIME 94 8876
TEST_TIME 95 8895
TEST_TIME 96 8906
TEST_TIME 97 8919
TEST_TIME 98 9034
TEST_TIME 99 9064
TEST_TIME 100 9040
TEST_TIME 101 8960
TEST_TIME 102 8927
TEST_TIME 103 8887
TEST_TIME 104 8812
TEST_TIME 105 8799
TEST_TIME 106 8773
TEST_TIME 107 8790
TEST_TIME 108 8756
TEST_TIME 109 8812
TEST_TIME 110 8793
TEST_TIME 111 8777
TEST_TIME 112 8771
TEST_TIME 113 8775
TEST_TIME 114 8801
TEST_TIME 115 8886
TEST_TIME 116 8866
TEST_TIME 117 8888
TEST_TIME 118 8896
TEST_TIME 119 8858
TEST_TIME 120 8784

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-02-2023 04:39:58 to 17-02-2023 04:40:02.

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 1465098 1465098 0 0.00 0 17202 12 0 0
doc_list_biud 1465090 1465090 0 0.00 0 34313 42 0 0
sp_add_invoice_to_stock 4935 4862 73 1.47 27 212662 24841 0 73
sp_cancel_adding_invoice 5373 5373 0 0.00 4 73795 1237 0 0
sp_cancel_client_order 1739 1739 0 0.00 6 8934 381 0 0
sp_cancel_customer_reserve 524238 524238 0 0.00 1 33042 101 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 4 3761 116 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 4 3854 122 0 0
sp_cancel_supplier_invoice 6012 6005 7 0.11 2 29015 980 0 7
sp_cancel_supplier_order 398 398 0 0.00 7 1522835 164275 0 0
sp_cancel_write_off 9185 9185 0 0.00 2 15594 175 0 0
sp_client_order 20099 20099 0 0.00 5 33140 528 0 0
sp_customer_reserve 444989 444989 0 0.00 0 103501 242 0 0
sp_fill_shopping_cart 31093 31093 0 0.00 1 16816 79 0 0
sp_fill_shopping_cart_clo_res 444965 444965 0 0.00 0 13218 18 0 0
sp_get_clo_for_invoice 4835 4835 0 0.00 0 23542 298 0 0
sp_kill_cost_storno 15613 15613 0 0.00 0 10756 66 0 0
sp_kill_qstorno_ret_qs2qd 1064064 1064064 0 0.00 0 32991 46 0 0
sp_kill_qty_storno 561700 561700 0 0.00 0 34312 102 0 0
sp_lock_selected_doc 20482 20482 0 0.00 0 13174 8 0 0
sp_make_cost_storno 27460 27460 0 0.00 0 13478 76 0 0
sp_multiply_rows_for_pdistr 27460 27460 0 0.00 0 7312 34 0 0
sp_multiply_rows_for_qdistr 20096 20096 0 0.00 0 22817 232 0 0
sp_pay_from_customer 6922 6922 0 0.00 5 13922 178 0 0
sp_pay_to_supplier 5776 5776 0 0.00 6 17727 186 0 0
sp_payment_common 12698 12698 0 0.00 2 8404 45 0 0
sp_qd_handle_on_cancel_clo 1739 1739 0 0.00 0 8903 309 0 0
sp_qd_handle_on_invoice_upd_sts 10208 10208 0 0.00 0 34308 472 0 0
sp_qd_handle_on_reserve_upd_sts 9927 9927 0 0.00 0 12658 54 0 0
sp_reserve_write_off 9927 9927 0 0.00 8 15460 258 0 0
sp_supplier_invoice 5615 5615 0 0.00 10 99796 2974 0 0
sp_supplier_order 5390 5390 0 0.00 7 36348 1427 0 0
srv_aggregate_perf_data 125 125 0 0.00 2229 183157 12848 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 722 138337 38259 0 0
srv_find_qd_qs_mism 1006534 1006534 0 0.00 0 17201 10 0 0
srv_make_invnt_saldo 467 467 0 0.00 71 80744 3688 0 0
srv_make_money_saldo 887 887 0 0.00 1 14669 352 0 0
srv_recalc_idx_stat 4 4 0 0.00 228329 493520 365469 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4865 4865 0 0.00 1 12554 857 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 10206 435 0 0
t$perf-norm:sp_cancel_client_order 1739 1739 0 0.00 0 3648 33 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 2238 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 2923 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 1507 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 6196 63 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 77426 5864 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 4678 22 0 0
t$perf-norm:sp_client_order 20099 20099 0 0.00 0 19816 34 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 3647 21 0 0
t$perf-norm:sp_pay_from_customer 6922 6922 0 0.00 0 3409 18 0 0
t$perf-norm:sp_pay_to_supplier 5776 5776 0 0.00 0 8852 21 0 0
t$perf-norm:sp_reserve_write_off 9927 9927 0 0.00 0 6139 22 0 0
t$perf-norm:sp_supplier_invoice 5615 5615 0 0.00 0 3563 27 0 0
t$perf-norm:sp_supplier_order 5390 5390 0 0.00 0 2499 31 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 93 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 188 6 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 1327 22 0 0
t$perf-norm:srv_make_money_saldo 887 887 0 0.00 0 5394 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 185 50 0 0
v_add_invoice_to_stock 9804 9658 146 1.48 0 9359 27 0 146
v_all_customers 36858 36858 0 0.00 0 79 4 0 0
v_all_suppliers 30656 30656 0 0.00 0 111 4 0 0
v_all_wares 40188 40188 0 0.00 0 52 4 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1592 18 0 0
v_cancel_client_order 3478 3478 0 0.00 0 3771 11 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 3702 16 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 1 1949 13 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 3773 14 0 0
v_cancel_supplier_invoice 842 828 14 1.66 0 3764 24 0 14
v_cancel_supplier_order 796 796 0 0.00 0 1350 12 0 0
v_cancel_write_off 2028 2028 0 0.00 0 7803 26 0 0
v_max_id_clo_ord 5388 5388 0 0.00 0 982 5 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 7753 30 0 0
v_max_id_ord_sup 5611 5611 0 0.00 0 3439 7 0 0
v_max_non_paid_invoice 1447 1447 0 0.00 0 382 7 0 0
v_max_non_paid_realizn 3664 3664 0 0.00 0 8146 9 0 0
v_min_id_clo_ord 5388 5388 0 0.00 0 2649 10 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 2577 11 0 0
v_min_id_ord_sup 5611 5611 0 0.00 0 6866 9 0 0
v_min_non_paid_invoice 5776 5776 0 0.00 0 7328 15 0 0
v_min_non_paid_realizn 6922 6922 0 0.00 0 8389 19 0 0
v_our_firm 9842 9842 0 0.00 0 50 4 0 0
v_reserve_write_off 19850 19850 0 0.00 0 6319 12 0 0
x_lock_depdocs_on_canc_invoice 10964 10964 0 0.00 0 26874 130 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 6008 370 0 0
x_make_qstorno_1000_1200 5390 5390 0 0.00 3 36306 1271 0 0
x_make_qstorno_1200_2000 5615 5615 0 0.00 4 90294 2756 0 0
x_make_qstorno_2100_3300 444988 444988 0 0.00 0 103496 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-02-2023 04:40:03 to 17-02-2023 04:40:07.

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:30 5 8192000000 8804122384 8817467392 7541024 0.091 4 2 3 0 7709856 107456 61376
02:08:31 40765 8192000000 9720859296 10019356672 792038144 8.816 100 92 184 0 823396928 8729024 22629760
02:10:19 19232 8192000000 9769096240 10078007296 839515824 9.295 100 88 176 0 874492512 13126192 21850496
02:11:42 13267 8192000000 9810496000 10127904768 873896992 9.639 100 89 178 0 916929616 22276048 20756576
02:13:08 11561 8192000000 9860498384 10198310912 916698384 10.063 100 77 149 1 972988512 40502160 15787968
02:14:22 3540 8192000000 9915261760 10261487616 966261952 10.550 100 81 158 0 1035379392 52564960 16552480
02:15:30 1200 8192000000 9991181120 10345459712 996788528 10.847 100 93 183 0 1107056000 91712784 18554688
02:16:33 2048 8192000000 9991662896 10356858880 1020989568 11.082 100 86 159 2 1105729792 69764192 14976032
02:17:38 993 8192000000 10023779264 10391257088 1044099632 11.304 100 96 181 0 1142480432 80791952 17588848
02:18:40 918 8192000000 10023678016 10394214400 1055952528 11.418 100 99 194 2 1145370512 69938640 19479344
02:19:43 1098 8192000000 10035881808 10417426432 1063160944 11.487 100 93 182 2 1159617904 77760480 18696480
02:20:45 2925 8192000000 10029574480 10409299968 1069775328 11.550 100 70 138 0 1152245648 69051504 13418816
02:21:49 2644 8192000000 10042184112 10433064960 1076576368 11.615 100 81 154 0 1161478016 69544752 15356896
02:22:56 1023 8192000000 10036260176 10419712000 1084775776 11.693 100 86 164 1 1158001888 55733952 17492160
02:23:59 1171 8192000000 10059366512 10451005440 1089087200 11.734 100 97 186 1 1182432608 74683712 18661696
02:25:01 1131 8192000000 10057426000 10441019392 1094300224 11.784 100 96 186 1 1175678096 62722112 18655760
02:26:03 1179 8192000000 10072444720 10460839936 1086187104 11.706 99 98 192 0 1193179280 88595424 18396752
02:27:06 967 8192000000 10043811712 10432860160 1098802944 11.826 100 90 169 0 1164584096 48688032 17093120
02:28:08 1017 8192000000 10069115056 10459521024 1097171520 11.811 100 84 166 1 1190142400 76184160 16786720
02:29:10 780 8192000000 10077869264 10471432192 1093072608 11.772 100 89 163 0 1200660608 90765440 16822560
02:30:12 951 8192000000 10059581200 10446114816 1085794880 11.703 100 92 176 0 1182889120 79883024 17211216
02:31:14 1132 8192000000 10031442672 10412949504 1079930352 11.647 100 97 186 0 1151533712 52034448 19568912
02:32:17 1257 8192000000 10016428464 10392379392 1042349184 11.287 100 85 156 1 1140026912 81649984 16027744
02:33:19 1150 8192000000 9985021216 10358812672 1039304144 11.258 100 91 155 4 1109222304 53866240 16051920
02:34:21 987 8192000000 9991751184 10367447040 1041598992 11.280 100 92 169 0 1117105472 57608544 17897936
02:35:23 1264 8192000000 9982447584 10356842496 1033454752 11.202 100 84 162 2 1102744496 52201200 17088544
02:36:26 1231 8192000000 9958752848 10329026560 1022171392 11.093 100 88 159 5 1079609392 39983312 17454688
02:37:28 713 8192000000 9958125360 10328338432 1040813056 11.272 100 78 142 4 1073129984 19295232 13021696
02:38:36 1924 8192000000 10036274496 10412990464 1058307520 11.440 100 83 162 0 1152235040 77709584 16217936
02:39:43 7373 8192000000 10037223200 10426306560 1074980528 11.600 100 68 125 4 1154077120 66868768 12227824
02:40:55 13016 8192000000 10069772352 10453905408 1091131392 11.753 100 74 146 2 1187375568 80607424 15636752
02:42:14 1674 8192000000 10136298368 10519478272 1104915152 11.884 100 85 158 0 1247282496 126007472 16359872
02:43:20 1310 8192000000 10148113952 10549440512 1119557776 12.023 100 89 169 2 1268922448 129772288 19592384
02:44:24 8691 8192000000 10158377536 10560499712 1125151168 12.076 100 66 128 0 1270838608 132057712 13629728
02:45:36 1083 8192000000 10169273920 10572382208 1138026240 12.197 100 83 145 4 1278223424 123539568 16657616
02:46:42 1146 8192000000 10235291424 10642558976 1151097232 12.320 100 99 192 3 1341191360 169345920 20748208
02:47:46 978 8192000000 10268152160 10667397120 1166679056 12.466 100 88 174 2 1380626736 194552432 19395248
02:48:51 2113 8192000000 10276356144 10685390848 1165528928 12.455 100 88 169 1 1389623952 205399856 18695168
02:49:58 1538 8192000000 10302614800 10709475328 1172936384 12.524 100 91 176 1 1415696848 222954480 19805984
02:51:05 784 8192000000 10320583840 10739109888 1173269552 12.527 100 97 188 0 1428063456 234054704 20739200
02:52:10 1030 8192000000 10324532240 10737422336 1170445424 12.501 100 89 176 0 1429552144 240308352 18798368
02:53:14 5469 8192000000 10356514448 10764992512 1168755296 12.485 100 74 146 1 1462206480 275858016 17593168
02:54:25 1192 8192000000 10407890672 10831036416 1183046720 12.619 100 93 180 1 1517870832 312182272 22641840
02:55:31 1467 8192000000 10428198240 10868338688 1196534320 12.744 100 95 187 2 1526938144 308893136 21510688
02:56:34 957 8192000000 10422215200 10868469760 1190202736 12.685 100 97 193 2 1527647648 314550448 22894464
02:57:37 1562 8192000000 10416894608 10836709376 1191062752 12.693 100 85 168 2 1521757616 311828560 18866304
02:58:39 7293 8192000000 10430661376 10872971264 1185656096 12.643 100 58 111 0 1531177856 331937232 13584528
02:59:51 8927 8192000000 10411551968 10836824064 1169715904 12.494 99 57 111 1 1500899632 318031056 13152672
03:01:02 10258 8192000000 10416386240 10854588416 1168790608 12.486 99 61 120 2 1504589888 321227616 14571664
03:02:13 3142 8192000000 10413730608 10867867648 1161720592 12.419 100 43 85 1 1517308688 344574656 11013440
03:03:27 7228 8192000000 10392990608 10859249664 1142671904 12.241 99 49 94 1 1493766880 339225376 11869600
03:04:44 30295 8192000000 10285446912 10727354368 1108548096 11.919 98 35 67 0 1376668480 259175632 8944752
03:06:17 58336 8192000000 10104823376 10614382592 1087887424 11.723 96 10 20 0 1227994768 137909664 2197680
03:08:19 9787 8192000000 10035084368 10466537472 1087870640 11.722 97 30 61 0 1121993504 27555504 6567360
03:09:34 66615 8192000000 10072254176 10510536704 1105146128 11.886 99 36 72 1 1176337584 63621408 7570048
03:12:12 47538 8192000000 10053583664 10481979392 1078865664 11.637 96 29 57 1 1126436560 41675168 5895728
03:14:01 64774 8192000000 10115643600 10545819648 1119486112 12.022 99 50 97 0 1212028288 81199488 11342688
03:16:06 40763 8192000000 10166662960 10591764480 1138329520 12.200 99 33 63 0 1240562928 95167552 7065856
03:17:53 21342 8192000000 10198471184 10633400320 1138743280 12.204 99 45 88 0 1290606880 141259088 10604512
03:19:18 49853 8192000000 10226669328 10675142656 1128735312 12.109 98 26 52 1 1320629520 185709232 6184976
03:21:09 52606 8192000000 10282100640 10742882304 1124542480 12.070 98 34 68 1 1367300208 234356992 8400736
03:23:11 62090 8192000000 10253128928 10702213120 1137548800 12.192 99 26 50 0 1342987600 199187184 6251616
03:25:15 53459 8192000000 10119952336 10642837504 1141679568 12.231 100 27 54 0 1333114976 184914768 6520640
03:27:28 60557 8192000000 10134817088 10601488384 1120900432 12.035 97 17 32 0 1193424976 69361712 3162832
03:29:29 95580 8192000000 10085788528 10536439808 1091567920 11.758 96 12 24 0 1147742800 53512688 2662192
03:32:05 56328 8192000000 10101878912 10535702528 1139312384 12.209 100 38 71 0 1177772624 30758832 7701408
03:34:08 138337 8192000000 10105604240 10569170944 1139385616 12.210 100 29 56 0 1222083728 76442016 6256096
03:37:34 45428 8192000000 10084279504 10547445760 1138883632 12.205 99 9 18 0 1178356080 37907072 1565376
03:39:24 59859 8192000000 10095923680 10551914496 1141689568 12.231 99 18 36 0 1191102752 45985232 3427952
03:41:24 98102 8192000000 10100353552 10574229504 1137452368 12.192 99 15 28 0 1191587824 51451120 2684336
03:44:04 51610 8192000000 10112334384 10570211328 1132736144 12.147 98 18 34 1 1190508304 54035248 3736912
03:46:03 75804 8192000000 10135069264 10608218112 1117730560 12.006 97 24 47 1 1204445584 81364144 5350880
03:48:24 77714 8192000000 10148975936 10590531584 1120132912 12.028 98 23 46 0 1225764848 100695824 4936112
03:50:48 54562 8192000000 10103160768 10593308672 1138295712 12.199 100 34 68 0 1234931344 89598000 7037632
03:52:44 62867 8192000000 10102882416 10537021440 1121134784 12.038 99 38 76 0 1168953472 40554368 7264320
03:54:49 9576 8192000000 10075813760 10498551808 1101788624 11.855 98 43 86 0 1143373504 33183856 8401024
03:56:02 44176 8192000000 10103076816 10564218880 1110836560 11.940 98 36 72 1 1198605376 80717200 7051616
03:57:51 28261 8192000000 10110031712 10537472000 1117072912 11.999 98 40 79 0 1187740816 62337664 8330240
03:59:20 73493 8192000000 10111514016 10560475136 1134951552 12.168 100 57 113 1 1222734384 76567472 11215360
04:01:35 76776 8192000000 10067864128 10537664512 1102436112 11.861 97 9 16 0 1148297088 43998464 1862512
04:04:39 52771 8192000000 10083211152 10556178432 1109537616 11.928 99 18 36 0 1150679616 37135520 4006480
04:06:47 41975 8192000000 10096095680 10532663296 1108838912 11.921 99 35 69 0 1188751408 72285840 7626656
04:08:33 24891 8192000000 10149380720 10595069952 1121339120 12.040 99 30 60 0 1241673840 113350560 6984160
04:10:01 45885 8192000000 10157771808 10621566976 1127973088 12.102 99 30 60 0 1263176000 127957424 7245488
04:11:50 22355 8192000000 10141129872 10609459200 1124168352 12.066 100 38 74 0 1256376832 123674368 8534112
04:13:14 79185 8192000000 10138992576 10627563520 1108129984 11.915 98 17 34 0 1250969664 139384048 3455632
04:15:34 17497 8192000000 10071393472 10506842112 1089330720 11.736 97 44 82 0 1139057472 40361024 9365728
04:16:53 79016 8192000000 10048273424 10513092608 1125108576 12.075 100 17 32 2 1158138320 30506432 2523312
04:19:30 98389 8192000000 10079294032 10553794560 1122386672 12.050 99 20 41 2 1183499712 56921664 4191376
04:22:09 110612 8192000000 10111800464 10577653760 1102828704 11.864 97 11 22 0 1185421968 80258592 2334672
04:25:00 27726 8192000000 10101307824 10545782784 1125658816 12.080 100 21 42 0 1191555040 61618704 4277520
04:26:33 75295 8192000000 10114508592 10575478784 1131546864 12.136 99 17 34 0 1193463200 58259008 3657328
04:28:51 78122 8192000000 10175026368 10667003904 1141904816 12.233 99 28 56 0 1270216544 122244064 6067664
04:31:30 59557 8192000000 10120537824 10649612288 1126649792 12.090 98 28 56 0 1231308400 98540048 6118560
04:33:39 31781 8192000000 10110779376 10552467456 1146791584 12.279 100 30 56 0 1191173088 39328848 5052656
04:35:17 45294 8192000000 10108034432 10546352128 1135274960 12.171 99 36 64 2 1181627072 38713440 7638672
04:37:20 30116 8192000000 9299434768 9628487680 418006624 4.854 36 7 14 0 480318976 60816304 1496048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-02-2023 04:40:09 to 17-02-2023 04:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 73 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-02-2023 04:40:26 to 17-02-2023 04:40:28.

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          223019
MON$OLDEST_ACTIVE               223345
MON$OLDEST_SNAPSHOT             223345
MON$NEXT_TRANSACTION            223345
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                       2039712
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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