Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2022 19: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 15557644 kB
MemAvailable 30458420 kB
Buffers 0 kB
Cached 16145804 kB
SwapCached 1944 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 2022-10-21 19:38:33
DTS_END                         2022-10-21 19:38:33.4750
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/37520
TRN_ID                          244748
ATT_ID                          722
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9498.49 19201 1139819
customer order: creation 181.20 394 21744
customer order: refuse 15.14 307 1817
order to supplier: creation 46.20 1206 5544
order to supplier: removal 3.62 91775 434
invoice (draft): creation 49.05 3242 5886
invoice (draft): removal 53.06 762 6367
invoice accept: apply 42.95 16614 5154
invoice accept: cancel 47.87 781 5744
customer reserve: creation 4013.07 163 481568
customer reserve: removal 4743.88 55 569265
realization accept: apply 88.73 169 10647
realization accept: cancel 79.08 110 9490
payment from customer: creation 62.58 121 7510
payment from customer: removal 5.63 69 676
payment to supplier: creation 50.02 132 6002
payment to supplier: removal 3.55 56 426
service: total inventory turnovers 5.37 948 644
service: total monetary turnovers 6.63 33 796
service: aggregate perf. data 0.84 19566 101
service: refresh index statistics 0.03 247515 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2022 19:39:12 to 21-10-2022 19:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2463
TEST_TIME 2 2580
TEST_TIME 3 3296
TEST_TIME 4 4381
TEST_TIME 5 4439
TEST_TIME 6 3933
TEST_TIME 7 4724
TEST_TIME 8 4981
TEST_TIME 9 5069
TEST_TIME 10 5910
TEST_TIME 11 6194
TEST_TIME 12 6372
TEST_TIME 13 6555
TEST_TIME 14 6463
TEST_TIME 15 6774
TEST_TIME 16 6776
TEST_TIME 17 7105
TEST_TIME 18 7489
TEST_TIME 19 7822
TEST_TIME 20 7820
TEST_TIME 21 7910
TEST_TIME 22 8523
TEST_TIME 23 8462
TEST_TIME 24 8485
TEST_TIME 25 8661
TEST_TIME 26 9120
TEST_TIME 27 9521
TEST_TIME 28 10505
TEST_TIME 29 10525
TEST_TIME 30 10281
TEST_TIME 31 10318
TEST_TIME 32 10402
TEST_TIME 33 10350
TEST_TIME 34 10410
TEST_TIME 35 10349
TEST_TIME 36 10110
TEST_TIME 37 10085
TEST_TIME 38 10016
TEST_TIME 39 9878
TEST_TIME 40 9786
TEST_TIME 41 9836
TEST_TIME 42 9748
TEST_TIME 43 9805
TEST_TIME 44 9778
TEST_TIME 45 9647
TEST_TIME 46 9649
TEST_TIME 47 9750
TEST_TIME 48 9729
TEST_TIME 49 9721
TEST_TIME 50 9772
TEST_TIME 51 9676
TEST_TIME 52 9837
TEST_TIME 53 9865
TEST_TIME 54 9899
TEST_TIME 55 9853
TEST_TIME 56 9920
TEST_TIME 57 9898
TEST_TIME 58 9937
TEST_TIME 59 9907
TEST_TIME 60 9771
TEST_TIME 61 9776
TEST_TIME 62 9747
TEST_TIME 63 9709
TEST_TIME 64 9607
TEST_TIME 65 9615
TEST_TIME 66 9555
TEST_TIME 67 9528
TEST_TIME 68 9510
TEST_TIME 69 9468
TEST_TIME 70 9460
TEST_TIME 71 9493
TEST_TIME 72 9504
TEST_TIME 73 9470
TEST_TIME 74 9524
TEST_TIME 75 9647
TEST_TIME 76 9623
TEST_TIME 77 9632
TEST_TIME 78 9609
TEST_TIME 79 9632
TEST_TIME 80 9565
TEST_TIME 81 9487
TEST_TIME 82 9471
TEST_TIME 83 9438
TEST_TIME 84 9418
TEST_TIME 85 9359
TEST_TIME 86 9369
TEST_TIME 87 9340
TEST_TIME 88 9437
TEST_TIME 89 9437
TEST_TIME 90 9450
TEST_TIME 91 9462
TEST_TIME 92 9488
TEST_TIME 93 9538
TEST_TIME 94 9546
TEST_TIME 95 9555
TEST_TIME 96 9610
TEST_TIME 97 9593
TEST_TIME 98 9601
TEST_TIME 99 9671
TEST_TIME 100 9609
TEST_TIME 101 9542
TEST_TIME 102 9512
TEST_TIME 103 9498
TEST_TIME 104 9502
TEST_TIME 105 9475
TEST_TIME 106 9453
TEST_TIME 107 9467
TEST_TIME 108 9420
TEST_TIME 109 9395
TEST_TIME 110 9389
TEST_TIME 111 9407
TEST_TIME 112 9384
TEST_TIME 113 9372
TEST_TIME 114 9431
TEST_TIME 115 9490
TEST_TIME 116 9460
TEST_TIME 117 9465
TEST_TIME 118 9464
TEST_TIME 119 9507
TEST_TIME 120 9500

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-10-2022 19:39:15 to 21-10-2022 19:39:19.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1584848 1584848 0 0.00 0 25135 6 0 0
doc_list_biud 1584846 1584846 0 0.00 0 62613 25 0 0
sp_add_invoice_to_stock 5284 5154 130 2.46 27 203165 16615 0 130
sp_cancel_adding_invoice 5744 5744 0 0.00 5 55312 782 0 0
sp_cancel_client_order 1817 1817 0 0.00 4 9705 308 0 0
sp_cancel_customer_reserve 569265 569265 0 0.00 0 62615 56 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 2 4995 69 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 2 2729 56 0 0
sp_cancel_supplier_invoice 6370 6367 3 0.04 1 28969 762 0 3
sp_cancel_supplier_order 434 434 0 0.00 5 966861 91776 0 0
sp_cancel_write_off 9490 9490 0 0.00 1 24903 110 0 0
sp_client_order 21744 21744 0 0.00 3 48901 394 0 0
sp_customer_reserve 481568 481568 0 0.00 0 77937 164 0 0
sp_fill_shopping_cart 33169 33169 0 0.00 1 11397 35 0 0
sp_fill_shopping_cart_clo_res 481564 481564 0 0.00 0 13968 13 0 0
sp_get_clo_for_invoice 5148 5148 0 0.00 0 24361 296 0 0
sp_kill_cost_storno 16336 16336 0 0.00 0 16443 51 0 0
sp_kill_qstorno_ret_qs2qd 1154821 1154821 0 0.00 0 62610 30 0 0
sp_kill_qty_storno 608911 608911 0 0.00 0 62613 65 0 0
sp_lock_selected_doc 21729 21729 0 0.00 0 9512 5 0 0
sp_make_cost_storno 29307 29307 0 0.00 0 25125 54 0 0
sp_multiply_rows_for_pdistr 29307 29307 0 0.00 0 16419 26 0 0
sp_multiply_rows_for_qdistr 21744 21744 0 0.00 0 48891 201 0 0
sp_pay_from_customer 7510 7510 0 0.00 4 24410 121 0 0
sp_pay_to_supplier 6002 6002 0 0.00 4 24225 132 0 0
sp_payment_common 13512 13512 0 0.00 1 16144 31 0 0
sp_qd_handle_on_cancel_clo 1817 1817 0 0.00 0 9699 282 0 0
sp_qd_handle_on_invoice_upd_sts 10891 10891 0 0.00 0 26747 293 0 0
sp_qd_handle_on_reserve_upd_sts 10647 10647 0 0.00 0 14593 56 0 0
sp_reserve_write_off 10647 10647 0 0.00 6 25142 169 0 0
sp_supplier_invoice 5886 5886 0 0.00 6 73585 3242 0 0
sp_supplier_order 5544 5544 0 0.00 4 29523 1207 0 0
srv_aggregate_perf_data 101 101 0 0.00 2544 216979 19566 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1884 126348 44172 0 0
srv_find_qd_qs_mism 1089575 1089575 0 0.00 0 13112 6 0 0
srv_make_invnt_saldo 644 644 0 0.00 43 88360 949 0 0
srv_make_money_saldo 796 796 0 0.00 1 8083 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 164443 277699 247515 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5154 5154 0 0.00 0 10326 145 0 0
t$perf-norm:sp_cancel_adding_invoice 461 461 0 0.00 0 9857 87 0 0
t$perf-norm:sp_cancel_client_order 1817 1817 0 0.00 0 8227 27 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 3421 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 3892 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 2049 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 581 12 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 38770 1585 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 6282 14 0 0
t$perf-norm:sp_client_order 21744 21744 0 0.00 0 16431 18 0 0
t$perf-norm:sp_customer_reserve 1099 1099 0 0.00 0 3313 13 0 0
t$perf-norm:sp_pay_from_customer 7510 7510 0 0.00 0 6788 9 0 0
t$perf-norm:sp_pay_to_supplier 6002 6002 0 0.00 0 12754 10 0 0
t$perf-norm:sp_reserve_write_off 10647 10647 0 0.00 0 10101 11 0 0
t$perf-norm:sp_supplier_invoice 5886 5886 0 0.00 0 32565 26 0 0
t$perf-norm:sp_supplier_order 5544 5544 0 0.00 0 36176 21 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 543 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 100 3 0 0
t$perf-norm:srv_make_invnt_saldo 644 644 0 0.00 0 3453 25 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 2389 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 431 109 0 0
v_add_invoice_to_stock 10554 10294 260 2.46 0 3557 14 0 260
v_all_customers 39638 39638 0 0.00 0 91 0 0 0
v_all_suppliers 31738 31738 0 0.00 0 19 1 0 0
v_all_wares 43488 43488 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 922 922 0 0.00 0 2992 13 0 0
v_cancel_client_order 3634 3634 0 0.00 0 2193 5 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 3944 8 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 1930 4 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 212 3 0 0
v_cancel_supplier_invoice 874 868 6 0.68 0 6756 22 0 6
v_cancel_supplier_order 868 868 0 0.00 0 273 4 0 0
v_cancel_write_off 2030 2030 0 0.00 0 9935 20 0 0
v_max_id_clo_ord 5540 5540 0 0.00 0 1024 1 0 0
v_max_id_clo_res 1099 1099 0 0.00 0 3160 18 0 0
v_max_id_ord_sup 5885 5885 0 0.00 0 5167 5 0 0
v_max_non_paid_invoice 1558 1558 0 0.00 0 965 3 0 0
v_max_non_paid_realizn 3992 3992 0 0.00 0 6080 5 0 0
v_min_id_clo_ord 5540 5540 0 0.00 0 9306 10 0 0
v_min_id_clo_res 1099 1099 0 0.00 0 1616 5 0 0
v_min_id_ord_sup 5885 5885 0 0.00 0 5676 7 0 0
v_min_non_paid_invoice 6002 6002 0 0.00 0 16141 21 0 0
v_min_non_paid_realizn 7510 7510 0 0.00 0 14569 28 0 0
v_our_firm 10886 10886 0 0.00 0 175 1 0 0
v_reserve_write_off 21294 21294 0 0.00 0 2960 6 0 0
x_lock_depdocs_on_canc_invoice 11677 11677 0 0.00 0 19175 101 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 11412 294 0 0
x_make_qstorno_1000_1200 5544 5544 0 0.00 1 29150 1132 0 0
x_make_qstorno_1200_2000 5886 5886 0 0.00 3 73571 3114 0 0
x_make_qstorno_2100_3300 481567 481567 0 0.00 0 77936 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2022 19:39:20 to 21-10-2022 19:39:24.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:37 3 8192000000 8801711424 8813842432 7737296 0.094 2 2 3 0 7878384 66144 74944
17:09:38 34315 8192000000 9913404672 10229329920 988757264 10.769 100 83 166 0 1017060016 5705024 22597728
17:11:20 13677 8192000000 9959766496 10285105152 1047583824 11.337 100 81 162 0 1076241088 7795792 20861472
17:12:42 13477 8192000000 9988168624 10321858560 1077737872 11.626 100 85 169 0 1107247024 8678096 20831056
17:14:04 7596 8192000000 10053861440 10404347904 1136770480 12.185 100 86 171 1 1171647376 14822832 20054064
17:15:17 7179 8192000000 10089891264 10450251776 1177307104 12.565 100 81 159 1 1207006192 10908480 18790608
17:16:28 3955 8192000000 10129824080 10499338240 1218197696 12.945 100 80 157 0 1247791296 12234240 17359360
17:17:36 1432 8192000000 10168022592 10548346880 1259080960 13.322 100 79 154 0 1288916000 12983248 16851792
17:18:39 2864 8192000000 10195008432 10584956928 1285404336 13.562 100 70 140 1 1312366160 11837216 15124608
17:19:44 1413 8192000000 10221503984 10606555136 1301796448 13.712 100 94 189 0 1336507664 14145984 20565232
17:20:46 19658 8192000000 10215085040 10610688000 1309490032 13.781 100 62 123 1 1332549536 7655920 15403584
17:22:07 5833 8192000000 10206136256 10613432320 1311820624 13.803 100 21 43 0 1323451648 6584480 5046544
17:23:18 9900 8192000000 10204369888 10618519552 1315609968 13.837 100 19 39 0 1326061744 5855584 4596192
17:24:29 6592 8192000000 10204262720 10622029824 1321413360 13.890 100 18 36 0 1331484672 5747472 4323840
17:25:36 68342 8192000000 10222273568 10641760256 1328091408 13.950 100 34 70 0 1341935952 4865200 8979344
17:27:46 13551 8192000000 10212782544 10633641984 1312577440 13.809 99 16 31 0 1322288096 5458160 4252496
17:29:04 10085 8192000000 10182623872 10597511168 1266905408 13.393 97 16 32 0 1275753376 5197792 3650176
17:30:14 10094 8192000000 10162812384 10566881280 1237689200 13.125 96 16 32 0 1246943152 5574320 3679632
17:31:25 31370 8192000000 10120478176 10526560256 1153135120 12.339 91 18 36 0 1162600016 5392544 4072352
17:32:57 4909 8192000000 10140350128 10543312896 1222001280 12.980 97 20 40 0 1231709568 4891744 4816544
17:34:04 5970 8192000000 10157354752 10561466368 1263449648 13.362 100 17 34 0 1271898800 4764128 3685024
17:35:11 10691 8192000000 10175487024 10583920640 1281693872 13.528 100 22 44 0 1292145536 5368896 5082768
17:36:22 40034 8192000000 10179914336 10595569664 1292068480 13.623 100 48 93 3 1309308144 5483696 11755968
17:38:04 10139 8192000000 10201898944 10619183104 1307722928 13.765 100 18 37 0 1316857152 5357712 3776512
17:39:22 43266 8192000000 10239201760 10655404032 1336088672 14.022 100 30 60 0 1351410576 8683936 6637968
17:41:13 34647 8192000000 10260930336 10678784000 1352586896 14.171 100 37 74 0 1375533584 13793520 9153168
17:43:02 45449 8192000000 10266524752 10697650176 1363455248 14.268 100 30 60 0 1382851584 11842544 7553792
17:45:07 18608 8192000000 10291055696 10718359552 1373460704 14.358 100 47 94 0 1404668176 20536720 10670752
17:47:05 7420 8192000000 10304140192 10727731200 1382023152 14.435 100 48 95 0 1410727712 17873584 10830976
17:48:24 2314 8192000000 10323801008 10744246272 1397677392 14.574 100 58 116 1 1431241808 19205024 14359392
17:49:31 17494 8192000000 10328528256 10759442432 1395963728 14.559 99 40 81 0 1426801200 20207616 10629856
17:50:57 3074 8192000000 10338656576 10762424320 1393387712 14.536 99 56 111 0 1429210784 22241440 13581632
17:52:06 5131 8192000000 10333001616 10763083776 1409841456 14.683 100 55 110 0 1444865680 21281984 13742240
17:53:29 2446 8192000000 10361045808 10781917184 1416027456 14.737 100 69 138 0 1465972304 32414544 17530304
17:54:39 2821 8192000000 10347242160 10767036416 1402221584 14.615 100 68 135 1 1451857536 32104144 17531808
17:55:47 5467 8192000000 10342717808 10765148160 1398041936 14.578 100 52 100 0 1446080048 34838912 13199200
17:56:57 2413 8192000000 10356830240 10771271680 1399879280 14.594 100 78 154 1 1456275264 37574848 18821136
17:58:03 1883 8192000000 10323188048 10741669888 1378457968 14.403 100 53 105 1 1427304304 35604144 13242192
17:59:12 5854 8192000000 10318991888 10736943104 1360260432 14.240 99 43 86 2 1417375872 45457216 11658224
18:00:32 6939 8192000000 10345100256 10770464768 1384848752 14.460 100 48 96 0 1453489056 55602208 13038096
18:01:48 21428 8192000000 10319332864 10738728960 1342023136 14.076 97 42 82 0 1401362512 47928304 11411072
18:03:30 9161 8192000000 10331666736 10767077376 1373757296 14.361 100 30 60 0 1440261408 58013648 8490464
18:04:49 28785 8192000000 10293550048 10732888064 1339585296 14.054 98 19 38 0 1388953968 43938480 5430192
18:06:18 35249 8192000000 10284663824 10742153216 1367533024 14.305 100 27 54 0 1407966944 33414160 7019760
18:07:54 18285 8192000000 10295033168 10727063552 1360758480 14.244 99 42 83 1 1382639936 12854256 9027200
18:09:25 42956 8192000000 10292331024 10740944896 1359677504 14.234 99 15 28 1 1381799424 18912672 3209248
18:11:14 90852 8192000000 10274392528 10714456064 1257047984 13.303 91 14 27 1 1274009360 13814432 3146944
18:13:47 62263 8192000000 10281991248 10718216192 1354087440 14.184 99 19 38 1 1368989408 10795328 4106640
18:15:54 105530 8192000000 10296045200 10730819584 1341528192 14.071 97 47 91 2 1364429600 13066656 9834752
18:18:43 51646 8192000000 10311013456 10757865472 1388283360 14.491 100 34 67 1 1415911296 19358704 8269232
18:20:47 67314 8192000000 10309300320 10768220160 1398310656 14.580 99 21 42 0 1422541072 19046784 5183632
18:22:55 60289 8192000000 10324847152 10782154752 1405154640 14.641 100 12 24 0 1430204816 22196736 2853440
18:25:31 42887 8192000000 10302522752 10777583616 1384767728 14.459 99 4 8 0 1397999184 12516352 715104
18:27:48 12259 8192000000 10331955248 10774880256 1410796288 14.691 100 50 95 3 1429601584 8932336 9872960
18:29:02 32058 8192000000 10314391696 10771697664 1402425792 14.617 99 26 52 0 1418859296 10114992 6318512
18:30:35 108940 8192000000 10299865120 10769788928 1359627760 14.234 97 9 18 0 1373296176 11916208 1752208
18:33:25 46128 8192000000 10318101136 10772062208 1400722528 14.601 100 25 50 0 1421065888 14949520 5393840
18:35:17 71897 8192000000 10311050576 10763030528 1380554656 14.422 99 28 56 1 1397157376 10391168 6211552
18:37:41 48502 8192000000 10282035856 10723049472 1358398320 14.223 98 33 66 0 1378157312 12737040 7021952
18:40:10 24803 8192000000 10283745744 10741465088 1378272512 14.401 100 22 42 0 1391396416 8808976 4314928
18:41:35 40367 8192000000 10253501952 10705866752 1345432736 14.106 98 12 24 0 1355208176 7057520 2717920
18:43:46 51673 8192000000 10257267152 10721570816 1346617008 14.117 99 9 18 0 1356664784 8156336 1891440
18:45:42 93340 8192000000 10254968992 10703257600 1299315936 13.689 95 10 20 0 1310077200 8819040 1942224
18:48:16 32487 8192000000 10259926240 10695942144 1312355312 13.807 97 43 84 0 1332890768 11246960 9288496
18:49:56 50190 8192000000 10236231120 10677792768 1265958160 13.385 94 23 46 0 1281702592 10614320 5130112
18:51:51 14997 8192000000 10267307856 10690576384 1330962528 13.976 99 53 106 0 1353611856 11061728 11587600
18:53:06 64999 8192000000 10263720416 10726625280 1343619152 14.090 99 21 42 0 1367559504 18859632 5080720
18:55:11 87969 8192000000 10262049680 10708512768 1291913968 13.622 94 12 24 0 1302564128 8155632 2494528
18:57:40 48225 8192000000 10287301360 10735009792 1372425616 14.349 100 34 68 1 1394456000 14890080 7140304
18:59:44 76382 8192000000 10275319808 10739179520 1368464768 14.313 100 26 53 0 1387741216 12961392 6315056
19:02:28 68420 8192000000 10264345728 10730012672 1367644080 14.306 100 7 12 1 1378984592 9913104 1427408
19:05:30 59625 8192000000 10284344416 10739056640 1342215408 14.077 97 14 28 0 1361958112 16490592 3252112
19:07:30 41064 8192000000 10281594992 10747899904 1349678192 14.145 98 15 30 0 1375006752 21758704 3569856
19:09:26 99463 8192000000 10291025040 10749468672 1359074928 14.229 98 14 28 0 1371378528 9653920 2649680
19:12:06 62630 8192000000 10293198256 10743627776 1360918432 14.246 98 15 30 0 1375347808 11140352 3289024
19:14:12 94740 8192000000 10304064032 10772987904 1369858112 14.326 98 14 28 0 1386460592 13840496 2761984
19:16:47 47022 8192000000 10310825680 10773938176 1399830864 14.593 100 12 24 0 1411074160 8752704 2490592
19:18:36 126348 8192000000 10274964256 10738888704 1331141456 13.977 95 20 40 1 1343575968 8327376 4107136
19:21:46 55199 8192000000 10269454816 10731401216 1352001856 14.165 98 17 34 0 1363845360 7964896 3878608
19:24:05 37980 8192000000 10283999520 10739376128 1370378048 14.330 99 20 38 1 1384309312 8440464 5490800
19:25:47 76046 8192000000 10278420144 10739740672 1362712864 14.262 99 13 26 0 1372586272 6794544 3078864
19:28:03 63949 8192000000 10281306448 10735112192 1356396672 14.205 98 15 30 0 1370168880 9879056 3893152
19:30:17 53026 8192000000 10305908032 10757656576 1360467584 14.242 98 20 38 1 1379437600 13960848 5009168
19:32:10 53317 8192000000 10280948752 10750967808 1344720384 14.100 97 16 30 0 1366716000 18287808 3707808
19:34:07 42181 8192000000 10288885040 10727714816 1308922672 13.776 95 24 46 0 1323993760 9645040 5426048
19:35:50 36981 8192000000 10252973936 10697707520 1292716448 13.629 95 29 58 0 1309842592 10860624 6265520
19:37:30 17679 8192000000 10293457888 10731708416 1370312464 14.330 100 53 106 0 1397465680 15439360 11713856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-10-2022 19:39:26 to 21-10-2022 19:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2022 19:39:42 to 21-10-2022 19:39: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          240984
MON$OLDEST_ACTIVE               244886
MON$OLDEST_SNAPSHOT             244886
MON$NEXT_TRANSACTION            244886
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                       2016640
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.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 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 ---
:19: 
:20: mscs-test-161.budzdorov.ru    Fri Oct 21 19:38:37 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Fri Oct 21 19:38:39 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Fri Oct 21 19:38:43 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
--- end of diff output ---

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

21.10.22 19:39: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
2022-10-21 19:39:48 No severe PSQL-related problems occured
21.10.22 19:39:49. End of report.