Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.05.2022 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 16573168 kB
MemAvailable 31449908 kB
Buffers 0 kB
Cached 15172952 kB
SwapCached 2588 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.10
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-05-21 22:38:13
DTS_END                         2022-05-21 22:38:13.4280
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54516
TRN_ID                          226683
ATT_ID                          651
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: 9044.24 22673 1085309
customer order: creation 155.97 353 18716
customer order: refuse 12.71 366 1525
order to supplier: creation 41.35 1281 4962
order to supplier: removal 3.33 127691 399
invoice (draft): creation 41.84 3655 5021
invoice (draft): removal 53.48 903 6417
invoice accept: apply 38.15 19392 4578
invoice accept: cancel 49.08 873 5889
customer reserve: creation 3613.98 188 433677
customer reserve: removal 4750.03 71 570004
realization accept: apply 75.67 174 9080
realization accept: cancel 90.43 106 10852
payment from customer: creation 54.58 103 6550
payment from customer: removal 5.12 77 614
payment to supplier: creation 44.32 116 5318
payment to supplier: removal 3.08 89 369
service: total inventory turnovers 4.13 1213 496
service: total monetary turnovers 6.15 19 738
service: aggregate perf. data 0.83 18707 100
service: refresh index statistics 0.03 278078 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-05-2022 22:39:48 to 21-05-2022 22:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3278
TEST_TIME 2 5114
TEST_TIME 3 4775
TEST_TIME 4 4790
TEST_TIME 5 4909
TEST_TIME 6 5236
TEST_TIME 7 5290
TEST_TIME 8 5633
TEST_TIME 9 5994
TEST_TIME 10 6025
TEST_TIME 11 6074
TEST_TIME 12 6116
TEST_TIME 13 6343
TEST_TIME 14 6217
TEST_TIME 15 6977
TEST_TIME 16 7211
TEST_TIME 17 7203
TEST_TIME 18 7293
TEST_TIME 19 7218
TEST_TIME 20 7689
TEST_TIME 21 7601
TEST_TIME 22 7821
TEST_TIME 23 8327
TEST_TIME 24 8565
TEST_TIME 25 9196
TEST_TIME 26 9151
TEST_TIME 27 10202
TEST_TIME 28 10854
TEST_TIME 29 11314
TEST_TIME 30 11047
TEST_TIME 31 10925
TEST_TIME 32 10807
TEST_TIME 33 10589
TEST_TIME 34 10644
TEST_TIME 35 10478
TEST_TIME 36 10280
TEST_TIME 37 10163
TEST_TIME 38 10184
TEST_TIME 39 10030
TEST_TIME 40 9955
TEST_TIME 41 9869
TEST_TIME 42 9747
TEST_TIME 43 9635
TEST_TIME 44 9660
TEST_TIME 45 9493
TEST_TIME 46 9495
TEST_TIME 47 9562
TEST_TIME 48 9449
TEST_TIME 49 9568
TEST_TIME 50 9589
TEST_TIME 51 9564
TEST_TIME 52 9447
TEST_TIME 53 9514
TEST_TIME 54 9676
TEST_TIME 55 9790
TEST_TIME 56 9810
TEST_TIME 57 9863
TEST_TIME 58 9778
TEST_TIME 59 9922
TEST_TIME 60 9880
TEST_TIME 61 9727
TEST_TIME 62 9709
TEST_TIME 63 9623
TEST_TIME 64 9570
TEST_TIME 65 9593
TEST_TIME 66 9518
TEST_TIME 67 9490
TEST_TIME 68 9412
TEST_TIME 69 9389
TEST_TIME 70 9376
TEST_TIME 71 9344
TEST_TIME 72 9378
TEST_TIME 73 9335
TEST_TIME 74 9359
TEST_TIME 75 9350
TEST_TIME 76 9320
TEST_TIME 77 9410
TEST_TIME 78 9454
TEST_TIME 79 9413
TEST_TIME 80 9452
TEST_TIME 81 9490
TEST_TIME 82 9490
TEST_TIME 83 9406
TEST_TIME 84 9334
TEST_TIME 85 9336
TEST_TIME 86 9288
TEST_TIME 87 9301
TEST_TIME 88 9247
TEST_TIME 89 9210
TEST_TIME 90 9157
TEST_TIME 91 9122
TEST_TIME 92 9167
TEST_TIME 93 9154
TEST_TIME 94 9177
TEST_TIME 95 9148
TEST_TIME 96 9230
TEST_TIME 97 9216
TEST_TIME 98 9198
TEST_TIME 99 9215
TEST_TIME 100 9315
TEST_TIME 101 9319
TEST_TIME 102 9270
TEST_TIME 103 9331
TEST_TIME 104 9317
TEST_TIME 105 9341
TEST_TIME 106 9333
TEST_TIME 107 9354
TEST_TIME 108 9275
TEST_TIME 109 9228
TEST_TIME 110 9219
TEST_TIME 111 9211
TEST_TIME 112 9191
TEST_TIME 113 9163
TEST_TIME 114 9142
TEST_TIME 115 9103
TEST_TIME 116 9045
TEST_TIME 117 9073
TEST_TIME 118 9056
TEST_TIME 119 9101
TEST_TIME 120 9044

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-05-2022 22:39:52 to 21-05-2022 22:39: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 1487376 1487376 0 0.00 0 19723 6 0 0
doc_list_biud 1487376 1487376 0 0.00 0 72488 34 0 0
sp_add_invoice_to_stock 4710 4578 132 2.80 22 210230 19393 0 132
sp_cancel_adding_invoice 5889 5889 0 0.00 5 66064 874 0 0
sp_cancel_client_order 1525 1525 0 0.00 4 14517 367 0 0
sp_cancel_customer_reserve 570004 570004 0 0.00 1 72489 72 0 0
sp_cancel_pay_from_customer 614 614 0 0.00 3 6176 78 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 3 4373 89 0 0
sp_cancel_supplier_invoice 6431 6417 14 0.21 2 17168 904 0 14
sp_cancel_supplier_order 399 399 0 0.00 6 1172553 127692 0 0
sp_cancel_write_off 10852 10852 0 0.00 1 11199 106 0 0
sp_client_order 18716 18716 0 0.00 3 20257 353 0 0
sp_customer_reserve 433677 433677 0 0.00 0 82659 188 0 0
sp_fill_shopping_cart 28670 28670 0 0.00 1 15906 36 0 0
sp_fill_shopping_cart_clo_res 433677 433677 0 0.00 0 21605 16 0 0
sp_get_clo_for_invoice 4578 4578 0 0.00 0 25970 390 0 0
sp_kill_cost_storno 17724 17724 0 0.00 0 11161 55 0 0
sp_kill_qstorno_ret_qs2qd 1157676 1157676 0 0.00 0 72482 39 0 0
sp_kill_qty_storno 608744 608744 0 0.00 0 72488 82 0 0
sp_lock_selected_doc 18894 18894 0 0.00 0 8955 6 0 0
sp_make_cost_storno 25526 25526 0 0.00 0 19720 52 0 0
sp_multiply_rows_for_pdistr 25526 25526 0 0.00 0 7861 24 0 0
sp_multiply_rows_for_qdistr 18716 18716 0 0.00 0 20243 175 0 0
sp_pay_from_customer 6550 6550 0 0.00 4 15432 103 0 0
sp_pay_to_supplier 5318 5318 0 0.00 5 19750 117 0 0
sp_payment_common 11868 11868 0 0.00 1 15421 29 0 0
sp_qd_handle_on_cancel_clo 1525 1525 0 0.00 0 14510 344 0 0
sp_qd_handle_on_invoice_upd_sts 10467 10467 0 0.00 0 16477 338 0 0
sp_qd_handle_on_reserve_upd_sts 9080 9080 0 0.00 0 28199 59 0 0
sp_reserve_write_off 9080 9080 0 0.00 6 28232 175 0 0
sp_supplier_invoice 5021 5021 0 0.00 6 67139 3655 0 0
sp_supplier_order 4962 4962 0 0.00 5 56061 1282 0 0
srv_aggregate_perf_data 100 100 0 0.00 2553 209232 18707 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2493 180545 48392 0 0
srv_find_qd_qs_mism 1041649 1041649 0 0.00 0 15061 5 0 0
srv_make_invnt_saldo 496 496 0 0.00 52 99132 1214 0 0
srv_make_money_saldo 738 738 0 0.00 1 231 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 209673 336017 278078 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4578 4578 0 0.00 0 12572 167 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 6720 108 0 0
t$perf-norm:sp_cancel_client_order 1525 1525 0 0.00 0 4580 16 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 6295 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 614 614 0 0.00 0 437 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 2362 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 1923 25 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 21545 1617 0 0
t$perf-norm:sp_cancel_write_off 901 901 0 0.00 0 2318 10 0 0
t$perf-norm:sp_client_order 18716 18716 0 0.00 0 5925 17 0 0
t$perf-norm:sp_customer_reserve 861 861 0 0.00 0 1603 7 0 0
t$perf-norm:sp_pay_from_customer 6550 6550 0 0.00 0 5606 9 0 0
t$perf-norm:sp_pay_to_supplier 5318 5318 0 0.00 0 4829 9 0 0
t$perf-norm:sp_reserve_write_off 9080 9080 0 0.00 0 6262 9 0 0
t$perf-norm:sp_supplier_invoice 5021 5021 0 0.00 0 3674 14 0 0
t$perf-norm:sp_supplier_order 4962 4962 0 0.00 0 9391 20 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 712 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 91 2 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 1972 26 0 0
t$perf-norm:srv_make_money_saldo 738 738 0 0.00 0 2985 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 9420 9156 264 2.80 0 5779 18 0 264
v_all_customers 34496 34496 0 0.00 0 89 0 0 0
v_all_suppliers 27682 27682 0 0.00 0 90 1 0 0
v_all_wares 37432 37432 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 2051 9 0 0
v_cancel_client_order 3050 3050 0 0.00 0 2558 4 0 0
v_cancel_customer_prepayment 1228 1228 0 0.00 0 1201 5 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 1974 5 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 4361 12 0 0
v_cancel_supplier_invoice 786 758 28 3.56 0 5742 23 0 28
v_cancel_supplier_order 798 798 0 0.00 0 129 3 0 0
v_cancel_write_off 1802 1802 0 0.00 0 5796 18 0 0
v_max_id_clo_ord 4962 4962 0 0.00 0 316 1 0 0
v_max_id_clo_res 861 861 0 0.00 0 3077 18 0 0
v_max_id_ord_sup 4992 4992 0 0.00 0 4135 4 0 0
v_max_non_paid_invoice 1431 1431 0 0.00 0 5021 7 0 0
v_max_non_paid_realizn 3405 3405 0 0.00 0 5020 7 0 0
v_min_id_clo_ord 4962 4962 0 0.00 0 1739 4 0 0
v_min_id_clo_res 861 861 0 0.00 0 4590 30 0 0
v_min_id_ord_sup 4992 4992 0 0.00 0 3248 6 0 0
v_min_non_paid_invoice 5318 5318 0 0.00 0 13638 20 0 0
v_min_non_paid_realizn 6550 6550 0 0.00 0 15414 19 0 0
v_our_firm 9226 9226 0 0.00 0 17 1 0 0
v_reserve_write_off 18160 18160 0 0.00 0 3811 6 0 0
x_lock_depdocs_on_canc_invoice 11927 11927 0 0.00 0 18916 133 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 36600 543 0 0
x_make_qstorno_1000_1200 4962 4962 0 0.00 1 56037 1209 0 0
x_make_qstorno_1200_2000 5021 5021 0 0.00 3 67097 3511 0 0
x_make_qstorno_2100_3300 433677 433677 0 0.00 0 82658 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-05-2022 22:39:56 to 21-05-2022 22:40: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
20:08:17 55 8192000000 8827169360 8847142912 21913888 0.266 5 5 6 0 22184880 99552 171440
20:09:19 22652 8192000000 9913224384 10223239168 999788912 10.876 100 90 180 1 1029026544 5893984 23343648
20:10:49 30793 8192000000 9963771968 10286309376 1046352048 11.326 100 94 188 0 1078903712 8480240 24071424
20:12:26 17820 8192000000 9983303120 10313809920 1062246592 11.478 100 80 160 0 1092445424 10347440 19851392
20:13:52 16636 8192000000 10045678352 10392981504 1125808288 12.082 100 79 158 0 1155840928 11390352 18642288
20:15:14 11086 8192000000 10090686592 10452455424 1178807952 12.579 100 83 166 2 1210873328 12940528 19124848
20:16:28 5911 8192000000 10129541424 10495012864 1210960832 12.878 100 85 170 0 1243866496 14177920 18727744
20:17:36 3885 8192000000 10175505824 10549506048 1255155408 13.286 100 85 170 0 1288268336 14034960 19077968
20:18:41 1501 8192000000 10201878080 10581319680 1281961936 13.531 100 94 188 2 1316793776 12903280 21928560
20:19:44 1383 8192000000 10223469248 10608128000 1302739504 13.720 100 92 184 2 1337185472 13796016 20649952
20:20:46 7294 8192000000 10200945008 10607591424 1309590304 13.782 100 33 66 1 1323784544 7005008 7189232
20:21:57 7882 8192000000 10210865920 10620796928 1317728080 13.856 100 40 80 0 1335960672 9269264 8963328
20:23:06 4726 8192000000 10208484800 10626646016 1321728464 13.892 100 23 46 0 1333273008 6511248 5033296
20:24:14 37358 8192000000 10245088912 10643013632 1329043648 13.959 100 80 164 2 1354107840 5490848 19573344
20:25:52 6743 8192000000 10232580112 10645975040 1334462304 14.007 100 41 83 3 1350261184 5881472 9917408
20:27:01 7979 8192000000 10232612464 10649399296 1335007856 14.012 100 31 62 0 1347854928 5464320 7382752
20:28:13 35418 8192000000 10201736832 10608848896 1294760096 13.648 99 55 110 2 1315235488 5563968 14911424
20:29:53 3223 8192000000 10183442256 10594152448 1284240480 13.552 98 27 54 0 1296232128 6132016 5859632
20:30:57 4610 8192000000 10169390176 10565947392 1238472944 13.132 97 29 58 0 1250780288 5040720 7266624
20:32:04 9537 8192000000 10135695712 10538213376 1215454880 12.920 96 29 58 0 1229081264 6584816 7041568
20:33:13 38293 8192000000 10141590656 10547073024 1232505248 13.077 98 27 54 1 1248064848 9175296 6384304
20:34:52 5435 8192000000 10165147008 10570711040 1270909760 13.430 100 29 59 0 1283990928 6977376 6103792
20:36:00 8964 8192000000 10188840832 10597957632 1290079728 13.605 100 30 60 3 1306578896 9920544 6578624
20:37:11 12085 8192000000 10194932240 10612998144 1302673616 13.720 100 13 25 1 1310883152 4926992 3282544
20:38:26 81365 8192000000 10242815520 10651881472 1323094192 13.905 100 51 100 2 1341931024 6662272 12174560
20:40:51 5301 8192000000 10263953632 10680111104 1353665568 14.180 100 56 110 1 1378294000 12649024 11979408
20:42:11 17598 8192000000 10290114560 10711998464 1377635344 14.395 100 42 84 2 1405546816 18253008 9658464
20:43:34 15318 8192000000 10304628080 10728886272 1387230816 14.481 100 46 90 2 1419277616 20426064 11620736
20:44:58 16087 8192000000 10302595152 10729619456 1377326576 14.393 99 50 100 1 1411855536 21963584 12565376
20:46:17 8527 8192000000 10310095792 10732941312 1386321936 14.473 100 49 98 1 1422942640 24721120 11899584
20:47:33 5084 8192000000 10320510192 10748579840 1398409120 14.581 100 52 105 0 1437346960 26616400 12321440
20:48:44 2571 8192000000 10339864768 10755235840 1399669568 14.592 99 74 147 0 1446420304 29181952 17568784
20:49:56 3374 8192000000 10354496656 10772422656 1410374832 14.687 99 75 150 1 1464170000 36313792 17481376
20:51:05 8456 8192000000 10358282416 10780835840 1401719296 14.610 99 54 107 0 1458748576 42870880 14158400
20:52:20 5190 8192000000 10372615824 10792058880 1414728528 14.726 100 64 127 4 1478933616 48112768 16092320
20:53:35 8216 8192000000 10384980384 10806951936 1426775008 14.833 100 67 134 2 1497784784 53331280 17678496
20:54:49 3459 8192000000 10393253168 10808061952 1429480000 14.857 100 82 163 2 1496348128 45144096 21724032
20:56:03 2492 8192000000 10390175600 10801618944 1418579344 14.760 100 83 165 1 1488524928 48640272 21305312
20:57:11 7991 8192000000 10380975200 10801606656 1393130496 14.534 97 50 100 1 1461207776 53845600 14231680
20:58:24 16946 8192000000 10403498240 10830942208 1436849584 14.922 100 56 112 2 1512934048 60576016 15508448
20:59:45 3748 8192000000 10398187920 10816532480 1413032448 14.711 99 71 141 0 1494383712 63131696 18219568
21:00:53 11787 8192000000 10375867408 10803986432 1398926768 14.585 100 47 94 2 1480465504 67860736 13678000
21:02:06 23435 8192000000 10375103472 10796179456 1377760288 14.397 98 45 90 0 1462865984 72170208 12935488
21:03:34 15766 8192000000 10325412960 10767507456 1370791984 14.334 100 33 66 0 1439024848 58651920 9580944
21:05:06 17585 8192000000 10330345872 10767208448 1343480912 14.089 99 27 54 1 1427015344 74980096 8554336
21:06:55 33522 8192000000 10273482288 10745749504 1341869312 14.074 99 6 12 0 1369953520 26140608 1943600
21:09:25 50525 8192000000 10278501712 10713661440 1361600208 14.252 100 40 80 1 1385910448 14995616 9314624
21:11:17 93620 8192000000 10268355184 10730577920 1350357344 14.151 98 23 46 2 1367858096 12002000 5498752
21:13:55 57391 8192000000 10257226256 10709438464 1342345968 14.079 98 17 34 0 1357519632 11583344 3590320
21:15:53 63435 8192000000 10288101888 10749022208 1374045824 14.363 100 13 25 0 1396029280 19327392 2656064
21:17:59 38641 8192000000 10273862704 10726666240 1350156048 14.149 98 12 24 0 1366429136 13342768 2930320
21:19:47 110189 8192000000 10281512672 10748588032 1374928912 14.371 99 22 43 3 1397589968 16880096 5780960
21:25:25 89937 8192000000 10294001392 10744569856 1377153344 14.391 99 14 28 0 1393289264 12736928 3398992
21:27:56 94623 8192000000 10318786128 10778775552 1399040448 14.586 100 24 48 0 1420454064 16179216 5234400
21:30:33 37841 8192000000 10335863824 10779885568 1392448880 14.528 99 49 98 1 1423255712 19359984 11446848
21:33:29 59034 8192000000 10316628560 10767409152 1390529120 14.511 99 14 28 1 1410384752 16608720 3246912
21:35:28 47625 8192000000 10309335504 10765242368 1401396592 14.607 100 17 32 1 1415001808 9704064 3901152
21:37:16 42571 8192000000 10313970160 10787467264 1394174704 14.543 99 27 54 0 1420167232 20091360 5901168
21:39:04 80518 8192000000 10312784416 10768887808 1395145296 14.552 99 28 56 0 1409638400 8297872 6195232
21:41:25 23825 8192000000 10317882672 10764812288 1394620704 14.547 99 41 81 0 1411958320 8760800 8576816
21:42:56 53125 8192000000 10292965680 10763669504 1380711984 14.423 98 19 38 0 1396595856 11636928 4246944
21:45:06 47216 8192000000 10273279264 10738401280 1369149120 14.319 98 19 37 0 1387041872 13717568 4175184
21:47:13 38157 8192000000 10271402240 10736353280 1370245728 14.329 99 16 32 0 1382025200 8136768 3642704
21:48:51 51887 8192000000 10294160976 10751016960 1371385856 14.339 98 13 26 1 1381673968 7168960 3119152
21:51:10 19143 8192000000 10287955280 10722455552 1328200672 13.951 96 47 92 0 1346460784 7913408 10346704
21:52:31 32329 8192000000 10279975344 10741604352 1368684416 14.315 99 17 35 0 1386973216 14864352 3424448
21:54:04 39355 8192000000 10276499360 10723217408 1325120384 13.923 96 23 44 2 1344997440 14585648 5291408
21:55:45 80707 8192000000 10264687696 10709274624 1267609408 13.400 92 11 22 0 1288938464 19291568 2037488
21:58:17 55506 8192000000 10280901856 10744139776 1374494864 14.367 100 10 20 0 1391514592 14869584 2150144
22:00:14 131716 8192000000 10228371024 10667515904 1290394880 13.608 94 9 18 0 1305583808 13102896 2086032
22:03:30 68228 8192000000 10270875168 10712641536 1339294672 14.051 98 53 104 1 1364285600 13730384 11260544
22:05:44 42783 8192000000 10246972720 10704187392 1355944976 14.201 100 12 24 1 1366044528 7411504 2688048
22:07:28 71899 8192000000 10265345296 10715734016 1342823616 14.083 98 13 26 0 1352925120 6853488 3248016
22:09:41 74217 8192000000 10242038080 10706235392 1322472112 13.899 97 16 32 0 1336967872 10952272 3543488
22:12:15 71651 8192000000 10265456144 10713358336 1320761584 13.884 96 19 39 0 1336200544 11266304 4172656
22:14:43 61473 8192000000 10278724544 10729689088 1364646384 14.279 100 20 40 0 1380536912 10952032 4938496
22:16:45 80766 8192000000 10290377920 10740592640 1369728768 14.325 99 25 50 1 1394722944 19373440 5620736
22:19:09 112231 8192000000 10292032496 10739634176 1370372208 14.330 99 15 30 1 1393652288 20003312 3276768
22:22:07 114891 8192000000 10295997296 10751918080 1373794720 14.361 99 14 28 1 1389221072 12421248 3005104
22:25:06 180545 8192000000 10289149904 10746613760 1355833488 14.200 97 16 32 0 1368633776 9743472 3056816
22:29:07 67612 8192000000 10295630512 10749919232 1395166896 14.552 100 28 56 1 1412821056 11160128 6494032
22:31:17 67562 8192000000 10279854432 10743537664 1342687488 14.082 96 13 26 1 1353404016 7913968 2802560
22:33:24 83117 8192000000 10302027232 10764783616 1389246064 14.499 99 22 44 1 1411345760 16890768 5208928
22:36:27 53695 8192000000 10284986304 10750205952 1351479824 14.161 97 18 36 0 1369587456 13773024 4334608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-05-2022 22:40:02 to 21-05-2022 22:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-05-2022 22:40:17 to 21-05-2022 22:40:19.

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          225419
MON$OLDEST_ACTIVE               226793
MON$OLDEST_SNAPSHOT             226793
MON$NEXT_TRANSACTION            226793
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                       2024320
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 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 ---
:148: 
:149: mscs-test-161.budzdorov.ru    Sat May 21 22:38:15 2022
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:151: 
:152: 
:153: mscs-test-161.budzdorov.ru    Sat May 21 22:38:16 2022
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Sat May 21 22:38:16 2022
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:159: 
:160: 
:161: mscs-test-161.budzdorov.ru    Sat May 21 22:38:17 2022
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:163: 
:164: 
:165: mscs-test-161.budzdorov.ru    Sat May 21 22:38:21 2022
:166:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:167: 
:168: 
:169: mscs-test-161.budzdorov.ru    Sat May 21 22:38:22 2022
:170:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:171: 
:172: 
:173: mscs-test-161.budzdorov.ru    Sat May 21 22:38:23 2022
:174:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:175: 
--- end of diff output ---

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

21.05.22 22:40:20. 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-05-21 22:40:23 No severe PSQL-related problems occured
21.05.22 22:40:24. End of report.