Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2022 01: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 15661456 kB
MemAvailable 30469024 kB
Buffers 0 kB
Cached 16037196 kB
SwapCached 2048 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-10-18 01:38:29
DTS_END                         2022-10-18 01:38:29.6920
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(57:17)
IP                              ::1/36634
TRN_ID                          236891
ATT_ID                          700
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: 9222.33 21625 1106679
customer order: creation 167.79 381 20135
customer order: refuse 14.23 373 1707
order to supplier: creation 44.06 1249 5287
order to supplier: removal 3.47 107947 416
invoice (draft): creation 44.39 3145 5327
invoice (draft): removal 51.84 830 6221
invoice accept: apply 40.65 17113 4878
invoice accept: cancel 46.83 784 5620
customer reserve: creation 3873.37 162 464804
customer reserve: removal 4645.89 64 557507
realization accept: apply 81.65 178 9798
realization accept: cancel 79.91 110 9589
payment from customer: creation 59.09 132 7091
payment from customer: removal 5.69 81 683
payment to supplier: creation 47.95 147 5754
payment to supplier: removal 3.47 88 416
service: total inventory turnovers 4.98 968 598
service: total monetary turnovers 6.15 28 738
service: aggregate perf. data 0.88 15999 106
service: refresh index statistics 0.03 282723 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2022 01:39:37 to 18-10-2022 01:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5252
TEST_TIME 2 4310
TEST_TIME 3 4596
TEST_TIME 4 4516
TEST_TIME 5 4798
TEST_TIME 6 4498
TEST_TIME 7 4521
TEST_TIME 8 4811
TEST_TIME 9 5007
TEST_TIME 10 5312
TEST_TIME 11 5352
TEST_TIME 12 5257
TEST_TIME 13 5456
TEST_TIME 14 5505
TEST_TIME 15 5533
TEST_TIME 16 5724
TEST_TIME 17 5828
TEST_TIME 18 5886
TEST_TIME 19 6422
TEST_TIME 20 7089
TEST_TIME 21 7420
TEST_TIME 22 7326
TEST_TIME 23 7720
TEST_TIME 24 8172
TEST_TIME 25 9053
TEST_TIME 26 9708
TEST_TIME 27 10510
TEST_TIME 28 10492
TEST_TIME 29 10252
TEST_TIME 30 10168
TEST_TIME 31 10120
TEST_TIME 32 10012
TEST_TIME 33 9859
TEST_TIME 34 9739
TEST_TIME 35 9579
TEST_TIME 36 9664
TEST_TIME 37 9542
TEST_TIME 38 9423
TEST_TIME 39 9351
TEST_TIME 40 9321
TEST_TIME 41 9258
TEST_TIME 42 9341
TEST_TIME 43 9214
TEST_TIME 44 9084
TEST_TIME 45 9090
TEST_TIME 46 9002
TEST_TIME 47 8933
TEST_TIME 48 8966
TEST_TIME 49 9010
TEST_TIME 50 9076
TEST_TIME 51 9044
TEST_TIME 52 9095
TEST_TIME 53 9258
TEST_TIME 54 9240
TEST_TIME 55 9234
TEST_TIME 56 9311
TEST_TIME 57 9373
TEST_TIME 58 9336
TEST_TIME 59 9464
TEST_TIME 60 9396
TEST_TIME 61 9298
TEST_TIME 62 9268
TEST_TIME 63 9199
TEST_TIME 64 9193
TEST_TIME 65 9225
TEST_TIME 66 9149
TEST_TIME 67 9085
TEST_TIME 68 9018
TEST_TIME 69 8962
TEST_TIME 70 8948
TEST_TIME 71 8941
TEST_TIME 72 9032
TEST_TIME 73 8973
TEST_TIME 74 9068
TEST_TIME 75 9097
TEST_TIME 76 9108
TEST_TIME 77 9162
TEST_TIME 78 9182
TEST_TIME 79 9211
TEST_TIME 80 9273
TEST_TIME 81 9262
TEST_TIME 82 9316
TEST_TIME 83 9315
TEST_TIME 84 9222
TEST_TIME 85 9228
TEST_TIME 86 9188
TEST_TIME 87 9146
TEST_TIME 88 9139
TEST_TIME 89 9101
TEST_TIME 90 9114
TEST_TIME 91 9091
TEST_TIME 92 9069
TEST_TIME 93 9059
TEST_TIME 94 9128
TEST_TIME 95 9135
TEST_TIME 96 9168
TEST_TIME 97 9224
TEST_TIME 98 9186
TEST_TIME 99 9227
TEST_TIME 100 9224
TEST_TIME 101 9237
TEST_TIME 102 9329
TEST_TIME 103 9365
TEST_TIME 104 9426
TEST_TIME 105 9423
TEST_TIME 106 9387
TEST_TIME 107 9343
TEST_TIME 108 9313
TEST_TIME 109 9275
TEST_TIME 110 9242
TEST_TIME 111 9284
TEST_TIME 112 9237
TEST_TIME 113 9202
TEST_TIME 114 9208
TEST_TIME 115 9173
TEST_TIME 116 9169
TEST_TIME 117 9198
TEST_TIME 118 9211
TEST_TIME 119 9267
TEST_TIME 120 9225

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-10-2022 01:39:40 to 18-10-2022 01:39:44.

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 1534598 1534598 0 0.00 0 18360 6 0 0
doc_list_biud 1534598 1534598 0 0.00 0 78042 29 0 0
sp_add_invoice_to_stock 5033 4878 155 3.07 38 147606 17114 0 155
sp_cancel_adding_invoice 5620 5620 0 0.00 4 98302 785 0 0
sp_cancel_client_order 1707 1707 0 0.00 4 17789 373 0 0
sp_cancel_customer_reserve 557507 557507 0 0.00 0 78042 64 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 2 5011 81 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 5413 89 0 0
sp_cancel_supplier_invoice 6234 6221 13 0.20 2 14253 831 0 13
sp_cancel_supplier_order 416 416 0 0.00 6 1149675 107947 0 0
sp_cancel_write_off 9589 9589 0 0.00 1 14057 111 0 0
sp_client_order 20135 20135 0 0.00 3 80990 381 0 0
sp_customer_reserve 464804 464804 0 0.00 0 34094 163 0 0
sp_fill_shopping_cart 30737 30737 0 0.00 1 12039 39 0 0
sp_fill_shopping_cart_clo_res 464799 464799 0 0.00 0 20536 17 0 0
sp_get_clo_for_invoice 4873 4873 0 0.00 0 48762 447 0 0
sp_kill_cost_storno 16308 16308 0 0.00 0 6930 51 0 0
sp_kill_qstorno_ret_qs2qd 1131240 1131240 0 0.00 0 78040 35 0 0
sp_kill_qty_storno 595731 595731 0 0.00 0 78041 74 0 0
sp_lock_selected_doc 20305 20305 0 0.00 0 6950 6 0 0
sp_make_cost_storno 27516 27516 0 0.00 0 18358 59 0 0
sp_multiply_rows_for_pdistr 27516 27516 0 0.00 0 8348 27 0 0
sp_multiply_rows_for_qdistr 20135 20135 0 0.00 0 77896 180 0 0
sp_pay_from_customer 7091 7091 0 0.00 4 13860 133 0 0
sp_pay_to_supplier 5754 5754 0 0.00 5 18366 147 0 0
sp_payment_common 12844 12844 0 0.00 1 13086 35 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 17783 335 0 0
sp_qd_handle_on_invoice_upd_sts 10493 10493 0 0.00 0 26432 313 0 0
sp_qd_handle_on_reserve_upd_sts 9798 9798 0 0.00 0 9361 46 0 0
sp_reserve_write_off 9798 9798 0 0.00 6 14279 179 0 0
sp_supplier_invoice 5327 5327 0 0.00 6 69468 3145 0 0
sp_supplier_order 5287 5287 0 0.00 5 47040 1250 0 0
srv_aggregate_perf_data 106 106 0 0.00 2478 136852 15999 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1314 128979 49102 0 0
srv_find_qd_qs_mism 1058198 1058198 0 0.00 0 17780 5 0 0
srv_make_invnt_saldo 598 598 0 0.00 30 80897 969 0 0
srv_make_money_saldo 738 738 0 0.00 1 3582 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 150392 437685 282724 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4878 4878 0 0.00 0 11898 152 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 4090 95 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 5110 13 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 1096 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 1558 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 524 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 6350 34 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 28189 1550 0 0
t$perf-norm:sp_cancel_write_off 924 924 0 0.00 0 703 4 0 0
t$perf-norm:sp_client_order 20135 20135 0 0.00 0 12848 18 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 2766 11 0 0
t$perf-norm:sp_pay_from_customer 7091 7091 0 0.00 0 5353 8 0 0
t$perf-norm:sp_pay_to_supplier 5754 5754 0 0.00 0 7668 10 0 0
t$perf-norm:sp_reserve_write_off 9798 9798 0 0.00 0 13918 17 0 0
t$perf-norm:sp_supplier_invoice 5327 5327 0 0.00 0 8203 18 0 0
t$perf-norm:sp_supplier_order 5287 5287 0 0.00 0 6061 14 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 518 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 77 2 0 0
t$perf-norm:srv_make_invnt_saldo 598 598 0 0.00 0 4795 27 0 0
t$perf-norm:srv_make_money_saldo 738 738 0 0.00 0 715 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10054 9744 310 3.08 0 11242 25 0 310
v_all_customers 36748 36748 0 0.00 0 67 0 0 0
v_all_suppliers 29862 29862 0 0.00 0 68 1 0 0
v_all_wares 40268 40268 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 2754 9 0 0
v_cancel_client_order 3414 3414 0 0.00 0 5937 12 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 4127 8 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 3010 11 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2184 16 0 0
v_cancel_supplier_invoice 876 850 26 2.96 0 864 15 0 26
v_cancel_supplier_order 832 832 0 0.00 0 2724 15 0 0
v_cancel_write_off 1848 1848 0 0.00 0 4006 18 0 0
v_max_id_clo_ord 5286 5286 0 0.00 0 309 1 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 2108 11 0 0
v_max_id_ord_sup 5317 5317 0 0.00 0 4847 5 0 0
v_max_non_paid_invoice 1426 1426 0 0.00 0 2113 5 0 0
v_max_non_paid_realizn 3764 3764 0 0.00 0 10920 10 0 0
v_min_id_clo_ord 5286 5286 0 0.00 0 6435 12 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 2563 9 0 0
v_min_id_ord_sup 5317 5317 0 0.00 0 4991 6 0 0
v_min_non_paid_invoice 5754 5754 0 0.00 0 13083 27 0 0
v_min_non_paid_realizn 7090 7090 0 0.00 0 11484 24 0 0
v_our_firm 10172 10172 0 0.00 0 22 1 0 0
v_reserve_write_off 19596 19596 0 0.00 0 10006 11 0 0
x_lock_depdocs_on_canc_invoice 11416 11416 0 0.00 0 19066 115 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 18213 370 0 0
x_make_qstorno_1000_1200 5287 5287 0 0.00 1 47032 1173 0 0
x_make_qstorno_1200_2000 5327 5327 0 0.00 3 68816 3002 0 0
x_make_qstorno_2100_3300 464804 464804 0 0.00 0 30829 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2022 01:39:45 to 18-10-2022 01:39:49.

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
23:08:33 12 8192000000 8808502096 8822951936 12646480 0.154 4 3 4 0 12831712 78128 107104
23:09:34 34922 8192000000 9908575744 10219646976 985140320 10.734 100 91 182 0 1019053936 10552816 23360800
23:11:16 29281 8192000000 9952751328 10277085184 1032407840 11.192 100 88 176 0 1064856752 9508336 22940576
23:12:56 10443 8192000000 9993952944 10330185728 1078965008 11.638 100 86 170 1 1110291568 10733536 20593024
23:14:09 9528 8192000000 10041248000 10386051072 1121429856 12.040 100 80 160 0 1152052288 11848400 18774032
23:15:21 4500 8192000000 10081038512 10441383936 1167748496 12.476 100 81 161 0 1199097040 12849616 18498928
23:16:28 2917 8192000000 10131199728 10501128192 1217273440 12.936 100 76 149 0 1245110720 11499936 16337344
23:17:35 3393 8192000000 10153372464 10533482496 1249768352 13.236 100 70 136 0 1276879280 12589168 14521760
23:18:43 1473 8192000000 10198668032 10578690048 1283646112 13.546 100 94 188 2 1317184128 12529760 21008256
23:19:46 17216 8192000000 10193509008 10586365952 1292350240 13.626 100 59 116 0 1315591712 9441424 13800048
23:21:05 7009 8192000000 10194091184 10603085824 1304122304 13.733 100 54 106 0 1326357136 10559600 11675232
23:22:20 4941 8192000000 10188879664 10606751744 1310493040 13.791 100 26 52 0 1322737264 6158272 6085952
23:23:31 15156 8192000000 10195235648 10616213504 1317933360 13.858 100 4 8 0 1323907360 5180032 793968
23:24:50 12686 8192000000 10213128256 10628837376 1325623184 13.928 100 35 71 1 1339318208 5440992 8254032
23:26:03 14591 8192000000 10234705408 10642468864 1329392672 13.962 100 51 102 0 1347489968 4984736 13112560
23:27:19 6975 8192000000 10222847376 10642837504 1326376976 13.934 100 22 42 0 1341483760 10330816 4775968
23:28:27 10597 8192000000 10214748752 10635988992 1326268592 13.933 100 23 46 0 1336936416 5269488 5398336
23:29:39 8358 8192000000 10163463648 10571665408 1254372416 13.278 97 23 46 0 1265787104 5813104 5601584
23:30:51 33467 8192000000 10144214576 10550693888 1169405392 12.491 91 33 64 0 1181871728 5373936 7092400
23:32:27 8259 8192000000 10155399472 10553225216 1247154528 13.212 99 35 71 1 1261291568 5909840 8227200
23:33:39 5541 8192000000 10165709424 10570428416 1263938896 13.366 99 27 52 1 1274826320 5280880 5606544
23:34:46 60213 8192000000 10185956688 10595700736 1292736608 13.629 100 33 66 3 1306240496 5590880 7913008
23:36:51 6273 8192000000 10196708320 10607550464 1301455120 13.708 100 30 61 0 1314406640 5506096 7445424
23:37:59 8512 8192000000 10218332816 10626805760 1314781568 13.829 100 22 42 1 1324479488 4890048 4807872
23:39:17 36465 8192000000 10251564432 10669965312 1349359728 14.142 100 32 62 0 1365924912 8814432 7750752
23:40:56 65437 8192000000 10261678336 10688696320 1344584992 14.099 99 35 70 0 1364000336 11539312 7876032
23:43:08 60297 8192000000 10285107408 10712248320 1377601088 14.395 100 22 44 0 1401322224 17654544 6066592
23:45:12 10348 8192000000 10319254096 10739318784 1393201728 14.534 100 55 110 0 1427251552 21216352 12833472
23:46:28 6599 8192000000 10333092576 10757152768 1401147376 14.605 100 52 103 0 1441776928 28247776 12381776
23:47:40 14328 8192000000 10357529856 10778599424 1421797600 14.789 100 58 115 0 1467277488 31259360 14220528
23:48:59 6687 8192000000 10367848336 10793238528 1432252208 14.881 100 59 117 2 1478789248 31599552 14937488
23:50:13 16083 8192000000 10372872608 10802397184 1435021168 14.906 100 57 112 1 1488661888 38386320 15254400
23:51:35 2472 8192000000 10382763152 10804703232 1431569040 14.875 99 62 120 1 1486938960 40371216 14998704
23:52:50 4955 8192000000 10381150352 10806374400 1426115008 14.827 99 56 112 1 1484751056 43969232 14666816
23:54:08 7222 8192000000 10404245744 10819342336 1434198128 14.898 99 83 166 1 1504349888 49289856 20861904
23:55:20 8574 8192000000 10421664800 10841194496 1437772384 14.930 99 81 157 2 1517662592 58337440 21552768
23:56:34 1402 8192000000 10414466960 10832941056 1437462656 14.927 100 68 136 0 1521225856 64755632 19007568
23:57:45 1313 8192000000 10411219728 10815639552 1424358976 14.811 100 93 184 2 1512499920 64177632 23963312
23:58:49 6821 8192000000 10378114720 10801790976 1386211248 14.472 98 43 84 0 1455087312 57272768 11603296
23:59:59 18765 8192000000 10369751152 10799923200 1405741600 14.646 100 44 89 0 1477298704 59031216 12525888
00:01:25 15451 8192000000 10350014080 10786213888 1383143488 14.445 100 44 88 0 1462120368 66157056 12819824
00:03:04 12911 8192000000 10350019536 10780434432 1377088448 14.391 100 32 64 0 1452267008 65640416 9538144
00:04:19 27748 8192000000 10290295280 10726223872 1306570784 13.755 97 17 34 0 1368450112 56757248 5122080
00:05:52 25879 8192000000 10263391216 10688782336 1322678096 13.901 98 41 83 0 1349374768 17377360 9319312
00:07:20 59844 8192000000 10247435328 10688966656 1340327392 14.060 99 38 79 0 1362025408 13613840 8084176
00:09:30 51226 8192000000 10261949024 10709262336 1355193184 14.194 100 12 25 1 1375213888 17290304 2730400
00:11:29 88449 8192000000 10261856784 10702942208 1343840832 14.092 99 20 38 0 1353770400 6532592 3396976
00:14:01 51313 8192000000 10287243168 10733346816 1377036512 14.390 100 32 64 1 1398906384 14521088 7348784
00:16:04 30365 8192000000 10276096624 10715762688 1368257312 14.311 99 27 53 0 1381507168 7495504 5754352
00:18:25 21569 8192000000 10290658544 10722361344 1351333472 14.159 97 37 72 0 1372869952 12625440 8911040
00:20:15 42488 8192000000 10281066624 10739830784 1371661280 14.342 99 18 32 1 1388785760 13737936 3386544
00:21:59 45588 8192000000 10267418000 10721599488 1368313520 14.312 99 11 22 0 1380521872 9406080 2802272
00:24:05 59683 8192000000 10280098960 10740748288 1363328832 14.267 98 9 18 0 1373644848 8653360 1662656
00:26:06 46275 8192000000 10289611104 10742370304 1357793424 14.218 97 20 39 1 1371321168 8745056 4782688
00:28:18 50702 8192000000 10302752672 10757718016 1397633184 14.574 100 15 30 0 1415645312 14993184 3018944
00:30:37 88942 8192000000 10309954976 10773893120 1411348304 14.696 100 15 30 0 1430546320 15300144 3897872
00:33:06 43779 8192000000 10324608048 10761928704 1404231328 14.633 100 35 66 0 1420547600 9364144 6952128
00:34:52 65600 8192000000 10314311488 10770960384 1406296400 14.651 100 17 34 0 1422278000 12283152 3698448
00:37:03 101426 8192000000 10302013712 10748440576 1352534736 14.170 96 27 51 0 1374670592 16579376 5556480
00:39:45 117396 8192000000 10302934912 10753662976 1337255584 14.033 95 23 45 0 1351692224 9319328 5117312
00:43:04 94038 8192000000 10264682784 10726326272 1311241056 13.797 95 6 12 0 1325679264 13272064 1166144
00:45:39 45915 8192000000 10272714496 10728124416 1374710240 14.369 100 8 16 0 1382252192 6052720 1489232
00:49:12 47443 8192000000 10233707472 10673819648 1307570608 13.764 96 19 38 0 1323866096 11816928 4478560
00:51:00 28698 8192000000 10263088240 10702319616 1336292608 14.024 98 31 62 1 1351427856 7522032 7613216
00:52:44 43538 8192000000 10248726944 10697912320 1304033776 13.732 96 10 20 0 1315118528 8912880 2171872
00:54:30 68195 8192000000 10260704640 10709819392 1301752080 13.711 96 21 43 0 1322719824 16041440 4926304
00:56:43 107027 8192000000 10272308416 10711543808 1340693552 14.064 99 23 46 0 1362636064 16118544 5823968
00:59:44 76788 8192000000 10281836656 10735259648 1351295920 14.159 99 23 46 0 1372512544 16563520 4653104
01:02:04 128978 8192000000 10272772016 10727804928 1315602688 13.837 96 28 56 1 1337424240 16371216 5450336
01:05:14 46692 8192000000 10256810400 10711179264 1346691488 14.118 99 11 22 0 1363612384 14812112 2108784
01:07:01 74811 8192000000 10263670048 10714869760 1363646960 14.270 100 8 16 0 1373974096 8291360 2035776
01:09:16 53301 8192000000 10268775568 10717372416 1324022592 13.913 96 14 28 0 1334254224 7088416 3143216
01:11:10 41391 8192000000 10277070576 10729512960 1356369984 14.205 98 21 42 0 1371251696 10397904 4483808
01:12:52 62170 8192000000 10281724032 10731016192 1339535376 14.053 97 17 34 0 1356778560 13246480 3996704
01:14:54 58016 8192000000 10291782144 10744541184 1385842480 14.469 100 17 32 0 1403551296 14171984 3536832
01:16:53 65445 8192000000 10291941184 10737422336 1363938592 14.273 99 29 58 0 1385288032 15196512 6152928
01:19:00 40869 8192000000 10297102800 10751291392 1361804928 14.254 98 14 28 0 1377821120 13084976 2931216
01:20:41 59452 8192000000 10308445280 10765811712 1392506800 14.528 100 8 16 0 1408569808 14257808 1805200
01:22:41 94679 8192000000 10301026448 10756210688 1368541504 14.314 98 5 10 0 1385676992 16315216 820272
01:25:16 104445 8192000000 10282766784 10733096960 1366664080 14.297 99 20 40 0 1379874128 8819760 4390288
01:28:04 27355 8192000000 10291824912 10740924416 1381800832 14.433 100 27 54 1 1400688864 12935152 5952880
01:29:32 68122 8192000000 10270266704 10737209344 1335013968 14.012 96 6 12 0 1350222352 13469200 1739184
01:31:40 107139 8192000000 10270883088 10736254976 1325247984 13.924 96 12 24 0 1343672560 15288704 3135872
01:34:38 46608 8192000000 10282534448 10725507072 1348259248 14.132 98 18 36 0 1365136656 12614992 4262416
01:36:27 89638 8192000000 10264600336 10715680768 1298585488 13.682 95 12 24 0 1316170752 15094768 2490496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-10-2022 01:39:51 to 18-10-2022 01:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 155 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2022 01:40:07 to 18-10-2022 01:40:09.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          236174
MON$OLDEST_ACTIVE               237240
MON$OLDEST_SNAPSHOT             237240
MON$NEXT_TRANSACTION            237240
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-03 16:41:55.3470
MON$PAGES                       2004416
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33635 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33635 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33635 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33635 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Tue Oct 18 01:38:36 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Tue Oct 18 01:38:36 2022
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Tue Oct 18 01:38:38 2022
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:72: 
--- end of diff output ---

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

18.10.22 01:40:10. There are 3 logs in /var/tmp/logs-oltp30 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-10-18 01:40:13 No severe PSQL-related problems occured
18.10.22 01:40:14. End of report.