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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-16 10:37:18
DTS_END                         2022-07-16 10:37:18.6230
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/41412
TRN_ID                          226520
ATT_ID                          636
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: 9142.44 25788 1097093
customer order: creation 174.17 476 20900
customer order: refuse 15.26 444 1831
order to supplier: creation 47.13 1360 5655
order to supplier: removal 3.56 143423 427
invoice (draft): creation 47.43 3323 5691
invoice (draft): removal 50.02 962 6002
invoice accept: apply 42.54 24040 5105
invoice accept: cancel 45.20 1208 5424
customer reserve: creation 3991.11 225 478933
customer reserve: removal 4427.02 89 531243
realization accept: apply 84.86 306 10183
realization accept: cancel 83.15 160 9978
payment from customer: creation 59.52 197 7142
payment from customer: removal 5.90 174 708
payment to supplier: creation 49.66 212 5959
payment to supplier: removal 3.71 131 445
service: total inventory turnovers 3.73 4652 447
service: total monetary turnovers 7.54 368 905
service: aggregate perf. data 0.93 17326 111
service: refresh index statistics 0.03 316678 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-07-2022 10:38:27 to 16-07-2022 10:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5323
TEST_TIME 2 4922
TEST_TIME 3 5024
TEST_TIME 4 4646
TEST_TIME 5 5033
TEST_TIME 6 5481
TEST_TIME 7 6241
TEST_TIME 8 6387
TEST_TIME 9 6712
TEST_TIME 10 6723
TEST_TIME 11 6803
TEST_TIME 12 7105
TEST_TIME 13 7135
TEST_TIME 14 7240
TEST_TIME 15 7387
TEST_TIME 16 7533
TEST_TIME 17 7483
TEST_TIME 18 7585
TEST_TIME 19 7790
TEST_TIME 20 7949
TEST_TIME 21 8072
TEST_TIME 22 8192
TEST_TIME 23 8242
TEST_TIME 24 8361
TEST_TIME 25 8413
TEST_TIME 26 8851
TEST_TIME 27 9232
TEST_TIME 28 9387
TEST_TIME 29 9715
TEST_TIME 30 10120
TEST_TIME 31 10147
TEST_TIME 32 10607
TEST_TIME 33 10985
TEST_TIME 34 10919
TEST_TIME 35 10874
TEST_TIME 36 10923
TEST_TIME 37 10967
TEST_TIME 38 10875
TEST_TIME 39 10766
TEST_TIME 40 10684
TEST_TIME 41 10655
TEST_TIME 42 10582
TEST_TIME 43 10519
TEST_TIME 44 10475
TEST_TIME 45 10333
TEST_TIME 46 10239
TEST_TIME 47 10303
TEST_TIME 48 10381
TEST_TIME 49 10223
TEST_TIME 50 10069
TEST_TIME 51 9994
TEST_TIME 52 10017
TEST_TIME 53 10011
TEST_TIME 54 9860
TEST_TIME 55 9793
TEST_TIME 56 9829
TEST_TIME 57 9763
TEST_TIME 58 9789
TEST_TIME 59 9808
TEST_TIME 60 9837
TEST_TIME 61 9748
TEST_TIME 62 9717
TEST_TIME 63 9596
TEST_TIME 64 9675
TEST_TIME 65 9598
TEST_TIME 66 9590
TEST_TIME 67 9698
TEST_TIME 68 9618
TEST_TIME 69 9613
TEST_TIME 70 9662
TEST_TIME 71 9624
TEST_TIME 72 9580
TEST_TIME 73 9545
TEST_TIME 74 9697
TEST_TIME 75 9681
TEST_TIME 76 9664
TEST_TIME 77 9767
TEST_TIME 78 9833
TEST_TIME 79 9756
TEST_TIME 80 9677
TEST_TIME 81 9641
TEST_TIME 82 9556
TEST_TIME 83 9547
TEST_TIME 84 9527
TEST_TIME 85 9504
TEST_TIME 86 9456
TEST_TIME 87 9397
TEST_TIME 88 9350
TEST_TIME 89 9287
TEST_TIME 90 9323
TEST_TIME 91 9401
TEST_TIME 92 9424
TEST_TIME 93 9401
TEST_TIME 94 9443
TEST_TIME 95 9412
TEST_TIME 96 9480
TEST_TIME 97 9498
TEST_TIME 98 9532
TEST_TIME 99 9543
TEST_TIME 100 9559
TEST_TIME 101 9579
TEST_TIME 102 9557
TEST_TIME 103 9471
TEST_TIME 104 9453
TEST_TIME 105 9418
TEST_TIME 106 9390
TEST_TIME 107 9386
TEST_TIME 108 9357
TEST_TIME 109 9339
TEST_TIME 110 9321
TEST_TIME 111 9273
TEST_TIME 112 9236
TEST_TIME 113 9219
TEST_TIME 114 9183
TEST_TIME 115 9201
TEST_TIME 116 9174
TEST_TIME 117 9191
TEST_TIME 118 9225
TEST_TIME 119 9226
TEST_TIME 120 9163

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-07-2022 10:38:31 to 16-07-2022 10:38:34.

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 1537465 1537465 0 0.00 0 31159 13 0 0
doc_list_biud 1537459 1537459 0 0.00 0 32539 37 0 0
sp_add_invoice_to_stock 5252 5105 147 2.79 43 183510 24040 0 147
sp_cancel_adding_invoice 5424 5424 0 0.00 6 111799 1208 0 0
sp_cancel_client_order 1831 1831 0 0.00 6 15806 444 0 0
sp_cancel_customer_reserve 531243 531243 0 0.00 1 32557 90 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 4 13881 175 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 4 10269 132 0 0
sp_cancel_supplier_invoice 6019 6002 17 0.28 3 16958 962 0 17
sp_cancel_supplier_order 427 427 0 0.00 6 1498961 143423 0 0
sp_cancel_write_off 9978 9978 0 0.00 2 16906 160 0 0
sp_client_order 20900 20900 0 0.00 5 27463 477 0 0
sp_customer_reserve 478933 478933 0 0.00 0 42682 225 0 0
sp_fill_shopping_cart 32234 32234 0 0.00 1 19493 91 0 0
sp_fill_shopping_cart_clo_res 478916 478916 0 0.00 0 10882 16 0 0
sp_get_clo_for_invoice 5076 5076 0 0.00 0 31990 274 0 0
sp_kill_cost_storno 16555 16555 0 0.00 0 25217 71 0 0
sp_kill_qstorno_ret_qs2qd 1078893 1078893 0 0.00 0 32504 39 0 0
sp_kill_qty_storno 570164 570164 0 0.00 0 32537 91 0 0
sp_lock_selected_doc 21212 21212 0 0.00 0 11377 9 0 0
sp_make_cost_storno 28359 28359 0 0.00 0 13626 76 0 0
sp_multiply_rows_for_pdistr 28358 28358 0 0.00 0 31153 47 0 0
sp_multiply_rows_for_qdistr 20899 20899 0 0.00 0 11677 155 0 0
sp_pay_from_customer 7142 7142 0 0.00 5 31165 197 0 0
sp_pay_to_supplier 5959 5959 0 0.00 6 14745 212 0 0
sp_payment_common 13098 13098 0 0.00 2 8922 49 0 0
sp_qd_handle_on_cancel_clo 1831 1831 0 0.00 1 15780 354 0 0
sp_qd_handle_on_invoice_upd_sts 10500 10500 0 0.00 0 15790 520 0 0
sp_qd_handle_on_reserve_upd_sts 10183 10183 0 0.00 0 16687 70 0 0
sp_reserve_write_off 10183 10183 0 0.00 9 21418 307 0 0
sp_supplier_invoice 5691 5691 0 0.00 9 95371 3323 0 0
sp_supplier_order 5655 5655 0 0.00 7 34397 1360 0 0
srv_aggregate_perf_data 111 111 0 0.00 2432 211650 17326 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 713 124734 33212 0 0
srv_find_qd_qs_mism 1046697 1046697 0 0.00 0 8388 9 0 0
srv_make_invnt_saldo 447 447 0 0.00 43 163165 4653 0 0
srv_make_money_saldo 905 905 0 0.00 1 9623 368 0 0
srv_recalc_idx_stat 4 4 0 0.00 76857 563992 316678 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5109 5109 0 0.00 1 16213 911 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 1 6002 455 0 0
t$perf-norm:sp_cancel_client_order 1831 1831 0 0.00 0 3605 30 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 2053 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 1036 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 3986 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 664 23 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 68962 5767 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 5622 23 0 0
t$perf-norm:sp_client_order 20900 20900 0 0.00 0 4395 34 0 0
t$perf-norm:sp_customer_reserve 1070 1070 0 0.00 0 2301 17 0 0
t$perf-norm:sp_pay_from_customer 7143 7143 0 0.00 0 9365 22 0 0
t$perf-norm:sp_pay_to_supplier 5959 5959 0 0.00 0 5861 19 0 0
t$perf-norm:sp_reserve_write_off 10183 10183 0 0.00 0 4249 22 0 0
t$perf-norm:sp_supplier_invoice 5691 5691 0 0.00 0 4968 30 0 0
t$perf-norm:sp_supplier_order 5655 5655 0 0.00 0 15009 34 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 475 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 1520 25 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 2278 21 0 0
t$perf-norm:srv_make_money_saldo 905 905 0 0.00 0 4477 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 139 55 0 0
v_add_invoice_to_stock 10434 10140 294 2.81 0 20303 37 0 294
v_all_customers 38070 38070 0 0.00 0 355 4 0 0
v_all_suppliers 31718 31718 0 0.00 0 175 4 0 0
v_all_wares 41795 41795 0 0.00 0 156 4 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 14334 33 0 0
v_cancel_client_order 3662 3662 0 0.00 0 10925 17 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 4665 25 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 2434 13 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 10156 25 0 0
v_cancel_supplier_invoice 814 780 34 4.17 0 2477 27 0 34
v_cancel_supplier_order 854 854 0 0.00 0 1545 11 0 0
v_cancel_write_off 2064 2064 0 0.00 0 14363 29 0 0
v_max_id_clo_ord 5652 5652 0 0.00 0 160 5 0 0
v_max_id_clo_res 1070 1070 0 0.00 0 2681 15 0 0
v_max_id_ord_sup 5683 5683 0 0.00 0 7440 10 0 0
v_max_non_paid_invoice 1434 1434 0 0.00 0 1090 9 0 0
v_max_non_paid_realizn 3777 3777 0 0.00 0 3013 8 0 0
v_min_id_clo_ord 5652 5652 0 0.00 0 3544 11 0 0
v_min_id_clo_res 1070 1070 0 0.00 0 5045 22 0 0
v_min_id_ord_sup 5683 5683 0 0.00 0 9158 14 0 0
v_min_non_paid_invoice 5958 5958 0 0.00 0 8523 21 0 0
v_min_non_paid_realizn 7140 7140 0 0.00 0 6612 16 0 0
v_our_firm 10450 10450 0 0.00 0 62 5 0 0
v_reserve_write_off 20366 20366 0 0.00 0 18895 18 0 0
x_lock_depdocs_on_canc_invoice 11036 11036 0 0.00 0 12796 117 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 14159 341 0 0
x_make_qstorno_1000_1200 5655 5655 0 0.00 3 34383 1193 0 0
x_make_qstorno_1200_2000 5691 5691 0 0.00 4 92509 3062 0 0
x_make_qstorno_2100_3300 478933 478933 0 0.00 0 42680 196 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-07-2022 10:38:35 to 16-07-2022 10:38:39.

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
08:07:22 3 8192000000 8803551216 8816549888 8160048 0.099 5 1 2 0 8264800 68976 35776
08:08:23 23151 8192000000 9720370336 10017624064 798294672 8.879 100 90 180 0 827707296 7748624 21664000
08:09:57 27662 8192000000 9766271664 10074644480 837438064 9.274 100 85 170 0 869745600 12206208 20101328
08:11:32 11882 8192000000 9805903120 10125791232 875575152 9.656 100 86 172 0 918885072 24210928 19098992
08:12:49 9089 8192000000 9863932240 10198188032 917682352 10.073 100 86 170 0 979950016 44094752 18172912
08:14:01 3573 8192000000 9908647280 10253385728 954628912 10.436 100 81 160 0 1031626240 60755824 16241504
08:15:07 1375 8192000000 9981602208 10331873280 987364848 10.756 100 95 180 0 1094539088 89093616 18080624
08:16:10 1340 8192000000 10003486608 10363645952 1015313696 11.027 100 90 175 1 1115412176 82917408 17181072
08:17:13 1005 8192000000 10029748416 10395713536 1039963120 11.264 100 98 190 1 1152127104 93632224 18531760
08:18:16 793 8192000000 10025892672 10400636928 1060184112 11.458 100 98 192 2 1145082272 65400688 19497472
08:19:18 1310 8192000000 10025489728 10402770944 1066463712 11.518 100 90 175 1 1153097680 67544672 19089296
08:20:20 1055 8192000000 10039055664 10414718976 1076019600 11.610 100 97 188 1 1158178816 63477920 18681296
08:21:21 1458 8192000000 10034899744 10418737152 1077224560 11.621 100 86 161 3 1155791088 60735328 17831200
08:22:24 910 8192000000 10036284080 10425040896 1088932512 11.733 100 88 170 3 1160717888 53936992 17848384
08:23:26 1063 8192000000 10062929872 10452705280 1091499968 11.757 100 97 188 0 1181607568 71260528 18847072
08:24:28 1128 8192000000 10053681520 10440691712 1096070992 11.800 100 98 188 0 1178113728 62545040 19497696
08:25:29 1143 8192000000 10064403856 10450378752 1098767328 11.826 100 95 184 0 1183242832 65567456 18908048
08:26:32 1031 8192000000 10085707488 10476838912 1101296976 11.850 100 92 180 0 1200568144 80903312 18367856
08:27:34 5047 8192000000 10049363520 10443808768 1096800288 11.807 100 68 125 0 1160399936 51490400 12109248
08:28:41 2368 8192000000 10039655168 10436308992 1098251120 11.821 100 66 123 1 1168826016 57760720 12814176
08:29:44 5215 8192000000 9998585712 10374844416 1060157392 11.458 99 79 153 4 1117963280 40409712 17396176
08:30:51 1566 8192000000 10013830784 10394349568 1044229424 11.305 98 89 170 3 1135065920 73469008 17367488
08:31:54 1525 8192000000 10014953488 10392621056 1055577376 11.414 100 95 183 1 1137220768 64760720 16882672
08:32:56 1178 8192000000 10029535168 10407038976 1050084288 11.361 100 97 185 2 1157905408 88144880 19676240
08:33:58 1236 8192000000 10019540400 10399944704 1052068416 11.381 100 97 188 1 1146291152 73498224 20724512
08:35:02 1055 8192000000 9964275200 10336546816 1019916160 11.071 99 84 161 0 1088010912 50419056 17675696
08:36:03 1474 8192000000 9997281536 10369327104 1037827520 11.244 100 87 169 1 1117449488 62035792 17586176
08:37:06 969 8192000000 10001733328 10377588736 1051986864 11.380 100 88 165 1 1126610352 56893952 17729536
08:38:17 1977 8192000000 10004562752 10379309056 1061459568 11.470 100 97 178 1 1122681120 40692400 20529152
08:39:34 1982 8192000000 10049813968 10438119424 1075396752 11.604 100 74 133 1 1171030512 80768688 14865072
08:40:45 10560 8192000000 10044109392 10442616832 1091106208 11.753 100 74 141 0 1165148496 57804752 16237536
08:42:04 3313 8192000000 10104089536 10497888256 1103063344 11.867 100 74 138 2 1215111920 97744816 14303760
08:43:14 705 8192000000 10140962784 10530664448 1111856416 11.950 100 94 179 2 1260945584 129003616 20085552
08:44:17 1034 8192000000 10139898816 10542473216 1118317136 12.011 100 94 177 5 1255065376 118825216 17923024
08:45:20 1859 8192000000 10175247120 10569170944 1132968752 12.149 100 90 171 0 1289619840 138517520 18133568
08:46:27 1710 8192000000 10175063936 10586243072 1148669296 12.297 100 76 143 1 1289366608 124937120 15760192
08:47:32 1177 8192000000 10210426720 10608660480 1169818096 12.495 100 94 179 1 1321174080 132159376 19196608
08:48:37 2039 8192000000 10223375120 10634842112 1177596928 12.568 100 86 166 2 1343533264 146351408 19584928
08:49:42 959 8192000000 10259768752 10668048384 1178069168 12.572 100 94 177 0 1369222896 171544224 19609504
08:50:48 1235 8192000000 10295438544 10701647872 1191252288 12.695 100 88 170 1 1406686096 196078208 19355600
08:51:52 973 8192000000 10343876000 10757603328 1200034240 12.777 100 95 190 0 1456034512 234084880 21915392
08:52:57 1678 8192000000 10360934544 10766082048 1194184240 12.722 100 94 182 2 1470604912 256241856 20178816
08:54:01 1898 8192000000 10391247952 10805809152 1198306272 12.761 100 87 166 0 1499374800 281533312 19535216
08:55:06 742 8192000000 10422050704 10847162368 1199148336 12.768 100 95 185 0 1529312160 308637024 21526800
08:56:12 2505 8192000000 10456340624 10894893056 1206296368 12.835 100 86 170 1 1559144000 332927344 19920288
08:57:17 1491 8192000000 10442888544 10865299456 1200966480 12.785 100 95 181 1 1554529520 332119664 21443376
08:58:21 1122 8192000000 10493872480 10901037056 1204472016 12.818 100 94 186 2 1596292848 370956240 20864592
08:59:25 841 8192000000 10508997792 10928521216 1197257072 12.751 100 91 175 2 1612725232 394487808 20980352
09:00:28 1794 8192000000 10477948080 10912555008 1176974400 12.562 100 64 126 0 1587426672 394864192 15588080
09:01:33 977 8192000000 10526470640 10939510784 1183120032 12.619 100 91 175 0 1632367216 427781920 21465264
09:02:37 2794 8192000000 10525189648 10986082304 1174909984 12.543 99 99 195 1 1623395456 423674608 24810864
09:03:41 7316 8192000000 10447565632 10945777664 1170957328 12.506 100 54 109 3 1552701456 367438768 14305360
09:04:59 10324 8192000000 10428950000 10881994752 1153193824 12.339 99 43 82 0 1520640928 356292896 11154208
09:06:35 9125 8192000000 10376268864 10843447296 1167186960 12.471 100 38 76 0 1497125696 320263664 9675072
09:07:45 32787 8192000000 10276555184 10715590656 1142238928 12.237 98 22 44 0 1360325456 212601136 5485392
09:09:31 37900 8192000000 10176732592 10676203520 1140165568 12.217 100 16 32 0 1326953520 182728672 4059280
09:11:17 52059 8192000000 10112847808 10547720192 1136884640 12.186 100 29 56 0 1247461248 104728144 5848464
09:13:09 62994 8192000000 10059956448 10599804928 1088983808 11.733 96 12 24 0 1204400352 112739760 2676784
09:17:07 54212 8192000000 10078102592 10537668608 1116215840 11.991 99 15 30 0 1190079312 70894512 2968960
09:19:58 74322 8192000000 10050028992 10492071936 1101360448 11.851 97 26 53 0 1145225664 38746352 5118864
09:22:12 38672 8192000000 10109494784 10530578432 1137547216 12.192 100 48 94 2 1185077216 37944256 9585744
09:23:56 103864 8192000000 10072492656 10576379904 1122242288 12.048 98 17 35 1 1200450928 74206768 4001872
09:26:43 124733 8192000000 10120289008 10575749120 1117935760 12.007 98 32 63 0 1189428096 64724784 6767552
09:29:48 50138 8192000000 10143215264 10600308736 1120740480 12.034 98 39 72 1 1244342944 115312016 8290448
09:31:42 40113 8192000000 10130527888 10564554752 1121973456 12.046 99 42 83 0 1214634224 83695888 8964880
09:33:23 31440 8192000000 10103403504 10540613632 1091584624 11.758 96 18 36 0 1168289968 72658320 4047024
09:35:04 10017 8192000000 10116994480 10541371392 1120273968 12.030 99 55 100 1 1205081712 74208448 10599296
09:36:16 36731 8192000000 10139956976 10609938432 1141243696 12.227 100 29 56 0 1268493904 120649056 6601152
09:38:11 47877 8192000000 10108177440 10563448832 1143904128 12.252 100 13 24 1 1201649040 55352032 2392880
09:40:04 52337 8192000000 10094826128 10571632640 1123494720 12.060 98 18 36 0 1191872656 64811920 3566016
09:41:58 56656 8192000000 10089779280 10530562048 1128867424 12.111 99 10 16 0 1164597664 34217184 1513056
09:43:55 60286 8192000000 10097752912 10599477248 1126320992 12.087 98 7 10 0 1179259792 51953392 985408
09:45:56 6120 8192000000 10102936848 10540843008 1127584960 12.099 99 56 107 1 1184901408 46544288 10772160
09:47:03 72859 8192000000 10085532384 10526310400 1094733312 11.788 96 27 46 0 1134396848 34691600 4971936
09:49:25 36056 8192000000 10134523472 10561572864 1085856256 11.703 97 24 48 0 1163199520 72330368 5012896
09:51:04 41848 8192000000 10151513280 10656296960 1131799984 12.138 100 20 40 0 1275215120 138822192 4592944
09:52:49 26171 8192000000 10154322560 10583130112 1102733856 11.864 97 50 95 3 1204575024 90876608 10964560
09:54:52 69566 8192000000 10083557408 10568978432 1119843104 12.026 99 24 44 0 1175974288 51259888 4871296
09:57:05 71442 8192000000 10063158432 10516844544 1130376512 12.125 100 29 58 0 1152190704 15563936 6250256
09:59:21 31405 8192000000 10063188672 10497331200 1117632112 12.005 99 38 76 1 1156765472 31065280 8068080
10:00:52 56332 8192000000 10062707376 10532028416 1099350432 11.831 97 15 30 0 1160976336 58184784 3441120
10:02:49 50762 8192000000 10100788544 10563203072 1109287728 11.926 98 18 36 0 1179204160 66113136 3803296
10:04:45 53380 8192000000 10114927184 10573438976 1107687936 11.911 99 6 12 0 1195418112 86358064 1372112
10:07:07 48949 8192000000 10138839424 10589970432 1119487792 12.022 99 23 45 0 1221033696 96698624 4847280
10:09:04 77784 8192000000 10102631664 10539020288 1096143632 11.801 96 25 50 0 1173318896 72106416 5068848
10:11:28 45024 8192000000 10114711776 10553479168 1088667984 11.730 96 27 54 0 1169542176 75331568 5542624
10:13:34 25883 8192000000 10080724208 10519846912 1103789648 11.874 98 43 85 0 1157086304 44578864 8717792
10:15:18 52789 8192000000 10122470560 10633310208 1101793584 11.855 97 30 60 1 1174368080 66306048 6268448
10:17:21 51274 8192000000 10110818688 10561355776 1104649824 11.882 98 25 51 0 1167821072 58167008 5004240
10:20:33 88212 8192000000 10059728400 10551693312 1129331792 12.115 100 32 62 0 1194602672 58256896 7013984
10:23:03 43982 8192000000 10110954016 10550591488 1134494496 12.164 100 52 102 1 1193277712 48140304 10642912
10:24:49 55730 8192000000 10073579872 10559008768 1129187440 12.114 99 15 30 0 1178945920 46083136 3675344
10:26:46 55152 8192000000 10059103920 10539925504 1137593056 12.193 100 10 20 0 1174284560 34706400 1985104
10:28:41 51576 8192000000 10075126928 10538598400 1123798128 12.063 98 19 38 0 1169661568 41960336 3903104
10:30:38 70262 8192000000 10122526400 10589294592 1144682224 12.260 100 18 36 0 1214640816 65612688 4345904
10:32:48 9660 8192000000 10059351632 10501083136 1131607792 12.137 99 45 73 1 1156398336 16678064 8112480
10:34:10 46091 8192000000 10125723840 10604023808 1134585904 12.165 100 25 50 0 1204115344 64212224 5317216
10:36:11 46947 8192000000 10080586064 10550353920 1112316000 11.954 98 32 64 1 1188625520 69842768 6466752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

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

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          224820
MON$OLDEST_ACTIVE               227337
MON$OLDEST_SNAPSHOT             227337
MON$NEXT_TRANSACTION            227337
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                       2089760
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2799 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2799 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2799 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2799 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Sat Jul 16 10:37:21 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Sat Jul 16 10:37:24 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
--- end of diff output ---

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

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