Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.12.2021 22:39
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 16653672 kB
MemAvailable 31514412 kB
Buffers 0 kB
Cached 15033132 kB
SwapCached 0 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.8
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 2021-12-04 22:38:03
DTS_END                         2021-12-04 22:38:03.1260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38052
TRN_ID                          238667
ATT_ID                          712
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: 9033.03 21664 1083964
customer order: creation 170.13 393 20415
customer order: refuse 14.60 426 1752
order to supplier: creation 44.78 1383 5374
order to supplier: removal 3.32 107284 398
invoice (draft): creation 45.68 3641 5481
invoice (draft): removal 51.34 862 6161
invoice accept: apply 41.97 17947 5036
invoice accept: cancel 45.80 805 5496
customer reserve: creation 3791.61 180 454993
customer reserve: removal 4532.41 62 543889
realization accept: apply 82.32 202 9878
realization accept: cancel 82.50 116 9900
payment from customer: creation 56.73 131 6807
payment from customer: removal 5.97 111 716
payment to supplier: creation 48.33 146 5800
payment to supplier: removal 3.61 69 433
service: total inventory turnovers 4.65 1184 558
service: total monetary turnovers 6.50 63 780
service: aggregate perf. data 0.78 22678 93
service: refresh index statistics 0.03 275587 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-12-2021 22:39:37 to 04-12-2021 22:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8899
TEST_TIME 2 6618
TEST_TIME 3 6640
TEST_TIME 4 6521
TEST_TIME 5 6632
TEST_TIME 6 7164
TEST_TIME 7 6851
TEST_TIME 8 6394
TEST_TIME 9 6409
TEST_TIME 10 6341
TEST_TIME 11 6424
TEST_TIME 12 6675
TEST_TIME 13 6797
TEST_TIME 14 7006
TEST_TIME 15 7844
TEST_TIME 16 7913
TEST_TIME 17 8060
TEST_TIME 18 8015
TEST_TIME 19 8130
TEST_TIME 20 8102
TEST_TIME 21 8391
TEST_TIME 22 8719
TEST_TIME 23 8899
TEST_TIME 24 8885
TEST_TIME 25 8872
TEST_TIME 26 9020
TEST_TIME 27 9187
TEST_TIME 28 9177
TEST_TIME 29 9309
TEST_TIME 30 9561
TEST_TIME 31 9959
TEST_TIME 32 10824
TEST_TIME 33 10663
TEST_TIME 34 10441
TEST_TIME 35 10266
TEST_TIME 36 10269
TEST_TIME 37 10211
TEST_TIME 38 10151
TEST_TIME 39 10037
TEST_TIME 40 9945
TEST_TIME 41 9859
TEST_TIME 42 9930
TEST_TIME 43 9931
TEST_TIME 44 9809
TEST_TIME 45 9844
TEST_TIME 46 9716
TEST_TIME 47 9596
TEST_TIME 48 9517
TEST_TIME 49 9556
TEST_TIME 50 9412
TEST_TIME 51 9328
TEST_TIME 52 9350
TEST_TIME 53 9297
TEST_TIME 54 9291
TEST_TIME 55 9257
TEST_TIME 56 9157
TEST_TIME 57 9071
TEST_TIME 58 9064
TEST_TIME 59 9041
TEST_TIME 60 9117
TEST_TIME 61 9101
TEST_TIME 62 9189
TEST_TIME 63 9236
TEST_TIME 64 9275
TEST_TIME 65 9282
TEST_TIME 66 9269
TEST_TIME 67 9326
TEST_TIME 68 9315
TEST_TIME 69 9278
TEST_TIME 70 9322
TEST_TIME 71 9331
TEST_TIME 72 9369
TEST_TIME 73 9348
TEST_TIME 74 9258
TEST_TIME 75 9234
TEST_TIME 76 9229
TEST_TIME 77 9165
TEST_TIME 78 9185
TEST_TIME 79 9158
TEST_TIME 80 9134
TEST_TIME 81 9110
TEST_TIME 82 9070
TEST_TIME 83 9064
TEST_TIME 84 9094
TEST_TIME 85 9070
TEST_TIME 86 9112
TEST_TIME 87 9096
TEST_TIME 88 9110
TEST_TIME 89 9125
TEST_TIME 90 9096
TEST_TIME 91 9137
TEST_TIME 92 9257
TEST_TIME 93 9314
TEST_TIME 94 9311
TEST_TIME 95 9354
TEST_TIME 96 9313
TEST_TIME 97 9314
TEST_TIME 98 9430
TEST_TIME 99 9432
TEST_TIME 100 9394
TEST_TIME 101 9310
TEST_TIME 102 9331
TEST_TIME 103 9280
TEST_TIME 104 9271
TEST_TIME 105 9247
TEST_TIME 106 9204
TEST_TIME 107 9206
TEST_TIME 108 9182
TEST_TIME 109 9155
TEST_TIME 110 9159
TEST_TIME 111 9122
TEST_TIME 112 9097
TEST_TIME 113 9043
TEST_TIME 114 9070
TEST_TIME 115 9053
TEST_TIME 116 9066
TEST_TIME 117 9122
TEST_TIME 118 9123
TEST_TIME 119 9112
TEST_TIME 120 9037

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-12-2021 22:39:41 to 04-12-2021 22:39:44.

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 1505370 1505370 0 0.00 0 26741 6 0 0
doc_list_biud 1505370 1505370 0 0.00 0 49844 29 0 0
sp_add_invoice_to_stock 5143 5036 107 2.08 29 146352 17947 0 107
sp_cancel_adding_invoice 5496 5496 0 0.00 5 59752 805 0 0
sp_cancel_client_order 1752 1752 0 0.00 4 9683 427 0 0
sp_cancel_customer_reserve 543889 543889 0 0.00 1 49848 62 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 2 10241 112 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 2082 69 0 0
sp_cancel_supplier_invoice 6171 6161 10 0.16 2 17823 863 0 10
sp_cancel_supplier_order 398 398 0 0.00 9 1074628 107284 0 0
sp_cancel_write_off 9900 9900 0 0.00 1 9333 117 0 0
sp_client_order 20415 20415 0 0.00 3 17741 394 0 0
sp_customer_reserve 454993 454993 0 0.00 0 36036 180 0 0
sp_fill_shopping_cart 31268 31268 0 0.00 1 16949 46 0 0
sp_fill_shopping_cart_clo_res 454985 454985 0 0.00 0 13878 15 0 0
sp_get_clo_for_invoice 5028 5028 0 0.00 0 35789 326 0 0
sp_kill_cost_storno 16545 16545 0 0.00 0 10912 61 0 0
sp_kill_qstorno_ret_qs2qd 1104237 1104237 0 0.00 0 47787 34 0 0
sp_kill_qty_storno 582502 582502 0 0.00 0 49844 73 0 0
sp_lock_selected_doc 20708 20708 0 0.00 0 10994 6 0 0
sp_make_cost_storno 27513 27513 0 0.00 0 26737 60 0 0
sp_multiply_rows_for_pdistr 27513 27513 0 0.00 0 7948 32 0 0
sp_multiply_rows_for_qdistr 20415 20415 0 0.00 0 17253 169 0 0
sp_pay_from_customer 6807 6807 0 0.00 4 15111 131 0 0
sp_pay_to_supplier 5800 5800 0 0.00 5 26753 146 0 0
sp_payment_common 12607 12607 0 0.00 1 15102 32 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 9674 386 0 0
sp_qd_handle_on_invoice_upd_sts 10524 10524 0 0.00 0 16225 333 0 0
sp_qd_handle_on_reserve_upd_sts 9878 9878 0 0.00 0 12773 68 0 0
sp_reserve_write_off 9878 9878 0 0.00 6 15877 203 0 0
sp_supplier_invoice 5481 5481 0 0.00 5 70451 3641 0 0
sp_supplier_order 5374 5374 0 0.00 5 25180 1383 0 0
srv_aggregate_perf_data 93 93 0 0.00 2974 268505 22679 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1181 198671 43414 0 0
srv_find_qd_qs_mism 1037063 1037063 0 0.00 0 11948 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 47 58752 1185 0 0
srv_make_money_saldo 780 780 0 0.00 0 23810 63 0 0
srv_recalc_idx_stat 4 4 0 0.00 113568 392486 275588 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 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 5036 5036 0 0.00 0 16100 176 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 3614 129 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 2311 17 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 1339 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 5754 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 1895 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 1367 17 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 23176 1684 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 8498 16 0 0
t$perf-norm:sp_client_order 20415 20415 0 0.00 0 10225 18 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 6230 11 0 0
t$perf-norm:sp_pay_from_customer 6807 6807 0 0.00 0 5095 9 0 0
t$perf-norm:sp_pay_to_supplier 5800 5800 0 0.00 0 9510 12 0 0
t$perf-norm:sp_reserve_write_off 9878 9878 0 0.00 0 3964 12 0 0
t$perf-norm:sp_supplier_invoice 5481 5481 0 0.00 0 3748 17 0 0
t$perf-norm:sp_supplier_order 5374 5374 0 0.00 0 5505 19 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 252 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 8599 142 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 986 23 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 2372 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 7 0 0
v_add_invoice_to_stock 10270 10056 214 2.08 0 10213 22 0 214
v_all_customers 36792 36792 0 0.00 0 59 1 0 0
v_all_suppliers 29984 29984 0 0.00 0 85 1 0 0
v_all_wares 40830 40830 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 2174 10 0 0
v_cancel_client_order 3504 3504 0 0.00 0 4325 7 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 10217 20 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 4251 14 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 1608 5 0 0
v_cancel_supplier_invoice 876 856 20 2.28 0 5052 20 0 20
v_cancel_supplier_order 796 796 0 0.00 0 2363 11 0 0
v_cancel_write_off 2044 2044 0 0.00 0 7933 17 0 0
v_max_id_clo_ord 5374 5374 0 0.00 0 5992 3 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 2539 13 0 0
v_max_id_ord_sup 5479 5479 0 0.00 0 4506 6 0 0
v_max_non_paid_invoice 1661 1661 0 0.00 0 3786 5 0 0
v_max_non_paid_realizn 3700 3700 0 0.00 0 2873 5 0 0
v_min_id_clo_ord 5374 5374 0 0.00 0 11447 13 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 2742 8 0 0
v_min_id_ord_sup 5479 5479 0 0.00 0 3572 8 0 0
v_min_non_paid_invoice 5800 5800 0 0.00 0 13706 22 0 0
v_min_non_paid_realizn 6807 6807 0 0.00 0 14718 25 0 0
v_our_firm 10252 10252 0 0.00 0 22 1 0 0
v_reserve_write_off 19756 19756 0 0.00 0 11178 9 0 0
x_lock_depdocs_on_canc_invoice 11229 11229 0 0.00 0 17744 120 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 8508 393 0 0
x_make_qstorno_1000_1200 5374 5374 0 0.00 2 25164 1281 0 0
x_make_qstorno_1200_2000 5481 5481 0 0.00 2 67388 3486 0 0
x_make_qstorno_2100_3300 454993 454993 0 0.00 0 36032 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-12-2021 22:39:45 to 04-12-2021 22:39:49.

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:06 29 8192000000 8815053904 8831471616 18073664 0.220 5 4 5 0 18301744 88816 139264
20:09:07 35940 8192000000 9919510464 10234298368 1000271952 10.881 100 91 182 0 1030051728 6596448 23183328
20:10:48 18330 8192000000 9952946704 10278735872 1036327216 11.229 100 87 174 0 1067643648 9053104 22263328
20:12:11 18034 8192000000 9987504384 10324160512 1075867120 11.608 100 87 171 1 1108103712 10377072 21859520
20:13:33 13951 8192000000 10022614592 10375188480 1114891712 11.979 100 73 146 1 1143720016 11794160 17034144
20:14:51 10721 8192000000 10083570416 10445955072 1171356512 12.510 100 81 162 2 1203771232 13728752 18685968
20:16:04 6772 8192000000 10133742528 10508804096 1222599248 12.986 100 80 160 1 1253243856 12393280 18251328
20:17:14 6643 8192000000 10172170400 10551959552 1263948112 13.366 100 78 155 1 1293701904 13183552 16570240
20:18:23 2033 8192000000 10218443648 10605821952 1299973088 13.695 100 88 174 0 1332718608 13146688 19598832
20:19:27 1669 8192000000 10235756032 10626273280 1318613776 13.864 100 93 186 2 1352093488 12702688 20777024
20:20:29 12547 8192000000 10217761808 10628415488 1322332560 13.898 100 49 96 2 1341709776 7599888 11777328
20:21:42 5555 8192000000 10222970864 10640117760 1333232928 13.996 100 32 64 0 1347155040 6350304 7571808
20:22:52 11114 8192000000 10223018880 10645667840 1335970272 14.021 100 16 33 0 1344872880 5473904 3428704
20:24:03 4381 8192000000 10228278784 10652348416 1345714384 14.109 100 24 48 0 1357427312 5778880 5934048
20:25:08 60727 8192000000 10225759152 10656681984 1330760560 13.974 99 28 54 0 1341941328 5230544 5950224
20:27:17 3711 8192000000 10222378016 10642464768 1328097744 13.950 100 28 56 2 1340545520 5889136 6558640
20:28:21 14203 8192000000 10179145392 10587103232 1234504848 13.096 94 18 36 1 1243635920 4835744 4295328
20:29:35 10669 8192000000 10128184720 10533048320 1207547760 12.846 95 17 34 1 1217234400 5260384 4426256
20:30:46 21436 8192000000 10143603392 10549817344 1203173728 12.806 95 24 48 3 1214884800 6363088 5347984
20:32:08 5942 8192000000 10134955872 10534875136 1229728864 13.052 98 29 58 0 1242017792 5743184 6545744
20:33:14 7965 8192000000 10175240368 10584072192 1271149504 13.432 99 29 58 1 1283533040 5448128 6935408
20:34:23 7985 8192000000 10174528864 10589143040 1286338608 13.571 100 15 30 1 1295551552 5398672 3814272
20:35:31 38845 8192000000 10193468176 10608177152 1300204032 13.697 100 29 58 1 1312789040 6411488 6173520
20:37:11 10141 8192000000 10208517600 10625421312 1311841392 13.803 100 30 60 2 1324601232 6066160 6693680
20:38:25 5796 8192000000 10246941280 10656284672 1339354432 14.052 100 40 79 1 1354705184 7068144 8282608
20:39:53 9699 8192000000 10277250336 10686943232 1359351952 14.232 100 74 146 2 1387799392 11352640 17094800
20:41:05 27870 8192000000 10266559072 10685431808 1357910432 14.219 100 51 102 2 1379021936 9205904 11905600
20:42:39 23014 8192000000 10288726160 10704723968 1359895120 14.236 99 59 118 0 1387342192 13887120 13559952
20:44:05 9400 8192000000 10304700144 10725163008 1387138064 14.480 100 50 100 2 1409522256 10361104 12023088
20:45:21 1508 8192000000 10321134912 10739470336 1402634288 14.618 100 56 111 4 1425875440 10977984 12263168
20:46:27 23305 8192000000 10315984688 10738909184 1392903520 14.532 99 55 111 1 1420449152 14301120 13244512
20:47:58 9878 8192000000 10320488448 10747039744 1399920768 14.594 99 39 76 2 1424951392 15376512 9654112
20:49:13 10625 8192000000 10316719312 10737516544 1397302592 14.571 100 58 116 2 1430820304 18945920 14571792
20:50:29 2059 8192000000 10335234048 10755768320 1404469232 14.635 100 67 129 1 1442120432 20851968 16799232
20:51:35 7190 8192000000 10334119200 10760421376 1406280416 14.651 100 63 127 0 1445682000 23693600 15707984
20:52:46 7726 8192000000 10350003968 10777264128 1403667232 14.628 99 63 124 1 1449933904 30777248 15489424
20:54:01 1177 8192000000 10321995936 10734944256 1385506032 14.466 99 68 134 0 1424657616 23176816 15974768
20:55:05 11197 8192000000 10296698864 10718593024 1358476960 14.224 99 52 104 1 1395689776 24751056 12461760
20:56:27 2218 8192000000 10307926592 10717990912 1350907920 14.156 98 81 163 2 1396872880 26308848 19656112
20:57:34 18694 8192000000 10280261872 10699431936 1355352032 14.196 100 42 83 0 1389365776 23934816 10078928
20:58:59 1644 8192000000 10313224624 10713767936 1359738368 14.235 100 76 149 0 1411135728 33052688 18344672
21:00:07 5260 8192000000 10307979920 10721247232 1362450224 14.259 100 60 120 0 1411182352 32835264 15896864
21:01:19 11653 8192000000 10318919232 10745884672 1384452800 14.456 100 38 76 2 1426749312 32335776 9960736
21:02:36 10170 8192000000 10311653520 10738597888 1380974416 14.425 100 35 70 1 1420439648 30370304 9094928
21:04:09 8326 8192000000 10340714752 10763739136 1392295840 14.526 100 42 85 0 1442563328 38543824 11723664
21:05:21 26099 8192000000 10340926832 10774351872 1402165840 14.614 100 28 56 1 1443721760 33819872 7736048
21:06:51 14733 8192000000 10343126672 10778791936 1398442064 14.581 100 30 60 0 1447819536 40690656 8686816
21:08:16 18024 8192000000 10335712624 10772963328 1369402192 14.322 98 26 52 0 1422475120 45393728 7679200
21:09:40 29026 8192000000 10326495440 10773352448 1386778448 14.477 100 15 30 0 1430041584 38935808 4327328
21:11:10 69052 8192000000 10322671088 10756476928 1385272112 14.464 99 25 50 2 1402372096 11101552 5998432
21:13:22 40363 8192000000 10310241248 10752909312 1385880080 14.469 99 36 72 2 1405557136 10898320 8778736
21:15:22 88704 8192000000 10294935360 10756857856 1370371088 14.330 98 11 22 0 1387526464 14492576 2662800
21:17:54 109817 8192000000 10318024544 10769756160 1378079008 14.399 98 25 49 0 1398803136 15178480 5545648
21:21:17 100127 8192000000 10313529200 10770440192 1403691008 14.628 100 13 27 0 1416019840 9631152 2697680
21:23:58 59351 8192000000 10308247824 10766200832 1403143488 14.623 100 13 26 0 1415802720 9141312 3517920
21:26:03 78003 8192000000 10304151488 10768211968 1399305264 14.589 100 31 62 0 1416119888 10403408 6411216
21:28:33 25488 8192000000 10301573328 10761293824 1402426176 14.617 100 18 36 0 1418183920 9924272 5833472
21:30:02 22954 8192000000 10328267776 10772934656 1403840672 14.629 100 56 112 0 1432923584 16125360 12957552
21:31:28 67763 8192000000 10300130272 10767491072 1374155152 14.364 98 14 27 2 1392473856 14560976 3757728
21:35:41 41210 8192000000 10288910912 10755739648 1388087776 14.489 99 15 30 0 1404871552 13317952 3465824
21:37:24 44528 8192000000 10291242240 10745737216 1330553824 13.972 95 13 26 0 1349551504 15940304 3057376
21:39:11 36161 8192000000 10287901968 10746179584 1362420720 14.259 98 31 63 3 1381274592 11708704 7145168
21:40:49 119141 8192000000 10265158768 10736635904 1298250080 13.679 94 17 34 0 1317341584 15636160 3455344
21:43:49 64237 8192000000 10276882560 10723045376 1325193040 13.924 96 21 42 0 1337356624 7389376 4774208
21:45:54 42023 8192000000 10278927456 10735341568 1341482864 14.071 97 19 36 1 1354861488 9374096 4004528
21:48:10 32208 8192000000 10257474672 10701459456 1338077760 14.040 99 25 50 1 1353403280 10244800 5080720
21:49:50 35419 8192000000 10263188080 10710532096 1346698944 14.118 100 22 44 0 1366486912 14797632 4990336
21:51:32 87651 8192000000 10257913472 10709823488 1355264096 14.195 100 18 36 0 1368975472 9684512 4026864
21:54:14 58915 8192000000 10262083952 10724974592 1357190416 14.212 99 27 54 1 1378275968 14902656 6182896
21:56:15 26143 8192000000 10265013376 10703089664 1345667312 14.108 99 50 98 1 1368022144 11191152 11163680
21:58:05 66589 8192000000 10263217536 10718068736 1352431616 14.169 99 10 20 0 1364176656 9540480 2204560
22:00:12 74169 8192000000 10260887440 10717122560 1364689264 14.279 100 12 24 0 1377426144 9801200 2935680
22:03:29 62033 8192000000 10261716272 10720419840 1356190704 14.203 99 18 36 0 1369106848 9048336 3867808
22:05:34 20893 8192000000 10270395504 10710114304 1349852640 14.146 99 44 88 0 1368233504 8542224 9838640
22:07:00 49216 8192000000 10285126000 10741854208 1377064864 14.390 100 24 48 0 1394516656 11661296 5790496
22:08:58 84856 8192000000 10300393136 10758303744 1384249408 14.455 100 9 18 0 1404662960 18253824 2159728
22:11:32 121959 8192000000 10292334832 10756706304 1363807472 14.272 98 11 22 0 1376230272 10220528 2202272
22:14:35 18537 8192000000 10313060784 10744881152 1353833760 14.182 97 37 75 2 1369043456 6347808 8861888
22:15:57 67504 8192000000 10291147536 10755436544 1383656528 14.449 100 7 14 1 1404668448 19517120 1494800
22:18:13 198671 8192000000 10284499312 10749272064 1347013728 14.121 97 19 39 0 1363984048 12715632 4254688
22:23:21 37483 8192000000 10274551104 10745139200 1375653040 14.378 100 9 18 0 1394747040 16934608 2159392
22:25:01 54268 8192000000 10265068272 10717343744 1341149168 14.068 97 16 32 0 1356017216 11401584 3466464
22:26:59 76444 8192000000 10263964896 10717020160 1334329040 14.006 97 13 27 0 1346730272 9306848 3094384
22:29:35 8446 8192000000 10288961328 10738016256 1380606464 14.422 100 25 50 1 1395751664 9254400 5890800
22:30:50 106845 8192000000 10258456160 10712576000 1302729344 13.720 95 18 36 0 1318680944 11526976 4424624
22:33:55 88380 8192000000 10287650480 10739314688 1323752608 13.911 97 15 30 1 1347654352 20369120 3532624
22:36:29 53170 8192000000 10273016384 10726764544 1330646976 13.973 97 22 44 1 1353413952 17519888 5247088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-12-2021 22:39:51 to 04-12-2021 22:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 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 04-12-2021 22:40:07 to 04-12-2021 22:40:09.

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          234693
MON$OLDEST_ACTIVE               238782
MON$OLDEST_SNAPSHOT             238782
MON$NEXT_TRANSACTION            238782
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                       2039776
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33541 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33541 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33541 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33541 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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Sat Dec  4 22:38:05 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Sat Dec  4 22:38:17 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Sat Dec  4 22:38:18 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Sat Dec  4 22:38:18 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Sat Dec  4 22:38:18 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Sat Dec  4 22:38:20 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Sat Dec  4 22:38:20 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Sat Dec  4 22:38:20 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
--- end of diff output ---

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

04.12.21 22:40:10. 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
2021-12-04 22:40:12 No severe PSQL-related problems occured
04.12.21 22:40:13. End of report.