Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.12.2022 22:40
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 15058308 kB
MemAvailable 30129032 kB
Buffers 0 kB
Cached 16676344 kB
SwapCached 3280 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-12-20 22:38:31
DTS_END                         2022-12-20 22:38:31.7990
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(57:17)
IP                              ::1/59734
TRN_ID                          244496
ATT_ID                          680
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: 8947.77 25591 1073732
customer order: creation 175.96 328 21115
customer order: refuse 15.66 346 1879
order to supplier: creation 45.95 1246 5514
order to supplier: removal 3.23 90376 388
invoice (draft): creation 47.54 3257 5705
invoice (draft): removal 49.03 799 5884
invoice accept: apply 43.45 17970 5214
invoice accept: cancel 43.53 830 5223
customer reserve: creation 3946.58 182 473589
customer reserve: removal 4280.06 52 513607
realization accept: apply 85.80 186 10296
realization accept: cancel 77.26 103 9271
payment from customer: creation 60.73 125 7287
payment from customer: removal 5.99 89 719
payment to supplier: creation 50.77 142 6092
payment to supplier: removal 3.50 80 420
service: total inventory turnovers 5.10 1018 612
service: total monetary turnovers 6.89 37 827
service: aggregate perf. data 0.72 23164 86
service: refresh index statistics 0.03 371494 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-12-2022 22:41:17 to 20-12-2022 22:41:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4000
TEST_TIME 2 4546
TEST_TIME 3 4310
TEST_TIME 4 4886
TEST_TIME 5 4819
TEST_TIME 6 4643
TEST_TIME 7 4738
TEST_TIME 8 5306
TEST_TIME 9 5558
TEST_TIME 10 6126
TEST_TIME 11 6283
TEST_TIME 12 6240
TEST_TIME 13 6298
TEST_TIME 14 6905
TEST_TIME 15 7314
TEST_TIME 16 7367
TEST_TIME 17 7754
TEST_TIME 18 7735
TEST_TIME 19 8164
TEST_TIME 20 8189
TEST_TIME 21 8202
TEST_TIME 22 8067
TEST_TIME 23 8339
TEST_TIME 24 8471
TEST_TIME 25 8500
TEST_TIME 26 8468
TEST_TIME 27 8728
TEST_TIME 28 9412
TEST_TIME 29 9872
TEST_TIME 30 10310
TEST_TIME 31 10047
TEST_TIME 32 9940
TEST_TIME 33 9909
TEST_TIME 34 10064
TEST_TIME 35 9929
TEST_TIME 36 9786
TEST_TIME 37 9774
TEST_TIME 38 9639
TEST_TIME 39 9778
TEST_TIME 40 9660
TEST_TIME 41 9580
TEST_TIME 42 9593
TEST_TIME 43 9623
TEST_TIME 44 9651
TEST_TIME 45 9683
TEST_TIME 46 9582
TEST_TIME 47 9531
TEST_TIME 48 9577
TEST_TIME 49 9618
TEST_TIME 50 9637
TEST_TIME 51 9581
TEST_TIME 52 9448
TEST_TIME 53 9437
TEST_TIME 54 9373
TEST_TIME 55 9401
TEST_TIME 56 9364
TEST_TIME 57 9320
TEST_TIME 58 9305
TEST_TIME 59 9299
TEST_TIME 60 9261
TEST_TIME 61 9155
TEST_TIME 62 9103
TEST_TIME 63 9189
TEST_TIME 64 9223
TEST_TIME 65 9166
TEST_TIME 66 9214
TEST_TIME 67 9191
TEST_TIME 68 9215
TEST_TIME 69 9247
TEST_TIME 70 9288
TEST_TIME 71 9322
TEST_TIME 72 9294
TEST_TIME 73 9296
TEST_TIME 74 9410
TEST_TIME 75 9380
TEST_TIME 76 9398
TEST_TIME 77 9371
TEST_TIME 78 9376
TEST_TIME 79 9398
TEST_TIME 80 9405
TEST_TIME 81 9317
TEST_TIME 82 9246
TEST_TIME 83 9171
TEST_TIME 84 9152
TEST_TIME 85 9162
TEST_TIME 86 9128
TEST_TIME 87 9110
TEST_TIME 88 9075
TEST_TIME 89 9038
TEST_TIME 90 9034
TEST_TIME 91 8965
TEST_TIME 92 9034
TEST_TIME 93 9033
TEST_TIME 94 9062
TEST_TIME 95 9096
TEST_TIME 96 9094
TEST_TIME 97 9072
TEST_TIME 98 9138
TEST_TIME 99 9106
TEST_TIME 100 9148
TEST_TIME 101 9125
TEST_TIME 102 9205
TEST_TIME 103 9220
TEST_TIME 104 9234
TEST_TIME 105 9212
TEST_TIME 106 9231
TEST_TIME 107 9226
TEST_TIME 108 9165
TEST_TIME 109 9162
TEST_TIME 110 9149
TEST_TIME 111 9104
TEST_TIME 112 9049
TEST_TIME 113 9054
TEST_TIME 114 9074
TEST_TIME 115 9027
TEST_TIME 116 9059
TEST_TIME 117 9014
TEST_TIME 118 9040
TEST_TIME 119 9016
TEST_TIME 120 8951

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-12-2022 22:41:21 to 20-12-2022 22:41:25.

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 1511862 1511862 0 0.00 0 15298 6 0 0
doc_list_biud 1511862 1511862 0 0.00 0 21417 23 0 0
sp_add_invoice_to_stock 5341 5214 127 2.37 27 204249 17970 0 127
sp_cancel_adding_invoice 5223 5223 0 0.00 5 55902 830 0 0
sp_cancel_client_order 1879 1879 0 0.00 4 12805 346 0 0
sp_cancel_customer_reserve 513607 513607 0 0.00 0 18248 53 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 3 12364 90 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 2881 81 0 0
sp_cancel_supplier_invoice 5894 5884 10 0.16 1 20542 799 0 10
sp_cancel_supplier_order 388 388 0 0.00 7 1026979 90376 0 0
sp_cancel_write_off 9271 9271 0 0.00 1 21501 103 0 0
sp_client_order 21115 21115 0 0.00 3 22687 328 0 0
sp_customer_reserve 473589 473589 0 0.00 0 142996 182 0 0
sp_fill_shopping_cart 32326 32326 0 0.00 1 11595 39 0 0
sp_fill_shopping_cart_clo_res 473581 473581 0 0.00 0 12903 13 0 0
sp_get_clo_for_invoice 5206 5206 0 0.00 0 28271 276 0 0
sp_kill_cost_storno 15633 15633 0 0.00 0 11465 52 0 0
sp_kill_qstorno_ret_qs2qd 1042757 1042757 0 0.00 0 21415 29 0 0
sp_kill_qty_storno 551754 551754 0 0.00 0 21416 63 0 0
sp_lock_selected_doc 21511 21511 0 0.00 0 7365 6 0 0
sp_make_cost_storno 28881 28881 0 0.00 0 13201 57 0 0
sp_multiply_rows_for_pdistr 28881 28881 0 0.00 0 15291 29 0 0
sp_multiply_rows_for_qdistr 21115 21115 0 0.00 0 12333 132 0 0
sp_pay_from_customer 7287 7287 0 0.00 3 15303 125 0 0
sp_pay_to_supplier 6092 6092 0 0.00 5 14188 143 0 0
sp_payment_common 13379 13379 0 0.00 1 11723 31 0 0
sp_qd_handle_on_cancel_clo 1879 1879 0 0.00 0 12798 321 0 0
sp_qd_handle_on_invoice_upd_sts 10429 10429 0 0.00 0 20985 310 0 0
sp_qd_handle_on_reserve_upd_sts 10296 10296 0 0.00 0 16368 57 0 0
sp_reserve_write_off 10296 10296 0 0.00 6 16497 187 0 0
sp_supplier_invoice 5705 5705 0 0.00 6 88108 3258 0 0
sp_supplier_order 5514 5514 0 0.00 5 19764 1246 0 0
srv_aggregate_perf_data 86 86 0 0.00 2933 278368 23164 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1044 156876 45482 0 0
srv_find_qd_qs_mism 1024554 1024554 0 0.00 0 12152 5 0 0
srv_make_invnt_saldo 612 612 0 0.00 28 29607 1019 0 0
srv_make_money_saldo 827 827 0 0.00 1 5676 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 127099 525069 371494 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 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 5215 5215 0 0.00 0 17905 185 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 4645 134 0 0
t$perf-norm:sp_cancel_client_order 1879 1879 0 0.00 0 1808 11 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 2524 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 510 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 5882 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 784 9 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 28809 1897 0 0
t$perf-norm:sp_cancel_write_off 1066 1066 0 0.00 0 4760 10 0 0
t$perf-norm:sp_client_order 21115 21115 0 0.00 0 8294 17 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 2949 8 0 0
t$perf-norm:sp_pay_from_customer 7287 7287 0 0.00 0 7589 11 0 0
t$perf-norm:sp_pay_to_supplier 6092 6092 0 0.00 0 4117 12 0 0
t$perf-norm:sp_reserve_write_off 10296 10296 0 0.00 0 10987 11 0 0
t$perf-norm:sp_supplier_invoice 5705 5705 0 0.00 0 4018 19 0 0
t$perf-norm:sp_supplier_order 5514 5514 0 0.00 0 10198 21 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 191 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 706 25 0 0
t$perf-norm:srv_make_invnt_saldo 612 612 0 0.00 0 5083 24 0 0
t$perf-norm:srv_make_money_saldo 827 827 0 0.00 0 5680 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10666 10412 254 2.38 0 3849 19 0 254
v_all_customers 38210 38210 0 0.00 0 33 1 0 0
v_all_suppliers 30870 30870 0 0.00 0 25 1 0 0
v_all_wares 42230 42230 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 1796 8 0 0
v_cancel_client_order 3758 3758 0 0.00 0 3975 7 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 1937 7 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 3068 8 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 2797 14 0 0
v_cancel_supplier_invoice 880 860 20 2.27 0 4729 25 0 20
v_cancel_supplier_order 776 776 0 0.00 0 1570 7 0 0
v_cancel_write_off 2132 2132 0 0.00 0 6189 23 0 0
v_max_id_clo_ord 5514 5514 0 0.00 0 2889 2 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 1423 10 0 0
v_max_id_ord_sup 5697 5697 0 0.00 0 3609 4 0 0
v_max_non_paid_invoice 1868 1868 0 0.00 0 589 3 0 0
v_max_non_paid_realizn 3951 3951 0 0.00 0 2750 5 0 0
v_min_id_clo_ord 5514 5514 0 0.00 0 6608 9 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 1178 8 0 0
v_min_id_ord_sup 5697 5697 0 0.00 0 7333 8 0 0
v_min_non_paid_invoice 6092 6092 0 0.00 0 11713 18 0 0
v_min_non_paid_realizn 7287 7287 0 0.00 0 10672 27 0 0
v_our_firm 10692 10692 0 0.00 0 141 1 0 0
v_reserve_write_off 20592 20592 0 0.00 0 6180 8 0 0
x_lock_depdocs_on_canc_invoice 10677 10677 0 0.00 0 13020 143 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 24011 396 0 0
x_make_qstorno_1000_1200 5514 5514 0 0.00 1 19746 1169 0 0
x_make_qstorno_1200_2000 5705 5705 0 0.00 3 88091 3119 0 0
x_make_qstorno_2100_3300 473589 473589 0 0.00 0 142994 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2022 22:41:26 to 20-12-2022 22:41:30.

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
20:08:35 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:36 31817 8192000000 9904898000 10216779776 984832368 10.731 100 84 167 0 1012653152 6287616 21533168
20:11:13 24443 8192000000 9935974352 10259857408 1028885696 11.158 100 86 173 0 1059263456 8205728 22172032
20:12:39 14690 8192000000 9980061584 10314260480 1065144496 11.506 100 79 158 0 1094749680 9535680 20069504
20:14:19 7601 8192000000 10039843440 10389520384 1128282832 12.105 100 78 156 0 1158199552 11272640 18644080
20:15:33 5144 8192000000 10093765136 10453594112 1181414240 12.603 100 83 163 0 1212501024 12192160 18894624
20:16:41 4031 8192000000 10142237296 10511613952 1227729040 13.033 100 83 164 0 1258119760 12818544 17572176
20:17:47 1979 8192000000 10168652400 10545033216 1256142288 13.295 100 84 167 0 1286837152 12222976 18471888
20:18:50 1363 8192000000 10195047600 10578460672 1283607008 13.546 100 89 178 0 1315816224 12272192 19937024
20:19:53 2722 8192000000 10202882240 10597117952 1296847744 13.667 100 59 119 1 1321389904 11014784 13527376
20:20:59 5511 8192000000 10207425088 10612330496 1306000864 13.750 100 52 105 0 1327291504 9909328 11381312
20:22:07 11693 8192000000 10198558848 10616934400 1316199024 13.842 100 16 33 0 1325421136 5485552 3736560
20:23:22 37649 8192000000 10220276368 10632183808 1324003664 13.913 100 47 96 0 1341370112 5607488 11758960
20:25:01 3224 8192000000 10229369648 10646028288 1336901248 14.029 100 25 51 0 1349312944 6991216 5420480
20:26:04 39490 8192000000 10223420528 10651025408 1340085824 14.058 100 18 37 0 1349724960 5739904 3899232
20:27:44 3768 8192000000 10229742464 10650562560 1336731040 14.028 100 43 84 2 1352568416 6490064 9347312
20:28:48 6954 8192000000 10218607600 10633199616 1320546528 13.882 100 25 50 0 1332293312 6729200 5017584
20:29:57 55109 8192000000 10140846976 10538315776 1136049200 12.178 88 26 52 0 1146894496 5042688 5802608
20:31:56 5333 8192000000 10155630592 10549473280 1258143824 13.313 100 25 50 1 1270062960 6122160 5796976
20:33:03 9626 8192000000 10158579264 10557636608 1229735856 13.052 97 22 44 0 1244504096 9426720 5341520
20:34:13 43929 8192000000 10166200944 10573443072 1274791888 13.465 100 25 52 1 1286553136 4902848 6858400
20:35:59 9939 8192000000 10170313872 10579144704 1280161536 13.514 100 20 42 0 1289566240 5067968 4336736
20:37:11 8321 8192000000 10182860528 10593181696 1291924880 13.622 100 23 42 2 1302276592 5267152 5084560
20:38:20 10648 8192000000 10211322944 10624094208 1319280368 13.870 100 30 61 0 1331505024 4969856 7254800
20:39:39 8868 8192000000 10234960560 10646142976 1334906240 14.011 100 35 68 0 1348930064 6207568 7816256
20:40:53 55181 8192000000 10252469536 10672017408 1347917424 14.129 100 43 85 0 1366893392 9171552 9804416
20:42:54 11511 8192000000 10271475312 10693988352 1364614880 14.279 100 38 76 0 1386770240 13607392 8547968
20:44:19 10922 8192000000 10278474144 10696962048 1371507440 14.341 100 53 103 0 1395075376 11232752 12335184
20:45:38 6668 8192000000 10301869840 10723602432 1385122720 14.462 100 51 102 0 1411548640 14451456 11974464
20:46:48 14281 8192000000 10316895696 10738675712 1391305808 14.518 100 64 126 1 1428752912 21757536 15689568
20:48:10 4558 8192000000 10303155088 10722287616 1378728784 14.405 99 56 111 0 1413772176 21678480 13364912
20:49:21 9475 8192000000 10326429088 10749640704 1404286400 14.633 100 52 105 0 1437973744 21078320 12609024
20:50:36 7794 8192000000 10310339648 10727546880 1389930880 14.505 100 61 120 2 1423039376 19087312 14021184
20:51:49 1041 8192000000 10342432736 10751692800 1402488496 14.617 100 83 163 2 1450032752 27533472 20010784
20:52:54 5709 8192000000 10323770176 10740355072 1391402048 14.518 100 72 140 1 1436521136 28245776 16873312
20:54:04 6214 8192000000 10336755728 10744197120 1390047008 14.506 100 81 156 0 1441023648 32543904 18432736
20:55:14 5304 8192000000 10324267664 10733543424 1364183440 14.275 99 68 136 0 1419235280 38253232 16798608
20:56:26 3278 8192000000 10296312720 10700795904 1343259968 14.087 99 77 147 1 1399112416 36942960 18909488
20:57:33 5953 8192000000 10295514928 10699304960 1346651616 14.117 99 54 108 2 1402350656 42418384 13280656
20:58:43 18712 8192000000 10310452640 10735398912 1372682256 14.351 100 34 66 0 1420513344 38869696 8961392
21:00:21 5298 8192000000 10336232560 10753384448 1380216944 14.418 99 60 119 1 1434197648 40023632 13957072
21:01:32 9685 8192000000 10321836032 10745475072 1376782608 14.388 99 38 76 0 1430281104 43438192 10060304
21:02:45 20374 8192000000 10294176528 10714042368 1333093968 13.995 97 32 63 0 1378904480 37435872 8374640
21:04:08 14461 8192000000 10315089168 10747703296 1373507008 14.358 100 29 58 0 1425773152 44012944 8253200
21:05:28 15606 8192000000 10314472688 10745991168 1371950304 14.345 100 34 68 0 1428558016 47293152 9314560
21:06:45 21560 8192000000 10304712176 10749087744 1365865840 14.290 100 17 34 0 1408677488 37503344 5308304
21:08:07 40501 8192000000 10302267760 10740342784 1383532672 14.448 100 14 29 0 1408568160 21713376 3322112
21:09:52 15361 8192000000 10333812160 10762485760 1406518912 14.653 100 45 88 1 1433960336 17842912 9598512
21:11:09 79088 8192000000 10308346464 10761322496 1373326992 14.357 98 25 50 0 1394287184 15283120 5677072
21:13:33 65365 8192000000 10291050928 10741407744 1370886224 14.335 98 17 34 0 1386876992 12059664 3931104
21:15:39 61089 8192000000 10316828112 10765213696 1400902912 14.603 100 13 26 1 1415794448 11921904 2969632
21:17:56 33294 8192000000 10301740288 10748465152 1397124368 14.569 100 23 46 1 1414856352 12720160 5011824
21:19:31 98219 8192000000 10316395952 10774974464 1408848304 14.674 100 26 51 1 1424393488 10281488 5263696
21:22:15 135184 8192000000 10318761632 10776846336 1398926208 14.585 99 3 6 0 1413549136 14063920 559008
21:25:31 95532 8192000000 10320150288 10783805440 1399104944 14.587 99 17 34 0 1416631488 13968960 3557584
21:28:21 121869 8192000000 10311960528 10779885568 1358151840 14.221 96 8 14 2 1377615600 17567088 1896672
21:31:35 85675 8192000000 10316209376 10770690048 1358308672 14.222 96 27 54 0 1376853936 12610944 5934320
21:34:10 100241 8192000000 10286768672 10734612480 1307818848 13.766 93 36 70 0 1326229616 10554736 7856032
21:36:57 24497 8192000000 10268287344 10713268224 1326761536 13.938 96 25 50 0 1339346080 6835616 5748928
21:38:32 53397 8192000000 10258276720 10708381696 1342763904 14.082 98 12 24 1 1357883712 12145200 2974608
21:40:28 43601 8192000000 10252081072 10705358848 1300365744 13.699 96 15 30 0 1317807728 13834736 3607248
21:42:16 74774 8192000000 10268882160 10710441984 1324532080 13.918 98 21 40 0 1340205568 11274560 4398928
21:44:31 82565 8192000000 10259343488 10708762624 1313142160 13.815 96 12 24 1 1331222320 14974592 3105568
21:46:54 7330 8192000000 10295613728 10728325120 1369119536 14.319 100 53 105 3 1389900624 8306096 12474992
21:48:46 53417 8192000000 10260485504 10702503936 1305940160 13.749 96 34 66 1 1323908688 10301600 7666928
21:50:54 93214 8192000000 10259297008 10718310400 1292347216 13.626 95 20 40 0 1314079456 17247264 4484976
21:53:42 51868 8192000000 10273004512 10723393536 1346894336 14.120 99 25 50 0 1362609744 10146064 5569344
21:55:40 21686 8192000000 10281131808 10711736320 1322085872 13.896 97 43 86 1 1338332128 6699488 9546768
21:57:02 156875 8192000000 10270332096 10721759232 1323732320 13.910 97 23 46 0 1351881568 23542512 4606736
22:00:53 67415 8192000000 10287681280 10728120320 1373385216 14.357 100 24 48 0 1385603152 6961408 5256528
22:03:43 79110 8192000000 10269007296 10729046016 1366937248 14.300 99 19 38 0 1380406496 9085792 4383456
22:07:07 72293 8192000000 10276520624 10737209344 1369278896 14.321 99 14 28 0 1386995312 13889712 3826704
22:09:19 25375 8192000000 10282942576 10722226176 1346550416 14.116 97 47 88 1 1367457968 11074304 9833248
22:10:45 73640 8192000000 10281387296 10748702720 1352446928 14.169 98 9 18 1 1370158352 15837024 1874400
22:12:59 64647 8192000000 10278902976 10734112768 1350653888 14.153 98 8 16 0 1363215040 11091616 1469536
22:15:24 22314 8192000000 10306242448 10755620864 1393172096 14.534 100 34 68 1 1411464368 10261968 8030304
22:17:00 88136 8192000000 10310664416 10758164480 1358352576 14.223 97 27 54 0 1377975392 13934704 5688112
22:19:36 52911 8192000000 10301660064 10765037568 1383661952 14.449 99 19 38 0 1402866368 14918752 4285664
22:21:35 76484 8192000000 10260939232 10701115392 1164992128 12.450 84 26 53 0 1182239744 11617536 5630080
22:24:00 15665 8192000000 10286795664 10727149568 1368277904 14.312 100 33 66 0 1385186224 9499888 7408432
22:25:16 40804 8192000000 10267491200 10706669568 1316558256 13.846 97 33 66 0 1334512576 10602512 7351808
22:26:57 65204 8192000000 10249054512 10705104896 1320604640 13.882 97 38 76 0 1343107328 13574320 8928368
22:30:29 48574 8192000000 10244868000 10701033472 1328528816 13.954 98 8 16 0 1342342752 11995312 1818624
22:32:18 55692 8192000000 10257026016 10716618752 1350642624 14.153 99 13 26 0 1364170736 10129648 3398464
22:34:24 83199 8192000000 10282599056 10726903808 1348797168 14.137 98 18 37 0 1363144928 10155264 4192496
22:36:55 31275 8192000000 10264633872 10717388800 1338992704 14.048 98 15 28 0 1352456208 9966912 3496592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-12-2022 22:41:32 to 20-12-2022 22:41:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 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 20-12-2022 22:41:48 to 20-12-2022 22:41:50.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242758
MON$OLDEST_ACTIVE               245290
MON$OLDEST_SNAPSHOT             245290
MON$NEXT_TRANSACTION            245290
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-03 16:41:55.3470
MON$PAGES                       2032096
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:930: 
:931: mscs-test-161.budzdorov.ru    Tue Dec 20 22:38:35 2022
:932:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:933: 
:934: 
:935: mscs-test-161.budzdorov.ru    Tue Dec 20 22:39:00 2022
:936:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:937: 
:938: 
:939: mscs-test-161.budzdorov.ru    Tue Dec 20 22:39:01 2022
:940:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:941: 
:942: 
:943: mscs-test-161.budzdorov.ru    Tue Dec 20 22:39:04 2022
:944:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:945: 
--- end of diff output ---

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

20.12.22 22:41:51. There are 3 logs in /var/tmp/logs-oltp30 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-12-20 22:41:53 No severe PSQL-related problems occured
20.12.22 22:41:55. End of report.