Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.2023 13:38
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 15026308 kB
MemAvailable 30435664 kB
Buffers 0 kB
Cached 16706456 kB
SwapCached 4484 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-01-12 13:37:25
DTS_END                         2023-01-12 13:37:25.0540
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/55348
TRN_ID                          223753
ATT_ID                          658
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: 9180.16 29605 1101619
customer order: creation 168.33 459 20199
customer order: refuse 14.39 389 1727
order to supplier: creation 46.64 1347 5597
order to supplier: removal 3.47 178465 416
invoice (draft): creation 46.09 3191 5531
invoice (draft): removal 51.18 1039 6141
invoice accept: apply 42.38 27593 5086
invoice accept: cancel 46.20 1304 5544
customer reserve: creation 3908.26 264 468991
customer reserve: removal 4563.03 107 547564
realization accept: apply 82.85 285 9942
realization accept: cancel 76.03 176 9123
payment from customer: creation 59.25 179 7110
payment from customer: removal 6.18 166 742
payment to supplier: creation 49.11 195 5893
payment to supplier: removal 3.75 139 450
service: total inventory turnovers 4.13 4204 496
service: total monetary turnovers 7.98 388 958
service: aggregate perf. data 0.88 15009 105
service: refresh index statistics 0.03 357208 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-01-2023 13:39:10 to 12-01-2023 13:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9248
TEST_TIME 2 7566
TEST_TIME 3 7162
TEST_TIME 4 7328
TEST_TIME 5 6967
TEST_TIME 6 7077
TEST_TIME 7 7149
TEST_TIME 8 7046
TEST_TIME 9 6947
TEST_TIME 10 6946
TEST_TIME 11 7135
TEST_TIME 12 7078
TEST_TIME 13 7029
TEST_TIME 14 6933
TEST_TIME 15 6993
TEST_TIME 16 7426
TEST_TIME 17 7681
TEST_TIME 18 7693
TEST_TIME 19 7848
TEST_TIME 20 7990
TEST_TIME 21 7957
TEST_TIME 22 8169
TEST_TIME 23 8268
TEST_TIME 24 8320
TEST_TIME 25 8476
TEST_TIME 26 8357
TEST_TIME 27 8532
TEST_TIME 28 8836
TEST_TIME 29 9353
TEST_TIME 30 9553
TEST_TIME 31 9753
TEST_TIME 32 10298
TEST_TIME 33 10822
TEST_TIME 34 11104
TEST_TIME 35 11333
TEST_TIME 36 11378
TEST_TIME 37 11158
TEST_TIME 38 10920
TEST_TIME 39 10838
TEST_TIME 40 10839
TEST_TIME 41 10815
TEST_TIME 42 10757
TEST_TIME 43 10581
TEST_TIME 44 10436
TEST_TIME 45 10473
TEST_TIME 46 10360
TEST_TIME 47 10294
TEST_TIME 48 10399
TEST_TIME 49 10284
TEST_TIME 50 10252
TEST_TIME 51 10172
TEST_TIME 52 10165
TEST_TIME 53 10110
TEST_TIME 54 10325
TEST_TIME 55 10270
TEST_TIME 56 10236
TEST_TIME 57 10135
TEST_TIME 58 10146
TEST_TIME 59 10155
TEST_TIME 60 10160
TEST_TIME 61 10201
TEST_TIME 62 10192
TEST_TIME 63 10196
TEST_TIME 64 10123
TEST_TIME 65 10121
TEST_TIME 66 10076
TEST_TIME 67 9974
TEST_TIME 68 9896
TEST_TIME 69 9810
TEST_TIME 70 9775
TEST_TIME 71 9754
TEST_TIME 72 9711
TEST_TIME 73 9680
TEST_TIME 74 9656
TEST_TIME 75 9682
TEST_TIME 76 9729
TEST_TIME 77 9681
TEST_TIME 78 9654
TEST_TIME 79 9641
TEST_TIME 80 9790
TEST_TIME 81 9750
TEST_TIME 82 9706
TEST_TIME 83 9713
TEST_TIME 84 9699
TEST_TIME 85 9710
TEST_TIME 86 9701
TEST_TIME 87 9723
TEST_TIME 88 9698
TEST_TIME 89 9625
TEST_TIME 90 9612
TEST_TIME 91 9547
TEST_TIME 92 9533
TEST_TIME 93 9526
TEST_TIME 94 9496
TEST_TIME 95 9463
TEST_TIME 96 9404
TEST_TIME 97 9364
TEST_TIME 98 9346
TEST_TIME 99 9360
TEST_TIME 100 9398
TEST_TIME 101 9406
TEST_TIME 102 9383
TEST_TIME 103 9362
TEST_TIME 104 9373
TEST_TIME 105 9390
TEST_TIME 106 9450
TEST_TIME 107 9490
TEST_TIME 108 9470
TEST_TIME 109 9593
TEST_TIME 110 9546
TEST_TIME 111 9480
TEST_TIME 112 9477
TEST_TIME 113 9411
TEST_TIME 114 9386
TEST_TIME 115 9351
TEST_TIME 116 9334
TEST_TIME 117 9303
TEST_TIME 118 9266
TEST_TIME 119 9252
TEST_TIME 120 9212

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-01-2023 13:39:14 to 12-01-2023 13:39:18.

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 1532171 1532171 0 0.00 0 12566 14 0 0
doc_list_biud 1532171 1532171 0 0.00 0 26568 44 0 0
sp_add_invoice_to_stock 5201 5086 115 2.21 33 241519 27593 0 115
sp_cancel_adding_invoice 5544 5544 0 0.00 6 108023 1305 0 0
sp_cancel_client_order 1727 1727 0 0.00 7 10895 389 0 0
sp_cancel_customer_reserve 547564 547564 0 0.00 1 26581 107 0 0
sp_cancel_pay_from_customer 742 742 0 0.00 3 10437 167 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 4 3685 139 0 0
sp_cancel_supplier_invoice 6150 6141 9 0.14 2 20042 1039 0 9
sp_cancel_supplier_order 416 416 0 0.00 18 1547820 178465 0 0
sp_cancel_write_off 9123 9123 0 0.00 2 7474 177 0 0
sp_client_order 20199 20199 0 0.00 6 27757 459 0 0
sp_customer_reserve 468991 468991 0 0.00 1 133912 265 0 0
sp_fill_shopping_cart 31326 31326 0 0.00 1 19370 84 0 0
sp_fill_shopping_cart_clo_res 468982 468982 0 0.00 0 15207 20 0 0
sp_get_clo_for_invoice 5033 5033 0 0.00 0 41818 247 0 0
sp_kill_cost_storno 15859 15859 0 0.00 0 7779 74 0 0
sp_kill_qstorno_ret_qs2qd 1110808 1110808 0 0.00 0 26539 47 0 0
sp_kill_qty_storno 585490 585490 0 0.00 0 26567 106 0 0
sp_lock_selected_doc 20807 20807 0 0.00 0 10180 9 0 0
sp_make_cost_storno 27978 27978 0 0.00 0 10146 79 0 0
sp_multiply_rows_for_pdistr 27978 27978 0 0.00 0 7340 36 0 0
sp_multiply_rows_for_qdistr 20199 20199 0 0.00 0 11961 176 0 0
sp_pay_from_customer 7110 7110 0 0.00 5 11308 180 0 0
sp_pay_to_supplier 5893 5893 0 0.00 7 9557 195 0 0
sp_payment_common 13003 13003 0 0.00 2 6622 47 0 0
sp_qd_handle_on_cancel_clo 1727 1727 0 0.00 0 10883 311 0 0
sp_qd_handle_on_invoice_upd_sts 10577 10577 0 0.00 0 13817 523 0 0
sp_qd_handle_on_reserve_upd_sts 9942 9942 0 0.00 0 17333 70 0 0
sp_reserve_write_off 9942 9942 0 0.00 8 17387 285 0 0
sp_supplier_invoice 5531 5531 0 0.00 11 83757 3192 0 0
sp_supplier_order 5597 5597 0 0.00 7 46836 1348 0 0
srv_aggregate_perf_data 105 105 0 0.00 2369 165531 15009 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 808 144524 35796 0 0
srv_find_qd_qs_mism 1051738 1051738 0 0.00 0 11754 11 0 0
srv_make_invnt_saldo 496 496 0 0.00 49 218863 4204 0 0
srv_make_money_saldo 958 958 0 0.00 1 12263 389 0 0
srv_recalc_idx_stat 4 4 0 0.00 187625 500490 357208 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 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 5086 5086 0 0.00 1 21979 973 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 2 11640 496 0 0
t$perf-norm:sp_cancel_client_order 1727 1727 0 0.00 0 9761 37 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 2114 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 742 742 0 0.00 0 1034 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 724 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 649 36 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 104355 7242 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 6604 23 0 0
t$perf-norm:sp_client_order 20199 20199 0 0.00 0 9129 35 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 609 13 0 0
t$perf-norm:sp_pay_from_customer 7110 7110 0 0.00 0 7077 20 0 0
t$perf-norm:sp_pay_to_supplier 5893 5893 0 0.00 0 3398 21 0 0
t$perf-norm:sp_reserve_write_off 9942 9942 0 0.00 0 5377 23 0 0
t$perf-norm:sp_supplier_invoice 5531 5531 0 0.00 0 11416 33 0 0
t$perf-norm:sp_supplier_order 5597 5597 0 0.00 0 7030 34 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 403 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 626 16 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 2585 19 0 0
t$perf-norm:srv_make_money_saldo 958 958 0 0.00 0 391 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 204 63 0 0
v_add_invoice_to_stock 10296 10066 230 2.23 0 8868 33 0 230
v_all_customers 36874 36874 0 0.00 0 66 4 0 0
v_all_suppliers 30984 30984 0 0.00 0 109 5 0 0
v_all_wares 40398 40398 0 0.00 0 131 4 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 3983 21 0 0
v_cancel_client_order 3454 3454 0 0.00 0 1271 9 0 0
v_cancel_customer_prepayment 1484 1484 0 0.00 0 6701 22 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 1 3962 15 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 1617 14 0 0
v_cancel_supplier_invoice 770 752 18 2.33 0 1999 29 0 18
v_cancel_supplier_order 832 832 0 0.00 0 3998 23 0 0
v_cancel_write_off 1976 1976 0 0.00 0 2183 27 0 0
v_max_id_clo_ord 5597 5597 0 0.00 0 254 5 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 1478 17 0 0
v_max_id_ord_sup 5530 5530 0 0.00 0 4257 8 0 0
v_max_non_paid_invoice 1528 1528 0 0.00 0 67 8 0 0
v_max_non_paid_realizn 3939 3939 0 0.00 0 560 7 0 0
v_min_id_clo_ord 5597 5597 0 0.00 0 1962 9 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 5273 21 0 0
v_min_id_ord_sup 5530 5530 0 0.00 0 3526 11 0 0
v_min_non_paid_invoice 5893 5893 0 0.00 0 6615 17 0 0
v_min_non_paid_realizn 7110 7110 0 0.00 0 6019 16 0 0
v_our_firm 9866 9866 0 0.00 0 75 5 0 0
v_reserve_write_off 19884 19884 0 0.00 0 6073 12 0 0
x_lock_depdocs_on_canc_invoice 11309 11309 0 0.00 0 22517 145 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 2712 342 0 0
x_make_qstorno_1000_1200 5597 5597 0 0.00 2 46819 1173 0 0
x_make_qstorno_1200_2000 5531 5531 0 0.00 5 83537 2966 0 0
x_make_qstorno_2100_3300 468991 468991 0 0.00 0 133904 231 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-01-2023 13:39:19 to 12-01-2023 13:39:23.

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
11:07:29 917 8192000000 8925808720 8981868544 21029216 0.256 5 4 8 0 21760576 201504 529856
11:08:31 19679 8192000000 9722754976 10021277696 798896656 8.885 100 86 171 0 826938048 7349616 20691776
11:09:58 22309 8192000000 9772005024 10081517568 843743840 9.337 100 88 176 0 877384832 12458848 21182144
11:11:29 10369 8192000000 9803973744 10125811712 874080736 9.641 100 87 174 0 919298864 24898688 20319440
11:12:52 6105 8192000000 9868665984 10208854016 927946048 10.174 100 85 170 0 983047072 36543728 18557296
11:14:03 3245 8192000000 9947429984 10297208832 974008880 10.626 100 88 172 1 1061192592 68899232 18284480
11:15:09 2271 8192000000 9977867504 10334588928 1006085696 10.937 100 89 176 0 1097982368 74036976 17859696
11:16:14 2852 8192000000 10005199232 10369282048 1019911456 11.071 100 86 169 0 1122992704 86552560 16528688
11:17:19 1600 8192000000 10010147968 10378883072 1045020032 11.313 100 92 177 0 1124288512 60872992 18395488
11:18:23 1077 8192000000 10045342336 10423595008 1058440512 11.442 100 96 185 0 1154964592 78600320 17923760
11:19:25 1013 8192000000 10054200736 10435162112 1067860512 11.532 100 97 192 0 1172006288 85735536 18410240
11:20:27 983 8192000000 10045901248 10427363328 1072585664 11.577 100 93 181 1 1167321760 76970176 17765920
11:21:30 1370 8192000000 10067670640 10452873216 1076760176 11.617 100 89 176 1 1191664400 95965328 18938896
11:22:33 1066 8192000000 10056078448 10445524992 1086281056 11.707 100 98 185 1 1179378112 74514608 18582448
11:23:35 1362 8192000000 10030030576 10416680960 1087553824 11.719 100 92 165 6 1148048624 43329840 17164960
11:24:38 1054 8192000000 10024298352 10411769856 1094052672 11.781 100 93 178 1 1147961504 34794544 19114288
11:25:40 1006 8192000000 10061485648 10455896064 1093285168 11.774 100 91 177 2 1182564928 70957744 18322016
11:26:41 1228 8192000000 10063991200 10453532672 1094775952 11.788 100 98 194 0 1188167792 75032688 18359152
11:27:43 925 8192000000 10069189920 10462830592 1102788096 11.864 100 88 164 2 1191124864 71417472 16919296
11:28:46 1111 8192000000 10022108352 10416308224 1093417296 11.775 100 81 148 5 1145624432 36113584 16093552
11:29:48 1322 8192000000 10061178672 10450481152 1097094576 11.810 100 96 176 0 1183921024 68743136 18083312
11:30:49 794 8192000000 10057022704 10443681792 1074973424 11.600 100 94 180 1 1172401456 80052512 17375520
11:31:51 1090 8192000000 10012094704 10392162304 1053390864 11.393 99 93 179 6 1134807856 62683376 18733616
11:32:53 1057 8192000000 10009114912 10386886656 1054866464 11.407 99 84 162 1 1129812064 58824608 16120992
11:33:54 5581 8192000000 9992286064 10374627328 1044377008 11.307 100 59 114 1 1110452000 53743888 12331104
11:35:01 5916 8192000000 9998378112 10375880704 1049136576 11.352 100 85 157 0 1116927728 53029392 14761760
11:36:08 3631 8192000000 9975478784 10357362688 1035237648 11.219 99 61 115 1 1097971312 49383792 13349872
11:37:12 1083 8192000000 9980935776 10352984064 1031279840 11.181 100 85 164 1 1105268144 57262976 16725328
11:38:23 16871 8192000000 10001175856 10382508032 1044448448 11.307 100 68 129 0 1134065536 76522816 13094272
11:39:44 2406 8192000000 10021866128 10410291200 1067153568 11.525 100 86 157 3 1143279632 57392800 18733264
11:40:51 16928 8192000000 10048502656 10443194368 1078582176 11.634 100 70 132 0 1180149504 88241152 13326176
11:42:16 1051 8192000000 10079420032 10461364224 1095983392 11.800 100 93 161 0 1193011984 80675696 16352896
11:43:19 2551 8192000000 10127865584 10516295680 1108223168 11.916 100 88 173 1 1253014592 125415552 19375872
11:44:23 1633 8192000000 10174883536 10571735040 1125988816 12.084 100 91 174 0 1295383616 150731472 18663328
11:45:30 2025 8192000000 10195644832 10593288192 1132112944 12.141 100 84 164 0 1310706464 160100528 18492992
11:46:38 3713 8192000000 10226069584 10622095360 1139147744 12.208 100 74 146 1 1338745280 182826720 16770816
11:47:47 3981 8192000000 10272512816 10669486080 1156571920 12.371 100 85 156 0 1380697312 207953952 16171440
11:48:57 5461 8192000000 10261575760 10687684608 1165841504 12.458 100 67 129 1 1380887248 201020432 14025312
11:50:06 3432 8192000000 10318607136 10727329792 1185136368 12.638 100 84 162 0 1427877264 223947328 18793568
11:51:14 1566 8192000000 10366234800 10770432000 1186794608 12.654 100 95 183 1 1480611136 273132752 20683776
11:52:19 1678 8192000000 10384653968 10797912064 1184339888 12.631 100 93 179 0 1491127376 287767600 19019888
11:53:28 915 8192000000 10390340096 10830622720 1177870880 12.570 100 96 186 0 1499422544 299511456 22040208
11:54:30 1191 8192000000 10410719120 10826022912 1188399024 12.668 100 87 172 0 1518896992 309506304 20991664
11:55:36 794 8192000000 10439471424 10842951680 1194962032 12.730 100 97 186 1 1544364496 328666656 20735808
11:56:39 1267 8192000000 10472808240 10900746240 1182439920 12.613 99 95 184 0 1571704480 367020464 22244096
11:57:44 1043 8192000000 10475854912 10884554752 1190705744 12.690 100 97 189 0 1579412000 365986064 22720192
11:58:51 1934 8192000000 10484133200 10912903168 1191636848 12.699 100 93 176 0 1593378848 379751984 21990016
11:59:56 855 8192000000 10517911664 10935996416 1187935744 12.664 100 99 195 1 1623751440 411950336 23865360
12:01:00 8544 8192000000 10531137808 10971697152 1181105472 12.601 99 63 125 1 1633094304 436202672 15786160
12:02:17 1805 8192000000 10558404128 11011801088 1188274256 12.667 100 73 143 2 1658553296 452398656 17880384
12:03:23 1492 8192000000 10600277744 11028357120 1194841632 12.728 100 90 174 1 1716466752 500326112 21299008
12:04:30 890 8192000000 10608772112 11073302528 1189566016 12.679 100 82 159 0 1708741328 499011120 20164192
12:05:33 4715 8192000000 10541715888 11037622272 1181347248 12.603 100 60 118 0 1642814832 446607984 14859600
12:06:46 10040 8192000000 10485228928 10970746880 1179014576 12.581 100 56 111 1 1602156128 409855680 13285872
12:07:58 8581 8192000000 10464294880 10925547520 1165056480 12.451 100 42 84 0 1586799488 410725040 11017968
12:09:14 25704 8192000000 10337349856 10868498432 1144557488 12.258 100 29 56 0 1467623808 315004832 8061488
12:11:00 112076 8192000000 10133142320 10706161664 1111373360 11.945 97 15 31 0 1272312832 156894288 4045184
12:13:53 47532 8192000000 10068743152 10518847488 1130236960 12.124 100 33 61 1 1171206944 34166992 6802992
12:16:44 108692 8192000000 10054275520 10488107008 1063131120 11.486 93 25 45 1 1112047808 43853664 5063024
12:19:33 83472 8192000000 10089370176 10532487168 1127561744 12.098 99 17 32 0 1190471552 59270656 3639152
12:22:29 25080 8192000000 10101877120 10525401088 1101939744 11.856 97 47 88 1 1184477008 72574064 9963200
12:24:00 11786 8192000000 10173303440 10607165440 1138750128 12.204 100 67 127 0 1268468160 115582208 14135824
12:25:14 144523 8192000000 10213183616 10675560448 1154582976 12.352 100 27 52 0 1299818096 138581040 6654080
12:30:17 65420 8192000000 10098089600 10638544896 1145630640 12.268 100 27 52 2 1280103072 127439136 7033296
12:32:39 76037 8192000000 10058358352 10512052224 1126040592 12.084 99 18 36 0 1165776464 35861840 3874032
12:34:56 11450 8192000000 10123760304 10559356928 1135902224 12.177 100 53 106 0 1216690256 70313808 10474224
12:36:11 113612 8192000000 10112253824 10574159872 1067598368 11.529 93 14 27 0 1151789472 81359360 2831744
12:39:05 15346 8192000000 10076712176 10517577728 1129749344 12.119 100 33 66 0 1166148592 29732112 6667136
12:40:25 28729 8192000000 10069471248 10524094464 1117099648 12.000 99 28 56 0 1158416672 35964560 5352464
12:41:58 6997 8192000000 10112570880 10534948864 1130472208 12.126 100 52 105 2 1202620080 61126800 11021072
12:43:12 17049 8192000000 10087346912 10515329024 1126842784 12.092 100 41 82 0 1176325968 41760032 7723152
12:44:29 56769 8192000000 10097529664 10543214592 1125910656 12.083 99 56 112 0 1187855776 50905552 11039568
12:46:32 21870 8192000000 10113675184 10539405312 1120015088 12.027 99 53 103 0 1209855040 79344416 10495536
12:47:54 68675 8192000000 10131916400 10634293248 1138334832 12.200 100 15 30 0 1263478272 121781840 3361600
12:50:03 80391 8192000000 10126490256 10623340544 1114458416 11.975 98 10 20 0 1223995664 107357120 2180128
12:52:26 65380 8192000000 10043783392 10505961472 1066591056 11.520 95 16 32 0 1128692544 58107616 3993872
12:54:32 39226 8192000000 10090688768 10531590144 1096747360 11.807 97 22 44 0 1157686608 56192192 4747056
12:56:12 56982 8192000000 10132368560 10572894208 1108713264 11.920 98 23 41 1 1195318112 82176896 4427952
12:58:09 36791 8192000000 10065174432 10497261568 1122520384 12.051 100 45 85 1 1162661712 30291232 9850096
12:59:49 11630 8192000000 10105476384 10530324480 1132110368 12.141 100 50 100 0 1194585408 52664384 9810656
13:01:05 19420 8192000000 10116085200 10540195840 1122381168 12.049 99 48 95 0 1187761440 55445952 9934320
13:02:26 53611 8192000000 10073616240 10507841536 1105687216 11.892 99 27 52 0 1156912048 45677200 5547632
13:04:43 21959 8192000000 10077998624 10510766080 1110668432 11.939 99 33 66 0 1174596864 57361168 6567264
13:06:08 113887 8192000000 10040261552 10497228800 1092929184 11.771 97 27 53 1 1139888384 41985824 4973376
13:10:10 78539 8192000000 10032694432 10523140096 1081141040 11.658 96 9 17 0 1127064832 43740816 2182976
13:13:33 72075 8192000000 10101210096 10570964992 1107719584 11.911 99 10 20 0 1181744848 71915392 2109872
13:15:58 64174 8192000000 10124125504 10562093056 1118272480 12.011 98 32 61 1 1192511440 68320544 5918416
13:18:09 30677 8192000000 10078634864 10546896896 1121944896 12.045 99 24 49 0 1177019936 50097680 4977360
13:19:44 40173 8192000000 10152288272 10603089920 1137967952 12.196 99 29 58 0 1248480832 104527152 5985728
13:21:26 41021 8192000000 10190179088 10643632128 1150970160 12.319 100 29 56 0 1267683312 110620192 6092960
13:23:08 91529 8192000000 10129852064 10625458176 1154199552 12.349 100 24 48 0 1282689776 123473328 5016896
13:25:40 15939 8192000000 10166395136 10599505920 1142675376 12.241 99 51 100 0 1251499488 98296928 10527184
13:26:56 61855 8192000000 10092016400 10533310464 1137895696 12.196 99 29 56 1 1173244528 29505424 5843408
13:28:59 111070 8192000000 10096141216 10538541056 1149955792 12.309 100 25 48 0 1189412880 34469184 4987904
13:31:55 80095 8192000000 10107995808 10614112256 1148628928 12.297 100 8 16 0 1222215808 71787776 1799104
13:34:16 81250 8192000000 10127039584 10605674496 1128446864 12.107 98 17 34 0 1216574464 84329520 3798080
13:36:37 53794 8192000000 9413683312 9815416832 529391664 6.070 47 8 14 1 591917520 60924336 1601520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-01-2023 13:39:25 to 12-01-2023 13:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 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 12-01-2023 13:39:42 to 12-01-2023 13:39:44.

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          224371
MON$OLDEST_ACTIVE               224670
MON$OLDEST_SNAPSHOT             224670
MON$NEXT_TRANSACTION            224670
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                       2069216
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 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 ---
:252: 
:253: mscs-test-161.budzdorov.ru    Thu Jan 12 13:37:35 2023
:254:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:255: 
:256: 
:257: mscs-test-161.budzdorov.ru    Thu Jan 12 13:37:36 2023
:258:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:259: 
:260: 
:261: mscs-test-161.budzdorov.ru    Thu Jan 12 13:37:37 2023
:262:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:263: 
:264: 
:265: mscs-test-161.budzdorov.ru    Thu Jan 12 13:37:48 2023
:266:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:267: 
--- end of diff output ---

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

12.01.23 13:39:45. 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-01-12 13:39:48 No severe PSQL-related problems occured
12.01.23 13:39:49. End of report.