Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.01.2023 22:38
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 15282424 kB
MemAvailable 30381612 kB
Buffers 0 kB
Cached 16410608 kB
SwapCached 4460 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-13 22:38:34
DTS_END                         2023-01-13 22:38:34.2400
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/33448
TRN_ID                          239701
ATT_ID                          694
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: 9226.12 22257 1107134
customer order: creation 165.66 371 19879
customer order: refuse 14.54 335 1745
order to supplier: creation 44.67 1226 5360
order to supplier: removal 3.33 107982 399
invoice (draft): creation 45.50 3359 5460
invoice (draft): removal 53.81 781 6457
invoice accept: apply 40.58 17192 4870
invoice accept: cancel 49.48 728 5937
customer reserve: creation 3698.21 171 443785
customer reserve: removal 4819.39 58 578327
realization accept: apply 81.23 194 9748
realization accept: cancel 84.48 100 10137
payment from customer: creation 57.03 111 6844
payment from customer: removal 5.48 59 657
payment to supplier: creation 47.90 164 5748
payment to supplier: removal 3.53 67 423
service: total inventory turnovers 4.57 1379 548
service: total monetary turnovers 6.07 25 728
service: aggregate perf. data 0.65 28872 78
service: refresh index statistics 0.03 281957 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-01-2023 22:39:15 to 13-01-2023 22:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3830
TEST_TIME 2 2728
TEST_TIME 3 3248
TEST_TIME 4 4321
TEST_TIME 5 4192
TEST_TIME 6 4004
TEST_TIME 7 4604
TEST_TIME 8 4849
TEST_TIME 9 5176
TEST_TIME 10 5539
TEST_TIME 11 5918
TEST_TIME 12 6017
TEST_TIME 13 6001
TEST_TIME 14 6045
TEST_TIME 15 6386
TEST_TIME 16 6580
TEST_TIME 17 6680
TEST_TIME 18 6931
TEST_TIME 19 8101
TEST_TIME 20 8299
TEST_TIME 21 8491
TEST_TIME 22 8920
TEST_TIME 23 9003
TEST_TIME 24 9190
TEST_TIME 25 9060
TEST_TIME 26 9198
TEST_TIME 27 8981
TEST_TIME 28 9559
TEST_TIME 29 9966
TEST_TIME 30 10430
TEST_TIME 31 10611
TEST_TIME 32 10522
TEST_TIME 33 10381
TEST_TIME 34 10372
TEST_TIME 35 10231
TEST_TIME 36 10150
TEST_TIME 37 10092
TEST_TIME 38 9946
TEST_TIME 39 9803
TEST_TIME 40 9887
TEST_TIME 41 9801
TEST_TIME 42 9706
TEST_TIME 43 9667
TEST_TIME 44 9568
TEST_TIME 45 9566
TEST_TIME 46 9512
TEST_TIME 47 9477
TEST_TIME 48 9403
TEST_TIME 49 9274
TEST_TIME 50 9216
TEST_TIME 51 9208
TEST_TIME 52 9302
TEST_TIME 53 9199
TEST_TIME 54 9149
TEST_TIME 55 9056
TEST_TIME 56 9134
TEST_TIME 57 9142
TEST_TIME 58 9220
TEST_TIME 59 9204
TEST_TIME 60 9164
TEST_TIME 61 9136
TEST_TIME 62 9245
TEST_TIME 63 9267
TEST_TIME 64 9342
TEST_TIME 65 9412
TEST_TIME 66 9350
TEST_TIME 67 9379
TEST_TIME 68 9380
TEST_TIME 69 9417
TEST_TIME 70 9513
TEST_TIME 71 9461
TEST_TIME 72 9351
TEST_TIME 73 9320
TEST_TIME 74 9363
TEST_TIME 75 9362
TEST_TIME 76 9295
TEST_TIME 77 9315
TEST_TIME 78 9342
TEST_TIME 79 9310
TEST_TIME 80 9268
TEST_TIME 81 9209
TEST_TIME 82 9210
TEST_TIME 83 9198
TEST_TIME 84 9220
TEST_TIME 85 9187
TEST_TIME 86 9162
TEST_TIME 87 9129
TEST_TIME 88 9224
TEST_TIME 89 9182
TEST_TIME 90 9231
TEST_TIME 91 9264
TEST_TIME 92 9245
TEST_TIME 93 9228
TEST_TIME 94 9294
TEST_TIME 95 9291
TEST_TIME 96 9263
TEST_TIME 97 9357
TEST_TIME 98 9329
TEST_TIME 99 9379
TEST_TIME 100 9377
TEST_TIME 101 9319
TEST_TIME 102 9308
TEST_TIME 103 9247
TEST_TIME 104 9214
TEST_TIME 105 9257
TEST_TIME 106 9214
TEST_TIME 107 9202
TEST_TIME 108 9176
TEST_TIME 109 9136
TEST_TIME 110 9134
TEST_TIME 111 9132
TEST_TIME 112 9137
TEST_TIME 113 9162
TEST_TIME 114 9175
TEST_TIME 115 9199
TEST_TIME 116 9197
TEST_TIME 117 9250
TEST_TIME 118 9220
TEST_TIME 119 9266
TEST_TIME 120 9227

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-01-2023 22:39:19 to 13-01-2023 22:39:22.

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 1520292 1520292 0 0.00 0 24080 6 0 0
doc_list_biud 1520280 1520280 0 0.00 0 21956 28 0 0
sp_add_invoice_to_stock 4969 4870 99 1.99 28 154084 17192 0 99
sp_cancel_adding_invoice 5937 5937 0 0.00 5 58863 728 0 0
sp_cancel_client_order 1745 1745 0 0.00 4 10182 335 0 0
sp_cancel_customer_reserve 578327 578327 0 0.00 0 21957 58 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 3 2354 59 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 3653 68 0 0
sp_cancel_supplier_invoice 6467 6457 10 0.15 1 20262 782 0 10
sp_cancel_supplier_order 399 399 0 0.00 9 1054820 107982 0 0
sp_cancel_write_off 10137 10137 0 0.00 1 12870 101 0 0
sp_client_order 19879 19879 0 0.00 3 31605 371 0 0
sp_customer_reserve 443785 443785 0 0.00 0 49161 172 0 0
sp_fill_shopping_cart 30699 30699 0 0.00 1 16380 40 0 0
sp_fill_shopping_cart_clo_res 443784 443784 0 0.00 0 34879 14 0 0
sp_get_clo_for_invoice 4867 4867 0 0.00 0 39877 368 0 0
sp_kill_cost_storno 17154 17154 0 0.00 0 8506 56 0 0
sp_kill_qstorno_ret_qs2qd 1173647 1173647 0 0.00 0 21953 31 0 0
sp_kill_qty_storno 617611 617611 0 0.00 0 21954 68 0 0
sp_lock_selected_doc 20262 20262 0 0.00 0 8760 4 0 0
sp_make_cost_storno 27207 27207 0 0.00 0 23325 68 0 0
sp_multiply_rows_for_pdistr 27195 27195 0 0.00 0 9040 29 0 0
sp_multiply_rows_for_qdistr 19879 19879 0 0.00 0 31593 169 0 0
sp_pay_from_customer 6844 6844 0 0.00 4 20051 112 0 0
sp_pay_to_supplier 5748 5748 0 0.00 5 26775 165 0 0
sp_payment_common 12586 12586 0 0.00 2 26745 30 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 10179 312 0 0
sp_qd_handle_on_invoice_upd_sts 10801 10801 0 0.00 0 20797 331 0 0
sp_qd_handle_on_reserve_upd_sts 9745 9745 0 0.00 0 20289 67 0 0
sp_reserve_write_off 9748 9748 0 0.00 6 20415 195 0 0
sp_supplier_invoice 5460 5460 0 0.00 7 78530 3359 0 0
sp_supplier_order 5360 5360 0 0.00 5 47901 1226 0 0
srv_aggregate_perf_data 78 78 0 0.00 2890 615164 28872 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 820 139699 45329 0 0
srv_find_qd_qs_mism 1062113 1062113 0 0.00 0 12361 5 0 0
srv_make_invnt_saldo 548 548 0 0.00 33 95341 1379 0 0
srv_make_money_saldo 728 728 0 0.00 1 693 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 143532 497703 281958 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 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 4870 4870 0 0.00 0 21211 204 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 6506 87 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 1939 9 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 1086 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 3961 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 698 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 2677 20 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 28415 1798 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 4814 10 0 0
t$perf-norm:sp_client_order 19879 19879 0 0.00 0 7769 20 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 9770 30 0 0
t$perf-norm:sp_pay_from_customer 6844 6844 0 0.00 0 5045 14 0 0
t$perf-norm:sp_pay_to_supplier 5748 5748 0 0.00 0 7634 14 0 0
t$perf-norm:sp_reserve_write_off 9748 9748 0 0.00 0 8269 14 0 0
t$perf-norm:sp_supplier_invoice 5460 5460 0 0.00 0 7933 23 0 0
t$perf-norm:sp_supplier_order 5360 5360 0 0.00 0 10941 27 0 0
t$perf-norm:srv_aggregate_perf_data 78 78 0 0.00 0 469 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 799 15 0 0
t$perf-norm:srv_make_invnt_saldo 548 548 0 0.00 0 2226 29 0 0
t$perf-norm:srv_make_money_saldo 728 728 0 0.00 0 1992 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 5 0 0
v_add_invoice_to_stock 9926 9728 198 1.99 0 8390 19 0 198
v_all_customers 36246 36246 0 0.00 0 19 1 0 0
v_all_suppliers 29888 29888 0 0.00 0 38 1 0 0
v_all_wares 39758 39758 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 2335 9 0 0
v_cancel_client_order 3490 3490 0 0.00 0 1050 3 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 1094 5 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 2502 7 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 3484 8 0 0
v_cancel_supplier_invoice 768 748 20 2.60 0 7432 27 0 20
v_cancel_supplier_order 798 798 0 0.00 0 1000 7 0 0
v_cancel_write_off 1916 1916 0 0.00 0 1266 12 0 0
v_max_id_clo_ord 5360 5360 0 0.00 0 2946 2 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 5274 17 0 0
v_max_id_ord_sup 5460 5460 0 0.00 0 6733 6 0 0
v_max_non_paid_invoice 1619 1619 0 0.00 0 26726 22 0 0
v_max_non_paid_realizn 3683 3683 0 0.00 0 5250 6 0 0
v_min_id_clo_ord 5360 5360 0 0.00 0 8547 15 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 7221 17 0 0
v_min_id_ord_sup 5460 5460 0 0.00 0 2543 4 0 0
v_min_non_paid_invoice 5743 5743 0 0.00 0 5877 17 0 0
v_min_non_paid_realizn 6843 6843 0 0.00 0 7225 20 0 0
v_our_firm 9832 9832 0 0.00 0 124 1 0 0
v_reserve_write_off 19488 19488 0 0.00 0 5203 7 0 0
x_lock_depdocs_on_canc_invoice 12020 12020 0 0.00 0 18304 113 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 7158 376 0 0
x_make_qstorno_1000_1200 5360 5360 0 0.00 1 47894 1144 0 0
x_make_qstorno_1200_2000 5460 5460 0 0.00 3 78438 3219 0 0
x_make_qstorno_2100_3300 443785 443785 0 0.00 0 49159 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2023 22:39:23 to 13-01-2023 22:39:27.

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:37 4 8192000000 8805874272 8819347456 10125680 0.123 3 2 3 0 10268144 67504 74960
20:09:39 14673 8192000000 9912371584 10221441024 996754720 10.847 100 92 184 0 1026765872 6261344 23749808
20:10:58 15871 8192000000 9954182192 10276175872 1035285584 11.219 100 86 172 0 1065700192 8479648 21934960
20:12:20 16384 8192000000 9974486912 10304970752 1056030480 11.418 100 85 170 0 1088374480 11258096 21085904
20:13:49 8698 8192000000 10035721184 10380562432 1117093760 12.000 100 85 170 0 1148797904 11740944 19963200
20:14:59 6262 8192000000 10085384736 10441027584 1165589008 12.456 100 84 169 0 1198055104 13094928 19371168
20:16:11 3856 8192000000 10128319616 10494009344 1209313200 12.863 100 80 159 0 1239663424 12557088 17793136
20:17:17 2183 8192000000 10156650384 10529927168 1242636496 13.171 100 85 168 0 1274933952 14295824 18001632
20:18:22 1529 8192000000 10180492240 10558926848 1269473840 13.417 100 79 152 0 1298798672 12244864 17079968
20:19:25 1144 8192000000 10212026384 10593632256 1290938240 13.613 100 94 189 1 1326712240 13870608 21903392
20:20:27 1914 8192000000 10212299136 10602586112 1297191568 13.670 100 77 153 0 1325486240 10823936 17470736
20:21:29 6693 8192000000 10193768848 10599477248 1303403520 13.726 100 23 47 1 1314565776 5709584 5452672
20:22:40 16619 8192000000 10194478688 10612793344 1311608016 13.801 100 14 25 0 1319906544 5689648 2608880
20:23:57 14061 8192000000 10215467184 10629795840 1326056832 13.932 100 21 42 0 1337023536 6203984 4762720
20:25:13 12382 8192000000 10236485488 10639388672 1327649408 13.946 100 60 116 1 1347159216 4971088 14538720
20:26:29 3474 8192000000 10234434256 10649493504 1332631728 13.991 100 38 76 2 1348668384 8005808 8030848
20:27:36 9788 8192000000 10217768512 10636673024 1315958640 13.840 99 16 30 0 1324682080 4871072 3852368
20:28:46 10011 8192000000 10180586640 10596233216 1257185072 13.304 96 14 28 0 1265688416 5595488 2907856
20:29:58 14083 8192000000 10137564672 10549989376 1219449152 12.957 95 15 30 1 1228004784 5416304 3139328
20:31:14 4433 8192000000 10145866560 10544435200 1220096752 12.963 97 31 63 2 1232878304 5874688 6906864
20:32:22 10222 8192000000 10136396832 10536681472 1223141248 12.991 97 21 40 2 1233216144 5008400 5066496
20:33:32 54767 8192000000 10173126992 10579578880 1280498576 13.518 100 30 58 2 1292303616 5529264 6275776
20:35:43 5430 8192000000 10180811456 10594496512 1286241264 13.570 99 17 32 0 1295494960 5813472 3440224
20:36:48 8364 8192000000 10197722320 10614697984 1307667136 13.765 100 19 36 1 1317307392 5656704 3983552
20:37:59 7647 8192000000 10209479184 10624610304 1320035168 13.877 100 16 32 0 1328453232 5437152 2980912
20:39:11 12504 8192000000 10242086496 10660950016 1346460432 14.116 100 51 101 0 1365697376 8211008 11025936
20:40:24 17650 8192000000 10266407360 10682871808 1355347184 14.196 100 52 104 0 1378550864 11740448 11463232
20:41:46 11676 8192000000 10276307632 10698829824 1367700160 14.306 100 45 90 0 1392964256 14700368 10563728
20:43:03 13760 8192000000 10275774656 10704691200 1374488064 14.367 100 40 80 0 1397995040 14413536 9093440
20:44:37 4259 8192000000 10301166208 10724638720 1385747728 14.468 100 46 92 2 1414983152 17908848 11326576
20:45:50 5738 8192000000 10303689040 10724024320 1373164832 14.355 99 60 120 0 1410113392 22389520 14559040
20:47:02 14975 8192000000 10324917680 10749874176 1401325664 14.607 100 51 101 0 1435405040 22119744 11959632
20:48:21 7056 8192000000 10324039072 10751455232 1392902720 14.532 99 53 103 1 1429690096 24063360 12724016
20:49:40 5776 8192000000 10330173312 10751307776 1393707360 14.539 99 58 116 1 1432647584 25135216 13805008
20:50:54 5874 8192000000 10322685536 10739449856 1386030080 14.470 99 59 116 0 1424920352 24255456 14634816
20:52:06 4351 8192000000 10358127488 10777530368 1420018320 14.773 100 64 127 0 1467770480 31576720 16175440
20:53:18 819 8192000000 10355693088 10767245312 1407200160 14.659 100 74 148 0 1456660576 30747984 18712432
20:54:25 3015 8192000000 10334322192 10747129856 1390692912 14.512 99 70 140 0 1441734784 33179200 17862672
20:55:35 2798 8192000000 10354590000 10766049280 1396400320 14.563 100 70 138 0 1455215872 41041424 17774128
20:56:45 3668 8192000000 10320138096 10727211008 1358967008 14.228 100 82 160 1 1426607184 47593648 20046528
20:57:53 14063 8192000000 10298668752 10722557952 1348307632 14.132 98 30 61 0 1388583840 32074448 8201760
20:59:10 17199 8192000000 10300563328 10726436864 1357925232 14.219 99 26 51 0 1403328016 38407296 6995488
21:00:35 13253 8192000000 10298558944 10717126656 1321208128 13.888 97 43 80 2 1368342880 37160896 9973856
21:01:52 10499 8192000000 10295843232 10721402880 1341468368 14.071 99 32 64 0 1390932752 41424144 8040240
21:03:07 21416 8192000000 10303821664 10733305856 1354491584 14.188 99 30 59 0 1399796784 38023392 7281808
21:04:30 27732 8192000000 10306693632 10736742400 1363684992 14.270 100 26 52 1 1416672784 45537376 7450416
21:06:04 22120 8192000000 10312108992 10752548864 1348720608 14.136 98 31 62 0 1406162800 48800800 8641392
21:07:32 32003 8192000000 10301208336 10750955520 1361207104 14.248 100 19 38 1 1415301104 48368576 5725424
21:09:45 45662 8192000000 10290982176 10718822400 1357684304 14.217 99 44 81 1 1374406672 8063632 8658736
21:11:43 56580 8192000000 10288731376 10727313408 1371669104 14.342 99 46 91 0 1398033216 15583536 10780576
21:13:42 85606 8192000000 10284144464 10737246208 1342691856 14.082 97 12 25 0 1356347584 11110336 2545392
21:16:10 77699 8192000000 10298455296 10748727296 1391726576 14.521 100 19 35 1 1410103632 13617024 4760032
21:19:02 82973 8192000000 10299247984 10749759488 1384905872 14.460 99 23 44 0 1398186624 8412960 4867792
21:21:25 92013 8192000000 10291859952 10742566912 1378486720 14.403 99 30 60 1 1394815536 9482416 6846400
21:23:58 56173 8192000000 10295835456 10738311168 1382669072 14.440 99 27 54 0 1396991344 8396960 5925312
21:25:57 63508 8192000000 10286756704 10741280768 1381267776 14.428 99 15 30 0 1392545648 7888464 3389408
21:28:02 67885 8192000000 10318059280 10758217728 1391040416 14.515 99 18 36 1 1407873808 12074768 4758624
21:30:14 61390 8192000000 10304012816 10770120704 1406131152 14.650 100 10 20 0 1420915568 12404720 2379696
21:32:15 79247 8192000000 10313095888 10775355392 1410795344 14.691 100 8 16 0 1423951952 11327536 1829072
21:35:01 97171 8192000000 10294688592 10759106560 1355148320 14.194 96 14 28 1 1372255904 13288288 3819296
21:37:39 17510 8192000000 10283435760 10741395456 1369591216 14.323 98 13 26 0 1378465984 6421664 2453104
21:38:58 44467 8192000000 10325681936 10774446080 1402432128 14.617 100 38 74 0 1432567616 21818144 8317344
21:40:43 90210 8192000000 10300444816 10757021696 1340700704 14.064 96 12 24 0 1358714880 15656080 2358096
21:43:42 67010 8192000000 10297379808 10728730624 1303200224 13.724 94 15 30 0 1316804144 10276208 3327712
21:45:50 50616 8192000000 10273919968 10711785472 1308346224 13.771 95 37 74 1 1329446224 13031120 8068880
21:47:52 108981 8192000000 10255676736 10710228992 1262216592 13.350 92 9 18 0 1277825856 13524496 2084768
21:50:42 59770 8192000000 10236106960 10675011584 1260254560 13.332 93 34 68 0 1274293312 6399200 7639552
21:52:42 55560 8192000000 10244611520 10701266944 1329508464 13.963 99 13 27 0 1348445072 15623408 3313200
21:54:40 39421 8192000000 10226741920 10675535872 1304202864 13.733 98 29 58 0 1323230768 11979168 7048736
21:56:20 36921 8192000000 10208308784 10661732352 1299878128 13.694 99 11 20 0 1316381104 14473792 2029184
21:58:15 46707 8192000000 10228218080 10676170752 1308067680 13.769 98 15 30 1 1325187664 13988736 3131248
22:00:02 61000 8192000000 10233047248 10683150336 1313732336 13.820 98 19 38 0 1331413792 13182912 4498544
22:02:20 22315 8192000000 10238752816 10703364096 1331838688 13.984 99 6 12 0 1345338544 11977808 1522048
22:03:43 73049 8192000000 10255266688 10700996608 1338442416 14.043 99 15 28 0 1351746000 10006432 3297152
22:05:58 93215 8192000000 10267062144 10716749824 1350160832 14.149 100 12 24 0 1367117984 14096224 2860928
22:08:45 47893 8192000000 10284083232 10747236352 1358203792 14.221 98 8 16 1 1372649472 12902944 1542736
22:10:33 58435 8192000000 10291782560 10742611968 1358931296 14.228 98 37 74 1 1381364160 14584224 7848640
22:12:32 93353 8192000000 10305575456 10753462272 1361663968 14.252 98 20 40 1 1378764880 11943840 5157072
22:15:06 31837 8192000000 10300415392 10764652544 1385346896 14.464 100 23 46 0 1410067856 19697216 5023744
22:16:40 83911 8192000000 10309188320 10769211392 1384209024 14.454 99 17 34 0 1404397264 16224816 3963424
22:19:12 139698 8192000000 10314893984 10766680064 1384760320 14.459 98 21 43 0 1402501664 13130672 4610672
22:22:38 60487 8192000000 10300647808 10772148224 1406629072 14.654 100 29 58 0 1424963376 11550304 6784000
22:24:52 64693 8192000000 10293160896 10750472192 1345288272 14.105 96 17 34 0 1358302832 8827696 4186864
22:26:57 63500 8192000000 10257769280 10706829312 1346938144 14.120 97 17 34 0 1363122272 12194240 3989888
22:29:01 56086 8192000000 10279392560 10741575680 1359276080 14.231 98 18 36 0 1374352880 10540352 4536448
22:31:48 42030 8192000000 10280862928 10730266624 1353551056 14.179 98 18 36 0 1365845120 8149344 4144720
22:33:40 49794 8192000000 10289106304 10738933760 1365511552 14.287 98 11 22 0 1382093584 13961440 2620592
22:35:31 53697 8192000000 10291392672 10743922688 1368803872 14.316 98 14 28 0 1384536064 12410816 3321376
22:37:28 50382 8192000000 10270492448 10713001984 1315150192 13.833 96 23 46 0 1329612640 9412160 5050288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-01-2023 22:39:29 to 13-01-2023 22:39:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 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 13-01-2023 22:39:46 to 13-01-2023 22:39:48.

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          238184
MON$OLDEST_ACTIVE               239829
MON$OLDEST_SNAPSHOT             239829
MON$NEXT_TRANSACTION            239829
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                       2031776
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.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 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 ---
:156: 
:157: mscs-test-161.budzdorov.ru    Fri Jan 13 22:38:34 2023
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:159: 
:160: 
:161: mscs-test-161.budzdorov.ru    Fri Jan 13 22:38:36 2023
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:163: 
:164: 
:165: mscs-test-161.budzdorov.ru    Fri Jan 13 22:38:38 2023
:166:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:167: 
--- end of diff output ---

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

13.01.23 22:39:49. 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-13 22:39:51 No severe PSQL-related problems occured
13.01.23 22:39:53. End of report.