Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.10.2022 07: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 15036108 kB
MemAvailable 30445744 kB
Buffers 0 kB
Cached 16644880 kB
SwapCached 2124 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 2022-10-23 07:37:21
DTS_END                         2022-10-23 07:37:21.7690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46130
TRN_ID                          221316
ATT_ID                          619
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: 9093.15 26016 1091178
customer order: creation 176.03 519 21124
customer order: refuse 14.64 473 1757
order to supplier: creation 45.66 1451 5479
order to supplier: removal 3.50 176910 420
invoice (draft): creation 46.79 3413 5615
invoice (draft): removal 50.44 1095 6053
invoice accept: apply 41.89 26450 5027
invoice accept: cancel 45.97 1347 5516
customer reserve: creation 3847.32 253 461678
customer reserve: removal 4517.76 106 542131
realization accept: apply 86.05 281 10326
realization accept: cancel 85.77 176 10292
payment from customer: creation 60.25 195 7230
payment from customer: removal 5.86 145 703
payment to supplier: creation 48.68 227 5842
payment to supplier: removal 3.38 155 406
service: total inventory turnovers 4.24 4215 509
service: total monetary turnovers 8.02 408 962
service: aggregate perf. data 0.87 16489 104
service: refresh index statistics 0.03 286019 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-10-2022 07:38:04 to 23-10-2022 07:38:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10625
TEST_TIME 2 6737
TEST_TIME 3 6782
TEST_TIME 4 6166
TEST_TIME 5 6094
TEST_TIME 6 6497
TEST_TIME 7 6820
TEST_TIME 8 7113
TEST_TIME 9 7495
TEST_TIME 10 7650
TEST_TIME 11 7758
TEST_TIME 12 7698
TEST_TIME 13 7790
TEST_TIME 14 7781
TEST_TIME 15 7580
TEST_TIME 16 7554
TEST_TIME 17 7703
TEST_TIME 18 7516
TEST_TIME 19 7571
TEST_TIME 20 7670
TEST_TIME 21 7647
TEST_TIME 22 7617
TEST_TIME 23 7725
TEST_TIME 24 7915
TEST_TIME 25 7999
TEST_TIME 26 7942
TEST_TIME 27 7974
TEST_TIME 28 8221
TEST_TIME 29 8324
TEST_TIME 30 8776
TEST_TIME 31 9136
TEST_TIME 32 9283
TEST_TIME 33 9604
TEST_TIME 34 10013
TEST_TIME 35 10261
TEST_TIME 36 10648
TEST_TIME 37 10770
TEST_TIME 38 10867
TEST_TIME 39 10690
TEST_TIME 40 10613
TEST_TIME 41 10494
TEST_TIME 42 10439
TEST_TIME 43 10378
TEST_TIME 44 10365
TEST_TIME 45 10200
TEST_TIME 46 10027
TEST_TIME 47 9929
TEST_TIME 48 9905
TEST_TIME 49 9814
TEST_TIME 50 9751
TEST_TIME 51 9741
TEST_TIME 52 9779
TEST_TIME 53 9695
TEST_TIME 54 9710
TEST_TIME 55 9802
TEST_TIME 56 9757
TEST_TIME 57 9679
TEST_TIME 58 9625
TEST_TIME 59 9651
TEST_TIME 60 9538
TEST_TIME 61 9434
TEST_TIME 62 9516
TEST_TIME 63 9450
TEST_TIME 64 9434
TEST_TIME 65 9357
TEST_TIME 66 9342
TEST_TIME 67 9330
TEST_TIME 68 9527
TEST_TIME 69 9468
TEST_TIME 70 9499
TEST_TIME 71 9465
TEST_TIME 72 9565
TEST_TIME 73 9527
TEST_TIME 74 9579
TEST_TIME 75 9623
TEST_TIME 76 9649
TEST_TIME 77 9655
TEST_TIME 78 9603
TEST_TIME 79 9487
TEST_TIME 80 9430
TEST_TIME 81 9520
TEST_TIME 82 9473
TEST_TIME 83 9420
TEST_TIME 84 9350
TEST_TIME 85 9323
TEST_TIME 86 9252
TEST_TIME 87 9276
TEST_TIME 88 9221
TEST_TIME 89 9199
TEST_TIME 90 9241
TEST_TIME 91 9264
TEST_TIME 92 9277
TEST_TIME 93 9247
TEST_TIME 94 9246
TEST_TIME 95 9381
TEST_TIME 96 9409
TEST_TIME 97 9398
TEST_TIME 98 9436
TEST_TIME 99 9403
TEST_TIME 100 9359
TEST_TIME 101 9310
TEST_TIME 102 9272
TEST_TIME 103 9283
TEST_TIME 104 9246
TEST_TIME 105 9212
TEST_TIME 106 9169
TEST_TIME 107 9130
TEST_TIME 108 9160
TEST_TIME 109 9124
TEST_TIME 110 9178
TEST_TIME 111 9179
TEST_TIME 112 9148
TEST_TIME 113 9159
TEST_TIME 114 9188
TEST_TIME 115 9167
TEST_TIME 116 9143
TEST_TIME 117 9178
TEST_TIME 118 9148
TEST_TIME 119 9151
TEST_TIME 120 9112

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-10-2022 07:38:08 to 23-10-2022 07:38:12.

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 1517768 1517768 0 0.00 0 15499 14 0 0
doc_list_biud 1517759 1517759 0 0.00 0 61143 44 0 0
sp_add_invoice_to_stock 5163 5027 136 2.63 38 198433 26451 0 136
sp_cancel_adding_invoice 5516 5516 0 0.00 6 68944 1348 0 0
sp_cancel_client_order 1757 1757 0 0.00 7 13404 473 0 0
sp_cancel_customer_reserve 542131 542131 0 0.00 1 61149 107 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 4 6069 145 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 4 6595 156 0 0
sp_cancel_supplier_invoice 6062 6053 9 0.14 3 35273 1095 0 9
sp_cancel_supplier_order 420 420 0 0.00 8 1512302 176911 0 0
sp_cancel_write_off 10292 10292 0 0.00 2 6873 176 0 0
sp_client_order 21124 21124 0 0.00 5 34835 519 0 0
sp_customer_reserve 461678 461678 0 0.00 1 92045 253 0 0
sp_fill_shopping_cart 32204 32204 0 0.00 1 28439 85 0 0
sp_fill_shopping_cart_clo_res 461641 461641 0 0.00 0 13134 19 0 0
sp_get_clo_for_invoice 4979 4979 0 0.00 0 14603 247 0 0
sp_kill_cost_storno 16917 16917 0 0.00 0 10013 80 0 0
sp_kill_qstorno_ret_qs2qd 1101027 1101027 0 0.00 0 61121 46 0 0
sp_kill_qty_storno 581471 581471 0 0.00 0 61142 106 0 0
sp_lock_selected_doc 21171 21171 0 0.00 0 6512 7 0 0
sp_make_cost_storno 28376 28376 0 0.00 0 14706 79 0 0
sp_multiply_rows_for_pdistr 28374 28374 0 0.00 0 8657 41 0 0
sp_multiply_rows_for_qdistr 21123 21123 0 0.00 0 18652 184 0 0
sp_pay_from_customer 7230 7230 0 0.00 5 19633 196 0 0
sp_pay_to_supplier 5842 5842 0 0.00 6 26627 227 0 0
sp_payment_common 13072 13072 0 0.00 2 11966 48 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 13210 391 0 0
sp_qd_handle_on_invoice_upd_sts 10492 10492 0 0.00 0 23829 560 0 0
sp_qd_handle_on_reserve_upd_sts 10326 10326 0 0.00 0 11396 62 0 0
sp_reserve_write_off 10326 10326 0 0.00 8 21195 281 0 0
sp_supplier_invoice 5615 5615 0 0.00 10 111609 3414 0 0
sp_supplier_order 5479 5479 0 0.00 8 43662 1452 0 0
srv_aggregate_perf_data 104 104 0 0.00 2207 194040 16490 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 848 127861 32774 0 0
srv_find_qd_qs_mism 1042539 1042539 0 0.00 0 15497 11 0 0
srv_make_invnt_saldo 509 509 0 0.00 58 106277 4216 0 0
srv_make_money_saldo 962 962 0 0.00 1 11622 409 0 0
srv_recalc_idx_stat 4 4 0 0.00 57140 532772 286020 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5030 5030 0 0.00 1 11776 1006 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 2 6747 515 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 4482 35 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 2311 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 558 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 1869 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1372 48 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 103739 7560 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 1116 20 0 0
t$perf-norm:sp_client_order 21124 21124 0 0.00 0 6207 42 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 5795 28 0 0
t$perf-norm:sp_pay_from_customer 7230 7230 0 0.00 0 13505 23 0 0
t$perf-norm:sp_pay_to_supplier 5842 5842 0 0.00 0 4591 25 0 0
t$perf-norm:sp_reserve_write_off 10326 10326 0 0.00 0 12632 27 0 0
t$perf-norm:sp_supplier_invoice 5615 5615 0 0.00 0 3886 30 0 0
t$perf-norm:sp_supplier_order 5479 5479 0 0.00 0 13748 38 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 1355 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1309 22 0 0
t$perf-norm:srv_make_invnt_saldo 509 509 0 0.00 0 4163 26 0 0
t$perf-norm:srv_make_money_saldo 962 962 0 0.00 0 2153 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 153 43 0 0
v_add_invoice_to_stock 10218 9946 272 2.66 0 13470 30 0 272
v_all_customers 38708 38708 0 0.00 0 174 4 0 0
v_all_suppliers 30624 30624 0 0.00 0 179 5 0 0
v_all_wares 42246 42246 0 0.00 0 136 4 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 13207 30 0 0
v_cancel_client_order 3514 3514 0 0.00 0 12909 16 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 3283 16 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 1603 13 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1482 16 0 0
v_cancel_supplier_invoice 854 836 18 2.10 0 1641 30 0 18
v_cancel_supplier_order 840 840 0 0.00 0 1277 13 0 0
v_cancel_write_off 2050 2050 0 0.00 0 4439 29 0 0
v_max_id_clo_ord 5478 5478 0 0.00 0 390 5 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 2665 19 0 0
v_max_id_ord_sup 5603 5603 0 0.00 0 6056 9 0 0
v_max_non_paid_invoice 1611 1611 0 0.00 0 479 8 0 0
v_max_non_paid_realizn 3700 3700 0 0.00 0 7259 12 0 0
v_min_id_clo_ord 5478 5478 0 0.00 0 2194 8 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 2004 11 0 0
v_min_id_ord_sup 5603 5603 0 0.00 0 8828 14 0 0
v_min_non_paid_invoice 5842 5842 0 0.00 0 5584 14 0 0
v_min_non_paid_realizn 7230 7230 0 0.00 0 11962 19 0 0
v_our_firm 10598 10598 0 0.00 0 63 5 0 0
v_reserve_write_off 20651 20651 0 0.00 0 10190 12 0 0
x_lock_depdocs_on_canc_invoice 11151 11151 0 0.00 0 15501 123 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 17811 417 0 0
x_make_qstorno_1000_1200 5479 5479 0 0.00 3 43642 1281 0 0
x_make_qstorno_1200_2000 5615 5615 0 0.00 4 108860 3183 0 0
x_make_qstorno_2100_3300 461678 461678 0 0.00 0 92043 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2022 07:38:13 to 23-10-2022 07:38:17.

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
05:07:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:26 31449 8192000000 9722541520 10021797888 797062352 8.867 100 86 172 0 825354240 7705472 20586416
05:10:02 15618 8192000000 9754920880 10061656064 831605760 9.215 100 88 176 0 864632960 11852688 21174512
05:11:21 21360 8192000000 9791800224 10107179008 859316048 9.493 100 87 173 0 897233200 18190768 19726384
05:12:48 11944 8192000000 9869481200 10201702400 911316608 10.010 100 77 152 0 973650960 45882160 16452192
05:14:03 3815 8192000000 9913518544 10258223104 961956064 10.508 100 85 169 0 1027555408 48023200 17576144
05:15:13 1409 8192000000 9955024512 10309443584 995338352 10.833 100 85 165 0 1075860832 64464144 16058336
05:16:17 913 8192000000 10013043040 10379456512 1020994416 11.082 100 96 183 2 1131790640 92452032 18344192
05:17:20 689 8192000000 9995662272 10361954304 1041909648 11.283 100 93 173 2 1112979456 54498752 16571056
05:18:22 1136 8192000000 10019475184 10396905472 1058668320 11.444 100 91 171 2 1144045280 67832592 17544368
05:19:25 1448 8192000000 10040437920 10419736576 1070538976 11.557 100 95 174 3 1159332864 71704320 17089568
05:20:28 2493 8192000000 10058062080 10435629056 1074849584 11.598 100 82 158 1 1174848448 85456112 14542752
05:21:32 1230 8192000000 10051406048 10432778240 1080875008 11.656 100 97 179 2 1172896336 74757696 17263632
05:22:35 1064 8192000000 10047304064 10433101824 1085390288 11.699 100 93 174 2 1169267872 65746256 18131328
05:23:37 1058 8192000000 10061655984 10450743296 1089169968 11.735 100 92 176 1 1184202976 77345456 17687552
05:24:39 1141 8192000000 10071576272 10458697728 1093731760 11.778 100 99 182 3 1187832224 74822224 19278240
05:25:41 3283 8192000000 10050946448 10442510336 1093946240 11.780 100 71 140 1 1172825408 64931776 13947392
05:26:45 3453 8192000000 10079075248 10476789760 1093994352 11.781 100 66 131 0 1188860288 82123328 12742608
05:27:50 1089 8192000000 10044553728 10438426624 1099110192 11.829 100 83 164 0 1165859392 49529488 17219712
05:28:52 1113 8192000000 10066683376 10458456064 1100976144 11.847 100 93 182 1 1189866144 70781984 18108016
05:29:55 1014 8192000000 10017271824 10408828928 1080957472 11.657 99 83 157 1 1138461264 42604912 14898880
05:30:57 1824 8192000000 10005848848 10383294464 1046786464 11.330 97 92 172 4 1126307328 62622816 16898048
05:32:00 1199 8192000000 10035728064 10414907392 1065650752 11.511 100 97 185 1 1159306144 73821248 19834144
05:33:03 1526 8192000000 10008457280 10383966208 1042332176 11.287 100 95 179 2 1133900320 72536688 19031456
05:34:05 1897 8192000000 9996945360 10372878336 1047083088 11.333 99 82 163 1 1118973920 55811840 16078992
05:35:08 4181 8192000000 9972583440 10351771648 1044883760 11.312 100 77 143 3 1094218992 33763904 15571328
05:36:13 1167 8192000000 9990948144 10367557632 1033228928 11.200 99 91 171 2 1110765024 59379920 18156176
05:37:15 974 8192000000 9963211232 10331811840 1030406464 11.172 99 91 175 0 1084562096 37073808 17081824
05:38:19 1697 8192000000 9990218000 10367545344 1059001568 11.447 100 81 148 3 1112786288 37036640 16748080
05:39:28 5629 8192000000 10023873696 10407231488 1074501600 11.595 100 68 131 1 1147266704 59465792 13299312
05:40:42 1862 8192000000 10096693088 10482954240 1094835616 11.789 100 82 154 2 1212813648 101231104 16746928
05:41:48 1548 8192000000 10092223744 10480168960 1100154112 11.839 100 71 138 1 1211105920 96417072 14534736
05:42:52 1056 8192000000 10111253616 10500886528 1112188832 11.953 100 88 155 2 1228911888 99220384 17502672
05:43:59 930 8192000000 10162963616 10549194752 1117969040 12.008 100 98 192 1 1279285040 140898384 20417616
05:45:05 6512 8192000000 10176757696 10584248320 1125162960 12.076 100 77 152 0 1302318864 160949920 16205984
05:46:18 1365 8192000000 10184179760 10600108032 1136441232 12.182 100 92 179 0 1302924592 145932256 20551104
05:47:24 921 8192000000 10186006720 10585477120 1142756832 12.241 100 95 182 3 1299507088 137509328 19240928
05:48:29 2150 8192000000 10187426784 10601488384 1155502048 12.361 100 87 169 0 1300503392 126960736 18040608
05:49:35 3095 8192000000 10222485968 10624684032 1162821040 12.430 100 84 161 1 1335388144 154003136 18563968
05:50:41 1696 8192000000 10238085744 10639147008 1169478560 12.492 100 80 160 0 1345783312 159049552 17255200
05:51:48 1740 8192000000 10262228288 10667773952 1181543648 12.605 100 83 160 1 1370722112 171226352 17952112
05:52:54 1129 8192000000 10320754512 10727067648 1183647760 12.624 100 96 191 0 1433181568 227566384 21967424
05:54:00 1834 8192000000 10344023872 10764144640 1178584272 12.577 99 94 181 3 1458699984 258607792 21507920
05:55:06 2025 8192000000 10368666736 10775805952 1186616528 12.652 100 80 157 0 1478741728 273489472 18635728
05:56:13 1263 8192000000 10415933760 10829910016 1185258624 12.639 100 96 185 2 1528723664 320241376 23223664
05:57:20 1098 8192000000 10462551280 10870722560 1180153664 12.592 100 97 192 0 1566733664 362727472 23852528
05:58:26 1443 8192000000 10476010192 10894753792 1173249568 12.527 99 94 181 1 1576532112 381585616 21696928
05:59:32 871 8192000000 10515411312 10929614848 1191203904 12.695 100 98 194 1 1618363616 402322416 24837296
06:00:40 1155 8192000000 10542109200 10992750592 1200859344 12.784 100 96 187 1 1646493680 421909488 23724848
06:01:44 954 8192000000 10558586848 10983165952 1206263440 12.834 100 97 191 0 1662834992 433883104 22688448
06:02:48 1514 8192000000 10583013008 11006976000 1204295328 12.816 100 97 188 1 1685040272 458551920 22193024
06:03:53 838 8192000000 10589199712 11037814784 1201579136 12.791 100 97 185 1 1689956656 465830448 22547072
06:04:57 1474 8192000000 10621489200 11091210240 1194553632 12.726 100 80 158 1 1726023328 511037824 20431872
06:06:04 1363 8192000000 10594286480 11047006208 1184433504 12.632 100 83 162 0 1698599456 493442256 20723696
06:07:15 2614 8192000000 10520059968 11020832768 1183978144 12.627 100 59 116 2 1648806880 449885952 14942784
06:08:30 19744 8192000000 10471253200 10933420032 1172433232 12.520 100 38 76 0 1563958016 381289072 10235712
06:10:45 24504 8192000000 10340006288 10896445440 1160048304 12.404 99 30 60 0 1465968624 298165664 7754656
06:12:10 26014 8192000000 10237762240 10737389568 1146425312 12.276 99 19 37 0 1402844688 252002128 4417248
06:13:37 71534 8192000000 10107695968 10611908608 1110447488 11.937 97 18 32 0 1212766784 98413584 3905712
06:16:15 41468 8192000000 10060429584 10487668736 1108116080 11.915 97 55 99 2 1133315440 14135120 11064240
06:17:58 56521 8192000000 10070563552 10516992000 1104487392 11.880 97 26 52 0 1136676112 27534496 4654224
06:19:55 42263 8192000000 10061209728 10505793536 1108263728 11.916 98 33 66 0 1145378128 30190320 6924080
06:21:43 40459 8192000000 10053953776 10474487808 1096017808 11.800 97 36 63 1 1127220976 24547168 6656000
06:23:25 83046 8192000000 10101783008 10559328256 1116587808 11.995 99 29 57 0 1194612624 71387408 6637408
06:26:06 125549 8192000000 10099601984 10528079872 1128374896 12.106 99 42 82 0 1167820480 30668432 8777152
06:29:13 21656 8192000000 10113380512 10574397440 1131229472 12.133 100 42 79 1 1223735712 83421360 9084880
06:30:39 30418 8192000000 10124344992 10547671040 1108668576 11.920 98 55 104 2 1214083328 94218704 11196048
06:32:13 77381 8192000000 10064349808 10560573440 1083605248 11.682 96 12 23 1 1170744960 84098960 3040752
06:34:31 27501 8192000000 10084924272 10534309888 1125027216 12.074 99 30 51 1 1179604496 48960880 5616400
06:36:02 38460 8192000000 10103505728 10556829696 1112354736 11.955 98 24 49 1 1180432576 62595696 5482144
06:38:50 51299 8192000000 10107799840 10602762240 1130662416 12.128 99 28 52 0 1221290816 84481408 6146992
06:40:47 47994 8192000000 10107346800 10584739840 1121583616 12.042 99 10 20 0 1190949952 67409920 1956416
06:42:36 54977 8192000000 10170161232 10626457600 1138379248 12.200 99 18 36 0 1256206272 113792288 4034736
06:44:34 32784 8192000000 10161741856 10600042496 1125558624 12.079 99 34 64 2 1255386048 122663040 7164384
06:46:08 31259 8192000000 10119078384 10547613696 1134317952 12.162 99 30 56 0 1203443968 63175728 5950288
06:47:40 81919 8192000000 10144584240 10594029568 1143212624 12.246 99 23 46 0 1242040288 94086624 4741040
06:50:08 22505 8192000000 10118723216 10570035200 1151465952 12.323 100 33 62 0 1195747808 38071888 6209968
06:51:33 35687 8192000000 10138500640 10564317184 1150891904 12.318 100 31 62 0 1223624960 65727152 7005904
06:53:09 57919 8192000000 10112301680 10570985472 1118391168 12.012 98 23 46 0 1175113072 52307456 4414448
06:55:11 119380 8192000000 10160565488 10606628864 1121801408 12.044 97 28 54 0 1238518000 109715408 7001184
06:59:58 67869 8192000000 10086602480 10546176000 1137495840 12.192 99 15 30 0 1187210496 46831088 2883568
07:02:17 36429 8192000000 10085418000 10559512576 1143300944 12.247 100 16 32 0 1199851616 51935232 4615440
07:03:58 57446 8192000000 10100018480 10566656000 1116621968 11.995 97 16 30 0 1199165680 78595936 3947776
07:06:03 32240 8192000000 10126440528 10571792384 1100290944 11.840 97 30 54 2 1204903504 98650928 5961632
07:07:38 55474 8192000000 10085078096 10526302208 1085634064 11.701 96 31 60 0 1169588624 77642512 6312048
07:09:35 32991 8192000000 10127231600 10581635072 1122908352 12.054 99 33 65 0 1203564336 74080624 6575360
07:11:09 65099 8192000000 10101681552 10641399808 1118074656 12.009 98 31 60 1 1260270096 135885360 6310080
07:13:37 53477 8192000000 10089456816 10539003904 1115385408 11.983 98 22 45 1 1163034400 42346896 5302096
07:15:32 49985 8192000000 10070598112 10500493312 1086994288 11.714 96 40 80 0 1156941744 62193952 7753504
07:17:23 127861 8192000000 10074983824 10527961088 1104144736 11.877 98 46 90 0 1169774832 55453904 10176192
07:20:48 51605 8192000000 10098133744 10563162112 1121904672 12.045 99 24 48 1 1184463712 57390464 5168576
07:22:44 57964 8192000000 10113330368 10565763072 1134257120 12.161 100 19 34 0 1211549792 73415440 3877232
07:24:49 72118 8192000000 10120886592 10626805760 1140202896 12.217 100 14 28 0 1248252528 104543264 3506368
07:28:11 59919 8192000000 10058394880 10528845824 1118958768 12.017 99 21 41 0 1154255680 31111712 4185200
07:30:12 107420 8192000000 10067367792 10546692096 1109744608 11.930 97 12 24 0 1150035472 37816688 2474176
07:32:59 21834 8192000000 10100936992 10547408896 1130966624 12.130 100 42 83 0 1186848800 47676816 8205360
07:34:22 38588 8192000000 10049448656 10481664000 1087959744 11.723 96 19 38 0 1109586528 18047728 3579056
07:36:01 2638 8192000000 10102250032 10518466560 1125948576 12.083 100 74 146 0 1188385264 48239664 14197024
07:37:05 23729 8192000000 9233339120 9518735360 367221232 4.290 33 24 48 0 395073600 23244032 4608336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 23-10-2022 07:38:19 to 23-10-2022 07:38:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2022 07:38:36 to 23-10-2022 07:38:38.

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          222300
MON$OLDEST_ACTIVE               222301
MON$OLDEST_SNAPSHOT             222301
MON$NEXT_TRANSACTION            222301
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                       2076384
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2861 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2861 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2861 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 ---
:750: 
:751: mscs-test-161.budzdorov.ru    Sun Oct 23 07:37:33 2022
:752:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:753: 
:754: 
:755: mscs-test-161.budzdorov.ru    Sun Oct 23 07:37:35 2022
:756:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:757: 
:758: 
:759: mscs-test-161.budzdorov.ru    Sun Oct 23 07:37:35 2022
:760:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:761: 
--- end of diff output ---

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

23.10.22 07:38:40. 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-10-23 07:38:42 No severe PSQL-related problems occured
23.10.22 07:38:44. End of report.