Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.05.2022 19: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 16243676 kB
MemAvailable 31483308 kB
Buffers 0 kB
Cached 15487236 kB
SwapCached 2644 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.10
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-05-14 19:38:17
DTS_END                         2022-05-14 19:38:17.5050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58482
TRN_ID                          246033
ATT_ID                          706
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: 9602.18 20864 1152262
customer order: creation 179.92 331 21590
customer order: refuse 15.77 363 1892
order to supplier: creation 47.69 1145 5723
order to supplier: removal 3.53 104002 424
invoice (draft): creation 46.90 3301 5628
invoice (draft): removal 53.89 773 6467
invoice accept: apply 42.88 16211 5146
invoice accept: cancel 49.48 890 5937
customer reserve: creation 3978.33 159 477399
customer reserve: removal 4874.34 60 584921
realization accept: apply 86.47 190 10376
realization accept: cancel 88.16 106 10579
payment from customer: creation 61.23 112 7348
payment from customer: removal 5.90 93 708
payment to supplier: creation 50.65 128 6078
payment to supplier: removal 3.87 69 464
service: total inventory turnovers 5.12 1121 614
service: total monetary turnovers 7.12 29 854
service: aggregate perf. data 0.92 18595 110
service: refresh index statistics 0.03 269600 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-05-2022 19:39:54 to 14-05-2022 19:39:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7594
TEST_TIME 2 7089
TEST_TIME 3 6463
TEST_TIME 4 6568
TEST_TIME 5 6749
TEST_TIME 6 6663
TEST_TIME 7 7148
TEST_TIME 8 7223
TEST_TIME 9 7228
TEST_TIME 10 7564
TEST_TIME 11 7468
TEST_TIME 12 7477
TEST_TIME 13 7285
TEST_TIME 14 7167
TEST_TIME 15 7207
TEST_TIME 16 7135
TEST_TIME 17 7078
TEST_TIME 18 7268
TEST_TIME 19 7559
TEST_TIME 20 7707
TEST_TIME 21 8193
TEST_TIME 22 8312
TEST_TIME 23 8776
TEST_TIME 24 9088
TEST_TIME 25 9276
TEST_TIME 26 9488
TEST_TIME 27 9715
TEST_TIME 28 9630
TEST_TIME 29 9840
TEST_TIME 30 10391
TEST_TIME 31 11184
TEST_TIME 32 11035
TEST_TIME 33 11029
TEST_TIME 34 10988
TEST_TIME 35 11036
TEST_TIME 36 10980
TEST_TIME 37 11157
TEST_TIME 38 11120
TEST_TIME 39 10919
TEST_TIME 40 10935
TEST_TIME 41 10744
TEST_TIME 42 10519
TEST_TIME 43 10599
TEST_TIME 44 10653
TEST_TIME 45 10666
TEST_TIME 46 10479
TEST_TIME 47 10448
TEST_TIME 48 10425
TEST_TIME 49 10336
TEST_TIME 50 10379
TEST_TIME 51 10480
TEST_TIME 52 10445
TEST_TIME 53 10350
TEST_TIME 54 10268
TEST_TIME 55 10278
TEST_TIME 56 10282
TEST_TIME 57 10196
TEST_TIME 58 10219
TEST_TIME 59 10114
TEST_TIME 60 10002
TEST_TIME 61 9952
TEST_TIME 62 9945
TEST_TIME 63 9972
TEST_TIME 64 9970
TEST_TIME 65 10007
TEST_TIME 66 9998
TEST_TIME 67 10049
TEST_TIME 68 10066
TEST_TIME 69 10009
TEST_TIME 70 10081
TEST_TIME 71 10121
TEST_TIME 72 10137
TEST_TIME 73 10103
TEST_TIME 74 10119
TEST_TIME 75 10206
TEST_TIME 76 10195
TEST_TIME 77 10072
TEST_TIME 78 10045
TEST_TIME 79 9996
TEST_TIME 80 9979
TEST_TIME 81 9934
TEST_TIME 82 9879
TEST_TIME 83 9848
TEST_TIME 84 9834
TEST_TIME 85 9765
TEST_TIME 86 9764
TEST_TIME 87 9867
TEST_TIME 88 9806
TEST_TIME 89 9853
TEST_TIME 90 9849
TEST_TIME 91 9883
TEST_TIME 92 9949
TEST_TIME 93 9927
TEST_TIME 94 9976
TEST_TIME 95 9982
TEST_TIME 96 9941
TEST_TIME 97 10062
TEST_TIME 98 10047
TEST_TIME 99 10068
TEST_TIME 100 10144
TEST_TIME 101 10111
TEST_TIME 102 10031
TEST_TIME 103 9994
TEST_TIME 104 10026
TEST_TIME 105 9998
TEST_TIME 106 9965
TEST_TIME 107 9916
TEST_TIME 108 9907
TEST_TIME 109 9860
TEST_TIME 110 9806
TEST_TIME 111 9820
TEST_TIME 112 9775
TEST_TIME 113 9790
TEST_TIME 114 9755
TEST_TIME 115 9728
TEST_TIME 116 9706
TEST_TIME 117 9699
TEST_TIME 118 9663
TEST_TIME 119 9631
TEST_TIME 120 9612

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-05-2022 19:39:58 to 14-05-2022 19:40:01.

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 1594198 1594198 0 0.00 0 34715 6 0 0
doc_list_biud 1594198 1594198 0 0.00 0 66541 28 0 0
sp_add_invoice_to_stock 5320 5146 174 3.27 36 149302 16212 0 174
sp_cancel_adding_invoice 5937 5937 0 0.00 5 66490 890 0 0
sp_cancel_client_order 1892 1892 0 0.00 4 21663 364 0 0
sp_cancel_customer_reserve 584921 584921 0 0.00 1 47151 60 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 2 5079 93 0 0
sp_cancel_pay_to_supplier 464 464 0 0.00 3 2566 70 0 0
sp_cancel_supplier_invoice 6478 6467 11 0.16 1 66546 774 0 11
sp_cancel_supplier_order 424 424 0 0.00 11 1038151 104002 0 0
sp_cancel_write_off 10579 10579 0 0.00 1 9382 106 0 0
sp_client_order 21590 21590 0 0.00 3 17362 332 0 0
sp_customer_reserve 477399 477399 0 0.00 0 108817 159 0 0
sp_fill_shopping_cart 32939 32939 0 0.00 1 15545 36 0 0
sp_fill_shopping_cart_clo_res 477383 477383 0 0.00 0 17067 13 0 0
sp_get_clo_for_invoice 5130 5130 0 0.00 0 38397 263 0 0
sp_kill_cost_storno 17688 17688 0 0.00 0 6695 56 0 0
sp_kill_qstorno_ret_qs2qd 1187312 1187312 0 0.00 0 66540 32 0 0
sp_kill_qty_storno 625726 625726 0 0.00 0 66541 70 0 0
sp_lock_selected_doc 21552 21552 0 0.00 0 8531 4 0 0
sp_make_cost_storno 28932 28932 0 0.00 0 31452 54 0 0
sp_multiply_rows_for_pdistr 28932 28932 0 0.00 0 16566 36 0 0
sp_multiply_rows_for_qdistr 21590 21590 0 0.00 0 16538 143 0 0
sp_pay_from_customer 7348 7348 0 0.00 4 16755 113 0 0
sp_pay_to_supplier 6078 6078 0 0.00 4 16276 128 0 0
sp_payment_common 13426 13426 0 0.00 1 16293 30 0 0
sp_qd_handle_on_cancel_clo 1892 1892 0 0.00 0 21657 338 0 0
sp_qd_handle_on_invoice_upd_sts 11067 11067 0 0.00 0 17710 330 0 0
sp_qd_handle_on_reserve_upd_sts 10376 10376 0 0.00 0 20607 64 0 0
sp_reserve_write_off 10376 10376 0 0.00 6 20648 191 0 0
sp_supplier_invoice 5628 5628 0 0.00 5 95202 3302 0 0
sp_supplier_order 5723 5723 0 0.00 5 95916 1146 0 0
srv_aggregate_perf_data 110 110 0 0.00 2458 324774 18596 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 956 117468 42092 0 0
srv_find_qd_qs_mism 1102666 1102666 0 0.00 0 34710 5 0 0
srv_make_invnt_saldo 614 614 0 0.00 28 51853 1122 0 0
srv_make_money_saldo 854 854 0 0.00 0 4519 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 194547 425550 269601 0 0
t$perf-abend:sp_add_invoice_to_stock 174 174 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5147 5147 0 0.00 0 13858 186 0 0
t$perf-norm:sp_cancel_adding_invoice 454 454 0 0.00 0 6547 125 0 0
t$perf-norm:sp_cancel_client_order 1892 1892 0 0.00 0 10082 21 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 2593 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 7506 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 464 464 0 0.00 0 727 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 567 11 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 20766 1586 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 4179 17 0 0
t$perf-norm:sp_client_order 21590 21590 0 0.00 0 7773 20 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 5641 13 0 0
t$perf-norm:sp_pay_from_customer 7348 7348 0 0.00 0 5766 12 0 0
t$perf-norm:sp_pay_to_supplier 6078 6078 0 0.00 0 4565 8 0 0
t$perf-norm:sp_reserve_write_off 10376 10376 0 0.00 0 5612 12 0 0
t$perf-norm:sp_supplier_invoice 5628 5628 0 0.00 0 7179 15 0 0
t$perf-norm:sp_supplier_order 5723 5723 0 0.00 0 7331 17 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 562 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 614 614 0 0.00 0 895 18 0 0
t$perf-norm:srv_make_money_saldo 854 854 0 0.00 0 8321 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10608 10260 348 3.28 0 3520 16 0 348
v_all_customers 39276 39276 0 0.00 0 16 1 0 0
v_all_suppliers 31290 31290 0 0.00 0 16 1 0 0
v_all_wares 43180 43180 0 0.00 0 56 1 0 0
v_cancel_adding_invoice 908 908 0 0.00 0 2053 11 0 0
v_cancel_client_order 3784 3784 0 0.00 0 3472 6 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 4734 11 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 1717 6 0 0
v_cancel_payment_to_supplier 928 928 0 0.00 0 2516 10 0 0
v_cancel_supplier_invoice 852 830 22 2.58 0 2576 18 0 22
v_cancel_supplier_order 848 848 0 0.00 0 4484 11 0 0
v_cancel_write_off 2082 2082 0 0.00 0 2801 14 0 0
v_max_id_clo_ord 5721 5721 0 0.00 0 484 2 0 0
v_max_id_clo_res 970 970 0 0.00 0 2247 8 0 0
v_max_id_ord_sup 5628 5628 0 0.00 0 8594 4 0 0
v_max_non_paid_invoice 1782 1782 0 0.00 0 7652 9 0 0
v_max_non_paid_realizn 3920 3920 0 0.00 0 3285 4 0 0
v_min_id_clo_ord 5721 5721 0 0.00 0 2913 7 0 0
v_min_id_clo_res 970 970 0 0.00 0 987 4 0 0
v_min_id_ord_sup 5628 5628 0 0.00 0 7872 6 0 0
v_min_non_paid_invoice 6078 6078 0 0.00 0 12330 18 0 0
v_min_non_paid_realizn 7348 7348 0 0.00 0 15933 20 0 0
v_our_firm 10760 10760 0 0.00 0 13 1 0 0
v_reserve_write_off 20752 20752 0 0.00 0 3545 6 0 0
x_lock_depdocs_on_canc_invoice 11989 11989 0 0.00 0 19605 122 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 17755 409 0 0
x_make_qstorno_1000_1200 5723 5723 0 0.00 1 95897 1059 0 0
x_make_qstorno_1200_2000 5628 5628 0 0.00 2 87892 3180 0 0
x_make_qstorno_2100_3300 477399 477399 0 0.00 0 108815 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-05-2022 19:40:02 to 14-05-2022 19:40:06.

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:21 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:21 14909 8192000000 9871732800 10173140992 962266976 10.511 100 94 188 0 993310864 5705312 25338576
17:10:46 21286 8192000000 9933951808 10249535488 1010224224 10.978 100 93 186 1 1042648208 8627120 23796864
17:12:10 17038 8192000000 9965091232 10290499584 1041751664 11.281 100 82 165 0 1071995136 9986672 20256800
17:13:29 11573 8192000000 10010039968 10352431104 1097417328 11.813 100 82 164 0 1128594592 10878080 20299184
17:14:49 6728 8192000000 10082447936 10437451776 1161591552 12.418 100 78 156 1 1192144112 12668128 17884432
17:15:58 6399 8192000000 10113474800 10477146112 1204334480 12.817 100 75 150 4 1234332624 12804464 17193680
17:17:07 2699 8192000000 10162092880 10534363136 1249224304 13.231 100 89 175 2 1281625648 13790656 18610688
17:18:12 1912 8192000000 10197114480 10575679488 1274905776 13.466 100 95 184 2 1311255328 15726800 20622752
17:19:16 1652 8192000000 10223548576 10607071232 1307994624 13.768 100 94 189 0 1342904304 13028800 21880880
17:20:19 32656 8192000000 10197720064 10606612480 1306368256 13.753 100 45 90 1 1325852672 9462864 10021552
17:22:00 4577 8192000000 10209794464 10616836096 1316161584 13.842 100 27 54 2 1327981072 5768864 6050624
17:23:08 9878 8192000000 10210494368 10626347008 1323616624 13.909 100 31 62 0 1337172048 6700784 6854640
17:24:19 9056 8192000000 10209663760 10627256320 1324809008 13.920 100 18 36 0 1338673216 9932544 3931664
17:25:35 70756 8192000000 10242541344 10645995520 1331328560 13.979 100 41 82 2 1346993712 4818096 10847056
17:27:46 4459 8192000000 10225452800 10644217856 1332424432 13.989 100 38 76 1 1348152864 6833024 8895408
17:28:53 7419 8192000000 10187902128 10601480192 1251486416 13.252 95 19 37 2 1260801488 5063632 4251440
17:30:02 45230 8192000000 10179745232 10591731712 1264110720 13.368 97 26 52 1 1275287232 5206016 5970496
17:31:48 6931 8192000000 10148816048 10546323456 1251781440 13.255 100 35 70 0 1266168912 5964656 8422816
17:32:55 15700 8192000000 10164510960 10568896512 1257741856 13.309 99 20 39 3 1271839712 9856560 4241296
17:34:13 19021 8192000000 10172734448 10578391040 1265654208 13.382 99 25 50 0 1276946624 5243808 6048608
17:35:32 3792 8192000000 10201639072 10592309248 1285371600 13.562 100 71 144 2 1308540848 5717824 17451424
17:36:36 9422 8192000000 10184915824 10601332736 1296502560 13.663 100 26 52 3 1307903040 5068928 6331552
17:37:50 6031 8192000000 10196084864 10609565696 1303207392 13.724 100 32 60 1 1316338144 6269008 6861744
17:39:02 21963 8192000000 10242846288 10647134208 1329787616 13.965 100 43 86 1 1348274304 8238848 10247840
17:40:26 37954 8192000000 10241419728 10666819584 1342266928 14.078 100 28 56 0 1356934528 8401120 6266480
17:42:11 14413 8192000000 10267120976 10686103552 1362931136 14.264 100 37 74 0 1380582240 9170832 8480272
17:43:27 5552 8192000000 10288302880 10707333120 1372598608 14.350 100 58 116 2 1397626432 11346480 13681344
17:44:35 3394 8192000000 10294329104 10710347776 1376885264 14.389 100 62 123 0 1403188688 13361872 12941552
17:45:50 953 8192000000 10322679568 10729668608 1390595728 14.511 100 96 187 2 1429953024 18345392 21011904
17:46:55 4463 8192000000 10324033232 10733985792 1389731856 14.503 100 76 151 1 1430523264 22724416 18066992
17:48:11 4035 8192000000 10328295360 10748416000 1394906688 14.550 100 56 112 0 1439622128 31169632 13545808
17:49:18 20215 8192000000 10331139008 10761297920 1406341488 14.651 100 42 84 2 1447994944 30961456 10692000
17:51:04 6254 8192000000 10332633936 10747195392 1397151504 14.570 100 63 127 3 1442798928 29548304 16099120
17:52:15 17739 8192000000 10338720688 10759147520 1401788384 14.611 100 42 82 4 1446041424 33775296 10477744
17:53:39 4798 8192000000 10353614640 10766942208 1401266336 14.606 100 66 130 1 1462651856 44202144 17183376
17:54:52 11032 8192000000 10349456144 10761207808 1391821184 14.522 100 66 132 2 1454387616 45361536 17204896
17:56:15 5909 8192000000 10339862672 10757226496 1376954656 14.389 99 54 108 0 1443048688 51587488 14506544
17:57:25 3407 8192000000 10349392992 10747072512 1364963568 14.282 99 84 165 1 1439397952 53069584 21364800
17:58:33 4832 8192000000 10358459120 10774454272 1392723024 14.530 100 53 106 1 1461505328 54286432 14495872
17:59:41 20000 8192000000 10337111296 10765090816 1380471552 14.421 100 37 73 1 1448046624 57537632 10037440
18:01:04 11006 8192000000 10341838080 10764845056 1382386704 14.438 100 44 87 1 1446918288 53099488 11432096
18:02:34 8078 8192000000 10321870288 10739306496 1350028336 14.148 99 48 95 1 1417237136 54759840 12448960
18:03:52 13823 8192000000 10353395488 10783760384 1381366000 14.429 99 38 76 0 1453390688 61465488 10559200
18:05:11 24006 8192000000 10335621568 10776338432 1385292192 14.464 100 26 50 1 1447197360 54591888 7313280
18:06:36 33512 8192000000 10345759472 10785574912 1385726752 14.468 100 37 74 0 1447201008 51855856 9618400
18:08:12 14338 8192000000 10323835152 10763624448 1357574096 14.216 100 14 28 1 1422467936 60347856 4545984
18:10:00 49391 8192000000 10286015024 10726694912 1359299760 14.231 99 18 36 0 1372660096 9566304 3794032
18:11:56 106309 8192000000 10289182272 10737209344 1346994544 14.120 98 10 20 0 1361371104 12457824 1918736
18:15:01 83005 8192000000 10311782608 10781069312 1388121632 14.489 100 13 26 0 1418050208 26854224 3074352
18:17:24 55808 8192000000 10312486624 10766184448 1397555856 14.573 100 28 55 0 1417693584 13769008 6368720
18:19:20 112860 8192000000 10306099024 10757681152 1366855056 14.299 98 26 51 1 1387002624 14164800 5982768
18:22:14 69805 8192000000 10290344464 10741202944 1346134832 14.113 97 23 46 1 1366069200 15124704 4809664
18:24:30 54911 8192000000 10301168416 10745434112 1368244912 14.311 99 45 90 2 1387987728 9412656 10330160
18:26:26 37410 8192000000 10293139808 10747711488 1376803456 14.388 99 19 36 2 1391075728 10241904 4030368
18:28:04 38680 8192000000 10323212816 10778517504 1400404720 14.599 100 44 87 0 1430416048 19303760 10707568
18:30:22 41988 8192000000 10270469872 10728673280 1351888480 14.164 98 21 42 0 1367884976 11007056 4989440
18:32:05 46562 8192000000 10272759024 10729132032 1349106304 14.139 97 11 22 1 1363335120 11513680 2715136
18:33:52 43370 8192000000 10258148704 10723389440 1342295600 14.078 98 9 18 0 1356971008 12264448 2410960
18:35:47 46433 8192000000 10264510816 10725797888 1351135744 14.158 99 12 24 0 1362976880 9049808 2791328
18:38:02 42394 8192000000 10259292400 10711982080 1337585168 14.036 98 24 47 1 1348687200 6544624 4557408
18:39:51 88653 8192000000 10275887072 10744958976 1326287360 13.934 96 9 18 2 1343838176 15403728 2147088
18:42:28 27469 8192000000 10282045728 10734612480 1371395344 14.340 100 28 53 1 1386144560 7941792 6807424
18:43:55 26567 8192000000 10280800096 10727899136 1356855584 14.209 99 35 70 2 1372541408 7261136 8424688
18:45:27 40817 8192000000 10257173424 10703929344 1291268848 13.616 95 22 44 1 1304136160 8161632 4705680
18:47:14 24086 8192000000 10289348208 10727981056 1352326336 14.168 99 45 90 1 1371180976 8894832 9959808
18:48:40 70320 8192000000 10265904944 10726776832 1303281008 13.725 95 18 36 1 1322198448 14389760 4527680
18:50:51 26410 8192000000 10263263712 10706341888 1327790992 13.947 98 18 36 0 1339034400 7413376 3830032
18:52:23 49004 8192000000 10287321856 10742050816 1364562832 14.278 99 14 28 0 1385220256 17567088 3090336
18:54:32 104174 8192000000 10273771616 10728558592 1362500992 14.260 99 28 56 0 1378944608 9937072 6506544
18:57:17 21141 8192000000 10284826128 10736824320 1368846368 14.317 100 22 44 0 1388176992 14471504 4859120
18:58:38 85506 8192000000 10274074816 10747883520 1380759344 14.423 100 9 18 0 1395496160 12656304 2080512
19:01:04 52642 8192000000 10289785904 10749091840 1373369952 14.357 99 17 34 0 1387050032 9424688 4255392
19:03:44 30872 8192000000 10307987024 10769551360 1403268064 14.624 100 16 32 0 1419841808 12204480 4369264
19:05:16 70104 8192000000 10284591072 10757652480 1340857968 14.065 96 12 24 0 1353171248 9859072 2454208
19:07:29 39931 8192000000 10307718528 10765979648 1397168656 14.570 100 22 44 0 1415587488 13681232 4737600
19:09:09 76061 8192000000 10291292992 10744127488 1328498880 13.954 95 28 56 1 1350284608 15401456 6384272
19:11:25 55133 8192000000 10299430848 10760429568 1370811360 14.334 98 18 34 0 1387201984 12943776 3446848
19:13:25 51941 8192000000 10292610624 10751537152 1336098528 14.022 96 18 36 1 1347766576 7994496 3673552
19:15:36 115686 8192000000 10316892896 10778112000 1373140432 14.355 98 14 28 0 1393696096 17687184 2868480
19:19:05 117468 8192000000 10272044272 10722934784 1301644464 13.710 93 14 28 0 1314602336 9656304 3301568
19:22:23 37979 8192000000 10270893616 10749018112 1368013792 14.309 100 5 10 0 1385024064 16101712 908560
19:24:01 52890 8192000000 10261690352 10715488256 1340313120 14.060 97 12 24 0 1349493744 6949616 2231008
19:25:54 57979 8192000000 10272952848 10737344512 1359683248 14.235 99 12 24 0 1370354640 8177632 2493760
19:27:52 58441 8192000000 10277131888 10735718400 1352686432 14.172 97 13 26 1 1364696144 8898064 3111648
19:29:51 69052 8192000000 10262713728 10716905472 1335382032 14.016 97 13 26 0 1346981552 8685616 2913904
19:32:01 65453 8192000000 10269632944 10735775744 1367726912 14.307 99 17 34 0 1379255712 7707312 3821488
19:35:18 46918 8192000000 10271396752 10728026112 1311864624 13.803 96 23 46 1 1334401472 17426464 5110384
19:37:07 52563 8192000000 10286896640 10739068928 1360206992 14.239 99 21 42 2 1393332912 27978176 5147744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-05-2022 19:40:08 to 14-05-2022 19:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 174 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-05-2022 19:40:24 to 14-05-2022 19:40:26.

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          240521
MON$OLDEST_ACTIVE               246330
MON$OLDEST_SNAPSHOT             246330
MON$NEXT_TRANSACTION            246330
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                       2063776
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:295: 
:296: mscs-test-161.budzdorov.ru    Sat May 14 19:38:40 2022
:297:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:298: 
:299: 
:300: mscs-test-161.budzdorov.ru    Sat May 14 19:38:41 2022
:301:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:302: 
--- end of diff output ---

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

14.05.22 19:40:27. 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-05-14 19:40:29 No severe PSQL-related problems occured
14.05.22 19:40:31. End of report.