Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.01.2023 19: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 15455260 kB
MemAvailable 30412088 kB
Buffers 0 kB
Cached 16254000 kB
SwapCached 4400 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 2023-01-09 19:38:39
DTS_END                         2023-01-09 19:38:39.7200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54834
TRN_ID                          239024
ATT_ID                          722
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: 9276.28 19544 1113153
customer order: creation 171.26 367 20551
customer order: refuse 14.72 373 1766
order to supplier: creation 45.29 1323 5435
order to supplier: removal 3.41 104088 409
invoice (draft): creation 45.27 3130 5432
invoice (draft): removal 53.09 794 6371
invoice accept: apply 42.03 17060 5043
invoice accept: cancel 48.05 812 5766
customer reserve: creation 3845.51 171 461461
customer reserve: removal 4720.51 60 566461
realization accept: apply 81.86 174 9823
realization accept: cancel 78.35 107 9402
payment from customer: creation 57.65 125 6918
payment from customer: removal 5.45 86 654
payment to supplier: creation 48.63 142 5836
payment to supplier: removal 3.58 64 430
service: total inventory turnovers 4.85 1303 582
service: total monetary turnovers 6.00 32 720
service: aggregate perf. data 0.74 29139 89
service: refresh index statistics 0.03 231533 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2023 19:40:12 to 09-01-2023 19:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1409
TEST_TIME 2 2861
TEST_TIME 3 3906
TEST_TIME 4 4427
TEST_TIME 5 4200
TEST_TIME 6 4249
TEST_TIME 7 4414
TEST_TIME 8 4727
TEST_TIME 9 4708
TEST_TIME 10 5251
TEST_TIME 11 5727
TEST_TIME 12 5705
TEST_TIME 13 6159
TEST_TIME 14 6263
TEST_TIME 15 6621
TEST_TIME 16 7155
TEST_TIME 17 6982
TEST_TIME 18 8371
TEST_TIME 19 8096
TEST_TIME 20 8744
TEST_TIME 21 8614
TEST_TIME 22 8741
TEST_TIME 23 8864
TEST_TIME 24 9182
TEST_TIME 25 9403
TEST_TIME 26 9963
TEST_TIME 27 10292
TEST_TIME 28 11026
TEST_TIME 29 10807
TEST_TIME 30 10676
TEST_TIME 31 10669
TEST_TIME 32 10417
TEST_TIME 33 10204
TEST_TIME 34 10082
TEST_TIME 35 9996
TEST_TIME 36 9977
TEST_TIME 37 9806
TEST_TIME 38 9771
TEST_TIME 39 9697
TEST_TIME 40 9654
TEST_TIME 41 9584
TEST_TIME 42 9533
TEST_TIME 43 9445
TEST_TIME 44 9367
TEST_TIME 45 9354
TEST_TIME 46 9192
TEST_TIME 47 9210
TEST_TIME 48 9173
TEST_TIME 49 9116
TEST_TIME 50 9108
TEST_TIME 51 9027
TEST_TIME 52 8946
TEST_TIME 53 8986
TEST_TIME 54 9019
TEST_TIME 55 8963
TEST_TIME 56 9079
TEST_TIME 57 9010
TEST_TIME 58 9008
TEST_TIME 59 9074
TEST_TIME 60 9058
TEST_TIME 61 9148
TEST_TIME 62 9097
TEST_TIME 63 9223
TEST_TIME 64 9289
TEST_TIME 65 9265
TEST_TIME 66 9397
TEST_TIME 67 9447
TEST_TIME 68 9442
TEST_TIME 69 9567
TEST_TIME 70 9594
TEST_TIME 71 9682
TEST_TIME 72 9599
TEST_TIME 73 9484
TEST_TIME 74 9447
TEST_TIME 75 9373
TEST_TIME 76 9389
TEST_TIME 77 9378
TEST_TIME 78 9298
TEST_TIME 79 9329
TEST_TIME 80 9275
TEST_TIME 81 9253
TEST_TIME 82 9218
TEST_TIME 83 9236
TEST_TIME 84 9243
TEST_TIME 85 9231
TEST_TIME 86 9267
TEST_TIME 87 9267
TEST_TIME 88 9246
TEST_TIME 89 9278
TEST_TIME 90 9279
TEST_TIME 91 9353
TEST_TIME 92 9390
TEST_TIME 93 9440
TEST_TIME 94 9379
TEST_TIME 95 9444
TEST_TIME 96 9401
TEST_TIME 97 9318
TEST_TIME 98 9284
TEST_TIME 99 9238
TEST_TIME 100 9249
TEST_TIME 101 9267
TEST_TIME 102 9251
TEST_TIME 103 9249
TEST_TIME 104 9207
TEST_TIME 105 9157
TEST_TIME 106 9189
TEST_TIME 107 9165
TEST_TIME 108 9222
TEST_TIME 109 9250
TEST_TIME 110 9243
TEST_TIME 111 9209
TEST_TIME 112 9276
TEST_TIME 113 9261
TEST_TIME 114 9289
TEST_TIME 115 9313
TEST_TIME 116 9335
TEST_TIME 117 9335
TEST_TIME 118 9347
TEST_TIME 119 9349
TEST_TIME 120 9277

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-01-2023 19:40:16 to 09-01-2023 19:40:19.

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 1540309 1540309 0 0.00 0 23831 6 0 0
doc_list_biud 1540307 1540307 0 0.00 0 27041 28 0 0
sp_add_invoice_to_stock 5208 5043 165 3.16 52 209021 17060 0 165
sp_cancel_adding_invoice 5766 5766 0 0.00 5 62358 812 0 0
sp_cancel_client_order 1766 1766 0 0.00 4 17985 374 0 0
sp_cancel_customer_reserve 566461 566461 0 0.00 0 27042 60 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 5156 87 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 2984 64 0 0
sp_cancel_supplier_invoice 6382 6371 11 0.17 2 15309 794 0 11
sp_cancel_supplier_order 409 409 0 0.00 8 1071392 104088 0 0
sp_cancel_write_off 9402 9402 0 0.00 1 8921 108 0 0
sp_client_order 20551 20551 0 0.00 3 23412 368 0 0
sp_customer_reserve 461461 461461 0 0.00 0 57542 171 0 0
sp_fill_shopping_cart 31410 31410 0 0.00 1 18506 40 0 0
sp_fill_shopping_cart_clo_res 461456 461456 0 0.00 0 30411 15 0 0
sp_get_clo_for_invoice 5039 5039 0 0.00 0 37296 367 0 0
sp_kill_cost_storno 16252 16252 0 0.00 0 9694 53 0 0
sp_kill_qstorno_ret_qs2qd 1149104 1149104 0 0.00 0 27038 32 0 0
sp_kill_qty_storno 605037 605037 0 0.00 0 27041 70 0 0
sp_lock_selected_doc 20571 20571 0 0.00 0 6671 5 0 0
sp_make_cost_storno 27616 27616 0 0.00 0 22360 50 0 0
sp_multiply_rows_for_pdistr 27616 27616 0 0.00 0 7264 24 0 0
sp_multiply_rows_for_qdistr 20551 20551 0 0.00 0 14348 151 0 0
sp_pay_from_customer 6918 6918 0 0.00 4 14896 126 0 0
sp_pay_to_supplier 5836 5836 0 0.00 5 13206 142 0 0
sp_payment_common 12754 12754 0 0.00 1 11797 31 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 0 17970 336 0 0
sp_qd_handle_on_invoice_upd_sts 10805 10805 0 0.00 0 18418 305 0 0
sp_qd_handle_on_reserve_upd_sts 9823 9823 0 0.00 0 13865 60 0 0
sp_reserve_write_off 9823 9823 0 0.00 6 13954 175 0 0
sp_supplier_invoice 5432 5432 0 0.00 7 73528 3130 0 0
sp_supplier_order 5435 5435 0 0.00 4 78914 1324 0 0
srv_aggregate_perf_data 89 89 0 0.00 2625 364903 29140 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1340 128932 47408 0 0
srv_find_qd_qs_mism 1065759 1065759 0 0.00 0 23831 5 0 0
srv_make_invnt_saldo 582 582 0 0.00 39 65463 1303 0 0
srv_make_money_saldo 720 720 0 0.00 1 8125 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 178297 343610 231534 0 0
t$perf-abend:sp_add_invoice_to_stock 165 165 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5043 5043 0 0.00 0 18688 155 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 5171 80 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 4482 14 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 687 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 2018 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 2140 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 388 7 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 17678 1482 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 3886 8 0 0
t$perf-norm:sp_client_order 20551 20551 0 0.00 0 9720 14 0 0
t$perf-norm:sp_customer_reserve 1049 1049 0 0.00 0 617 5 0 0
t$perf-norm:sp_pay_from_customer 6918 6918 0 0.00 0 10538 14 0 0
t$perf-norm:sp_pay_to_supplier 5836 5836 0 0.00 0 5457 10 0 0
t$perf-norm:sp_reserve_write_off 9823 9823 0 0.00 0 19957 12 0 0
t$perf-norm:sp_supplier_invoice 5432 5432 0 0.00 0 11556 16 0 0
t$perf-norm:sp_supplier_order 5435 5435 0 0.00 0 6286 17 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 338 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 297 10 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 966 15 0 0
t$perf-norm:srv_make_money_saldo 720 720 0 0.00 0 739 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 6 0 0
v_add_invoice_to_stock 10408 10078 330 3.17 0 18072 26 0 330
v_all_customers 37100 37100 0 0.00 0 28 0 0 0
v_all_suppliers 30176 30176 0 0.00 0 19 1 0 0
v_all_wares 41102 41102 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 5647 15 0 0
v_cancel_client_order 3532 3532 0 0.00 0 15898 12 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 3103 9 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 2208 6 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 2215 6 0 0
v_cancel_supplier_invoice 792 770 22 2.77 0 2172 22 0 22
v_cancel_supplier_order 818 818 0 0.00 0 12976 44 0 0
v_cancel_write_off 1906 1906 0 0.00 0 6041 26 0 0
v_max_id_clo_ord 5435 5435 0 0.00 0 2293 2 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 8670 22 0 0
v_max_id_ord_sup 5424 5424 0 0.00 0 4637 4 0 0
v_max_non_paid_invoice 1607 1607 0 0.00 0 4714 7 0 0
v_max_non_paid_realizn 3697 3697 0 0.00 0 4835 6 0 0
v_min_id_clo_ord 5435 5435 0 0.00 0 5928 7 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 5278 11 0 0
v_min_id_ord_sup 5424 5424 0 0.00 0 3862 4 0 0
v_min_non_paid_invoice 5836 5836 0 0.00 0 10490 25 0 0
v_min_non_paid_realizn 6918 6918 0 0.00 0 11787 23 0 0
v_our_firm 10444 10444 0 0.00 0 17 1 0 0
v_reserve_write_off 19646 19646 0 0.00 0 12978 12 0 0
x_lock_depdocs_on_canc_invoice 11752 11752 0 0.00 0 13637 113 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 8211 300 0 0
x_make_qstorno_1000_1200 5435 5435 0 0.00 1 78904 1248 0 0
x_make_qstorno_1200_2000 5432 5432 0 0.00 2 69470 2981 0 0
x_make_qstorno_2100_3300 461461 461461 0 0.00 0 57523 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2023 19:40:20 to 09-01-2023 19:40:24.

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
17:08:43 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:44 21036 8192000000 9911456288 10222632960 994525264 10.825 100 91 182 0 1024560624 5980304 24055056
17:11:08 18680 8192000000 9943359296 10265051136 1023631952 11.107 100 84 168 0 1053889792 8198656 22059184
17:12:34 13898 8192000000 9984180304 10316341248 1069474000 11.547 100 86 172 0 1100664304 9720640 21469664
17:13:56 14788 8192000000 10035360304 10384740352 1120260368 12.029 100 77 154 0 1149125296 10360832 18504096
17:15:17 5262 8192000000 10072723136 10430984192 1164163968 12.442 100 83 166 1 1196062880 12344688 19554224
17:16:26 4516 8192000000 10117775776 10484219904 1209617728 12.866 100 75 149 1 1238750048 12181008 16951312
17:17:32 1571 8192000000 10168385648 10546257920 1252688528 13.263 100 85 168 0 1284271616 13436128 18146960
17:18:35 2081 8192000000 10189561472 10575044608 1273169808 13.451 100 76 152 0 1304334432 14264592 16900032
17:19:38 924 8192000000 10222429504 10609893376 1307774400 13.766 100 88 174 2 1340984320 13184320 20025600
17:20:40 1476 8192000000 10211889024 10617917440 1315491200 13.836 100 48 95 0 1332429392 6118000 10820192
17:21:43 6516 8192000000 10205996688 10622390272 1320004752 13.877 100 26 52 0 1332203280 6502464 5696064
17:22:52 3699 8192000000 10217833152 10635571200 1331254656 13.978 100 28 56 0 1343237952 6157904 5825392
17:23:57 58040 8192000000 10226976400 10648555520 1332729184 13.992 100 23 46 0 1343425904 5543296 5153424
17:25:56 9457 8192000000 10232831680 10656219136 1339520496 14.053 100 31 62 0 1353678576 7260624 6897456
17:27:07 8233 8192000000 10239025440 10662014976 1343364112 14.088 100 15 30 0 1352415072 6076832 2974128
17:28:17 23230 8192000000 10209079264 10632286208 1304893872 13.740 98 27 54 0 1316144736 5129920 6120944
17:29:40 11365 8192000000 10207569424 10624655360 1276427264 13.480 96 27 53 1 1287985712 6043744 5514704
17:30:52 4162 8192000000 10150329648 10544627712 1221415360 12.975 97 30 60 0 1234548912 5175632 7957920
17:31:58 6404 8192000000 10140152560 10538467328 1223836880 12.997 98 23 46 0 1234977472 5556992 5583600
17:33:05 40098 8192000000 10166060368 10572357632 1272290784 13.443 100 40 81 0 1287855072 5499648 10064640
17:34:53 5495 8192000000 10182130800 10590281728 1285478592 13.563 100 33 66 0 1299607712 5905280 8223840
17:36:00 7136 8192000000 10190277344 10605006848 1296913408 13.667 100 24 48 0 1308576016 6152352 5510256
17:37:08 12863 8192000000 10189707920 10608287744 1303386000 13.726 100 22 44 1 1314113376 5291488 5435888
17:38:25 90639 8192000000 10242796672 10652848128 1332387680 13.989 100 41 78 0 1347106720 6324768 8394272
17:41:01 7432 8192000000 10270880144 10683113472 1357266208 14.213 100 53 106 2 1381584320 12445296 11872816
17:42:12 22004 8192000000 10271588128 10696740864 1367652800 14.306 100 36 69 1 1388921504 12676592 8592112
17:43:38 6720 8192000000 10301047360 10723319808 1387411936 14.483 100 43 84 0 1414685072 17462736 9810400
17:44:48 11441 8192000000 10320873632 10742652928 1399253376 14.588 100 44 86 0 1433415600 24063520 10098704
17:46:06 15137 8192000000 10328327424 10756349952 1407015520 14.657 100 46 92 2 1442794928 23935024 11844384
17:47:25 9060 8192000000 10336518432 10762547200 1401332288 14.607 99 54 108 0 1445471184 29903600 14235296
17:48:40 3264 8192000000 10353713520 10779152384 1418573824 14.760 100 55 109 1 1463088512 30432880 14081808
17:49:47 5381 8192000000 10362622048 10774843392 1414031584 14.720 100 83 164 0 1462527232 29388032 19107616
17:50:59 2161 8192000000 10338402768 10753499136 1384510144 14.457 98 79 154 0 1440505456 35594144 20401168
17:52:05 5190 8192000000 10354926352 10773737472 1404823488 14.638 100 71 142 1 1461755072 39281984 17649600
17:53:14 5860 8192000000 10373165744 10790625280 1418860304 14.763 100 67 133 0 1475968976 40354576 16754096
17:54:25 3709 8192000000 10345022112 10758213632 1381790720 14.433 99 65 128 1 1442360896 44136384 16433792
17:55:31 1339 8192000000 10348128416 10759872512 1387329984 14.482 100 76 151 0 1453440528 45347904 20762640
17:56:46 10027 8192000000 10323793696 10751819776 1385312688 14.464 100 41 80 0 1438599072 42673024 10613360
17:58:24 10799 8192000000 10314635776 10745602048 1383298240 14.446 100 30 60 0 1426991744 35279584 8413920
17:59:52 9361 8192000000 10307062864 10728275968 1356221504 14.203 99 38 76 0 1401143312 34446768 10475040
18:01:08 16139 8192000000 10325072912 10747666432 1374468256 14.367 100 40 78 3 1428510432 43161584 10880592
18:02:29 15414 8192000000 10334616880 10767425536 1383522768 14.448 100 35 70 0 1439550768 46287376 9740624
18:03:46 13888 8192000000 10325132704 10754342912 1373619312 14.359 100 20 40 0 1422878192 43897024 5361856
18:05:04 32504 8192000000 10311839664 10745942016 1348585616 14.135 99 24 47 2 1402988976 47812448 6590912
18:06:38 32514 8192000000 10266336912 10723950592 1355686608 14.199 100 15 30 0 1380836592 21378400 3771584
18:08:35 34964 8192000000 10271329632 10730987520 1367073200 14.301 100 14 27 0 1387071360 16929120 3069040
18:10:11 88947 8192000000 10272547536 10718842880 1356411936 14.205 98 23 46 0 1368633760 7322368 4899456
18:12:41 64139 8192000000 10266735696 10708848640 1313932368 13.822 95 11 22 0 1325723376 9591280 2199728
18:14:47 39427 8192000000 10281618976 10725261312 1374149824 14.364 100 19 38 0 1388284416 10100944 4033648
18:16:31 18272 8192000000 10280580208 10722050048 1371018768 14.336 100 43 86 0 1393830128 13745008 9066352
18:18:07 79707 8192000000 10285611280 10744172544 1387663584 14.485 100 10 19 0 1399699280 9565104 2470592
18:21:48 61726 8192000000 10287678576 10747269120 1381054832 14.426 99 18 35 0 1397546800 11591248 4900720
18:23:51 61494 8192000000 10305623312 10753998848 1396157888 14.561 100 58 114 2 1418535776 8560432 13817456
18:25:57 80527 8192000000 10298614480 10762698752 1399211200 14.588 100 21 42 0 1414760688 11002928 4546560
18:28:25 48334 8192000000 10301751664 10763849728 1405122608 14.641 100 20 40 1 1422678928 12543488 5012832
18:30:14 57874 8192000000 10288109776 10746257408 1367872352 14.308 97 16 30 0 1387137024 15261440 4003232
18:32:12 67609 8192000000 10312322160 10777763840 1390909360 14.514 99 22 44 0 1410381440 14530512 4941568
18:34:50 100967 8192000000 10311018160 10778681344 1365938432 14.291 97 5 10 0 1383122608 16119552 1064624
18:38:40 102236 8192000000 10294344736 10738171904 1283248656 13.543 91 26 50 0 1301624016 13169152 5206208
18:41:22 65633 8192000000 10314652496 10774601728 1391596096 14.520 99 15 30 0 1408130576 13103936 3430544
18:44:08 62235 8192000000 10290429456 10751332352 1348016112 14.130 97 15 30 0 1370757344 19194000 3547232
18:46:10 52619 8192000000 10312129712 10767749120 1376545952 14.386 99 11 22 0 1404507888 24380896 3581040
18:48:10 89592 8192000000 10280244848 10740219904 1292226208 13.625 93 17 34 0 1311560720 16010576 3323936
18:50:40 89703 8192000000 10266455760 10715086848 1355561568 14.197 99 21 42 0 1373101088 12817872 4721648
18:53:11 19608 8192000000 10274042944 10717204480 1354308400 14.186 99 18 35 0 1368375488 10609152 3457936
18:54:31 88949 8192000000 10263897264 10727235584 1309265632 13.779 95 11 22 0 1323175024 11677920 2231472
18:57:45 89482 8192000000 10259097920 10710237184 1320918912 13.885 96 18 37 3 1336622656 11198720 4505024
19:00:15 26993 8192000000 10253761584 10709155840 1344790464 14.101 99 13 24 0 1356043296 8750128 2502704
19:01:42 71810 8192000000 10263584976 10722193408 1298819872 13.685 95 13 26 0 1318068400 16397744 2850784
19:04:01 39485 8192000000 10239783536 10688409600 1305771984 13.748 96 12 24 1 1318536000 9903072 2860944
19:05:45 37350 8192000000 10265931952 10712842240 1344785712 14.101 99 25 50 0 1360357440 10018080 5553648
19:07:25 33031 8192000000 10271377568 10715148288 1345278560 14.105 99 43 86 0 1366517440 12014096 9224784
19:09:00 72408 8192000000 10279166032 10739920896 1361977168 14.255 99 29 58 1 1385578752 17676416 5925168
19:11:13 94026 8192000000 10301410496 10739617792 1355936928 14.201 98 31 63 1 1372440944 8789664 7714352
19:13:51 110148 8192000000 10286317584 10740113408 1375819904 14.379 99 13 26 0 1388010576 9175232 3015440
19:16:45 66223 8192000000 10292348240 10742099968 1360535072 14.242 98 23 46 0 1378096096 12820336 4740688
19:19:10 72283 8192000000 10284440672 10748346368 1364915008 14.281 98 14 28 0 1381821776 13560256 3346512
19:22:00 67856 8192000000 10294059264 10750849024 1387593232 14.484 100 19 38 0 1403533616 11378720 4561664
19:24:51 110050 8192000000 10289162384 10752823296 1348807552 14.137 97 12 24 1 1367958208 16546160 2604496
19:27:42 34358 8192000000 10283540720 10742841344 1369330880 14.321 99 11 22 0 1382146576 10306752 2508944
19:29:32 47240 8192000000 10263094432 10713997312 1326442160 13.935 97 32 64 0 1351652736 18064336 7146240
19:31:23 37621 8192000000 10269378672 10713575424 1329508240 13.963 97 25 48 0 1346073520 11130704 5434576
19:33:01 128932 8192000000 10267784768 10735939584 1314467152 13.827 96 11 22 0 1337541008 20364960 2708896
19:36:28 35718 8192000000 10270260528 10704359424 1351066240 14.157 100 35 69 0 1368192640 9417312 7709088
19:38:05 59649 8192000000 8976041200 9171005440 149842128 1.796 12 2 4 0 151060976 905200 313648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-01-2023 19:40:26 to 09-01-2023 19:40:41.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-01-2023 19:40:42 to 09-01-2023 19:40:44.

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          239326
MON$OLDEST_ACTIVE               239327
MON$OLDEST_SNAPSHOT             239327
MON$NEXT_TRANSACTION            239327
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                       2017728
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.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 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 ---
:207: 
:208: mscs-test-161.budzdorov.ru    Mon Jan  9 19:39:07 2023
:209:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:210: 
:211: 
:212: mscs-test-161.budzdorov.ru    Mon Jan  9 19:39:07 2023
:213:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:214: 
:215: 
:216: mscs-test-161.budzdorov.ru    Mon Jan  9 19:39:07 2023
:217:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:218: 
:219: 
:220: mscs-test-161.budzdorov.ru    Mon Jan  9 19:39:07 2023
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:222: 
:223: 
:224: mscs-test-161.budzdorov.ru    Mon Jan  9 19:39:07 2023
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:226: 
:227: 
:228: mscs-test-161.budzdorov.ru    Mon Jan  9 19:39:07 2023
:229:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:230: 
--- end of diff output ---

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

09.01.23 19:40:45. 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
2023-01-09 19:40:48 No severe PSQL-related problems occured
09.01.23 19:40:49. End of report.