Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.06.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 15721868 kB
MemAvailable 31229000 kB
Buffers 0 kB
Cached 15943624 kB
SwapCached 2452 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-06-17 07:37:15
DTS_END                         2022-06-17 07:37:15.9490
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/50894
TRN_ID                          215282
ATT_ID                          571
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: 9071.45 24034 1088574
customer order: creation 168.14 487 20177
customer order: refuse 14.31 488 1717
order to supplier: creation 43.68 1449 5242
order to supplier: removal 3.57 187591 428
invoice (draft): creation 43.61 3562 5233
invoice (draft): removal 51.17 1107 6140
invoice accept: apply 40.52 29065 4862
invoice accept: cancel 46.50 1399 5580
customer reserve: creation 3803.99 271 456479
customer reserve: removal 4567.77 114 548132
realization accept: apply 79.56 304 9547
realization accept: cancel 82.63 171 9916
payment from customer: creation 56.92 190 6830
payment from customer: removal 5.84 126 701
payment to supplier: creation 47.51 211 5701
payment to supplier: removal 3.36 134 403
service: total inventory turnovers 3.71 5043 445
service: total monetary turnovers 7.71 426 925
service: aggregate perf. data 0.93 16924 112
service: refresh index statistics 0.03 231613 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-06-2022 07:38:07 to 17-06-2022 07:38:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8585
TEST_TIME 2 7579
TEST_TIME 3 6447
TEST_TIME 4 6720
TEST_TIME 5 6392
TEST_TIME 6 6429
TEST_TIME 7 6513
TEST_TIME 8 6582
TEST_TIME 9 6636
TEST_TIME 10 6891
TEST_TIME 11 6931
TEST_TIME 12 6744
TEST_TIME 13 6886
TEST_TIME 14 7017
TEST_TIME 15 6987
TEST_TIME 16 6970
TEST_TIME 17 7018
TEST_TIME 18 7233
TEST_TIME 19 7292
TEST_TIME 20 7300
TEST_TIME 21 7372
TEST_TIME 22 7397
TEST_TIME 23 7385
TEST_TIME 24 7275
TEST_TIME 25 7406
TEST_TIME 26 7525
TEST_TIME 27 7737
TEST_TIME 28 7784
TEST_TIME 29 7809
TEST_TIME 30 8005
TEST_TIME 31 8257
TEST_TIME 32 8290
TEST_TIME 33 8715
TEST_TIME 34 8939
TEST_TIME 35 9096
TEST_TIME 36 9074
TEST_TIME 37 9127
TEST_TIME 38 9669
TEST_TIME 39 9893
TEST_TIME 40 10633
TEST_TIME 41 10767
TEST_TIME 42 10673
TEST_TIME 43 10551
TEST_TIME 44 10442
TEST_TIME 45 10465
TEST_TIME 46 10335
TEST_TIME 47 10151
TEST_TIME 48 10035
TEST_TIME 49 9960
TEST_TIME 50 10058
TEST_TIME 51 9961
TEST_TIME 52 9813
TEST_TIME 53 9713
TEST_TIME 54 9658
TEST_TIME 55 9595
TEST_TIME 56 9641
TEST_TIME 57 9704
TEST_TIME 58 9666
TEST_TIME 59 9582
TEST_TIME 60 9645
TEST_TIME 61 9632
TEST_TIME 62 9595
TEST_TIME 63 9573
TEST_TIME 64 9535
TEST_TIME 65 9603
TEST_TIME 66 9536
TEST_TIME 67 9560
TEST_TIME 68 9495
TEST_TIME 69 9469
TEST_TIME 70 9548
TEST_TIME 71 9579
TEST_TIME 72 9583
TEST_TIME 73 9596
TEST_TIME 74 9638
TEST_TIME 75 9582
TEST_TIME 76 9650
TEST_TIME 77 9616
TEST_TIME 78 9541
TEST_TIME 79 9458
TEST_TIME 80 9446
TEST_TIME 81 9433
TEST_TIME 82 9378
TEST_TIME 83 9312
TEST_TIME 84 9321
TEST_TIME 85 9240
TEST_TIME 86 9211
TEST_TIME 87 9195
TEST_TIME 88 9182
TEST_TIME 89 9121
TEST_TIME 90 9146
TEST_TIME 91 9132
TEST_TIME 92 9095
TEST_TIME 93 9104
TEST_TIME 94 9116
TEST_TIME 95 9151
TEST_TIME 96 9157
TEST_TIME 97 9186
TEST_TIME 98 9167
TEST_TIME 99 9189
TEST_TIME 100 9211
TEST_TIME 101 9250
TEST_TIME 102 9259
TEST_TIME 103 9244
TEST_TIME 104 9191
TEST_TIME 105 9158
TEST_TIME 106 9157
TEST_TIME 107 9139
TEST_TIME 108 9101
TEST_TIME 109 9039
TEST_TIME 110 9049
TEST_TIME 111 9062
TEST_TIME 112 9108
TEST_TIME 113 9081
TEST_TIME 114 9088
TEST_TIME 115 9057
TEST_TIME 116 9043
TEST_TIME 117 9072
TEST_TIME 118 9115
TEST_TIME 119 9160
TEST_TIME 120 9094

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-06-2022 07:38:11 to 17-06-2022 07:38:14.

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 1509237 1509237 0 0.00 0 19442 15 0 0
doc_list_biud 1509220 1509220 0 0.00 0 34513 48 0 0
sp_add_invoice_to_stock 4985 4862 123 2.46 28 274786 29065 0 123
sp_cancel_adding_invoice 5580 5580 0 0.00 7 100086 1400 0 0
sp_cancel_client_order 1717 1717 0 0.00 7 9254 489 0 0
sp_cancel_customer_reserve 548132 548132 0 0.00 1 25109 115 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 4 1938 127 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 4 1552 134 0 0
sp_cancel_supplier_invoice 6151 6140 11 0.17 3 34518 1108 0 11
sp_cancel_supplier_order 428 428 0 0.00 15 1440411 187592 0 0
sp_cancel_write_off 9916 9916 0 0.00 2 17655 171 0 0
sp_client_order 20177 20177 0 0.00 6 25978 488 0 0
sp_customer_reserve 456479 456479 0 0.00 0 157269 271 0 0
sp_fill_shopping_cart 30640 30640 0 0.00 1 14787 92 0 0
sp_fill_shopping_cart_clo_res 456445 456445 0 0.00 0 17622 20 0 0
sp_get_clo_for_invoice 4811 4811 0 0.00 0 22956 304 0 0
sp_kill_cost_storno 16600 16600 0 0.00 0 6445 82 0 0
sp_kill_qstorno_ret_qs2qd 1112748 1112748 0 0.00 0 34510 48 0 0
sp_kill_qty_storno 586268 586268 0 0.00 0 34513 113 0 0
sp_lock_selected_doc 19976 19976 0 0.00 0 10820 8 0 0
sp_make_cost_storno 26889 26889 0 0.00 0 19426 90 0 0
sp_multiply_rows_for_pdistr 26887 26887 0 0.00 0 7096 40 0 0
sp_multiply_rows_for_qdistr 20175 20175 0 0.00 0 12839 183 0 0
sp_pay_from_customer 6830 6830 0 0.00 6 8862 190 0 0
sp_pay_to_supplier 5701 5701 0 0.00 7 8807 211 0 0
sp_payment_common 12531 12531 0 0.00 2 8199 53 0 0
sp_qd_handle_on_cancel_clo 1717 1717 0 0.00 1 7996 398 0 0
sp_qd_handle_on_invoice_upd_sts 10390 10390 0 0.00 0 23643 622 0 0
sp_qd_handle_on_reserve_upd_sts 9545 9545 0 0.00 0 10950 71 0 0
sp_reserve_write_off 9547 9547 0 0.00 9 12251 305 0 0
sp_supplier_invoice 5233 5233 0 0.00 9 80339 3563 0 0
sp_supplier_order 5242 5242 0 0.00 7 20736 1449 0 0
srv_aggregate_perf_data 112 112 0 0.00 2564 183952 16925 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 738 107880 29015 0 0
srv_find_qd_qs_mism 1040947 1040947 0 0.00 0 14847 11 0 0
srv_make_invnt_saldo 445 445 0 0.00 41 106874 5043 0 0
srv_make_money_saldo 925 925 0 0.00 2 14279 427 0 0
srv_recalc_idx_stat 4 4 0 0.00 78352 350712 231614 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4864 4864 0 0.00 1 10863 1158 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 2 6513 606 0 0
t$perf-norm:sp_cancel_client_order 1717 1717 0 0.00 0 1615 34 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 5086 42 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 5604 29 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 700 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 353 353 0 0.00 0 4533 63 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 88325 7986 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 5728 30 0 0
t$perf-norm:sp_client_order 20177 20177 0 0.00 0 7894 47 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 6442 29 0 0
t$perf-norm:sp_pay_from_customer 6830 6830 0 0.00 0 4367 22 0 0
t$perf-norm:sp_pay_to_supplier 5701 5701 0 0.00 0 3557 26 0 0
t$perf-norm:sp_reserve_write_off 9548 9548 0 0.00 0 9854 34 0 0
t$perf-norm:sp_supplier_invoice 5233 5233 0 0.00 0 5911 40 0 0
t$perf-norm:sp_supplier_order 5242 5242 0 0.00 0 3987 39 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 419 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 536 15 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 3786 29 0 0
t$perf-norm:srv_make_money_saldo 925 925 0 0.00 0 679 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 186 77 0 0
v_add_invoice_to_stock 9862 9616 246 2.49 0 16224 30 0 246
v_all_customers 36728 36728 0 0.00 0 139 4 0 0
v_all_suppliers 29426 29426 0 0.00 0 137 5 0 0
v_all_wares 40342 40342 0 0.00 0 138 5 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1968 17 0 0
v_cancel_client_order 3434 3434 0 0.00 0 694 10 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 1795 11 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 1 1798 13 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1539 16 0 0
v_cancel_supplier_invoice 728 706 22 3.02 0 512 23 0 22
v_cancel_supplier_order 856 856 0 0.00 0 899 12 0 0
v_cancel_write_off 1914 1914 0 0.00 0 4953 27 0 0
v_max_id_clo_ord 5238 5238 0 0.00 0 1488 6 0 0
v_max_id_clo_res 963 963 0 0.00 0 3911 15 0 0
v_max_id_ord_sup 5226 5226 0 0.00 0 2238 7 0 0
v_max_non_paid_invoice 1451 1451 0 0.00 0 2589 10 0 0
v_max_non_paid_realizn 3602 3602 0 0.00 0 5149 11 0 0
v_min_id_clo_ord 5238 5238 0 0.00 0 8040 15 0 0
v_min_id_clo_res 963 963 0 0.00 0 4844 20 0 0
v_min_id_ord_sup 5226 5226 0 0.00 0 5629 9 0 0
v_min_non_paid_invoice 5701 5701 0 0.00 0 6068 16 0 0
v_min_non_paid_realizn 6828 6828 0 0.00 0 4672 18 0 0
v_our_firm 10070 10070 0 0.00 0 132 5 0 0
v_reserve_write_off 19087 19087 0 0.00 0 11969 11 0 0
x_lock_depdocs_on_canc_invoice 11367 11367 0 0.00 0 11312 140 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 17101 422 0 0
x_make_qstorno_1000_1200 5242 5242 0 0.00 3 20696 1238 0 0
x_make_qstorno_1200_2000 5233 5233 0 0.00 4 80100 3335 0 0
x_make_qstorno_2100_3300 456478 456478 0 0.00 0 157262 237 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-06-2022 07:38:15 to 17-06-2022 07:38:19.

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:19 10 8192000000 8798449840 8809598976 4478816 0.054 2 1 2 0 4578208 63616 35776
05:08:20 31624 8192000000 9722660256 10018762752 796190096 8.858 100 83 166 1 823900240 7337264 20372880
05:09:57 25369 8192000000 9761510688 10068606976 832984032 9.229 100 90 180 0 868315760 13671792 21659936
05:11:27 16778 8192000000 9803998064 10123472896 868066608 9.581 100 82 164 1 910848800 23751504 19030688
05:12:52 8715 8192000000 9868968496 10203693056 911650480 10.014 100 84 166 0 979981504 49652432 18678592
05:14:06 6634 8192000000 9923852496 10265743360 948401936 10.375 100 89 178 1 1032603216 65137728 19063552
05:15:16 3328 8192000000 9964187328 10318454784 993909808 10.819 100 94 183 0 1084677312 71667632 19099872
05:16:21 2100 8192000000 9983188448 10342875136 1025023184 11.120 100 89 168 0 1100588832 58437232 17128416
05:17:24 1464 8192000000 10048526304 10419728384 1045864976 11.321 100 92 181 1 1168506400 105002720 17638704
05:18:28 1118 8192000000 10019364432 10392199168 1062215264 11.478 100 91 170 2 1141222800 61157424 17850112
05:19:30 1253 8192000000 10049687152 10427564032 1072956080 11.580 100 96 169 2 1168460368 78817104 16687184
05:20:34 1283 8192000000 10040345280 10418790400 1076945744 11.618 100 95 179 0 1160474192 65950128 17578320
05:21:37 1525 8192000000 10075834896 10464264192 1082932672 11.675 100 81 154 1 1198995232 99819584 16242976
05:22:40 907 8192000000 10049254688 10439397376 1088264736 11.726 100 81 155 1 1164141472 60701056 15175680
05:23:42 3952 8192000000 10039858288 10423324672 1092456400 11.766 100 85 155 5 1159858784 53485184 13917200
05:24:47 1239 8192000000 10064083616 10450767872 1096801136 11.807 100 97 184 3 1182382048 66940176 18640736
05:25:48 1394 8192000000 10074220144 10459807744 1098940864 11.828 100 93 173 1 1194939824 77662096 18336864
05:26:50 5760 8192000000 10075254256 10469924864 1103300960 11.869 100 76 149 1 1199167952 81291840 14575152
05:27:58 2051 8192000000 10080684496 10476146688 1104593984 11.881 100 83 161 1 1198921664 78270704 16056976
05:29:04 1022 8192000000 10019705712 10407682048 1078859088 11.637 100 90 177 0 1144316384 45630784 19826512
05:30:07 1469 8192000000 10048006496 10440531968 1100472224 11.842 100 84 166 3 1171557152 54290416 16794512
05:31:10 1255 8192000000 10046004432 10429579264 1079906576 11.647 100 95 181 2 1167576896 69594016 18076304
05:32:12 1047 8192000000 10019946240 10398048256 1059335328 11.450 100 96 181 1 1139758976 62601104 17822544
05:33:15 1638 8192000000 10012060736 10387853312 1054133232 11.400 100 89 162 1 1128189376 58472304 15583840
05:34:18 1062 8192000000 9973244880 10343936000 1024735984 11.118 99 94 170 0 1091102864 48229488 18137392
05:35:19 2821 8192000000 9966939328 10345254912 1039800240 11.263 100 74 135 2 1094963840 41107904 14055696
05:36:24 972 8192000000 9946994416 10315976704 1016149456 11.035 100 91 166 3 1063816720 29877568 17789696
05:37:26 12013 8192000000 10011859520 10395332608 1069546288 11.548 100 66 117 0 1136200816 55421296 11233232
05:39:02 893 8192000000 10019541120 10396782592 1064585664 11.500 100 92 163 4 1138644144 56242752 17815728
05:40:07 1837 8192000000 10047944224 10429321216 1078609760 11.634 100 90 167 1 1156314784 59545120 18159904
05:41:16 1089 8192000000 10058596208 10444656640 1091167840 11.754 100 94 183 0 1183325440 73633104 18524496
05:42:23 3239 8192000000 10067131760 10461126656 1093200064 11.773 100 66 121 0 1178157088 73008144 11948880
05:43:38 1323 8192000000 10098548992 10493063168 1105598064 11.891 100 78 140 1 1216150032 95416960 15135008
05:44:44 1043 8192000000 10123410560 10514485248 1117273040 12.001 100 90 168 2 1237983056 104100976 16609040
05:45:48 2650 8192000000 10121911888 10522091520 1125871456 12.082 100 73 140 0 1240426864 98558864 15996544
05:46:56 1252 8192000000 10148777808 10549555200 1134508128 12.164 100 75 142 2 1259751472 110382688 14860656
05:48:00 735 8192000000 10184341824 10585161728 1145797200 12.270 100 85 164 1 1295549264 131699584 18052480
05:49:06 1501 8192000000 10189316688 10590367744 1156497600 12.370 100 93 169 0 1297495120 120468016 20529504
05:50:11 1674 8192000000 10214653392 10620473344 1163852976 12.439 100 97 185 0 1326901200 141956128 21092096
05:51:17 904 8192000000 10252813536 10658959360 1170703456 12.503 100 98 188 0 1365625136 173020608 21901072
05:52:21 1272 8192000000 10273325952 10674790400 1175727952 12.550 100 93 178 0 1381357904 185956928 19673024
05:53:28 2507 8192000000 10303948912 10712563712 1185440128 12.641 100 90 176 1 1412472880 207211152 19821600
05:54:35 1984 8192000000 10329548576 10750341120 1198990912 12.767 100 88 164 1 1435737760 218472304 18274544
05:55:41 1018 8192000000 10364343888 10771501056 1196191040 12.741 100 94 183 0 1473347136 254797472 22358624
05:56:47 2151 8192000000 10381309120 10798854144 1189187680 12.676 100 89 173 1 1486988704 277417312 20383712
05:57:58 2235 8192000000 10403565136 10828308480 1199050336 12.768 100 89 174 1 1514010512 294043264 20916912
05:59:04 1330 8192000000 10441820048 10856529920 1201080240 12.786 100 98 193 0 1540200224 315938912 23181072
06:00:10 1033 8192000000 10481307504 10887176192 1202173264 12.797 100 94 190 2 1588987440 362760704 24053472
06:01:15 960 8192000000 10530866704 10935541760 1197109840 12.749 100 98 193 0 1634724560 414558384 23056336
06:02:18 1338 8192000000 10584304912 11007328256 1198386816 12.761 100 98 191 0 1688092880 466198048 23508016
06:03:21 862 8192000000 10582532896 11039989760 1191999264 12.702 100 95 189 0 1688121952 472273360 23849328
06:04:25 933 8192000000 10580940832 11014262784 1185914864 12.645 100 97 189 2 1684731584 475886352 22930368
06:05:29 1412 8192000000 10636981296 11052400640 1189084944 12.675 100 81 158 0 1742211488 533707728 19418816
06:06:37 1060 8192000000 10626870032 11100434432 1184271952 12.630 99 81 160 0 1731094784 526863408 19959424
06:07:45 923 8192000000 10639659776 11081768960 1185607712 12.642 100 89 177 0 1744116976 537225600 21283664
06:08:49 5581 8192000000 10591723712 11068092416 1175236752 12.546 100 57 105 0 1721590896 533107296 13246848
06:10:08 793 8192000000 10594900416 11038904320 1179025712 12.581 100 94 177 2 1696975712 495116896 22833104
06:11:12 12665 8192000000 10548048032 11034976256 1184378032 12.631 100 47 94 1 1668681392 472115984 12187376
06:12:27 16961 8192000000 10539417872 10977112064 1168287088 12.481 100 37 74 0 1641679008 463657680 9734240
06:13:56 22345 8192000000 10558391568 11037265920 1164110240 12.442 100 40 80 2 1661814512 487346464 10357808
06:15:24 14552 8192000000 10417068752 10981072896 1150227680 12.312 100 28 56 0 1541874176 384176192 7470304
06:17:37 42068 8192000000 10116656240 10593574912 1129396656 12.116 99 17 34 2 1238746032 105470240 3879136
06:19:20 78460 8192000000 10047688560 10484854784 1072648512 11.577 94 14 28 1 1109286064 33862176 2775376
06:21:42 37127 8192000000 10076134784 10535055360 1111738544 11.949 98 34 63 1 1172681952 54810240 6133168
06:23:20 47387 8192000000 10095231792 10553577472 1135987744 12.178 100 31 57 1 1175446752 32815792 6643216
06:25:08 34502 8192000000 10068218080 10499915776 1122135232 12.047 98 43 76 1 1163374672 32389936 8849504
06:26:49 62130 8192000000 10049765920 10530582528 1101962192 11.856 97 30 60 2 1170691776 62353392 6376192
06:29:03 86828 8192000000 10114609232 10553413632 1141681504 12.231 100 32 63 1 1198649776 50276752 6691520
06:31:32 11710 8192000000 10179030192 10614898688 1155892512 12.365 100 53 102 1 1281157680 113404080 11861088
06:32:47 5915 8192000000 10236504880 10664558592 1157956960 12.384 100 79 159 1 1322918704 148145184 16816560
06:34:14 53450 8192000000 10136576592 10608472064 1137255600 12.190 99 27 52 1 1264545568 121585264 5704704
06:36:23 80373 8192000000 10115133328 10598617088 1126961232 12.093 98 20 40 0 1243022880 111316912 4744736
06:38:44 81187 8192000000 10073721680 10553987072 1088342928 11.727 95 14 28 1 1150920480 60004368 2573184
06:41:42 58549 8192000000 10084482784 10610036736 1147622144 12.287 100 17 33 0 1211099712 60019024 3458544
06:43:41 27919 8192000000 10085060752 10532356096 1128897600 12.111 99 52 97 3 1172159536 34210640 9051296
06:45:19 24246 8192000000 10117169824 10561142784 1131775360 12.138 99 50 98 1 1193728368 52624832 9328176
06:46:45 39148 8192000000 10128291424 10624651264 1142149792 12.236 100 27 54 1 1269028160 120954176 5924192
06:48:25 84147 8192000000 10104788016 10551382016 1097702128 11.816 96 14 28 1 1150815168 50139760 2973280
06:51:16 34122 8192000000 10064986992 10505867264 1132357600 12.144 100 22 44 0 1163968000 27318848 4291552
06:52:51 27774 8192000000 10162294704 10610483200 1116194672 11.991 98 45 88 1 1228322768 103588576 8539520
06:54:19 107879 8192000000 10094419056 10551992320 1121504512 12.041 99 19 37 0 1157156288 31504416 4147360
06:57:19 7962 8192000000 10115670800 10582384640 1135731504 12.175 100 28 56 0 1219328384 77275792 6321088
06:58:49 88791 8192000000 10082624096 10532409344 1111129504 11.943 97 17 34 1 1146988896 32182944 3676448
07:01:21 83232 8192000000 10113557904 10602237952 1135755600 12.176 99 17 34 0 1210739808 71417504 3566704
07:03:44 67541 8192000000 10073645392 10566270976 1121387712 12.040 98 9 18 0 1179166128 56114816 1663600
07:05:59 65149 8192000000 10095681024 10571943936 1129846208 12.120 99 22 43 3 1192386064 57458224 5081632
07:08:22 44190 8192000000 10070422368 10535120896 1129536240 12.117 99 22 42 1 1190970192 56300528 5133424
07:10:14 42307 8192000000 10088059904 10546708480 1094578208 11.786 96 21 42 1 1171514320 72452992 4483120
07:12:01 28280 8192000000 10113654896 10565664768 1135673616 12.175 100 41 81 1 1217153408 73420640 8059152
07:14:02 49932 8192000000 10150163984 10634072064 1144712800 12.260 100 33 66 3 1232196448 80980464 6503184
07:16:14 17034 8192000000 10157671792 10610720768 1147207856 12.283 100 58 115 0 1256109168 97299824 11601488
07:17:35 51006 8192000000 10075740208 10526646272 1129817216 12.120 99 23 46 3 1199409136 64263760 5328160
07:19:27 40554 8192000000 10116187104 10559766528 1120442864 12.031 98 44 88 1 1198783920 69693664 8647392
07:21:09 2030 8192000000 10142067328 10566987776 1140454864 12.220 100 60 118 2 1239161600 86476480 12230256
07:22:11 97469 8192000000 10163948176 10622328832 1116589312 11.995 98 32 64 0 1244402928 120922640 6890976
07:25:25 67132 8192000000 10135973488 10616844288 1132109648 12.141 99 13 26 0 1215972032 81104176 2758208
07:27:32 87004 8192000000 10071808720 10604085248 1123351536 12.059 98 11 20 1 1237917824 112381872 2184416
07:30:01 34467 8192000000 10094342896 10536644608 1136590128 12.183 99 34 68 2 1173761248 30101648 7069472
07:31:40 45277 8192000000 10127927760 10580226048 1133237328 12.152 98 21 42 0 1193244192 55703936 4302928
07:33:36 55496 8192000000 10139712816 10610958336 1127603040 12.099 98 21 40 4 1244414560 112381968 4429552
07:35:35 30856 8192000000 10110219424 10556342272 1121243296 12.039 97 22 42 1 1178972720 52797984 4931440
07:37:08 21094 8192000000 9024442944 9276551168 179323808 2.142 17 2 4 0 188272288 8877360 71120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-06-2022 07:38:21 to 17-06-2022 07:38:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-06-2022 07:38:38 to 17-06-2022 07:38:40.

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          215042
MON$OLDEST_ACTIVE               215621
MON$OLDEST_SNAPSHOT             215621
MON$NEXT_TRANSACTION            215621
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                       2097600
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2780 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2780 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2780 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2780 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 ---
:102: 
:103: mscs-test-161.budzdorov.ru    Fri Jun 17 07:37:32 2022
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:105: 
--- end of diff output ---

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

17.06.22 07:38:41. 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-06-17 07:38:44 No severe PSQL-related problems occured
17.06.22 07:38:45. End of report.