Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.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 15273788 kB
MemAvailable 30357964 kB
Buffers 0 kB
Cached 16421808 kB
SwapCached 4556 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-06 22:38:32
DTS_END                         2023-01-06 22:38:32.1580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38906
TRN_ID                          250902
ATT_ID                          707
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: 9439.35 16633 1132722
customer order: creation 183.95 336 22074
customer order: refuse 15.70 361 1884
order to supplier: creation 48.19 1188 5783
order to supplier: removal 3.42 93936 410
invoice (draft): creation 48.48 3099 5817
invoice (draft): removal 52.98 762 6357
invoice accept: apply 43.29 16768 5195
invoice accept: cancel 47.74 749 5729
customer reserve: creation 3960.12 168 475214
customer reserve: removal 4730.06 53 567607
realization accept: apply 87.28 199 10473
realization accept: cancel 80.22 111 9627
payment from customer: creation 62.94 141 7553
payment from customer: removal 6.02 67 722
payment to supplier: creation 52.61 155 6313
payment to supplier: removal 3.92 70 470
service: total inventory turnovers 4.83 1166 579
service: total monetary turnovers 6.68 40 801
service: aggregate perf. data 0.92 18897 110
service: refresh index statistics 0.03 194386 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-01-2023 22:40:43 to 06-01-2023 22:40:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3137
TEST_TIME 2 4430
TEST_TIME 3 3416
TEST_TIME 4 5101
TEST_TIME 5 4918
TEST_TIME 6 5015
TEST_TIME 7 5161
TEST_TIME 8 5275
TEST_TIME 9 5652
TEST_TIME 10 5913
TEST_TIME 11 5805
TEST_TIME 12 6421
TEST_TIME 13 6343
TEST_TIME 14 6371
TEST_TIME 15 6293
TEST_TIME 16 6305
TEST_TIME 17 6621
TEST_TIME 18 6734
TEST_TIME 19 7308
TEST_TIME 20 7684
TEST_TIME 21 7653
TEST_TIME 22 7941
TEST_TIME 23 7961
TEST_TIME 24 8028
TEST_TIME 25 8346
TEST_TIME 26 8418
TEST_TIME 27 9112
TEST_TIME 28 9455
TEST_TIME 29 9844
TEST_TIME 30 10253
TEST_TIME 31 10124
TEST_TIME 32 10197
TEST_TIME 33 10216
TEST_TIME 34 10100
TEST_TIME 35 9987
TEST_TIME 36 9974
TEST_TIME 37 9840
TEST_TIME 38 9959
TEST_TIME 39 9797
TEST_TIME 40 9782
TEST_TIME 41 9700
TEST_TIME 42 9668
TEST_TIME 43 9860
TEST_TIME 44 9901
TEST_TIME 45 9770
TEST_TIME 46 9753
TEST_TIME 47 9668
TEST_TIME 48 9674
TEST_TIME 49 9621
TEST_TIME 50 9538
TEST_TIME 51 9575
TEST_TIME 52 9506
TEST_TIME 53 9588
TEST_TIME 54 9633
TEST_TIME 55 9643
TEST_TIME 56 9591
TEST_TIME 57 9675
TEST_TIME 58 9622
TEST_TIME 59 9689
TEST_TIME 60 9761
TEST_TIME 61 9743
TEST_TIME 62 9776
TEST_TIME 63 9794
TEST_TIME 64 9818
TEST_TIME 65 9815
TEST_TIME 66 9938
TEST_TIME 67 9891
TEST_TIME 68 9950
TEST_TIME 69 9868
TEST_TIME 70 9780
TEST_TIME 71 9724
TEST_TIME 72 9739
TEST_TIME 73 9664
TEST_TIME 74 9600
TEST_TIME 75 9585
TEST_TIME 76 9533
TEST_TIME 77 9566
TEST_TIME 78 9525
TEST_TIME 79 9467
TEST_TIME 80 9454
TEST_TIME 81 9414
TEST_TIME 82 9366
TEST_TIME 83 9364
TEST_TIME 84 9329
TEST_TIME 85 9357
TEST_TIME 86 9334
TEST_TIME 87 9401
TEST_TIME 88 9373
TEST_TIME 89 9401
TEST_TIME 90 9389
TEST_TIME 91 9443
TEST_TIME 92 9458
TEST_TIME 93 9501
TEST_TIME 94 9505
TEST_TIME 95 9472
TEST_TIME 96 9486
TEST_TIME 97 9570
TEST_TIME 98 9605
TEST_TIME 99 9518
TEST_TIME 100 9467
TEST_TIME 101 9485
TEST_TIME 102 9448
TEST_TIME 103 9423
TEST_TIME 104 9388
TEST_TIME 105 9361
TEST_TIME 106 9306
TEST_TIME 107 9256
TEST_TIME 108 9269
TEST_TIME 109 9273
TEST_TIME 110 9288
TEST_TIME 111 9256
TEST_TIME 112 9246
TEST_TIME 113 9297
TEST_TIME 114 9341
TEST_TIME 115 9354
TEST_TIME 116 9402
TEST_TIME 117 9374
TEST_TIME 118 9413
TEST_TIME 119 9452
TEST_TIME 120 9441

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-01-2023 22:40:47 to 06-01-2023 22:40:50.

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 1573274 1573274 0 0.00 0 23548 6 0 0
doc_list_biud 1573274 1573274 0 0.00 0 43451 25 0 0
sp_add_invoice_to_stock 5337 5195 142 2.66 33 194163 16768 0 142
sp_cancel_adding_invoice 5729 5729 0 0.00 4 64470 749 0 0
sp_cancel_client_order 1884 1884 0 0.00 3 14064 362 0 0
sp_cancel_customer_reserve 567607 567607 0 0.00 0 19931 54 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 2382 67 0 0
sp_cancel_pay_to_supplier 470 470 0 0.00 2 4137 70 0 0
sp_cancel_supplier_invoice 6367 6357 10 0.15 1 43460 763 0 10
sp_cancel_supplier_order 410 410 0 0.00 8 878869 93936 0 0
sp_cancel_write_off 9627 9627 0 0.00 1 12210 112 0 0
sp_client_order 22074 22074 0 0.00 3 25633 337 0 0
sp_customer_reserve 475214 475214 0 0.00 0 42946 169 0 0
sp_fill_shopping_cart 33664 33664 0 0.00 1 22590 35 0 0
sp_fill_shopping_cart_clo_res 475210 475210 0 0.00 0 17130 13 0 0
sp_get_clo_for_invoice 5190 5190 0 0.00 0 18945 270 0 0
sp_kill_cost_storno 16548 16548 0 0.00 0 21773 52 0 0
sp_kill_qstorno_ret_qs2qd 1151608 1151608 0 0.00 0 43447 30 0 0
sp_kill_qty_storno 607277 607277 0 0.00 0 43450 64 0 0
sp_lock_selected_doc 21833 21833 0 0.00 0 8487 5 0 0
sp_make_cost_storno 29529 29529 0 0.00 0 21741 66 0 0
sp_multiply_rows_for_pdistr 29529 29529 0 0.00 0 23545 32 0 0
sp_multiply_rows_for_qdistr 22074 22074 0 0.00 0 11841 155 0 0
sp_pay_from_customer 7553 7553 0 0.00 4 22002 141 0 0
sp_pay_to_supplier 6313 6313 0 0.00 4 23812 156 0 0
sp_payment_common 13866 13866 0 0.00 1 21823 54 0 0
sp_qd_handle_on_cancel_clo 1884 1884 0 0.00 0 14055 328 0 0
sp_qd_handle_on_invoice_upd_sts 10919 10919 0 0.00 0 26938 284 0 0
sp_qd_handle_on_reserve_upd_sts 10473 10473 0 0.00 0 21320 65 0 0
sp_reserve_write_off 10473 10473 0 0.00 6 21765 199 0 0
sp_supplier_invoice 5817 5817 0 0.00 6 86576 3099 0 0
sp_supplier_order 5783 5783 0 0.00 4 67760 1188 0 0
srv_aggregate_perf_data 110 110 0 0.00 2712 522433 18897 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1297 119163 41587 0 0
srv_find_qd_qs_mism 1082752 1082752 0 0.00 0 13163 5 0 0
srv_make_invnt_saldo 579 579 0 0.00 22 85127 1166 0 0
srv_make_money_saldo 801 801 0 0.00 0 11327 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 131700 247760 194387 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 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 5195 5195 0 0.00 0 13555 138 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 3200 85 0 0
t$perf-norm:sp_cancel_client_order 1884 1884 0 0.00 0 4084 12 0 0
t$perf-norm:sp_cancel_customer_reserve 759 759 0 0.00 0 2927 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 572 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 470 470 0 0.00 0 1743 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 6150 34 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 25078 1477 0 0
t$perf-norm:sp_cancel_write_off 1054 1054 0 0.00 0 5442 14 0 0
t$perf-norm:sp_client_order 22074 22074 0 0.00 0 9984 17 0 0
t$perf-norm:sp_customer_reserve 1090 1090 0 0.00 0 371 3 0 0
t$perf-norm:sp_pay_from_customer 7553 7553 0 0.00 0 5662 8 0 0
t$perf-norm:sp_pay_to_supplier 6313 6313 0 0.00 0 10687 11 0 0
t$perf-norm:sp_reserve_write_off 10473 10473 0 0.00 0 10663 14 0 0
t$perf-norm:sp_supplier_invoice 5817 5817 0 0.00 0 16094 21 0 0
t$perf-norm:sp_supplier_order 5783 5783 0 0.00 0 3876 12 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 336 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 7469 39 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 2647 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10664 10380 284 2.66 0 4995 17 0 284
v_all_customers 40020 40020 0 0.00 0 46 0 0 0
v_all_suppliers 32370 32370 0 0.00 0 67 1 0 0
v_all_wares 44148 44148 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 3096 11 0 0
v_cancel_client_order 3768 3768 0 0.00 0 5176 8 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 2010 8 0 0
v_cancel_customer_reserve 1518 1518 0 0.00 0 3061 6 0 0
v_cancel_payment_to_supplier 940 940 0 0.00 0 3046 11 0 0
v_cancel_supplier_invoice 910 890 20 2.19 0 1724 16 0 20
v_cancel_supplier_order 820 820 0 0.00 0 1253 6 0 0
v_cancel_write_off 2108 2108 0 0.00 0 3005 14 0 0
v_max_id_clo_ord 5781 5781 0 0.00 0 2771 2 0 0
v_max_id_clo_res 1090 1090 0 0.00 0 1514 8 0 0
v_max_id_ord_sup 5808 5808 0 0.00 0 7584 6 0 0
v_max_non_paid_invoice 1717 1717 0 0.00 0 5544 9 0 0
v_max_non_paid_realizn 4117 4117 0 0.00 0 4657 7 0 0
v_min_id_clo_ord 5781 5781 0 0.00 0 3529 7 0 0
v_min_id_clo_res 1090 1090 0 0.00 0 3237 4 0 0
v_min_id_ord_sup 5808 5808 0 0.00 0 6429 8 0 0
v_min_non_paid_invoice 6313 6313 0 0.00 0 21819 41 0 0
v_min_non_paid_realizn 7553 7553 0 0.00 0 21661 43 0 0
v_our_firm 11000 11000 0 0.00 0 13 1 0 0
v_reserve_write_off 20946 20946 0 0.00 0 6913 5 0 0
x_lock_depdocs_on_canc_invoice 11641 11641 0 0.00 0 21878 106 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 9105 321 0 0
x_make_qstorno_1000_1200 5783 5783 0 0.00 1 67751 1112 0 0
x_make_qstorno_1200_2000 5817 5817 0 0.00 3 86426 2971 0 0
x_make_qstorno_2100_3300 475214 475214 0 0.00 0 42945 153 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 06-01-2023 22:40:51 to 06-01-2023 22:40:56.

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:36 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:37 28159 8192000000 9893083824 10201554944 971403536 10.600 100 86 172 0 1000346912 6050848 22892528
20:11:10 16712 8192000000 9926502384 10242564096 1008515520 10.961 100 93 185 0 1040104112 7667344 23921248
20:12:33 11161 8192000000 9945734080 10270707712 1033827920 11.205 100 84 168 0 1064700016 9573968 21298128
20:13:54 8736 8192000000 10022373824 10367770624 1110755216 11.940 100 85 170 1 1141607408 10365040 20487152
20:15:08 4486 8192000000 10073027152 10433839104 1164543600 12.446 100 78 153 0 1195127264 12665968 17917696
20:16:16 3874 8192000000 10121899680 10492878848 1211599984 12.884 100 72 144 0 1240099744 11875584 16624176
20:17:21 2385 8192000000 10167714336 10545680384 1257377648 13.306 100 77 152 0 1286120944 12432384 16310912
20:18:26 1259 8192000000 10194232544 10578636800 1283334672 13.543 100 86 169 1 1315157872 13165616 18657584
20:19:29 1303 8192000000 10216042544 10603495424 1300510224 13.700 100 88 174 2 1332061392 12306304 19244864
20:20:32 12483 8192000000 10205080608 10611712000 1309742656 13.784 100 42 82 1 1329625840 10480736 9402448
20:21:45 6714 8192000000 10225074736 10631966720 1324464816 13.917 100 35 70 1 1340152000 7300688 8386496
20:22:53 5827 8192000000 10214756928 10635120640 1330912352 13.975 100 26 52 0 1344007008 7009584 6085072
20:24:01 7601 8192000000 10229484848 10642608128 1335746848 14.019 100 37 73 0 1349496416 5324880 8424688
20:25:13 15394 8192000000 10242307072 10656657408 1340894416 14.065 100 56 114 0 1361800640 7257648 13648576
20:26:30 5114 8192000000 10227973792 10648264704 1338945072 14.048 100 29 57 0 1352013536 6433008 6635456
20:27:36 7471 8192000000 10223948112 10647785472 1328377776 13.952 99 35 71 0 1342198208 5654016 8166416
20:28:46 8723 8192000000 10205085536 10623959040 1307760496 13.766 99 25 50 0 1319668848 6283808 5624544
20:29:55 33065 8192000000 10176637856 10589712384 1207977824 12.850 92 18 34 0 1216410928 4335040 4098064
20:31:28 7427 8192000000 10145942352 10547240960 1222618704 12.986 97 19 38 0 1231975232 5384992 3971536
20:32:38 6235 8192000000 10154946592 10557788160 1260149360 13.331 100 21 43 0 1269971520 5039824 4782336
20:33:47 61718 8192000000 10185605408 10586963968 1282684816 13.538 100 23 45 1 1292635872 5054528 4896528
20:35:56 4987 8192000000 10195182272 10604969984 1302388992 13.717 100 28 56 0 1314921008 5571760 6960256
20:37:03 8349 8192000000 10201051296 10615640064 1310281904 13.789 100 21 43 0 1320796304 5467920 5046480
20:38:11 11203 8192000000 10217602384 10638106624 1331681888 13.982 100 13 26 0 1339354656 4863728 2809040
20:39:29 39722 8192000000 10252127776 10669625344 1354003504 14.183 100 40 80 1 1370439232 7252656 9183072
20:41:13 57490 8192000000 10252966832 10684903424 1361735824 14.253 100 28 56 0 1376180336 7813232 6631280
20:43:19 14194 8192000000 10287333904 10710089728 1377511968 14.394 100 53 104 1 1399299344 10199536 11587840
20:44:40 12395 8192000000 10296657936 10722316288 1386433216 14.474 100 46 92 1 1412800640 15838368 10529056
20:46:11 7338 8192000000 10313288384 10734010368 1396179472 14.561 100 65 126 1 1426686032 15611920 14894640
20:47:22 14268 8192000000 10314634608 10737807360 1396709008 14.566 100 52 105 0 1425833856 16587232 12537616
20:48:41 9598 8192000000 10320791920 10753183744 1404411584 14.634 100 39 75 1 1431795280 17681904 9701792
20:49:55 4650 8192000000 10336667008 10762821632 1403416160 14.625 99 56 112 0 1438677968 21955952 13305856
20:51:07 1296 8192000000 10339956848 10759929856 1409362400 14.678 100 75 150 2 1447969808 20441136 18166272
20:52:12 6261 8192000000 10308757680 10730782720 1382500432 14.439 99 53 104 0 1420529216 25386800 12641984
20:53:32 14036 8192000000 10323089744 10748239872 1389124448 14.498 99 45 90 1 1430417040 30322688 10969904
20:54:52 2667 8192000000 10317843376 10732486656 1375414304 14.376 99 55 110 0 1423448864 34204592 13829968
20:56:02 8004 8192000000 10308489472 10731495424 1349375200 14.142 97 41 82 0 1392751792 33075264 10301328
20:57:20 8718 8192000000 10318758576 10739077120 1357740736 14.217 98 35 66 0 1406982656 39404800 9837120
20:58:37 11687 8192000000 10312888080 10734026752 1378568320 14.404 100 41 82 0 1425449504 36236416 10644768
20:59:55 10879 8192000000 10307587600 10727514112 1355035472 14.193 99 39 78 1 1405663120 40043152 10584496
21:01:14 17153 8192000000 10319005344 10748186624 1373463664 14.358 100 34 68 0 1428970400 46554160 8952576
21:02:33 23697 8192000000 10329553792 10751983616 1367319024 14.303 99 39 76 0 1424712000 47294432 10098544
21:04:00 11810 8192000000 10338163568 10763436032 1378495968 14.403 100 40 79 1 1441810848 52603280 10711600
21:05:15 23124 8192000000 10303766304 10742456320 1345946112 14.111 99 34 68 1 1409644064 54108800 9589152
21:06:40 52697 8192000000 10272348496 10706952192 1328740960 13.956 99 16 32 0 1379497200 45255488 5500752
21:08:33 23333 8192000000 10247782704 10680324096 1326749792 13.938 98 16 33 0 1337310864 7505888 3055184
21:09:57 19875 8192000000 10278710720 10707468288 1361146240 14.248 100 42 82 0 1379340480 8097184 10097056
21:11:23 22022 8192000000 10282453648 10713563136 1354228528 14.186 99 27 53 1 1369820112 8983824 6607760
21:12:46 38900 8192000000 10289662464 10720280576 1366709040 14.298 100 36 74 0 1389546336 13629776 9207520
21:14:27 81038 8192000000 10277712768 10714714112 1336818672 14.029 97 25 48 0 1349865808 7767776 5279360
21:16:52 43899 8192000000 10296449392 10734444544 1374541120 14.368 100 15 30 0 1392745696 14576368 3628208
21:18:44 32833 8192000000 10290650448 10737147904 1379346496 14.411 99 11 23 0 1397821616 15512928 2962192
21:20:25 38440 8192000000 10301743104 10756788224 1382860208 14.442 100 56 111 0 1424863360 28607568 13395584
21:22:09 28807 8192000000 10281988768 10736517120 1371040048 14.336 99 13 27 0 1384986128 10753200 3192880
21:23:37 48535 8192000000 10293101248 10752462848 1390929648 14.514 100 12 24 0 1410391952 16625184 2837120
21:25:33 76898 8192000000 10295146944 10762424320 1392419024 14.527 100 23 46 0 1409357824 11686352 5252448
21:28:05 70617 8192000000 10299300912 10758189056 1362513744 14.260 97 20 40 0 1379949360 12330048 5105568
21:30:17 47916 8192000000 10312399104 10773946368 1408789600 14.673 100 18 36 0 1428350320 15253280 4307440
21:32:48 87740 8192000000 10292160144 10758402048 1384517280 14.457 99 6 12 0 1401557456 15711824 1328352
21:35:19 39224 8192000000 10287083120 10744954880 1367489136 14.305 98 29 58 0 1383357040 9528464 6339440
21:37:03 79863 8192000000 10277095152 10741665792 1325525792 13.927 95 28 56 0 1345347216 13297344 6524080
21:39:23 11859 8192000000 10302401056 10737397760 1378710224 14.405 100 42 82 0 1396543664 8897040 8936400
21:40:42 28223 8192000000 10275289904 10719784960 1315555152 13.836 95 20 40 0 1328632736 8966832 4110752
21:42:15 31002 8192000000 10262453232 10705264640 1329024016 13.958 98 27 54 0 1348417728 13434048 5959664
21:44:12 112100 8192000000 10274091248 10716143616 1255149600 13.286 92 12 24 0 1272849056 15343392 2356064
21:47:07 89108 8192000000 10270261248 10697433088 1329223840 13.960 98 20 37 0 1353780336 20719616 3836880
21:49:42 35603 8192000000 10262301312 10710392832 1354273216 14.186 100 21 43 1 1370114512 10592624 5248672
21:51:18 51372 8192000000 10250645312 10696433664 1309649824 13.783 96 24 48 0 1322428160 7577472 5200864
21:53:20 95213 8192000000 10254478640 10702827520 1334497504 14.008 98 14 28 0 1345645792 8008064 3140224
21:56:09 34813 8192000000 10243542688 10689134592 1315260288 13.834 97 18 37 0 1326890992 7473920 4156784
21:57:47 69458 8192000000 10260644048 10706182144 1350191472 14.149 100 36 70 1 1370177648 12102528 7883648
22:00:01 68191 8192000000 10251011600 10707603456 1350443232 14.151 99 11 22 0 1360281040 7502016 2335792
22:02:12 64717 8192000000 10273085488 10730545152 1360185504 14.239 99 14 28 0 1379918320 16318240 3414576
22:04:38 37453 8192000000 10287992944 10734608384 1367225648 14.302 99 27 54 0 1385262176 12250960 5785568
22:06:28 26821 8192000000 10282083808 10727698432 1373483808 14.358 100 37 74 0 1392460448 10944256 8032384
22:08:00 40853 8192000000 10284231648 10727407616 1334758624 14.010 96 29 58 0 1352252480 11245072 6248784
22:09:46 32171 8192000000 10298369552 10756698112 1391244832 14.517 100 29 58 0 1411069776 13853072 5971872
22:11:18 71770 8192000000 10318179536 10774687744 1402553440 14.618 100 12 24 0 1416855776 11706656 2595680
22:14:15 31915 8192000000 10314524656 10758672384 1378231248 14.401 98 36 73 1 1397315280 11202400 7881632
22:15:48 79631 8192000000 10294873408 10764382208 1358069120 14.220 97 4 8 0 1377297408 18654336 573952
22:18:09 73047 8192000000 10285117216 10740453376 1302600560 13.719 93 37 74 0 1324436032 13393616 8441856
22:20:29 48417 8192000000 10263323936 10727849984 1342505824 14.080 97 10 20 0 1353036560 8345824 2184912
22:23:30 54824 8192000000 10305236432 10759139328 1394348944 14.545 100 12 24 0 1404155696 7629264 2177488
22:25:39 63937 8192000000 10258957952 10717089792 1335088656 14.013 97 11 22 0 1347656896 9926720 2641520
22:27:51 57845 8192000000 10264475104 10716798976 1338016224 14.040 98 22 45 0 1356823360 13989504 4817632
22:29:57 36203 8192000000 10297757344 10743037952 1378347760 14.402 100 31 62 0 1398635504 13283744 7004000
22:31:39 53184 8192000000 10298323056 10752483328 1379988208 14.416 100 17 34 0 1400977632 17160544 3828880
22:33:32 119137 8192000000 10271727840 10735132672 1295648592 13.656 94 9 18 0 1314306224 16570976 2086656
22:36:32 52612 8192000000 10288377504 10755244032 1363158848 14.266 100 34 68 0 1395528880 24706128 7663904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-01-2023 22:40:57 to 06-01-2023 22:41:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 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 06-01-2023 22:41:14 to 06-01-2023 22:41:16.

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          250354
MON$OLDEST_ACTIVE               251226
MON$OLDEST_SNAPSHOT             251226
MON$NEXT_TRANSACTION            251226
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                       2031712
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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Fri Jan  6 22:38:34 2023
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:27: 
--- end of diff output ---

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

06.01.23 22:41:17. 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-06 22:41:20 No severe PSQL-related problems occured
06.01.23 22:41:21. End of report.