Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.07.2022 07:48
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 16029240 kB
MemAvailable 31099264 kB
Buffers 0 kB
Cached 15664732 kB
SwapCached 2520 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-11 07:47:23
DTS_END                         2022-07-11 07:47:23.5550
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/47134
TRN_ID                          216475
ATT_ID                          592
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: 9042.88 25781 1085145
customer order: creation 170.96 449 20515
customer order: refuse 14.97 378 1796
order to supplier: creation 46.56 1393 5587
order to supplier: removal 3.55 156980 426
invoice (draft): creation 46.23 3082 5547
invoice (draft): removal 51.23 1004 6147
invoice accept: apply 41.29 25993 4955
invoice accept: cancel 46.16 1200 5539
customer reserve: creation 3789.59 252 454751
customer reserve: removal 4539.77 97 544773
realization accept: apply 83.56 267 10027
realization accept: cancel 78.18 164 9381
payment from customer: creation 60.13 150 7215
payment from customer: removal 5.49 115 659
payment to supplier: creation 49.04 166 5885
payment to supplier: removal 3.47 132 416
service: total inventory turnovers 3.91 3632 469
service: total monetary turnovers 7.74 325 929
service: aggregate perf. data 1.03 13399 123
service: refresh index statistics 0.04 306450 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-07-2022 07:48:48 to 11-07-2022 07:48:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5117
TEST_TIME 2 5450
TEST_TIME 3 4934
TEST_TIME 4 5032
TEST_TIME 5 4750
TEST_TIME 6 4510
TEST_TIME 7 4883
TEST_TIME 8 5437
TEST_TIME 9 5507
TEST_TIME 10 6381
TEST_TIME 11 6383
TEST_TIME 12 6806
TEST_TIME 13 6936
TEST_TIME 14 7067
TEST_TIME 15 7124
TEST_TIME 16 7229
TEST_TIME 17 7225
TEST_TIME 18 7333
TEST_TIME 19 7311
TEST_TIME 20 7526
TEST_TIME 21 7727
TEST_TIME 22 8065
TEST_TIME 23 8132
TEST_TIME 24 7969
TEST_TIME 25 8151
TEST_TIME 26 8862
TEST_TIME 27 9884
TEST_TIME 28 10330
TEST_TIME 29 10542
TEST_TIME 30 11143
TEST_TIME 31 11136
TEST_TIME 32 11006
TEST_TIME 33 10868
TEST_TIME 34 10675
TEST_TIME 35 10534
TEST_TIME 36 10489
TEST_TIME 37 10317
TEST_TIME 38 10203
TEST_TIME 39 10266
TEST_TIME 40 10189
TEST_TIME 41 10170
TEST_TIME 42 10099
TEST_TIME 43 9930
TEST_TIME 44 9961
TEST_TIME 45 9850
TEST_TIME 46 9924
TEST_TIME 47 10118
TEST_TIME 48 10068
TEST_TIME 49 10126
TEST_TIME 50 10049
TEST_TIME 51 10079
TEST_TIME 52 10035
TEST_TIME 53 10027
TEST_TIME 54 10017
TEST_TIME 55 10125
TEST_TIME 56 10114
TEST_TIME 57 10082
TEST_TIME 58 10056
TEST_TIME 59 9933
TEST_TIME 60 9882
TEST_TIME 61 9867
TEST_TIME 62 9780
TEST_TIME 63 9747
TEST_TIME 64 9687
TEST_TIME 65 9650
TEST_TIME 66 9622
TEST_TIME 67 9508
TEST_TIME 68 9436
TEST_TIME 69 9411
TEST_TIME 70 9443
TEST_TIME 71 9425
TEST_TIME 72 9388
TEST_TIME 73 9413
TEST_TIME 74 9404
TEST_TIME 75 9460
TEST_TIME 76 9464
TEST_TIME 77 9432
TEST_TIME 78 9468
TEST_TIME 79 9533
TEST_TIME 80 9508
TEST_TIME 81 9563
TEST_TIME 82 9567
TEST_TIME 83 9577
TEST_TIME 84 9484
TEST_TIME 85 9419
TEST_TIME 86 9379
TEST_TIME 87 9324
TEST_TIME 88 9261
TEST_TIME 89 9249
TEST_TIME 90 9168
TEST_TIME 91 9174
TEST_TIME 92 9127
TEST_TIME 93 9238
TEST_TIME 94 9246
TEST_TIME 95 9208
TEST_TIME 96 9225
TEST_TIME 97 9274
TEST_TIME 98 9323
TEST_TIME 99 9361
TEST_TIME 100 9347
TEST_TIME 101 9360
TEST_TIME 102 9428
TEST_TIME 103 9406
TEST_TIME 104 9328
TEST_TIME 105 9309
TEST_TIME 106 9274
TEST_TIME 107 9210
TEST_TIME 108 9170
TEST_TIME 109 9186
TEST_TIME 110 9145
TEST_TIME 111 9122
TEST_TIME 112 9101
TEST_TIME 113 9101
TEST_TIME 114 9087
TEST_TIME 115 9061
TEST_TIME 116 9058
TEST_TIME 117 9065
TEST_TIME 118 9044
TEST_TIME 119 9123
TEST_TIME 120 9062

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-07-2022 07:48:52 to 11-07-2022 07:48:55.

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 1504866 1504866 0 0.00 0 24754 13 0 0
doc_list_biud 1504866 1504866 0 0.00 0 37014 41 0 0
sp_add_invoice_to_stock 5079 4955 124 2.44 29 231592 25993 0 124
sp_cancel_adding_invoice 5539 5539 0 0.00 6 93456 1200 0 0
sp_cancel_client_order 1796 1796 0 0.00 6 10127 379 0 0
sp_cancel_customer_reserve 544773 544773 0 0.00 1 37043 97 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 4930 115 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 4 5302 132 0 0
sp_cancel_supplier_invoice 6157 6147 10 0.16 3 29668 1004 0 10
sp_cancel_supplier_order 426 426 0 0.00 11 1652974 156980 0 0
sp_cancel_write_off 9381 9381 0 0.00 2 10615 165 0 0
sp_client_order 20515 20515 0 0.00 5 26279 450 0 0
sp_customer_reserve 454751 454751 0 0.00 1 119040 253 0 0
sp_fill_shopping_cart 31644 31644 0 0.00 1 28377 82 0 0
sp_fill_shopping_cart_clo_res 454738 454738 0 0.00 0 29875 21 0 0
sp_get_clo_for_invoice 4929 4929 0 0.00 0 27441 311 0 0
sp_kill_cost_storno 15995 15995 0 0.00 0 14469 69 0 0
sp_kill_qstorno_ret_qs2qd 1105500 1105500 0 0.00 0 36930 43 0 0
sp_kill_qty_storno 583016 583016 0 0.00 0 37012 98 0 0
sp_lock_selected_doc 20791 20791 0 0.00 0 6489 7 0 0
sp_make_cost_storno 28054 28054 0 0.00 0 24737 73 0 0
sp_multiply_rows_for_pdistr 28054 28054 0 0.00 0 10774 33 0 0
sp_multiply_rows_for_qdistr 20515 20515 0 0.00 0 16483 196 0 0
sp_pay_from_customer 7215 7215 0 0.00 5 12294 150 0 0
sp_pay_to_supplier 5885 5885 0 0.00 6 11632 167 0 0
sp_payment_common 13100 13100 0 0.00 2 7296 43 0 0
sp_qd_handle_on_cancel_clo 1796 1796 0 0.00 0 10120 316 0 0
sp_qd_handle_on_invoice_upd_sts 10466 10466 0 0.00 0 16010 484 0 0
sp_qd_handle_on_reserve_upd_sts 10027 10027 0 0.00 0 21915 69 0 0
sp_reserve_write_off 10027 10027 0 0.00 8 22326 268 0 0
sp_supplier_invoice 5547 5547 0 0.00 10 141738 3083 0 0
sp_supplier_order 5587 5587 0 0.00 8 115941 1393 0 0
srv_aggregate_perf_data 123 123 0 0.00 2494 134772 13400 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 721 133701 36621 0 0
srv_find_qd_qs_mism 1036862 1036862 0 0.00 0 10053 11 0 0
srv_make_invnt_saldo 469 469 0 0.00 37 82594 3633 0 0
srv_make_money_saldo 929 929 0 0.00 1 8565 325 0 0
srv_recalc_idx_stat 5 5 0 0.00 197331 440439 306450 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4957 4957 0 0.00 1 34908 819 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 2 7436 457 0 0
t$perf-norm:sp_cancel_client_order 1796 1796 0 0.00 0 2234 27 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 1397 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 534 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 705 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 1936 35 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 93532 7392 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 1766 18 0 0
t$perf-norm:sp_client_order 20515 20515 0 0.00 0 15711 33 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 3368 17 0 0
t$perf-norm:sp_pay_from_customer 7215 7215 0 0.00 0 2834 14 0 0
t$perf-norm:sp_pay_to_supplier 5885 5885 0 0.00 0 35832 24 0 0
t$perf-norm:sp_reserve_write_off 10027 10027 0 0.00 0 6494 20 0 0
t$perf-norm:sp_supplier_invoice 5547 5547 0 0.00 0 3708 27 0 0
t$perf-norm:sp_supplier_order 5587 5587 0 0.00 0 2436 29 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 1344 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 313 10 0 0
t$perf-norm:srv_make_invnt_saldo 469 469 0 0.00 0 1385 23 0 0
t$perf-norm:srv_make_money_saldo 929 929 0 0.00 0 1019 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 8 201 52 0 0
v_add_invoice_to_stock 10102 9854 248 2.45 0 7288 22 0 248
v_all_customers 37954 37954 0 0.00 0 94 3 0 0
v_all_suppliers 30994 30994 0 0.00 0 77 4 0 0
v_all_wares 41030 41030 0 0.00 0 210 4 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 1982 15 0 0
v_cancel_client_order 3592 3592 0 0.00 0 2486 9 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 4898 15 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 1681 11 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2539 14 0 0
v_cancel_supplier_invoice 902 882 20 2.21 0 2255 23 0 20
v_cancel_supplier_order 852 852 0 0.00 0 2005 15 0 0
v_cancel_write_off 1986 1986 0 0.00 0 1069 20 0 0
v_max_id_clo_ord 5587 5587 0 0.00 0 961 5 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 15328 28 0 0
v_max_id_ord_sup 5542 5542 0 0.00 0 4592 7 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 151 7 0 0
v_max_non_paid_realizn 3613 3613 0 0.00 0 4522 10 0 0
v_min_id_clo_ord 5587 5587 0 0.00 0 2715 8 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 4647 12 0 0
v_min_id_ord_sup 5542 5542 0 0.00 0 3631 10 0 0
v_min_non_paid_invoice 5885 5885 0 0.00 0 5886 16 0 0
v_min_non_paid_realizn 7215 7215 0 0.00 0 5261 15 0 0
v_our_firm 10280 10280 0 0.00 0 57 4 0 0
v_reserve_write_off 20054 20054 0 0.00 0 5484 10 0 0
x_lock_depdocs_on_canc_invoice 11245 11245 0 0.00 0 12403 111 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 6250 330 0 0
x_make_qstorno_1000_1200 5587 5587 0 0.00 3 115746 1225 0 0
x_make_qstorno_1200_2000 5547 5547 0 0.00 3 141444 2857 0 0
x_make_qstorno_2100_3300 454751 454751 0 0.00 0 119035 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-07-2022 07:48:56 to 11-07-2022 07:49:00.

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:17:27 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:28 36209 8192000000 9741778352 10042904576 818573840 9.084 100 91 182 0 847452720 7615024 21263856
05:20:09 16648 8192000000 9775585440 10085384192 854006544 9.440 100 90 180 0 888866976 13797824 21062608
05:21:30 12999 8192000000 9813847888 10133803008 878643424 9.686 100 87 172 0 922057360 24175824 19238112
05:22:47 11182 8192000000 9880015328 10215395328 926153600 10.157 100 88 176 0 992651728 47704128 18794000
05:24:01 3985 8192000000 9927995712 10274193408 957875440 10.468 100 85 165 0 1051542160 76688848 16977872
05:25:11 2687 8192000000 9965573360 10322493440 990166352 10.783 100 89 175 0 1076905200 68614432 18124416
05:26:16 1144 8192000000 10003812256 10364116992 1023166928 11.103 100 94 180 1 1124639312 83584176 17888208
05:27:20 1110 8192000000 10007710384 10376929280 1045384160 11.316 100 97 181 2 1127676976 63524160 18768656
05:28:22 1008 8192000000 10037041632 10417958912 1062864752 11.484 100 90 175 0 1158920144 78195520 17859872
05:29:24 998 8192000000 10037267600 10417516544 1070116800 11.553 100 94 185 1 1160995616 70966880 19911936
05:30:27 1110 8192000000 10041828096 10423963648 1073994224 11.590 100 95 177 0 1162203072 70347520 17861328
05:31:29 1332 8192000000 10072066784 10456379392 1079648848 11.644 100 96 192 1 1192774928 94167680 18958400
05:32:32 1079 8192000000 10049266752 10436718592 1084381440 11.689 100 95 187 1 1169627552 66556864 18689248
05:33:34 1386 8192000000 10050834592 10441846784 1087669440 11.720 100 85 161 1 1173370736 67993216 17708080
05:34:37 1390 8192000000 10056341296 10445336576 1090700672 11.749 100 95 179 2 1181916512 71909296 19306544
05:35:39 1165 8192000000 10061241824 10452905984 1091779568 11.760 100 95 180 0 1185256144 72978160 20498416
05:36:41 968 8192000000 10069203904 10460880896 1101391760 11.851 100 97 184 1 1196877584 75583248 19902576
05:37:44 1094 8192000000 10063425040 10457300992 1103738672 11.873 100 86 160 2 1185304720 65290208 16275840
05:38:46 1710 8192000000 10037019328 10428866560 1092928192 11.770 100 83 155 1 1166338352 58002528 15407632
05:39:49 1159 8192000000 10039011312 10428022784 1085339088 11.698 100 81 152 0 1157878784 56538016 16001680
05:40:51 1243 8192000000 10037483632 10423058432 1071265904 11.564 100 96 182 2 1159360208 68535744 19558560
05:41:53 1254 8192000000 10029949056 10410799104 1067623456 11.529 100 96 191 0 1150570720 64885152 18062112
05:42:55 1147 8192000000 9991638320 10364252160 1044580272 11.309 100 96 180 0 1114610048 51888928 18140848
05:43:56 4087 8192000000 9974061824 10358898688 1031419088 11.182 99 59 108 0 1095067456 53208128 10440240
05:45:02 1836 8192000000 9972342432 10346160128 1025997856 11.130 100 76 141 2 1090836032 50368576 14469600
05:46:06 8553 8192000000 9976207872 10374877184 1042181408 11.286 100 65 128 0 1108871888 53750096 12940384
05:47:16 8641 8192000000 10004345376 10392276992 1048584480 11.347 100 56 99 2 1121136512 62821280 9730752
05:48:32 9303 8192000000 10009537488 10393878528 1062611792 11.481 100 71 130 3 1130445488 55163264 12670432
05:49:48 4308 8192000000 10040480928 10421022720 1075369824 11.603 100 77 146 1 1156613840 66988320 14255696
05:50:59 2967 8192000000 10080363760 10475347968 1078594128 11.634 99 78 147 1 1193994320 100710496 14689696
05:52:11 6315 8192000000 10126991952 10509398016 1097752432 11.816 100 71 135 2 1240126704 126833440 15540832
05:53:26 1036 8192000000 10170538400 10561462272 1115376352 11.983 100 74 143 0 1284243488 153496176 15370960
05:54:32 3997 8192000000 10218332416 10623713280 1125979920 12.083 100 81 153 1 1336993456 193227856 17785680
05:55:39 1067 8192000000 10261894624 10661412864 1135249168 12.171 100 78 150 2 1382458960 230972544 16237248
05:56:44 2528 8192000000 10294146304 10724093952 1156633392 12.372 100 81 159 1 1402230752 226404384 19192976
05:57:52 5774 8192000000 10300363072 10705027072 1159691968 12.400 100 79 142 2 1413489280 237395504 16401808
05:59:08 921 8192000000 10281617024 10709958656 1161320800 12.416 99 96 183 0 1394260672 211452288 21487584
06:00:13 1209 8192000000 10330655296 10737537024 1179837968 12.589 100 87 169 2 1440521776 241482288 19201520
06:01:17 1503 8192000000 10362134688 10770710528 1182190896 12.611 100 89 169 0 1470328640 269820624 18317120
06:02:21 1367 8192000000 10402913648 10816577536 1188806672 12.672 100 92 179 0 1505590464 296267184 20516608
06:03:26 866 8192000000 10417480768 10827993088 1193098704 12.712 100 98 186 3 1523394768 307320176 22975888
06:04:29 1918 8192000000 10473595056 10879565824 1185010848 12.637 99 98 194 1 1575851216 368276080 22564288
06:05:34 1148 8192000000 10530870080 10949484544 1206486304 12.837 100 94 187 1 1639625872 410853440 22286128
06:06:37 714 8192000000 10560834256 10978447360 1205921792 12.831 100 97 196 1 1665576112 435652832 24001488
06:07:41 1665 8192000000 10573005120 10989113344 1193878064 12.719 99 79 156 1 1675765392 463150480 18736848
06:08:52 2618 8192000000 10579778064 11025440768 1205381024 12.826 100 89 173 1 1679167488 453519488 20266976
06:09:58 3263 8192000000 10551554304 11011461120 1193455840 12.716 100 59 114 1 1666060176 457716096 14888240
06:11:04 9587 8192000000 10569746256 11019808768 1170320656 12.500 100 50 100 0 1687691440 503601168 13769616
06:12:15 762 8192000000 10544470416 11016675328 1157090736 12.376 100 84 144 1 1650742480 474751440 18900304
06:13:19 3588 8192000000 10448033936 11006025728 1161332400 12.416 100 70 139 1 1556581376 377987232 17261744
06:14:26 18052 8192000000 10323680688 10812653568 1159347376 12.397 100 37 74 0 1454850096 285913968 9588752
06:15:45 45403 8192000000 10229985088 10686685184 1142147792 12.236 99 22 40 1 1337768624 190834320 4786512
06:17:31 67116 8192000000 10079677648 10589130752 1090104928 11.744 96 16 31 0 1165184800 72045456 3034416
06:19:38 133698 8192000000 10060201136 10493227008 1093496944 11.776 98 34 62 2 1127271552 25969648 7804960
06:22:54 61335 8192000000 10080208112 10521423872 1089132112 11.734 97 36 66 1 1142707760 46345088 7230560
06:24:56 110517 8192000000 10081887040 10545909760 1102761424 11.864 98 30 58 0 1157274736 47321232 7192080
06:27:58 10203 8192000000 10122303312 10554355712 1131658736 12.137 100 67 130 4 1217751632 72729712 13363184
06:29:11 15841 8192000000 10098130320 10542915584 1112762384 11.959 98 17 34 1 1187234704 70748272 3724048
06:30:27 31650 8192000000 10158648240 10609795072 1126502480 12.088 99 16 32 0 1239426272 108991008 3932784
06:31:59 27279 8192000000 10142882224 10590285824 1134115376 12.160 100 26 52 0 1252253456 111952704 6185376
06:34:01 45759 8192000000 10176021936 10623643648 1115603056 11.985 98 21 43 0 1258480960 137800208 5077696
06:35:47 61152 8192000000 10063702272 10526384128 1091598976 11.758 96 21 40 0 1147530144 51697248 4233920
06:38:05 48801 8192000000 10083862880 10519924736 1118705952 12.015 99 31 62 0 1207040848 82348944 5985952
06:39:54 63961 8192000000 10117895808 10555801600 1106645184 11.901 97 16 30 0 1182963904 73270624 3048096
06:41:58 44565 8192000000 10074701776 10535022592 1125937168 12.083 99 21 43 0 1199479600 69019488 4522944
06:43:43 38043 8192000000 10097135648 10557030400 1133547408 12.155 100 29 58 0 1188329504 48456592 6325504
06:45:22 37094 8192000000 10091726144 10508222464 1118590512 12.014 99 40 80 0 1187224480 60337728 8296240
06:48:11 61518 8192000000 10082818592 10552012800 1135974736 12.178 100 20 40 0 1198411280 58463216 3973328
06:50:17 68329 8192000000 10080281776 10541387776 1129083136 12.113 99 22 44 0 1186386672 52859808 4443728
06:53:14 73033 8192000000 10056549136 10520371200 1139642928 12.212 100 18 35 0 1188983088 45021584 4318576
06:55:58 37432 8192000000 10086824448 10564304896 1137089520 12.188 99 9 18 0 1202027696 62670880 2267296
06:58:17 55554 8192000000 10099964240 10557464576 1137823984 12.195 98 17 32 0 1192109888 51217808 3068096
07:00:13 38981 8192000000 10127594368 10596196352 1145800848 12.270 99 21 42 0 1229475456 79531312 4143296
07:01:57 42070 8192000000 10115770800 10555875328 1129162304 12.113 98 28 56 0 1192002672 57281056 5559312
07:03:39 33707 8192000000 10131797824 10588868608 1111493248 11.947 97 44 88 0 1218241968 97692288 9056432
07:05:17 68704 8192000000 10055266288 10487840768 1079133856 11.639 95 38 76 2 1192035968 104984000 7918112
07:07:26 87503 8192000000 10137152368 10617069568 1123825872 12.063 99 41 82 0 1199534592 67695712 8013008
07:09:56 53093 8192000000 10096237808 10569875456 1137083264 12.188 100 40 78 0 1226374464 81484272 7806928
07:11:53 42047 8192000000 10069027104 10500481024 1125168960 12.076 99 73 130 1 1168626080 27717648 15739472
07:13:35 30474 8192000000 10059116720 10510303232 1122394560 12.050 99 17 33 0 1190470480 63896416 4179504
07:15:37 64967 8192000000 10121301728 10598928384 1121413024 12.040 98 18 37 0 1203624416 78137088 4074304
07:18:10 74485 8192000000 10147025824 10627223552 1078028736 11.629 94 18 36 0 1194012368 111835600 4148032
07:20:24 52986 8192000000 10090338096 10535268352 1117954576 12.008 99 16 32 0 1186626640 64919024 3753040
07:22:18 26731 8192000000 10143128000 10582196224 1143124432 12.245 99 37 74 1 1229955456 78631280 8199744
07:23:53 91850 8192000000 10146225280 10682556416 1124626256 12.071 98 20 41 0 1269941648 140987712 4327680
07:26:25 86014 8192000000 10100467488 10535440384 1095807376 11.798 96 28 52 0 1174583488 73240080 5536032
07:29:10 75908 8192000000 10090349616 10535927808 1074505728 11.595 95 21 43 0 1146785728 68089904 4190096
07:31:27 88280 8192000000 10085618864 10539253760 1132665264 12.146 100 32 64 0 1187900960 48846048 6389648
07:34:07 77990 8192000000 10078718672 10527592448 1116801520 11.997 98 9 18 0 1180202512 61432272 1968720
07:36:27 68834 8192000000 10052412496 10515787776 1077286960 11.622 95 11 23 0 1108667632 29299424 2081248
07:38:36 24102 8192000000 10104657952 10546163712 1133668768 12.156 100 26 52 0 1197590448 58607232 5314448
07:40:22 44396 8192000000 10121307216 10562424832 1124208416 12.067 99 19 38 0 1201967808 73414816 4344576
07:42:10 25641 8192000000 10167690640 10602565632 1145473712 12.267 100 42 79 1 1260892800 107154272 8264816
07:43:36 54711 8192000000 10177622128 10651820032 1140127680 12.217 99 26 51 0 1276212432 130762720 5322032
07:45:39 39629 8192000000 10234842512 10688090112 1156229136 12.368 100 43 86 0 1325724400 160284992 9210272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-07-2022 07:49:02 to 11-07-2022 07:49:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-07-2022 07:49:19 to 11-07-2022 07:49:21.

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          216474
MON$OLDEST_ACTIVE               225012
MON$OLDEST_SNAPSHOT             225012
MON$NEXT_TRANSACTION            225012
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                       2039456
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.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Mon Jul 11 07:47:33 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Mon Jul 11 07:47:40 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

11.07.22 07:49:22. 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-11 07:49:24 No severe PSQL-related problems occured
11.07.22 07:49:26. End of report.