Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.02.2023 01: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 15356452 kB
MemAvailable 30395152 kB
Buffers 0 kB
Cached 16334560 kB
SwapCached 3404 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-02-14 01:38:43
DTS_END                         2023-02-14 01:38:43.2230
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/58725
TRN_ID                          243711
ATT_ID                          723
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: 9321.80 20680 1118616
customer order: creation 170.38 359 20446
customer order: refuse 15.43 349 1852
order to supplier: creation 46.11 1262 5533
order to supplier: removal 3.53 92718 423
invoice (draft): creation 46.46 3369 5575
invoice (draft): removal 52.31 786 6277
invoice accept: apply 42.57 18006 5108
invoice accept: cancel 46.97 769 5636
customer reserve: creation 4001.84 176 480221
customer reserve: removal 4599.31 55 551917
realization accept: apply 83.23 160 9988
realization accept: cancel 82.57 103 9908
payment from customer: creation 60.01 115 7201
payment from customer: removal 5.73 81 687
payment to supplier: creation 50.22 126 6026
payment to supplier: removal 3.47 54 416
service: total inventory turnovers 4.53 1329 544
service: total monetary turnovers 6.16 33 739
service: aggregate perf. data 0.96 14872 115
service: refresh index statistics 0.03 278883 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-02-2023 01:39:40 to 14-02-2023 01:39:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3123
TEST_TIME 2 3902
TEST_TIME 3 3878
TEST_TIME 4 4588
TEST_TIME 5 4509
TEST_TIME 6 4847
TEST_TIME 7 4769
TEST_TIME 8 5475
TEST_TIME 9 5624
TEST_TIME 10 5976
TEST_TIME 11 6174
TEST_TIME 12 6214
TEST_TIME 13 6456
TEST_TIME 14 6727
TEST_TIME 15 6840
TEST_TIME 16 6952
TEST_TIME 17 7245
TEST_TIME 18 7439
TEST_TIME 19 7828
TEST_TIME 20 8143
TEST_TIME 21 8579
TEST_TIME 22 9047
TEST_TIME 23 9676
TEST_TIME 24 10186
TEST_TIME 25 10418
TEST_TIME 26 10952
TEST_TIME 27 10665
TEST_TIME 28 10520
TEST_TIME 29 10590
TEST_TIME 30 10518
TEST_TIME 31 10457
TEST_TIME 32 10447
TEST_TIME 33 10286
TEST_TIME 34 10297
TEST_TIME 35 10247
TEST_TIME 36 10154
TEST_TIME 37 10039
TEST_TIME 38 10054
TEST_TIME 39 9966
TEST_TIME 40 9909
TEST_TIME 41 9766
TEST_TIME 42 9740
TEST_TIME 43 9573
TEST_TIME 44 9668
TEST_TIME 45 9683
TEST_TIME 46 9634
TEST_TIME 47 9539
TEST_TIME 48 9535
TEST_TIME 49 9555
TEST_TIME 50 9477
TEST_TIME 51 9511
TEST_TIME 52 9530
TEST_TIME 53 9526
TEST_TIME 54 9534
TEST_TIME 55 9620
TEST_TIME 56 9636
TEST_TIME 57 9614
TEST_TIME 58 9693
TEST_TIME 59 9756
TEST_TIME 60 9727
TEST_TIME 61 9748
TEST_TIME 62 9727
TEST_TIME 63 9643
TEST_TIME 64 9542
TEST_TIME 65 9486
TEST_TIME 66 9465
TEST_TIME 67 9407
TEST_TIME 68 9358
TEST_TIME 69 9397
TEST_TIME 70 9378
TEST_TIME 71 9373
TEST_TIME 72 9485
TEST_TIME 73 9462
TEST_TIME 74 9502
TEST_TIME 75 9444
TEST_TIME 76 9467
TEST_TIME 77 9495
TEST_TIME 78 9559
TEST_TIME 79 9541
TEST_TIME 80 9517
TEST_TIME 81 9574
TEST_TIME 82 9481
TEST_TIME 83 9461
TEST_TIME 84 9455
TEST_TIME 85 9395
TEST_TIME 86 9375
TEST_TIME 87 9334
TEST_TIME 88 9388
TEST_TIME 89 9317
TEST_TIME 90 9290
TEST_TIME 91 9328
TEST_TIME 92 9319
TEST_TIME 93 9328
TEST_TIME 94 9354
TEST_TIME 95 9390
TEST_TIME 96 9346
TEST_TIME 97 9370
TEST_TIME 98 9377
TEST_TIME 99 9409
TEST_TIME 100 9445
TEST_TIME 101 9487
TEST_TIME 102 9475
TEST_TIME 103 9489
TEST_TIME 104 9467
TEST_TIME 105 9397
TEST_TIME 106 9371
TEST_TIME 107 9337
TEST_TIME 108 9346
TEST_TIME 109 9331
TEST_TIME 110 9298
TEST_TIME 111 9272
TEST_TIME 112 9213
TEST_TIME 113 9236
TEST_TIME 114 9241
TEST_TIME 115 9252
TEST_TIME 116 9319
TEST_TIME 117 9306
TEST_TIME 118 9267
TEST_TIME 119 9353
TEST_TIME 120 9325

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-02-2023 01:39:43 to 14-02-2023 01:39:47.

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 1561027 1561027 0 0.00 0 20592 6 0 0
doc_list_biud 1561025 1561025 0 0.00 0 36636 25 0 0
sp_add_invoice_to_stock 5230 5108 122 2.33 30 164263 18007 0 122
sp_cancel_adding_invoice 5636 5636 0 0.00 4 69746 769 0 0
sp_cancel_client_order 1852 1852 0 0.00 4 13066 350 0 0
sp_cancel_customer_reserve 551917 551917 0 0.00 0 36638 56 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 4306 81 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 2 2994 54 0 0
sp_cancel_supplier_invoice 6288 6277 11 0.17 1 16712 787 0 11
sp_cancel_supplier_order 423 423 0 0.00 5 1243091 92719 0 0
sp_cancel_write_off 9908 9908 0 0.00 1 13423 104 0 0
sp_client_order 20446 20446 0 0.00 3 17401 359 0 0
sp_customer_reserve 480221 480221 0 0.00 0 70283 176 0 0
sp_fill_shopping_cart 31552 31552 0 0.00 1 12980 37 0 0
sp_fill_shopping_cart_clo_res 480217 480217 0 0.00 0 16846 14 0 0
sp_get_clo_for_invoice 5102 5102 0 0.00 0 30382 314 0 0
sp_kill_cost_storno 16647 16647 0 0.00 0 9438 53 0 0
sp_kill_qstorno_ret_qs2qd 1120442 1120442 0 0.00 0 36634 30 0 0
sp_kill_qty_storno 591099 591099 0 0.00 0 36636 66 0 0
sp_lock_selected_doc 20975 20975 0 0.00 0 10964 8 0 0
sp_make_cost_storno 28314 28314 0 0.00 0 20591 50 0 0
sp_multiply_rows_for_pdistr 28314 28314 0 0.00 0 9812 25 0 0
sp_multiply_rows_for_qdistr 20446 20446 0 0.00 0 10634 164 0 0
sp_pay_from_customer 7201 7201 0 0.00 4 9163 116 0 0
sp_pay_to_supplier 6026 6026 0 0.00 4 56386 127 0 0
sp_payment_common 13227 13227 0 0.00 1 56378 28 0 0
sp_qd_handle_on_cancel_clo 1852 1852 0 0.00 0 13059 307 0 0
sp_qd_handle_on_invoice_upd_sts 10735 10735 0 0.00 0 18005 300 0 0
sp_qd_handle_on_reserve_upd_sts 9987 9987 0 0.00 0 19865 59 0 0
sp_reserve_write_off 9988 9988 0 0.00 6 20601 161 0 0
sp_supplier_invoice 5575 5575 0 0.00 6 93489 3370 0 0
sp_supplier_order 5533 5533 0 0.00 4 28491 1262 0 0
srv_aggregate_perf_data 115 115 0 0.00 2400 145658 14872 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1661 134711 44995 0 0
srv_find_qd_qs_mism 1068900 1068900 0 0.00 0 15235 5 0 0
srv_make_invnt_saldo 544 544 0 0.00 41 77107 1330 0 0
srv_make_money_saldo 739 739 0 0.00 1 3310 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 144366 386175 278883 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 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 5109 5109 0 0.00 0 13358 160 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 5703 74 0 0
t$perf-norm:sp_cancel_client_order 1852 1852 0 0.00 0 4181 16 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 957 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 1323 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 511 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 1037 10 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 20569 1370 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 3767 9 0 0
t$perf-norm:sp_client_order 20446 20446 0 0.00 0 12512 17 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 5657 18 0 0
t$perf-norm:sp_pay_from_customer 7201 7201 0 0.00 0 6162 12 0 0
t$perf-norm:sp_pay_to_supplier 6026 6026 0 0.00 0 10327 10 0 0
t$perf-norm:sp_reserve_write_off 9988 9988 0 0.00 0 6562 12 0 0
t$perf-norm:sp_supplier_invoice 5575 5575 0 0.00 0 6497 18 0 0
t$perf-norm:sp_supplier_order 5533 5533 0 0.00 0 3994 17 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 302 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 420 9 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 1089 19 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 1371 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10440 10196 244 2.33 0 10949 19 0 244
v_all_customers 37538 37538 0 0.00 0 59 0 0 0
v_all_suppliers 30794 30794 0 0.00 0 45 1 0 0
v_all_wares 40892 40892 0 0.00 0 62 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 3074 15 0 0
v_cancel_client_order 3704 3704 0 0.00 0 8623 9 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 3009 7 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 1203 4 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2975 10 0 0
v_cancel_supplier_invoice 872 850 22 2.52 0 4127 26 0 22
v_cancel_supplier_order 846 846 0 0.00 0 1103 5 0 0
v_cancel_write_off 2006 2006 0 0.00 0 3944 14 0 0
v_max_id_clo_ord 5533 5533 0 0.00 0 229 1 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 3921 20 0 0
v_max_id_ord_sup 5573 5573 0 0.00 0 4293 4 0 0
v_max_non_paid_invoice 1735 1735 0 0.00 0 1211 2 0 0
v_max_non_paid_realizn 3816 3816 0 0.00 0 5286 5 0 0
v_min_id_clo_ord 5533 5533 0 0.00 0 3459 5 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 6662 11 0 0
v_min_id_ord_sup 5573 5573 0 0.00 0 4428 5 0 0
v_min_non_paid_invoice 6026 6026 0 0.00 0 56372 24 0 0
v_min_non_paid_realizn 7201 7201 0 0.00 0 6559 16 0 0
v_our_firm 10124 10124 0 0.00 0 59 1 0 0
v_reserve_write_off 19974 19974 0 0.00 0 3901 6 0 0
x_lock_depdocs_on_canc_invoice 11488 11488 0 0.00 0 30231 121 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 22980 381 0 0
x_make_qstorno_1000_1200 5533 5533 0 0.00 1 28484 1196 0 0
x_make_qstorno_1200_2000 5575 5575 0 0.00 3 93475 3228 0 0
x_make_qstorno_2100_3300 480221 480221 0 0.00 0 70282 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-02-2023 01:39:48 to 14-02-2023 01:39:52.

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
23:08:47 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:48 33503 8192000000 9906362192 10217816064 979129920 10.676 100 87 174 0 1008646320 6247696 23268704
23:11:25 22076 8192000000 9952567424 10274758656 1030791424 11.176 100 95 190 0 1064636560 8517936 25327200
23:12:54 13581 8192000000 9981864288 10313555968 1066957664 11.523 100 83 166 0 1096602048 9140656 20503728
23:14:15 7180 8192000000 10039537408 10385018880 1130161488 12.123 100 83 164 0 1160279856 10164240 19954128
23:15:26 3871 8192000000 10082639616 10445312000 1177862848 12.570 100 74 147 1 1206360576 11888032 16609696
23:16:33 4393 8192000000 10124503808 10493026304 1216117520 12.926 100 81 161 1 1247495600 13126592 18251488
23:17:40 2158 8192000000 10157971056 10531586048 1244672432 13.189 100 90 180 0 1279160528 14752736 19735360
23:18:44 1665 8192000000 10192394976 10577752064 1285649232 13.565 100 84 168 0 1317674576 12987328 19038016
23:19:47 4345 8192000000 10206205072 10596216832 1295808960 13.657 100 70 141 2 1323172016 11921312 15441744
23:20:52 10062 8192000000 10194772784 10603941888 1303839952 13.730 100 28 57 0 1316117552 6129888 6147712
23:22:04 9791 8192000000 10201997648 10616344576 1313739712 13.820 100 20 40 0 1324891088 6164736 4986640
23:23:16 14551 8192000000 10204438096 10624430080 1321814128 13.893 100 13 26 0 1330092720 5271984 3006608
23:24:35 41174 8192000000 10225059056 10645172224 1330548016 13.972 100 26 52 0 1342007120 5771200 5687904
23:26:18 6172 8192000000 10235768368 10652786688 1330223328 13.969 99 37 74 0 1345759632 6721776 8814528
23:27:24 12462 8192000000 10225980784 10652991488 1343242464 14.087 100 17 35 0 1351892832 4852288 3798080
23:28:38 42707 8192000000 10200645728 10622357504 1272017328 13.440 95 33 66 0 1285741616 5365056 8359232
23:30:26 7361 8192000000 10145569584 10541490176 1217154688 12.935 96 28 56 0 1229584128 6267984 6161456
23:31:37 9705 8192000000 10142238768 10546253824 1244672192 13.189 99 13 26 0 1253017184 5329248 3015744
23:32:49 52464 8192000000 10170858352 10567393280 1268578624 13.409 100 46 91 4 1285076960 4983264 11515072
23:34:42 2914 8192000000 10180700336 10593566720 1286824448 13.575 100 30 58 0 1300522976 7310064 6388464
23:35:45 2567 8192000000 10206325472 10610266112 1299799632 13.693 100 50 99 0 1317029552 4530448 12699472
23:36:49 7536 8192000000 10187464528 10604650496 1298107344 13.678 100 7 14 1 1304945504 5363616 1474544
23:37:57 30160 8192000000 10208083376 10621128704 1311392608 13.799 100 42 84 1 1326276960 5741808 9142544
23:39:34 19302 8192000000 10220721808 10635657216 1321882000 13.894 99 35 70 0 1340694016 11079600 7732416
23:41:04 21635 8192000000 10253761888 10673582080 1349854656 14.146 100 36 72 0 1369386400 11476208 8055536
23:42:29 17714 8192000000 10282996864 10700165120 1370323792 14.330 100 51 101 0 1400789920 19078608 11387520
23:43:49 18394 8192000000 10289720704 10710761472 1374237600 14.365 100 35 70 0 1401508896 19011296 8260000
23:45:14 7439 8192000000 10313715104 10728869888 1391115344 14.516 100 55 110 0 1425139840 21609024 12415472
23:46:24 3469 8192000000 10324432224 10746425344 1396107232 14.560 100 58 110 0 1438275616 28235744 13932640
23:47:33 3507 8192000000 10326726896 10747809792 1393606480 14.538 99 50 100 0 1434711696 28942832 12162384
23:48:41 4514 8192000000 10346272992 10774016000 1413314272 14.713 100 53 104 0 1458841280 32249248 13277760
23:49:49 5147 8192000000 10348872176 10772287488 1407676624 14.663 100 61 120 1 1454226192 31621456 14928112
23:50:58 4516 8192000000 10345636896 10772897792 1387315088 14.482 98 56 112 1 1436864784 35701680 13848016
23:52:11 3302 8192000000 10345710688 10770800640 1409010464 14.675 100 51 100 1 1455005232 32725952 13268816
23:53:19 1897 8192000000 10343376480 10754048000 1390455472 14.510 100 74 147 0 1445220304 36018208 18746624
23:54:25 1659 8192000000 10363865568 10772590592 1395097568 14.551 99 88 173 3 1455923552 40193280 20632704
23:55:32 1843 8192000000 10346142736 10744590336 1378411264 14.402 100 91 175 0 1445650080 44854112 22384704
23:56:37 4283 8192000000 10335206752 10749104128 1377518880 14.394 100 54 107 0 1440682368 49126736 14036752
23:57:46 1744 8192000000 10327164224 10737958912 1365718880 14.289 99 58 116 0 1427064208 45765104 15580224
23:58:50 3921 8192000000 10310549584 10727849984 1350135296 14.149 99 42 86 0 1406548832 44145872 12267664
23:59:55 17643 8192000000 10326703696 10754609152 1378026064 14.399 100 31 61 1 1433130272 46200448 8903760
00:01:20 14891 8192000000 10300715360 10738659328 1359863232 14.236 100 33 66 0 1418181984 49053648 9265104
00:02:40 37346 8192000000 10283529248 10727428096 1357584224 14.216 100 13 26 0 1396481168 35184272 3712672
00:04:19 35129 8192000000 10271666224 10718040064 1359509824 14.233 100 10 20 0 1383195568 20334160 3351584
00:06:17 103045 8192000000 10250044192 10694803456 1339675008 14.054 99 45 90 0 1363812336 13173968 10963360
00:09:12 63182 8192000000 10280071104 10731773952 1354691472 14.190 98 22 45 0 1372847088 13439200 4716416
00:11:20 17263 8192000000 10277515312 10711367680 1355917408 14.201 99 32 65 0 1370956432 8054656 6984368
00:12:40 16885 8192000000 10277819328 10720669696 1362951472 14.264 99 21 42 0 1379239856 11816656 4471728
00:13:59 30163 8192000000 10279675328 10730790912 1380057312 14.417 100 18 36 0 1391903616 7951424 3894880
00:15:30 106620 8192000000 10277673632 10734407680 1370401440 14.331 99 18 37 0 1386593008 11772768 4418800
00:19:16 64035 8192000000 10303374976 10760040448 1397292848 14.571 100 20 40 0 1415552864 13232752 5027264
00:21:51 134711 8192000000 10292439216 10753056768 1350378416 14.151 97 19 38 0 1376435584 21539488 4517680
00:25:06 20611 8192000000 10286205472 10742812672 1388199728 14.490 100 21 40 2 1402536688 9574352 4762608
00:26:33 56576 8192000000 10287776656 10761236480 1398784128 14.584 100 6 12 0 1411147088 11034496 1328464
00:28:29 51766 8192000000 10290289568 10746712064 1367440912 14.304 98 22 45 0 1382174192 9482128 5251152
00:30:21 50500 8192000000 10309258480 10766077952 1386239920 14.472 99 26 52 2 1406648720 14845504 5563296
00:32:44 48842 8192000000 10298057120 10761609216 1397648736 14.574 100 14 28 0 1410214912 9607776 2958400
00:34:39 104832 8192000000 10303507952 10759712768 1360611168 14.243 98 13 26 1 1377487360 13960256 2915936
00:37:33 77221 8192000000 10283917600 10737487872 1322712912 13.901 95 9 18 0 1337561456 12262496 2586048
00:39:51 73156 8192000000 10270662416 10728759296 1348664336 14.135 98 10 20 0 1363478480 12579216 2234928
00:42:40 45110 8192000000 10270667424 10719813632 1326113040 13.932 96 18 36 0 1339950320 10049136 3788144
00:44:27 74205 8192000000 10270921344 10725298176 1351256768 14.159 98 12 24 0 1367829312 13711952 2860592
00:46:42 67738 8192000000 10284143344 10736840704 1323699584 13.910 96 14 28 1 1340978672 13332608 3946480
00:49:05 65655 8192000000 10270497760 10729283584 1358117152 14.220 99 28 52 0 1384436272 19996336 6322784
00:51:39 86260 8192000000 10247545824 10695331840 1285162624 13.560 94 11 22 0 1301673872 14248928 2262320
00:54:06 26747 8192000000 10269612784 10711056384 1340871232 14.065 99 27 54 0 1359576960 12821168 5884560
00:55:46 50019 8192000000 10272994384 10720731136 1340395408 14.061 98 27 54 0 1364008544 17700240 5912896
00:57:36 38743 8192000000 10290293328 10725330944 1356768576 14.208 99 27 54 0 1372114912 9137280 6209056
00:59:18 122084 8192000000 10290893296 10726481920 1362971520 14.264 99 31 62 0 1382713552 13189664 6552368
01:02:36 44345 8192000000 10252321248 10698948608 1345212928 14.104 99 38 74 1 1363906576 10069328 8624320
01:04:22 64695 8192000000 10270987712 10728210432 1370943216 14.335 100 17 33 1 1387087696 12116608 4027872
01:06:30 31722 8192000000 10286509888 10730409984 1370407552 14.331 100 27 54 1 1392942048 15470544 7063952
01:08:02 79739 8192000000 10267852384 10724917248 1339350576 14.052 97 14 27 0 1352059424 9889264 2819584
01:10:22 49612 8192000000 10286329744 10740809728 1372658544 14.351 99 15 30 0 1383355840 7801584 2895712
01:12:12 79637 8192000000 10308930048 10756968448 1381978864 14.434 99 23 46 0 1394860720 7587056 5294800
01:14:32 31403 8192000000 10322598688 10759208960 1375046272 14.372 98 37 74 1 1391386928 8201456 8139200
01:16:05 49673 8192000000 10316366208 10765742080 1394310080 14.544 99 31 62 0 1412916304 11570416 7035808
01:17:56 81687 8192000000 10305226256 10751246336 1360057664 14.238 97 13 26 0 1374381120 11372896 2950560
01:20:23 86076 8192000000 10322879232 10771636224 1358163328 14.221 97 17 35 0 1377172960 15165296 3844336
01:23:03 80197 8192000000 10296048000 10750447616 1381995856 14.434 99 47 92 0 1403320016 10334608 10989552
01:25:24 32124 8192000000 10300827152 10755596288 1368253264 14.311 98 28 55 1 1387132256 12872128 6006864
01:27:00 58390 8192000000 10286209776 10757988352 1385406272 14.465 99 10 20 0 1397869488 10352976 2110240
01:28:59 61276 8192000000 10314903808 10772885504 1381880464 14.433 98 18 36 0 1403469232 17188112 4400656
01:31:41 59806 8192000000 10283031520 10738765824 1335005120 14.012 96 15 28 0 1356475936 18394272 3076544
01:33:42 68231 8192000000 10282015952 10741952512 1351685760 14.163 98 18 36 0 1372819936 16519200 4614976
01:36:15 50737 8192000000 10259596784 10702987264 1329072064 13.959 97 40 76 0 1351451040 13754752 8624224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-02-2023 01:39:54 to 14-02-2023 01:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 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 14-02-2023 01:40:10 to 14-02-2023 01:40:12.

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          242377
MON$OLDEST_ACTIVE               243831
MON$OLDEST_SNAPSHOT             243831
MON$NEXT_TRANSACTION            243831
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                       2020416
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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 ---
:261: 
:262: mscs-test-161.budzdorov.ru    Tue Feb 14 01:38:45 2023
:263:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:264: 
:265: 
:266: mscs-test-161.budzdorov.ru    Tue Feb 14 01:38:50 2023
:267:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:268: 
--- end of diff output ---

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

14.02.23 01:40:13. 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-02-14 01:40:16 No severe PSQL-related problems occured
14.02.23 01:40:17. End of report.