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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15485464 kB
MemAvailable 30446544 kB
Buffers 0 kB
Cached 16251252 kB
SwapCached 3140 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-22 01:38:39
DTS_END                         2023-02-22 01:38:39.6460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40826
TRN_ID                          238811
ATT_ID                          717
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: 9039.15 22328 1084698
customer order: creation 176.14 358 21137
customer order: refuse 14.68 358 1761
order to supplier: creation 46.02 1284 5522
order to supplier: removal 3.45 85643 414
invoice (draft): creation 45.86 3441 5503
invoice (draft): removal 51.02 714 6122
invoice accept: apply 40.91 18507 4909
invoice accept: cancel 46.00 766 5520
customer reserve: creation 3814.54 182 457745
customer reserve: removal 4494.82 50 539378
realization accept: apply 83.64 184 10037
realization accept: cancel 93.97 109 11276
payment from customer: creation 58.18 145 6981
payment from customer: removal 5.62 100 674
payment to supplier: creation 48.98 167 5878
payment to supplier: removal 3.31 94 397
service: total inventory turnovers 4.54 1074 545
service: total monetary turnovers 6.60 32 792
service: aggregate perf. data 0.86 20224 103
service: refresh index statistics 0.03 313124 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4276
TEST_TIME 2 6020
TEST_TIME 3 6054
TEST_TIME 4 5830
TEST_TIME 5 6780
TEST_TIME 6 6791
TEST_TIME 7 6978
TEST_TIME 8 6658
TEST_TIME 9 6557
TEST_TIME 10 6442
TEST_TIME 11 6827
TEST_TIME 12 6909
TEST_TIME 13 7015
TEST_TIME 14 7207
TEST_TIME 15 7384
TEST_TIME 16 7502
TEST_TIME 17 7702
TEST_TIME 18 7712
TEST_TIME 19 7852
TEST_TIME 20 7764
TEST_TIME 21 8053
TEST_TIME 22 8308
TEST_TIME 23 8423
TEST_TIME 24 8433
TEST_TIME 25 8725
TEST_TIME 26 9310
TEST_TIME 27 9314
TEST_TIME 28 10058
TEST_TIME 29 9812
TEST_TIME 30 9819
TEST_TIME 31 9820
TEST_TIME 32 9760
TEST_TIME 33 9480
TEST_TIME 34 9399
TEST_TIME 35 9428
TEST_TIME 36 9388
TEST_TIME 37 9655
TEST_TIME 38 9467
TEST_TIME 39 9422
TEST_TIME 40 9380
TEST_TIME 41 9321
TEST_TIME 42 9362
TEST_TIME 43 9207
TEST_TIME 44 9203
TEST_TIME 45 9150
TEST_TIME 46 9095
TEST_TIME 47 9043
TEST_TIME 48 9007
TEST_TIME 49 8907
TEST_TIME 50 8763
TEST_TIME 51 8751
TEST_TIME 52 8764
TEST_TIME 53 8737
TEST_TIME 54 8666
TEST_TIME 55 8714
TEST_TIME 56 8717
TEST_TIME 57 8960
TEST_TIME 58 9016
TEST_TIME 59 9080
TEST_TIME 60 9118
TEST_TIME 61 9051
TEST_TIME 62 9238
TEST_TIME 63 9217
TEST_TIME 64 9255
TEST_TIME 65 9334
TEST_TIME 66 9253
TEST_TIME 67 9142
TEST_TIME 68 9206
TEST_TIME 69 9216
TEST_TIME 70 9190
TEST_TIME 71 9178
TEST_TIME 72 9142
TEST_TIME 73 9121
TEST_TIME 74 9083
TEST_TIME 75 9060
TEST_TIME 76 9048
TEST_TIME 77 9014
TEST_TIME 78 9024
TEST_TIME 79 9074
TEST_TIME 80 9103
TEST_TIME 81 9171
TEST_TIME 82 9164
TEST_TIME 83 9178
TEST_TIME 84 9173
TEST_TIME 85 9188
TEST_TIME 86 9212
TEST_TIME 87 9299
TEST_TIME 88 9254
TEST_TIME 89 9191
TEST_TIME 90 9157
TEST_TIME 91 9164
TEST_TIME 92 9166
TEST_TIME 93 9171
TEST_TIME 94 9098
TEST_TIME 95 9111
TEST_TIME 96 9131
TEST_TIME 97 9140
TEST_TIME 98 9134
TEST_TIME 99 9158
TEST_TIME 100 9183
TEST_TIME 101 9200
TEST_TIME 102 9189
TEST_TIME 103 9192
TEST_TIME 104 9241
TEST_TIME 105 9248
TEST_TIME 106 9239
TEST_TIME 107 9318
TEST_TIME 108 9295
TEST_TIME 109 9334
TEST_TIME 110 9284
TEST_TIME 111 9237
TEST_TIME 112 9223
TEST_TIME 113 9197
TEST_TIME 114 9183
TEST_TIME 115 9147
TEST_TIME 116 9128
TEST_TIME 117 9103
TEST_TIME 118 9069
TEST_TIME 119 9061
TEST_TIME 120 9044

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-02-2023 01:40:15 to 22-02-2023 01:40: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 1509175 1509175 0 0.00 0 23561 6 0 0
doc_list_biud 1509173 1509173 0 0.00 0 54661 24 0 0
sp_add_invoice_to_stock 4985 4909 76 1.52 40 190781 18507 0 76
sp_cancel_adding_invoice 5520 5520 0 0.00 4 66319 767 0 0
sp_cancel_client_order 1761 1761 0 0.00 4 8484 359 0 0
sp_cancel_customer_reserve 539378 539378 0 0.00 0 54663 51 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 2 13117 100 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 2 8720 95 0 0
sp_cancel_supplier_invoice 6128 6122 6 0.09 2 31104 714 0 6
sp_cancel_supplier_order 414 414 0 0.00 9 897727 85643 0 0
sp_cancel_write_off 11276 11276 0 0.00 1 13819 109 0 0
sp_client_order 21137 21137 0 0.00 3 37975 358 0 0
sp_customer_reserve 457745 457745 0 0.00 0 58698 183 0 0
sp_fill_shopping_cart 32158 32158 0 0.00 1 22653 36 0 0
sp_fill_shopping_cart_clo_res 457741 457741 0 0.00 0 12995 14 0 0
sp_get_clo_for_invoice 4899 4899 0 0.00 0 22308 271 0 0
sp_kill_cost_storno 17867 17867 0 0.00 0 23552 58 0 0
sp_kill_qstorno_ret_qs2qd 1096568 1096568 0 0.00 0 54657 28 0 0
sp_kill_qty_storno 579407 579407 0 0.00 0 54660 61 0 0
sp_lock_selected_doc 20673 20673 0 0.00 0 7896 6 0 0
sp_make_cost_storno 27795 27795 0 0.00 0 12339 59 0 0
sp_multiply_rows_for_pdistr 27795 27795 0 0.00 0 8569 29 0 0
sp_multiply_rows_for_qdistr 21137 21137 0 0.00 0 11430 161 0 0
sp_pay_from_customer 6981 6981 0 0.00 4 48958 146 0 0
sp_pay_to_supplier 5878 5878 0 0.00 4 30168 168 0 0
sp_payment_common 12859 12859 0 0.00 1 48913 44 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 0 8432 326 0 0
sp_qd_handle_on_invoice_upd_sts 10419 10419 0 0.00 0 23639 317 0 0
sp_qd_handle_on_reserve_upd_sts 10037 10037 0 0.00 0 12990 55 0 0
sp_reserve_write_off 10037 10037 0 0.00 6 13969 184 0 0
sp_supplier_invoice 5503 5503 0 0.00 6 108839 3442 0 0
sp_supplier_order 5522 5522 0 0.00 5 30774 1285 0 0
srv_aggregate_perf_data 103 103 0 0.00 1924 503566 20224 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2139 137861 46968 0 0
srv_find_qd_qs_mism 1037330 1037330 0 0.00 0 17221 5 0 0
srv_make_invnt_saldo 545 545 0 0.00 24 35787 1074 0 0
srv_make_money_saldo 792 792 0 0.00 1 2850 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 119076 475871 313124 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4910 4910 0 0.00 0 8247 176 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 1933 63 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 4006 18 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 3943 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 546 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 1761 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 3531 32 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 32089 1549 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 3436 11 0 0
t$perf-norm:sp_client_order 21137 21137 0 0.00 0 5773 18 0 0
t$perf-norm:sp_customer_reserve 1054 1054 0 0.00 0 1263 7 0 0
t$perf-norm:sp_pay_from_customer 6981 6981 0 0.00 0 17849 14 0 0
t$perf-norm:sp_pay_to_supplier 5878 5878 0 0.00 0 5089 11 0 0
t$perf-norm:sp_reserve_write_off 10037 10037 0 0.00 0 5178 12 0 0
t$perf-norm:sp_supplier_invoice 5503 5503 0 0.00 0 5216 15 0 0
t$perf-norm:sp_supplier_order 5522 5522 0 0.00 0 2537 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 369 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 293 8 0 0
t$perf-norm:srv_make_invnt_saldo 545 545 0 0.00 0 1051 21 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 1071 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9950 9798 152 1.52 0 10278 22 0 152
v_all_customers 37942 37942 0 0.00 0 18 1 0 0
v_all_suppliers 30286 30286 0 0.00 0 118 1 0 0
v_all_wares 42274 42274 0 0.00 0 79 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 3495 15 0 0
v_cancel_client_order 3522 3522 0 0.00 0 7320 6 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 2579 8 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 10080 16 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 3191 7 0 0
v_cancel_supplier_invoice 834 822 12 1.43 0 7264 22 0 12
v_cancel_supplier_order 828 828 0 0.00 0 734 5 0 0
v_cancel_write_off 1926 1926 0 0.00 0 5148 15 0 0
v_max_id_clo_ord 5522 5522 0 0.00 0 687 1 0 0
v_max_id_clo_res 1054 1054 0 0.00 0 1580 14 0 0
v_max_id_ord_sup 5499 5499 0 0.00 0 4492 3 0 0
v_max_non_paid_invoice 1756 1756 0 0.00 0 4885 5 0 0
v_max_non_paid_realizn 3762 3762 0 0.00 0 1877 4 0 0
v_min_id_clo_ord 5522 5522 0 0.00 0 7438 7 0 0
v_min_id_clo_res 1054 1054 0 0.00 0 7641 17 0 0
v_min_id_ord_sup 5499 5499 0 0.00 0 2358 4 0 0
v_min_non_paid_invoice 5878 5878 0 0.00 0 30144 36 0 0
v_min_non_paid_realizn 6981 6981 0 0.00 0 30419 28 0 0
v_our_firm 10770 10770 0 0.00 0 19 1 0 0
v_reserve_write_off 20074 20074 0 0.00 0 6777 5 0 0
x_lock_depdocs_on_canc_invoice 11231 11231 0 0.00 0 12475 113 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 8561 370 0 0
x_make_qstorno_1000_1200 5522 5522 0 0.00 1 30687 1210 0 0
x_make_qstorno_1200_2000 5503 5503 0 0.00 2 108823 3308 0 0
x_make_qstorno_2100_3300 457745 457745 0 0.00 0 46811 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-02-2023 01:40:20 to 22-02-2023 01:40: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
23:08:43 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:44 27092 8192000000 9901603264 10207301632 979402896 10.678 100 92 184 0 1009538048 5516240 24618912
23:11:14 16942 8192000000 9937028304 10255785984 1021529264 11.087 100 88 176 0 1052280752 7531408 23220080
23:12:37 16782 8192000000 9970526000 10301341696 1053725936 11.396 100 82 164 0 1083432880 8762304 20944640
23:13:59 6673 8192000000 10023138816 10372792320 1114743568 11.977 100 71 142 0 1141549504 10162496 16643440
23:15:08 7106 8192000000 10076243136 10434199552 1164188576 12.442 100 82 164 0 1196726624 13475776 19062272
23:16:17 3060 8192000000 10116498272 10485923840 1213507040 12.902 100 77 151 0 1241187232 11242256 16437936
23:17:23 2692 8192000000 10166629328 10539180032 1244317040 13.186 100 88 177 0 1278292272 14598256 19376976
23:18:28 1368 8192000000 10200944496 10581270528 1280586784 13.518 100 96 193 0 1316352208 14187184 21578240
23:19:30 1194 8192000000 10211349984 10595778560 1295813376 13.657 100 92 184 1 1329588848 13974496 19800976
23:20:32 6438 8192000000 10205183344 10600054784 1296821632 13.666 100 65 126 1 1322116656 10981936 14313088
23:21:40 11143 8192000000 10203005936 10612346880 1310024512 13.786 100 15 30 0 1319489920 6225328 3240080
23:22:53 32881 8192000000 10217237600 10627416064 1319800848 13.875 100 33 65 1 1334198304 5618096 8779360
23:24:49 3614 8192000000 10221995968 10633818112 1328551200 13.954 100 24 48 0 1340520768 6912432 5057136
23:25:57 11204 8192000000 10225598256 10645323776 1339277808 14.051 100 23 46 0 1350809968 6123456 5408704
23:27:09 13589 8192000000 10202427120 10621116416 1310095008 13.787 98 21 39 1 1320007392 5044288 4868096
23:28:22 20050 8192000000 10221937728 10648047616 1335292320 14.015 100 15 30 0 1344233728 5981824 2959584
23:29:48 6211 8192000000 10199750448 10605723648 1265778928 13.383 96 25 50 0 1277150848 5605824 5766096
23:30:59 7598 8192000000 10160793424 10559897600 1247837296 13.218 98 20 39 0 1257697104 5616912 4242896
23:32:07 49617 8192000000 10121844656 10515779584 1208077712 12.851 97 23 46 1 1222287328 9164576 5045040
23:34:16 5321 8192000000 10155975952 10561712128 1267442096 13.398 100 31 62 0 1279872896 5623744 6807056
23:35:24 6922 8192000000 10178171152 10585309184 1286419552 13.572 100 23 45 1 1296569504 4736592 5413360
23:36:35 15612 8192000000 10191103728 10603225088 1301335568 13.707 100 12 25 0 1309387680 5508384 2543728
23:37:51 30814 8192000000 10211682688 10615943168 1308929440 13.776 100 50 102 0 1326416272 5470672 12016160
23:39:27 12086 8192000000 10236035728 10646425600 1333560432 13.999 100 37 70 1 1348797248 7147648 8089168
23:40:43 6797 8192000000 10259689200 10667184128 1349638336 14.144 100 59 118 1 1374168832 11672496 12858000
23:41:54 2888 8192000000 10271276160 10685820928 1360523168 14.242 100 51 102 0 1382522128 10325488 11673472
23:43:00 24469 8192000000 10283140960 10705932288 1375668688 14.378 100 48 94 1 1398255872 11721808 10865376
23:44:29 9535 8192000000 10286831296 10708566016 1377826304 14.397 100 49 96 1 1398637392 9187232 11623856
23:45:44 44374 8192000000 10284297760 10719105024 1373448112 14.358 99 35 68 1 1395640336 14643024 7549200
23:47:41 3130 8192000000 10307433744 10730205184 1393672656 14.539 100 53 103 0 1419630240 13598192 12359392
23:48:51 6194 8192000000 10306163184 10734006272 1385300784 14.464 99 52 102 0 1413100080 14897792 12901504
23:50:04 8026 8192000000 10307614144 10734391296 1381256320 14.428 99 50 100 1 1408356544 14953136 12147088
23:51:16 13261 8192000000 10329646080 10747666432 1385074528 14.462 99 67 135 0 1422017744 21315360 15627856
23:52:33 4466 8192000000 10303745856 10720739328 1376291072 14.383 100 62 123 0 1410183584 19545920 14346592
23:53:48 2135 8192000000 10322106128 10737086464 1381292624 14.428 100 65 130 1 1424168384 27973248 14902512
23:54:54 5877 8192000000 10305710160 10719543296 1368862976 14.317 100 68 136 0 1416004864 30587984 16553904
23:56:03 10036 8192000000 10305078336 10724532224 1373587584 14.359 100 52 104 0 1415937424 29274640 13075200
23:57:17 3156 8192000000 10293477168 10713583616 1352880320 14.173 99 49 96 1 1391970832 27318496 11772016
23:58:40 3579 8192000000 10308021760 10723926016 1361345536 14.249 99 58 116 2 1406336624 30243040 14748048
23:59:47 7842 8192000000 10290484912 10696200192 1336802544 14.029 99 60 119 1 1382187264 29676448 15708272
00:01:01 10275 8192000000 10293120880 10719977472 1365848272 14.290 100 39 76 0 1404836112 29322272 9665568
00:02:14 7865 8192000000 10285787664 10709766144 1342665728 14.081 99 39 78 0 1388898512 35651008 10581776
00:03:26 23882 8192000000 10292973536 10721304576 1339429440 14.052 98 32 61 0 1382570240 34262672 8878128
00:04:51 42635 8192000000 10268482896 10704297984 1331201456 13.978 98 20 38 0 1360610208 24263536 5145216
00:07:03 42126 8192000000 10275003984 10710466560 1364176864 14.275 100 23 43 0 1380016784 10914368 4925552
00:08:47 27386 8192000000 10260497936 10708791296 1344863280 14.101 99 17 36 0 1359535824 10870480 3802064
00:10:19 76717 8192000000 10293255040 10731585536 1373033664 14.354 100 38 76 1 1397693024 16274112 8385248
00:13:14 116005 8192000000 10278926352 10726391808 1371512672 14.341 99 20 41 0 1396727504 20448000 4766832
00:16:10 43466 8192000000 10286288800 10733252608 1371413648 14.340 99 30 56 1 1389004960 11182192 6409120
00:17:55 56345 8192000000 10305832256 10749652992 1388745424 14.495 100 20 40 0 1410219200 16958256 4515520
00:20:03 54240 8192000000 10295473536 10752450560 1389020528 14.497 100 10 20 0 1407810880 16481424 2308928
00:22:36 57190 8192000000 10294725712 10751983616 1391896096 14.523 100 6 12 0 1410650416 17235728 1518592
00:24:34 74711 8192000000 10287712624 10740928512 1383858576 14.451 99 9 18 1 1391825856 6074320 1892960
00:26:50 43063 8192000000 10310607568 10759823360 1399632944 14.592 100 11 22 0 1412727968 10592000 2503024
00:28:36 89228 8192000000 10301214256 10773766144 1407581680 14.662 100 15 30 1 1421669552 10928464 3159408
00:31:11 64073 8192000000 10302614736 10756153344 1346869344 14.119 96 19 38 0 1358801504 7573136 4359024
00:33:21 53393 8192000000 10332468912 10799734784 1419786848 14.771 100 22 44 0 1446575552 21252992 5535712
00:35:16 69895 8192000000 10299468384 10759417856 1368495936 14.314 98 6 12 0 1388957008 19162096 1298976
00:37:29 78881 8192000000 10306497840 10762919936 1364811648 14.281 97 15 30 0 1381154400 12918512 3424240
00:39:54 64592 8192000000 10294668848 10750332928 1352135264 14.167 97 18 36 0 1371879472 16032480 3711728
00:42:04 21493 8192000000 10303391072 10736803840 1381743776 14.432 100 45 89 1 1401722880 9376224 10602880
00:43:26 72041 8192000000 10266662768 10704044032 1315479536 13.836 96 24 48 0 1332050080 11035856 5534688
00:45:39 76448 8192000000 10249655232 10695426048 1295273088 13.652 95 37 73 3 1317116832 12782992 9060752
00:48:02 47782 8192000000 10241403376 10688671744 1319031424 13.868 97 20 41 0 1331792592 8008848 4752320
00:50:12 43385 8192000000 10243109216 10691792896 1339954320 14.057 99 12 24 0 1352074096 9341840 2777936
00:52:01 34740 8192000000 10230550176 10682941440 1334283664 14.006 100 17 34 0 1347929584 9766528 3879392
00:53:37 58737 8192000000 10265119584 10692460544 1318174688 13.860 97 23 44 0 1329584928 6606480 4803760
00:55:40 11728 8192000000 10272521472 10714525696 1362764512 14.262 100 39 75 1 1384351856 12816480 8770864
00:57:08 74003 8192000000 10282039520 10732834816 1357947728 14.219 99 15 30 0 1381326160 19708800 3669632
00:59:22 68652 8192000000 10274150272 10710286336 1310880464 13.794 96 18 36 0 1322583696 8087840 3615392
01:01:36 58856 8192000000 10264035984 10696699904 1318506256 13.863 97 41 82 3 1338918624 10713008 9699360
01:03:43 47032 8192000000 10288346864 10724802560 1365381616 14.286 100 19 38 0 1381337184 11610752 4344816
01:05:41 86329 8192000000 10292855792 10725670912 1348377888 14.133 98 23 46 0 1363869248 9989328 5502032
01:08:10 109254 8192000000 10285384688 10737410048 1351970624 14.165 98 23 46 0 1371015920 14054256 4991040
01:11:09 89160 8192000000 10295509392 10754863104 1390626320 14.511 100 18 37 0 1403367328 8752384 3988624
01:13:38 51916 8192000000 10303737024 10764783616 1396186544 14.561 100 8 16 0 1411798304 13842832 1768928
01:15:54 77478 8192000000 10309584464 10765676544 1390524832 14.511 99 18 36 0 1413239568 18260240 4454496
01:18:36 20060 8192000000 10298104384 10744516608 1377273536 14.392 99 42 81 0 1398666096 11396624 9995936
01:20:02 85492 8192000000 10294544736 10739068928 1315413536 13.835 94 18 36 0 1330626592 11317520 3895536
01:22:33 137861 8192000000 10288401952 10737119232 1324725920 13.919 96 13 26 0 1340369520 12940192 2703408
01:25:53 94811 8192000000 10273343872 10718937088 1323980208 13.913 96 20 40 0 1338549696 10200016 4369472
01:28:55 95436 8192000000 10271199104 10719580160 1352725280 14.172 98 28 56 0 1370216192 10876544 6614368
01:31:39 34856 8192000000 10240897328 10693959680 1303131424 13.724 95 10 20 0 1313059312 7751520 2176368
01:33:17 60775 8192000000 10248547968 10696060928 1336944112 14.030 98 14 27 0 1347432432 7093648 3394672
01:35:21 67383 8192000000 10252574240 10706636800 1328572448 13.954 97 11 22 0 1340348656 9276848 2499360
01:37:31 69386 8192000000 9433430816 9797623808 553967648 6.333 41 1 2 0 557045744 3035744 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-02-2023 01:40:26 to 22-02-2023 01:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-02-2023 01:40:42 to 22-02-2023 01: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          238018
MON$OLDEST_ACTIVE               239054
MON$OLDEST_SNAPSHOT             239054
MON$NEXT_TRANSACTION            239054
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                       2009728
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 ---
:381: 
:382: mscs-test-161.budzdorov.ru    Wed Feb 22 01:38:44 2023
:383:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:384: 
:385: 
:386: mscs-test-161.budzdorov.ru    Wed Feb 22 01:38:44 2023
:387:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:388: 
:389: 
:390: mscs-test-161.budzdorov.ru    Wed Feb 22 01:38:44 2023
:391:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:392: 
:393: 
:394: mscs-test-161.budzdorov.ru    Wed Feb 22 01:38:44 2023
:395:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:396: 
:397: 
:398: mscs-test-161.budzdorov.ru    Wed Feb 22 01:38:44 2023
:399:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:400: 
:401: 
:402: mscs-test-161.budzdorov.ru    Wed Feb 22 01:38:44 2023
:403:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:404: 
:405: 
:406: mscs-test-161.budzdorov.ru    Wed Feb 22 01:38:44 2023
:407:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:408: 
--- end of diff output ---

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

22.02.23 01: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-02-22 01:40:48 No severe PSQL-related problems occured
22.02.23 01:40:49. End of report.