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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16782152 kB
MemAvailable 31387036 kB
Buffers 0 kB
Cached 14903236 kB
SwapCached 2588 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-20 22:38:19
DTS_END                         2022-05-20 22:38:19.7970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39336
TRN_ID                          248931
ATT_ID                          703
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: 9222.04 17551 1106645
customer order: creation 186.50 363 22380
customer order: refuse 15.22 283 1826
order to supplier: creation 49.63 1234 5955
order to supplier: removal 3.42 85314 410
invoice (draft): creation 48.69 3070 5843
invoice (draft): removal 51.15 676 6138
invoice accept: apply 44.08 16494 5289
invoice accept: cancel 45.43 716 5452
customer reserve: creation 3978.18 169 477381
customer reserve: removal 4496.43 51 539571
realization accept: apply 91.18 164 10942
realization accept: cancel 74.12 116 8894
payment from customer: creation 62.58 98 7510
payment from customer: removal 6.26 123 751
payment to supplier: creation 52.78 136 6334
payment to supplier: removal 3.94 78 473
service: total inventory turnovers 4.91 1135 589
service: total monetary turnovers 6.53 19 783
service: aggregate perf. data 1.00 14263 120
service: refresh index statistics 0.03 226509 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-05-2022 22:38:58 to 20-05-2022 22:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3805
TEST_TIME 2 4319
TEST_TIME 3 4375
TEST_TIME 4 4508
TEST_TIME 5 4414
TEST_TIME 6 5040
TEST_TIME 7 4864
TEST_TIME 8 4851
TEST_TIME 9 5303
TEST_TIME 10 5392
TEST_TIME 11 6087
TEST_TIME 12 6411
TEST_TIME 13 7239
TEST_TIME 14 7355
TEST_TIME 15 7772
TEST_TIME 16 8097
TEST_TIME 17 8579
TEST_TIME 18 9327
TEST_TIME 19 9605
TEST_TIME 20 9715
TEST_TIME 21 9962
TEST_TIME 22 10431
TEST_TIME 23 10919
TEST_TIME 24 10741
TEST_TIME 25 10454
TEST_TIME 26 10381
TEST_TIME 27 10332
TEST_TIME 28 10164
TEST_TIME 29 10163
TEST_TIME 30 9979
TEST_TIME 31 10011
TEST_TIME 32 9897
TEST_TIME 33 9668
TEST_TIME 34 9696
TEST_TIME 35 9545
TEST_TIME 36 9465
TEST_TIME 37 9388
TEST_TIME 38 9435
TEST_TIME 39 9391
TEST_TIME 40 9281
TEST_TIME 41 9466
TEST_TIME 42 9380
TEST_TIME 43 9477
TEST_TIME 44 9453
TEST_TIME 45 9496
TEST_TIME 46 9397
TEST_TIME 47 9509
TEST_TIME 48 9569
TEST_TIME 49 9643
TEST_TIME 50 9535
TEST_TIME 51 9390
TEST_TIME 52 9317
TEST_TIME 53 9319
TEST_TIME 54 9268
TEST_TIME 55 9250
TEST_TIME 56 9174
TEST_TIME 57 9117
TEST_TIME 58 9102
TEST_TIME 59 9052
TEST_TIME 60 9032
TEST_TIME 61 9126
TEST_TIME 62 9111
TEST_TIME 63 9246
TEST_TIME 64 9202
TEST_TIME 65 9252
TEST_TIME 66 9248
TEST_TIME 67 9311
TEST_TIME 68 9351
TEST_TIME 69 9386
TEST_TIME 70 9367
TEST_TIME 71 9435
TEST_TIME 72 9412
TEST_TIME 73 9467
TEST_TIME 74 9382
TEST_TIME 75 9304
TEST_TIME 76 9263
TEST_TIME 77 9263
TEST_TIME 78 9261
TEST_TIME 79 9208
TEST_TIME 80 9148
TEST_TIME 81 9166
TEST_TIME 82 9130
TEST_TIME 83 9090
TEST_TIME 84 9069
TEST_TIME 85 9147
TEST_TIME 86 9122
TEST_TIME 87 9167
TEST_TIME 88 9177
TEST_TIME 89 9215
TEST_TIME 90 9218
TEST_TIME 91 9236
TEST_TIME 92 9218
TEST_TIME 93 9231
TEST_TIME 94 9356
TEST_TIME 95 9387
TEST_TIME 96 9409
TEST_TIME 97 9382
TEST_TIME 98 9397
TEST_TIME 99 9402
TEST_TIME 100 9334
TEST_TIME 101 9271
TEST_TIME 102 9245
TEST_TIME 103 9217
TEST_TIME 104 9241
TEST_TIME 105 9206
TEST_TIME 106 9178
TEST_TIME 107 9116
TEST_TIME 108 9091
TEST_TIME 109 9134
TEST_TIME 110 9202
TEST_TIME 111 9168
TEST_TIME 112 9200
TEST_TIME 113 9197
TEST_TIME 114 9191
TEST_TIME 115 9196
TEST_TIME 116 9181
TEST_TIME 117 9253
TEST_TIME 118 9242
TEST_TIME 119 9282
TEST_TIME 120 9223

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-05-2022 22:39:01 to 20-05-2022 22:39:05.

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 1548165 1548165 0 0.00 0 18293 6 0 0
doc_list_biud 1548164 1548164 0 0.00 0 29812 23 0 0
sp_add_invoice_to_stock 5421 5289 132 2.43 19 170278 16494 0 132
sp_cancel_adding_invoice 5452 5452 0 0.00 4 84646 716 0 0
sp_cancel_client_order 1826 1826 0 0.00 4 11613 283 0 0
sp_cancel_customer_reserve 539571 539571 0 0.00 1 29813 51 0 0
sp_cancel_pay_from_customer 751 751 0 0.00 3 9846 123 0 0
sp_cancel_pay_to_supplier 473 473 0 0.00 3 4599 79 0 0
sp_cancel_supplier_invoice 6144 6138 6 0.09 1 22420 676 0 6
sp_cancel_supplier_order 410 410 0 0.00 5 893196 85315 0 0
sp_cancel_write_off 8894 8894 0 0.00 1 9223 117 0 0
sp_client_order 22380 22380 0 0.00 3 29786 364 0 0
sp_customer_reserve 477381 477381 0 0.00 0 95238 169 0 0
sp_fill_shopping_cart 34176 34176 0 0.00 1 25942 41 0 0
sp_fill_shopping_cart_clo_res 477378 477378 0 0.00 0 9373 15 0 0
sp_get_clo_for_invoice 5286 5286 0 0.00 0 37395 306 0 0
sp_kill_cost_storno 15570 15570 0 0.00 0 4978 47 0 0
sp_kill_qstorno_ret_qs2qd 1094584 1094584 0 0.00 0 29809 28 0 0
sp_kill_qty_storno 578519 578519 0 0.00 0 29811 60 0 0
sp_lock_selected_doc 22282 22282 0 0.00 0 7218 5 0 0
sp_make_cost_storno 30072 30072 0 0.00 0 10196 49 0 0
sp_multiply_rows_for_pdistr 30072 30072 0 0.00 0 9480 27 0 0
sp_multiply_rows_for_qdistr 22380 22380 0 0.00 0 13051 166 0 0
sp_pay_from_customer 7510 7510 0 0.00 4 10186 99 0 0
sp_pay_to_supplier 6334 6334 0 0.00 5 16232 137 0 0
sp_payment_common 13844 13844 0 0.00 1 16214 31 0 0
sp_qd_handle_on_cancel_clo 1826 1826 0 0.00 0 11600 265 0 0
sp_qd_handle_on_invoice_upd_sts 10738 10738 0 0.00 0 18034 265 0 0
sp_qd_handle_on_reserve_upd_sts 10942 10942 0 0.00 0 15006 49 0 0
sp_reserve_write_off 10942 10942 0 0.00 6 15542 164 0 0
sp_supplier_invoice 5843 5843 0 0.00 7 110520 3070 0 0
sp_supplier_order 5955 5955 0 0.00 5 36362 1235 0 0
srv_aggregate_perf_data 120 120 0 0.00 2760 115231 14263 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1898 148720 47669 0 0
srv_find_qd_qs_mism 1055810 1055810 0 0.00 0 18292 6 0 0
srv_make_invnt_saldo 589 589 0 0.00 38 128719 1135 0 0
srv_make_money_saldo 783 783 0 0.00 1 1769 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 132370 474308 226510 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 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 5289 5289 0 0.00 0 11957 143 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 7468 90 0 0
t$perf-norm:sp_cancel_client_order 1826 1826 0 0.00 0 4144 13 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 3373 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 751 751 0 0.00 0 1831 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 473 473 0 0.00 0 1181 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 4820 19 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 19019 1219 0 0
t$perf-norm:sp_cancel_write_off 1037 1037 0 0.00 0 2077 5 0 0
t$perf-norm:sp_client_order 22380 22380 0 0.00 0 7014 15 0 0
t$perf-norm:sp_customer_reserve 1115 1115 0 0.00 0 9412 11 0 0
t$perf-norm:sp_pay_from_customer 7510 7510 0 0.00 0 7883 11 0 0
t$perf-norm:sp_pay_to_supplier 6334 6334 0 0.00 0 7105 10 0 0
t$perf-norm:sp_reserve_write_off 10942 10942 0 0.00 0 6152 11 0 0
t$perf-norm:sp_supplier_invoice 5843 5843 0 0.00 0 4704 17 0 0
t$perf-norm:sp_supplier_order 5955 5955 0 0.00 0 5499 13 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 293 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 218 4 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 528 15 0 0
t$perf-norm:srv_make_money_saldo 783 783 0 0.00 0 2375 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10836 10572 264 2.43 0 5090 16 0 264
v_all_customers 40260 40260 0 0.00 0 15 0 0 0
v_all_suppliers 32478 32478 0 0.00 0 14 1 0 0
v_all_wares 44760 44760 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 3472 14 0 0
v_cancel_client_order 3652 3652 0 0.00 0 2062 3 0 0
v_cancel_customer_prepayment 1502 1502 0 0.00 0 1125 5 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 972 4 0 0
v_cancel_payment_to_supplier 946 946 0 0.00 0 968 5 0 0
v_cancel_supplier_invoice 890 878 12 1.34 0 3147 18 0 12
v_cancel_supplier_order 820 820 0 0.00 0 5094 13 0 0
v_cancel_write_off 2074 2074 0 0.00 0 9180 15 0 0
v_max_id_clo_ord 5954 5954 0 0.00 0 1523 1 0 0
v_max_id_clo_res 1115 1115 0 0.00 0 6763 19 0 0
v_max_id_ord_sup 5842 5842 0 0.00 0 5538 6 0 0
v_max_non_paid_invoice 1891 1891 0 0.00 0 493 2 0 0
v_max_non_paid_realizn 4216 4216 0 0.00 0 5952 7 0 0
v_min_id_clo_ord 5954 5954 0 0.00 0 3661 7 0 0
v_min_id_clo_res 1115 1115 0 0.00 0 3128 11 0 0
v_min_id_ord_sup 5842 5842 0 0.00 0 6280 7 0 0
v_min_non_paid_invoice 6334 6334 0 0.00 0 16209 30 0 0
v_min_non_paid_realizn 7510 7510 0 0.00 0 9995 19 0 0
v_our_firm 11088 11088 0 0.00 0 15 1 0 0
v_reserve_write_off 21884 21884 0 0.00 0 4025 5 0 0
x_lock_depdocs_on_canc_invoice 11151 11151 0 0.00 0 28901 112 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 2477 249 0 0
x_make_qstorno_1000_1200 5955 5955 0 0.00 1 36255 1164 0 0
x_make_qstorno_1200_2000 5843 5843 0 0.00 4 97142 2906 0 0
x_make_qstorno_2100_3300 477381 477381 0 0.00 0 95189 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-05-2022 22:39:06 to 20-05-2022 22:39:10.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:23 14 8192000000 8806912288 8820985856 11748432 0.143 4 3 4 0 11906384 72992 84960
20:09:25 14945 8192000000 9890065840 10198286336 980629504 10.690 100 85 170 0 1008779936 5621008 22529424
20:10:46 23176 8192000000 9954384960 10278367232 1038404896 11.249 100 85 170 0 1068005312 7912624 21687792
20:12:15 16782 8192000000 9988179856 10321096704 1070686464 11.559 100 86 172 0 1101497072 9308096 21502512
20:13:36 14021 8192000000 10054248720 10404057088 1138805168 12.204 100 81 162 0 1168156928 10195584 19156176
20:14:53 9805 8192000000 10106400736 10469576704 1191940592 12.701 100 83 166 0 1222975792 12578416 18456784
20:16:04 7318 8192000000 10143062464 10517295104 1230616704 13.060 100 78 156 0 1261460672 13321584 17522384
20:17:14 9952 8192000000 10164409440 10544123904 1255646064 13.290 100 75 150 0 1285128592 13413040 16069488
20:18:26 1725 8192000000 10202613344 10585251840 1288809104 13.593 100 83 166 0 1319010432 12028304 18173024
20:19:29 25787 8192000000 10214953552 10605576192 1305277248 13.743 100 77 153 1 1333627136 11091792 17258096
20:20:55 4669 8192000000 10202649392 10611093504 1311695184 13.801 100 56 113 0 1332549568 8044480 12809904
20:22:00 9258 8192000000 10207217856 10617933824 1315913104 13.840 100 22 44 1 1327026736 6520848 4592784
20:23:10 33577 8192000000 10220804352 10625236992 1322089216 13.896 100 31 62 1 1334702912 5897200 6716496
20:24:44 9724 8192000000 10212425888 10636767232 1327488944 13.944 100 19 38 2 1336691904 5177648 4025312
20:25:55 6904 8192000000 10224825312 10645270528 1332375216 13.989 100 29 58 1 1344761120 5842432 6543472
20:27:03 7864 8192000000 10202727856 10618568704 1297198080 13.670 98 16 32 2 1306327504 5658464 3470960
20:28:16 17662 8192000000 10211793920 10636697600 1325991296 13.931 100 22 44 0 1337759552 6502288 5265968
20:29:34 6781 8192000000 10164029568 10578505728 1242651584 13.171 96 25 49 2 1254595840 6371232 5573024
20:30:41 9724 8192000000 10129208384 10525888512 1189480144 12.679 94 19 38 0 1198889744 5418992 3990608
20:31:55 41876 8192000000 10148617296 10548310016 1227232880 13.029 98 26 52 0 1239201072 6087152 5881040
20:33:57 6655 8192000000 10169041744 10573357056 1273090720 13.450 100 34 68 1 1287984832 6720592 8173520
20:35:04 11424 8192000000 10176648768 10587234304 1284889792 13.558 100 19 37 0 1294637328 5443920 4303616
20:36:17 16502 8192000000 10192995568 10608467968 1301066992 13.705 100 14 27 2 1313517344 9557936 2892416
20:37:34 43535 8192000000 10198651888 10620796928 1310919136 13.794 100 26 52 0 1321590656 4610304 6061216
20:39:29 11320 8192000000 10246933376 10658004992 1339258272 14.051 100 43 84 0 1358725728 9811792 9655664
20:40:44 43443 8192000000 10273083216 10694639616 1358418416 14.223 100 39 78 2 1381852720 14728928 8705376
20:42:31 17549 8192000000 10287356208 10708754432 1373842736 14.361 100 40 80 1 1401546016 18243968 9459312
20:43:50 9674 8192000000 10302330560 10721091584 1383698144 14.450 100 40 80 0 1418271584 24844432 9729008
20:45:04 10542 8192000000 10309786672 10738593792 1392508992 14.528 100 37 73 2 1421575456 19588096 9478368
20:46:22 17899 8192000000 10330373616 10754293760 1402984480 14.622 100 48 92 3 1438241952 23757648 11499824
20:47:43 2266 8192000000 10352585504 10772115456 1416892656 14.745 100 82 164 2 1467542432 30280640 20369136
20:48:48 1896 8192000000 10359947072 10779856896 1416982496 14.746 100 77 154 0 1472326768 36327760 19016512
20:49:58 7572 8192000000 10355047200 10770092032 1402611440 14.618 99 74 148 0 1459077696 38346960 18119296
20:51:10 3478 8192000000 10374389504 10791342080 1417537952 14.751 100 78 156 1 1480584912 43189872 19857088
20:52:17 6440 8192000000 10369110896 10782789632 1401498896 14.608 99 67 134 0 1459625536 40912080 17214560
20:53:26 4514 8192000000 10355418432 10773458944 1404781600 14.638 100 63 124 0 1463914240 43110320 16022320
20:54:33 17883 8192000000 10327832112 10749116416 1364130288 14.274 98 38 76 1 1412342112 37910192 10301632
20:55:55 10065 8192000000 10307798352 10722324480 1349407856 14.142 98 50 102 2 1401287824 37148128 14731840
20:57:10 19545 8192000000 10299612656 10725343232 1344718976 14.100 98 27 54 3 1385966384 33691968 7555440
20:58:31 27413 8192000000 10299908064 10737025024 1359055200 14.229 99 16 32 0 1393062496 29202128 4805168
21:00:01 25170 8192000000 10253738800 10685235200 1300104384 13.696 97 15 30 0 1329822448 25060912 4657152
21:01:32 68440 8192000000 10240016464 10676740096 1312883424 13.812 97 22 44 1 1327866368 10107344 4875600
21:03:43 61937 8192000000 10234631184 10673139712 1329015376 13.958 99 32 64 3 1347458688 11346512 7096800
21:05:50 95169 8192000000 10255477328 10704678912 1346199312 14.113 99 12 24 1 1362718576 13688496 2830768
21:08:35 79204 8192000000 10258734448 10709778432 1351239584 14.159 99 9 17 0 1362109984 8938784 1931616
21:10:55 115628 8192000000 10242824352 10692292608 1330708640 13.974 98 32 64 0 1347755184 9675856 7370688
21:13:58 6853 8192000000 10247611344 10688589824 1340603120 14.063 99 27 52 0 1353381072 6996432 5781520
21:15:11 56171 8192000000 10263996480 10711375872 1367410160 14.304 100 15 30 1 1380382160 8921184 4050816
21:17:08 37934 8192000000 10283714848 10724917248 1374058512 14.363 100 15 30 0 1388218320 10734176 3425632
21:18:53 61371 8192000000 10289627008 10745724928 1370874624 14.335 99 26 52 2 1395352448 18141808 6336016
21:21:05 38131 8192000000 10292797520 10742808576 1377218240 14.392 99 34 68 2 1397626448 12829536 7578672
21:22:45 57428 8192000000 10295136176 10739101696 1372754848 14.352 99 36 72 1 1391983216 11112272 8116096
21:24:43 34526 8192000000 10313075056 10752053248 1394812592 14.549 100 32 64 0 1416625392 14946432 6866368
21:26:30 109812 8192000000 10295801648 10740060160 1355572640 14.198 97 10 20 0 1372566288 14785232 2208416
21:29:20 73388 8192000000 10298350816 10743218176 1393874208 14.540 100 38 76 0 1412370832 9508736 8987888
21:31:35 23562 8192000000 10270442976 10712121344 1327238816 13.942 96 26 52 0 1344986832 11864832 5883184
21:33:00 30694 8192000000 10251294720 10704257024 1343655344 14.090 98 13 26 0 1355109840 8423168 3031328
21:34:33 40620 8192000000 10265218288 10716450816 1355639024 14.198 99 15 30 0 1367047824 7919456 3489344
21:36:34 39508 8192000000 10250845936 10708336640 1333060160 13.995 98 6 12 0 1346230384 12190496 979728
21:38:14 37518 8192000000 10286314064 10740776960 1373176160 14.355 100 17 35 0 1388216416 11150992 3889264
21:39:57 73744 8192000000 10298940416 10754113536 1350342832 14.151 97 16 32 0 1376783888 22297056 4144000
21:42:11 16856 8192000000 10282704896 10722877440 1349819872 14.146 97 26 52 0 1372284496 16751136 5713488
21:43:28 66853 8192000000 10306781952 10749730816 1381616384 14.431 100 16 32 1 1401860272 16666320 3577568
21:45:39 43181 8192000000 10254874160 10697293824 1310696816 13.792 96 48 96 1 1337150416 15336160 11117440
21:47:31 69425 8192000000 10276731392 10717888512 1325404528 13.926 97 19 38 1 1340178880 10644208 4130144
21:49:44 62649 8192000000 10270283616 10710114304 1329876608 13.966 98 16 32 0 1349711152 16209680 3624864
21:51:54 72424 8192000000 10238214576 10678743040 1315065296 13.832 97 16 32 0 1327504384 8588608 3850480
21:54:28 46869 8192000000 10272911568 10709762048 1330820704 13.975 98 31 59 2 1345385280 7799648 6764928
21:56:22 42476 8192000000 10249657712 10702737408 1334412400 14.007 98 19 38 1 1347991888 9110240 4469248
21:58:10 62102 8192000000 10276693632 10722578432 1354242560 14.186 99 8 16 1 1368612832 12174560 2195712
22:00:16 68188 8192000000 10262768384 10720825344 1368535344 14.314 100 11 22 0 1377425792 6445904 2444544
22:02:35 71006 8192000000 10272652256 10742648832 1371248816 14.338 99 12 24 0 1382916976 8951152 2717008
22:05:00 83763 8192000000 10298995280 10760732672 1367951504 14.309 99 17 34 0 1383709440 12050656 3707280
22:08:00 28096 8192000000 10294194288 10731425792 1355882480 14.200 98 45 91 3 1375463936 9938480 9642976
22:09:28 39771 8192000000 10292503568 10737471488 1349574256 14.144 97 19 38 1 1360295472 6930336 3790880
22:11:10 36593 8192000000 10302970720 10743754752 1383188496 14.445 100 37 74 2 1412065888 20191184 8686208
22:13:20 77553 8192000000 10297816208 10760650752 1377153520 14.391 99 6 12 1 1394026352 15841472 1031360
22:15:41 19109 8192000000 10282223120 10719182848 1344107728 14.094 97 39 79 0 1363116112 10432032 8576352
22:17:06 137223 8192000000 10284133152 10731606016 1362033344 14.256 99 17 34 0 1379465760 13158064 4274352
22:20:24 40384 8192000000 10293218032 10746060800 1382226752 14.436 100 13 26 0 1396440464 11344112 2869600
22:22:21 48463 8192000000 10274899536 10747195392 1368932768 14.317 99 9 18 1 1384263248 12919440 2411040
22:24:09 66998 8192000000 10260563440 10709315584 1271685744 13.437 92 11 22 0 1286997072 12559168 2752160
22:26:32 86601 8192000000 10265393712 10727776256 1347161632 14.122 99 17 33 0 1374343664 22970592 4211440
22:28:59 59673 8192000000 10263059376 10718494720 1338057440 14.040 98 17 34 1 1354337408 12356384 3923584
22:31:09 59374 8192000000 10267400672 10717052928 1323175008 13.905 97 22 44 0 1336662112 8490512 4996592
22:33:44 70431 8192000000 10266174016 10707406848 1317199344 13.851 96 25 50 2 1336738336 13771792 5767200
22:35:55 148719 8192000000 9673008928 10025472000 772576912 8.618 57 3 6 0 786413936 13215616 621408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-05-2022 22:39:12 to 20-05-2022 22:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 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 20-05-2022 22:39:27 to 20-05-2022 22:39:29.

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          248416
MON$OLDEST_ACTIVE               249230
MON$OLDEST_SNAPSHOT             249230
MON$NEXT_TRANSACTION            249230
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                       1993664
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.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 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 ---
:72: 
:73: mscs-test-161.budzdorov.ru    Fri May 20 22:38:30 2022
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Fri May 20 22:38:30 2022
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Fri May 20 22:38:30 2022
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Fri May 20 22:38:30 2022
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Fri May 20 22:38:30 2022
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Fri May 20 22:38:30 2022
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:95: 
--- end of diff output ---

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

20.05.22 22:39:30. 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-20 22:39:33 No severe PSQL-related problems occured
20.05.22 22:39:34. End of report.