Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.03.2023 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15209472 kB
MemAvailable 30464816 kB
Buffers 0 kB
Cached 16518436 kB
SwapCached 3032 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-03-09 22:38:45
DTS_END                         2023-03-09 22:38:45.2380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58308
TRN_ID                          240413
ATT_ID                          642
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: 9413.56 24075 1129627
customer order: creation 170.76 343 20491
customer order: refuse 15.17 367 1820
order to supplier: creation 45.78 1227 5494
order to supplier: removal 3.60 91855 432
invoice (draft): creation 46.43 3140 5571
invoice (draft): removal 56.17 666 6740
invoice accept: apply 41.84 17063 5021
invoice accept: cancel 50.17 699 6020
customer reserve: creation 3814.68 171 457761
customer reserve: removal 4864.00 53 583680
realization accept: apply 83.48 168 10017
realization accept: cancel 90.33 107 10840
payment from customer: creation 59.87 96 7184
payment from customer: removal 5.63 91 675
payment to supplier: creation 50.04 120 6005
payment to supplier: removal 3.23 59 388
service: total inventory turnovers 4.96 1123 595
service: total monetary turnovers 6.58 26 790
service: aggregate perf. data 0.83 23266 99
service: refresh index statistics 0.03 340859 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-03-2023 22:40:11 to 09-03-2023 22:40:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5070
TEST_TIME 2 4476
TEST_TIME 3 4921
TEST_TIME 4 5468
TEST_TIME 5 5187
TEST_TIME 6 5631
TEST_TIME 7 5516
TEST_TIME 8 5606
TEST_TIME 9 5522
TEST_TIME 10 6062
TEST_TIME 11 6181
TEST_TIME 12 6263
TEST_TIME 13 6403
TEST_TIME 14 6580
TEST_TIME 15 6674
TEST_TIME 16 7152
TEST_TIME 17 7206
TEST_TIME 18 7302
TEST_TIME 19 7825
TEST_TIME 20 8268
TEST_TIME 21 8718
TEST_TIME 22 9054
TEST_TIME 23 9419
TEST_TIME 24 9503
TEST_TIME 25 9467
TEST_TIME 26 9548
TEST_TIME 27 10082
TEST_TIME 28 10626
TEST_TIME 29 10532
TEST_TIME 30 10513
TEST_TIME 31 10362
TEST_TIME 32 10503
TEST_TIME 33 10272
TEST_TIME 34 10004
TEST_TIME 35 9976
TEST_TIME 36 9800
TEST_TIME 37 9795
TEST_TIME 38 9746
TEST_TIME 39 9759
TEST_TIME 40 9629
TEST_TIME 41 9589
TEST_TIME 42 9692
TEST_TIME 43 9628
TEST_TIME 44 9646
TEST_TIME 45 9708
TEST_TIME 46 9675
TEST_TIME 47 9619
TEST_TIME 48 9584
TEST_TIME 49 9533
TEST_TIME 50 9622
TEST_TIME 51 9695
TEST_TIME 52 9694
TEST_TIME 53 9669
TEST_TIME 54 9581
TEST_TIME 55 9663
TEST_TIME 56 9780
TEST_TIME 57 9815
TEST_TIME 58 9813
TEST_TIME 59 9959
TEST_TIME 60 9880
TEST_TIME 61 9762
TEST_TIME 62 9887
TEST_TIME 63 9759
TEST_TIME 64 9705
TEST_TIME 65 9606
TEST_TIME 66 9662
TEST_TIME 67 9604
TEST_TIME 68 9616
TEST_TIME 69 9570
TEST_TIME 70 9583
TEST_TIME 71 9639
TEST_TIME 72 9577
TEST_TIME 73 9555
TEST_TIME 74 9594
TEST_TIME 75 9696
TEST_TIME 76 9618
TEST_TIME 77 9676
TEST_TIME 78 9783
TEST_TIME 79 9764
TEST_TIME 80 9908
TEST_TIME 81 9803
TEST_TIME 82 9716
TEST_TIME 83 9670
TEST_TIME 84 9585
TEST_TIME 85 9624
TEST_TIME 86 9563
TEST_TIME 87 9532
TEST_TIME 88 9556
TEST_TIME 89 9485
TEST_TIME 90 9554
TEST_TIME 91 9513
TEST_TIME 92 9510
TEST_TIME 93 9512
TEST_TIME 94 9558
TEST_TIME 95 9550
TEST_TIME 96 9543
TEST_TIME 97 9566
TEST_TIME 98 9610
TEST_TIME 99 9600
TEST_TIME 100 9564
TEST_TIME 101 9667
TEST_TIME 102 9705
TEST_TIME 103 9674
TEST_TIME 104 9702
TEST_TIME 105 9704
TEST_TIME 106 9728
TEST_TIME 107 9733
TEST_TIME 108 9683
TEST_TIME 109 9623
TEST_TIME 110 9643
TEST_TIME 111 9596
TEST_TIME 112 9554
TEST_TIME 113 9522
TEST_TIME 114 9505
TEST_TIME 115 9537
TEST_TIME 116 9480
TEST_TIME 117 9439
TEST_TIME 118 9427
TEST_TIME 119 9444
TEST_TIME 120 9417

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-03-2023 22:40:15 to 09-03-2023 22:40:18.

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 1554360 1554360 0 0.00 0 27008 6 0 0
doc_list_biud 1554360 1554360 0 0.00 0 21334 25 0 0
sp_add_invoice_to_stock 5125 5021 104 2.02 30 223003 17064 0 104
sp_cancel_adding_invoice 6020 6020 0 0.00 3 45030 700 0 0
sp_cancel_client_order 1820 1820 0 0.00 4 18610 367 0 0
sp_cancel_customer_reserve 583680 583680 0 0.00 0 19829 54 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 2 4776 91 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 2 1794 60 0 0
sp_cancel_supplier_invoice 6744 6740 4 0.05 1 30465 667 0 4
sp_cancel_supplier_order 432 432 0 0.00 4 1047487 91856 0 0
sp_cancel_write_off 10840 10840 0 0.00 1 19306 107 0 0
sp_client_order 20491 20491 0 0.00 3 12996 343 0 0
sp_customer_reserve 457761 457761 0 0.00 0 79919 171 0 0
sp_fill_shopping_cart 31553 31553 0 0.00 1 27764 37 0 0
sp_fill_shopping_cart_clo_res 457752 457752 0 0.00 0 16927 11 0 0
sp_get_clo_for_invoice 5008 5008 0 0.00 0 23204 276 0 0
sp_kill_cost_storno 17923 17923 0 0.00 0 7559 50 0 0
sp_kill_qstorno_ret_qs2qd 1185372 1185372 0 0.00 0 19295 29 0 0
sp_kill_qty_storno 624556 624556 0 0.00 0 21333 63 0 0
sp_lock_selected_doc 20901 20901 0 0.00 0 8184 7 0 0
sp_make_cost_storno 28213 28213 0 0.00 0 13803 50 0 0
sp_multiply_rows_for_pdistr 28213 28213 0 0.00 0 7337 27 0 0
sp_multiply_rows_for_qdistr 20491 20491 0 0.00 0 9490 159 0 0
sp_pay_from_customer 7184 7184 0 0.00 4 12693 97 0 0
sp_pay_to_supplier 6005 6005 0 0.00 4 13812 121 0 0
sp_payment_common 13189 13189 0 0.00 1 6446 20 0 0
sp_qd_handle_on_cancel_clo 1820 1820 0 0.00 0 18600 325 0 0
sp_qd_handle_on_invoice_upd_sts 11027 11027 0 0.00 0 21333 301 0 0
sp_qd_handle_on_reserve_upd_sts 10017 10017 0 0.00 0 16476 49 0 0
sp_reserve_write_off 10017 10017 0 0.00 6 17376 168 0 0
sp_supplier_invoice 5571 5571 0 0.00 6 73532 3141 0 0
sp_supplier_order 5494 5494 0 0.00 4 26620 1227 0 0
srv_aggregate_perf_data 99 99 0 0.00 2935 548840 23267 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2271 141010 44404 0 0
srv_find_qd_qs_mism 1082335 1082335 0 0.00 0 27007 6 0 0
srv_make_invnt_saldo 595 595 0 0.00 37 54261 1123 0 0
srv_make_money_saldo 790 790 0 0.00 1 796 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 178367 566327 340860 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5021 5021 0 0.00 0 11572 196 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 17363 137 0 0
t$perf-norm:sp_cancel_client_order 1820 1820 0 0.00 0 8291 19 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 14235 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 7291 34 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 1050 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 1552 18 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 37517 1656 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 2321 8 0 0
t$perf-norm:sp_client_order 20491 20491 0 0.00 0 16094 19 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 6035 14 0 0
t$perf-norm:sp_pay_from_customer 7184 7184 0 0.00 0 13735 16 0 0
t$perf-norm:sp_pay_to_supplier 6005 6005 0 0.00 0 2868 7 0 0
t$perf-norm:sp_reserve_write_off 10017 10017 0 0.00 0 17540 14 0 0
t$perf-norm:sp_supplier_invoice 5571 5571 0 0.00 0 6996 23 0 0
t$perf-norm:sp_supplier_order 5494 5494 0 0.00 0 6960 15 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 632 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 160 4 0 0
t$perf-norm:srv_make_invnt_saldo 595 595 0 0.00 0 2280 23 0 0
t$perf-norm:srv_make_money_saldo 790 790 0 0.00 0 3697 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10218 10010 208 2.03 0 11108 20 0 208
v_all_customers 37116 37116 0 0.00 0 25 1 0 0
v_all_suppliers 30766 30766 0 0.00 0 23 1 0 0
v_all_wares 40982 40982 0 0.00 0 99 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 3058 11 0 0
v_cancel_client_order 3640 3640 0 0.00 0 4658 9 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 4718 14 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 4655 10 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 1784 8 0 0
v_cancel_supplier_invoice 860 852 8 0.93 0 3775 23 0 8
v_cancel_supplier_order 864 864 0 0.00 0 3116 8 0 0
v_cancel_write_off 2058 2058 0 0.00 0 1978 13 0 0
v_max_id_clo_ord 5494 5494 0 0.00 0 2863 2 0 0
v_max_id_clo_res 974 974 0 0.00 0 5238 15 0 0
v_max_id_ord_sup 5568 5568 0 0.00 0 1743 3 0 0
v_max_non_paid_invoice 1684 1684 0 0.00 0 1163 3 0 0
v_max_non_paid_realizn 3946 3946 0 0.00 0 2331 5 0 0
v_min_id_clo_ord 5494 5494 0 0.00 0 3019 6 0 0
v_min_id_clo_res 974 974 0 0.00 0 3816 6 0 0
v_min_id_ord_sup 5568 5568 0 0.00 0 3291 3 0 0
v_min_non_paid_invoice 6005 6005 0 0.00 0 4987 13 0 0
v_min_non_paid_realizn 7184 7184 0 0.00 0 4605 13 0 0
v_our_firm 10342 10342 0 0.00 0 16 1 0 0
v_reserve_write_off 20034 20034 0 0.00 0 6357 8 0 0
x_lock_depdocs_on_canc_invoice 12334 12334 0 0.00 0 22060 109 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 23851 363 0 0
x_make_qstorno_1000_1200 5494 5494 0 0.00 1 26571 1154 0 0
x_make_qstorno_1200_2000 5571 5571 0 0.00 3 72859 3010 0 0
x_make_qstorno_2100_3300 457761 457761 0 0.00 0 79918 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-03-2023 22:40:19 to 09-03-2023 22:40:23.

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:49 12 8192000000 8818490448 8836329472 21761216 0.264 6 5 6 0 22032128 99472 171440
20:09:50 29980 8192000000 9897419808 10206580736 980639296 10.690 100 89 178 1 1009705520 5572304 23493920
20:11:39 18985 8192000000 9959654336 10285744128 1043446896 11.298 100 80 160 0 1071424096 7650208 20326992
20:13:04 13110 8192000000 10009462032 10349543424 1092886464 11.770 100 78 154 0 1121029808 9173344 18970000
20:14:22 10478 8192000000 10058542976 10410708992 1146547056 12.277 100 87 174 0 1179027552 11627856 20852640
20:15:36 5420 8192000000 10098671872 10459697152 1184182992 12.629 100 87 174 0 1218007504 13494912 20329600
20:16:42 2684 8192000000 10130035936 10497355776 1218258256 12.946 100 90 177 1 1251507600 12830960 20418384
20:17:47 3140 8192000000 10153872800 10534739968 1245855872 13.200 100 66 130 0 1272779680 12172064 14751744
20:18:52 2739 8192000000 10184917216 10572410880 1282845216 13.539 100 74 147 1 1311450448 13076288 15528944
20:19:57 1067 8192000000 10222349360 10611941376 1311324704 13.798 100 84 169 0 1342018720 12339632 18354384
20:20:59 7195 8192000000 10207066784 10621407232 1320785984 13.884 100 21 40 0 1331619072 6441552 4391536
20:22:09 5703 8192000000 10218533472 10634047488 1333340864 13.997 100 21 38 0 1343639968 5563872 4735232
20:23:18 12077 8192000000 10223462176 10641297408 1335238672 14.014 100 19 38 0 1345070320 5445920 4385728
20:24:31 40015 8192000000 10250798992 10660048896 1340390416 14.061 100 67 134 1 1362419760 6022176 16007168
20:26:12 4225 8192000000 10240970880 10664361984 1344584720 14.099 100 23 45 1 1359809024 10018336 5205968
20:27:18 11591 8192000000 10219684032 10640670720 1293472640 13.636 96 20 40 0 1303811104 5507664 4830800
20:28:30 50447 8192000000 10213258048 10634948608 1313729248 13.820 98 47 92 0 1330049520 5152512 11167760
20:30:27 2911 8192000000 10194697680 10609221632 1303816416 13.730 100 30 60 0 1316845632 5847424 7181792
20:31:35 9211 8192000000 10136016448 10535804928 1186068400 12.647 94 21 42 0 1196348032 5402352 4877280
20:32:47 5265 8192000000 10145315152 10548125696 1247032208 13.211 99 26 52 0 1258323968 5787456 5504304
20:33:54 23971 8192000000 10171134336 10571698176 1251988688 13.256 98 27 53 1 1263245552 5514032 5742832
20:35:20 3546 8192000000 10191287664 10599677952 1294383968 13.644 100 33 68 1 1308181760 6368832 7428960
20:36:26 6854 8192000000 10201856960 10614616064 1307109840 13.760 100 34 66 0 1320582976 5286464 8186672
20:37:37 82040 8192000000 10222396752 10644054016 1326942112 13.940 100 18 36 0 1336333696 5471648 3919936
20:40:16 2271 8192000000 10274283296 10684190720 1363384144 14.268 100 57 111 1 1385611824 9576336 12651344
20:41:45 12158 8192000000 10286248832 10705035264 1375051408 14.372 100 50 100 0 1403253632 17355472 10846752
20:43:02 15151 8192000000 10295657520 10717253632 1385106928 14.462 100 45 88 0 1407744352 11504096 11133328
20:44:23 21019 8192000000 10308677840 10737152000 1393441472 14.537 100 35 68 0 1418965632 17185296 8338864
20:46:01 6253 8192000000 10323409680 10746970112 1393249584 14.535 99 47 94 0 1428157296 23289568 11618144
20:47:16 20284 8192000000 10331357360 10762309632 1400019920 14.595 99 37 74 0 1433467296 24019968 9427408
20:48:41 3605 8192000000 10340056176 10759970816 1395963792 14.559 99 60 118 1 1438888432 28828496 14096144
20:49:49 3669 8192000000 10350180096 10769403904 1415803312 14.735 100 64 127 1 1459869632 28743920 15322400
20:50:58 2679 8192000000 10369663136 10782584832 1420743280 14.779 100 85 162 0 1472420560 32449216 19228064
20:52:06 10895 8192000000 10342409696 10757537792 1399632192 14.592 100 60 120 0 1449215936 34810320 14773424
20:53:22 3643 8192000000 10343128176 10754629632 1391764736 14.522 100 70 140 1 1446576240 37520320 17291184
20:54:31 2449 8192000000 10331040800 10734145536 1376791648 14.388 100 78 153 1 1430441808 34781136 18869024
20:55:48 5698 8192000000 10347970096 10762297344 1395384560 14.554 100 58 113 0 1449867664 40290272 14192832
20:57:03 2448 8192000000 10346003840 10755510272 1387131456 14.480 100 72 141 1 1456848912 50555152 19162304
20:58:08 15453 8192000000 10318800880 10755977216 1384451488 14.456 100 39 76 2 1436363296 41721136 10190672
20:59:26 7955 8192000000 10337431216 10756751360 1377444288 14.394 100 48 95 1 1436458320 47016640 11997392
21:00:39 6064 8192000000 10333791424 10759835648 1382543488 14.439 100 45 88 0 1440645792 46493776 11608528
21:01:50 10804 8192000000 10338968592 10772090880 1398813328 14.584 100 26 49 0 1439131904 33430096 6888480
21:03:05 14187 8192000000 10312626432 10748620800 1378931152 14.407 100 28 56 0 1418690912 32136496 7623264
21:04:26 26926 8192000000 10284381008 10719240192 1342243136 14.078 99 15 30 0 1382562016 35360352 4958528
21:05:55 70240 8192000000 10261934352 10705055744 1328124192 13.950 98 6 12 1 1355639488 26160288 1355008
21:08:14 85902 8192000000 10309184656 10741288960 1372208944 14.347 99 29 58 1 1387362224 8697968 6455312
21:10:49 141010 8192000000 10292898864 10734092288 1357647168 14.216 98 32 60 0 1372696016 8100400 6948448
21:14:19 22545 8192000000 10286571184 10737811456 1385328144 14.464 100 39 75 0 1399870800 6081504 8461152
21:15:43 17806 8192000000 10290705792 10747609088 1387018816 14.479 100 23 46 0 1402587856 10233008 5336032
21:17:05 10069 8192000000 10311311760 10769403904 1397730304 14.575 100 31 61 0 1420336896 14370000 8236592
21:18:16 65944 8192000000 10321773824 10777874432 1391233808 14.517 99 16 32 0 1415069408 19505824 4329776
21:20:36 117633 8192000000 10321230704 10775851008 1370517408 14.332 98 14 28 0 1393517968 19368640 3631920
21:23:41 31228 8192000000 10303479456 10773291008 1396455968 14.563 100 20 38 1 1414486624 13738448 4292208
21:25:23 28631 8192000000 10309786288 10765164544 1401883248 14.612 100 24 48 0 1416284352 8984176 5416928
21:27:02 21093 8192000000 10313393696 10758156288 1377973200 14.398 98 47 94 0 1398937168 9927840 11036128
21:28:30 45276 8192000000 10295564112 10752733184 1379116960 14.409 99 16 32 1 1395211248 12479232 3615056
21:30:25 58776 8192000000 10308877344 10754576384 1379148352 14.409 99 30 60 0 1398048960 12403840 6496768
21:32:25 22765 8192000000 10299156448 10744123392 1363953664 14.273 98 36 70 1 1384320304 11965328 8401312
21:33:54 59787 8192000000 10299015600 10764869632 1386634480 14.476 100 9 18 0 1407697696 18871008 2192208
21:35:54 58933 8192000000 10296949776 10754224128 1355797152 14.200 98 14 28 1 1375383808 16443104 3143552
21:37:54 120896 8192000000 10295547184 10745892864 1330449744 13.971 96 18 36 0 1354442624 19635984 4356896
21:41:13 37772 8192000000 10266134560 10723876864 1335882752 14.020 97 17 35 0 1357411648 17618128 3910768
21:42:54 40078 8192000000 10286922832 10746429440 1371781248 14.343 99 10 20 0 1391599872 17857760 1960864
21:44:36 25503 8192000000 10263989104 10732433408 1351930288 14.165 99 7 14 0 1370275136 16952160 1392688
21:46:11 86008 8192000000 10283642656 10741764096 1371788288 14.343 100 12 24 0 1388599712 14259488 2551936
21:48:39 60945 8192000000 10268850944 10721202176 1310622544 13.792 95 21 42 0 1324791456 9507952 4660960
21:50:40 31948 8192000000 10271238640 10710900736 1353882704 14.182 100 38 76 0 1376118256 14081440 8154112
21:52:24 79455 8192000000 10270123680 10738589696 1320979104 13.886 96 27 54 0 1341311328 14518304 5813920
21:54:45 17465 8192000000 10288005920 10723848192 1342048336 14.076 98 40 80 0 1361199888 10235312 8916240
21:56:02 85209 8192000000 10285525056 10732605440 1314597168 13.828 96 16 32 0 1341230240 22948880 3684192
21:58:32 126150 8192000000 10268363232 10721071104 1353707568 14.181 99 8 16 0 1375668528 20247712 1713248
22:01:51 52511 8192000000 10296011872 10747789312 1383843056 14.451 100 27 54 2 1404981376 14651232 6487088
22:03:45 50000 8192000000 10284193088 10751004672 1371836128 14.343 99 11 22 0 1387814544 13659952 2318464
22:05:40 60634 8192000000 10297293488 10757132288 1358311664 14.222 98 15 31 0 1373186256 11356512 3518080
22:07:46 80564 8192000000 10276588896 10751004672 1385523920 14.466 100 16 32 0 1401872256 12443344 3904992
22:10:07 64488 8192000000 10290278592 10759602176 1385502208 14.466 99 12 24 0 1398305984 9983520 2820256
22:12:32 91839 8192000000 10294165792 10765033472 1373104432 14.355 98 11 22 0 1392108736 16527808 2476496
22:15:04 67092 8192000000 10317218688 10780078080 1394428032 14.545 99 18 36 0 1410953360 12278944 4246384
22:17:12 61060 8192000000 10328680064 10789400576 1372246608 14.347 97 24 48 0 1396629968 18903536 5479824
22:19:17 60983 8192000000 10307898416 10790113280 1396879360 14.567 100 9 18 1 1419698624 20690384 2128880
22:21:19 15586 8192000000 10332290944 10772922368 1394558144 14.547 99 43 80 0 1412108192 9020704 8529344
22:22:36 34985 8192000000 10311937424 10767015936 1352557696 14.170 96 31 62 0 1372757568 13550640 6649232
22:24:11 29669 8192000000 10294119184 10741886976 1351414864 14.160 98 26 52 0 1366969152 10090144 5464144
22:25:43 67351 8192000000 10290362448 10757513216 1374642240 14.369 99 23 46 0 1389666960 10082592 4942128
22:28:21 89843 8192000000 10297088608 10766413824 1367052304 14.301 98 13 26 0 1383109296 13774304 2282688
22:31:00 68641 8192000000 10272783952 10728927232 1355616288 14.198 98 8 16 0 1369613536 12188432 1808816
22:33:28 71479 8192000000 10270169264 10734010368 1350045808 14.148 98 15 30 1 1366571952 13016944 3509200
22:35:39 52802 8192000000 10271441184 10733350912 1350549200 14.152 98 15 30 0 1364177824 10152544 3476080
22:37:38 66330 8192000000 10275635696 10741100544 1354466304 14.188 99 12 24 0 1370500672 13415136 2619232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-03-2023 22:40:25 to 09-03-2023 22:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240719
MON$OLDEST_ACTIVE               240720
MON$OLDEST_SNAPSHOT             240720
MON$NEXT_TRANSACTION            240720
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                       2039904
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
--- end of diff output ---

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

09.03.23 22:40:45. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-03-09 22:40:47 No severe PSQL-related problems occured
09.03.23 22:40:49. End of report.