Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.11.2021 01: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15809488 kB
MemAvailable 30814192 kB
Buffers 0 kB
Cached 15859024 kB
SwapCached 4364 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.8
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 2021-11-01 01:37:57
DTS_END                         2021-11-01 01:37:57.7540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58074
TRN_ID                          242652
ATT_ID                          724
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: 9327.85 23864 1119342
customer order: creation 175.93 372 21111
customer order: refuse 14.87 398 1784
order to supplier: creation 48.15 1317 5778
order to supplier: removal 3.24 115022 389
invoice (draft): creation 46.25 3847 5550
invoice (draft): removal 51.74 833 6209
invoice accept: apply 42.48 18219 5097
invoice accept: cancel 47.28 852 5674
customer reserve: creation 3938.63 178 472636
customer reserve: removal 4657.23 63 558868
realization accept: apply 85.69 194 10283
realization accept: cancel 84.47 115 10136
payment from customer: creation 61.08 122 7329
payment from customer: removal 5.67 98 680
payment to supplier: creation 49.30 130 5916
payment to supplier: removal 3.30 88 396
service: total inventory turnovers 4.71 1385 565
service: total monetary turnovers 7.00 78 840
service: aggregate perf. data 0.81 22618 97
service: refresh index statistics 0.03 311359 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-11-2021 01:39:02 to 01-11-2021 01:39:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3265
TEST_TIME 2 3609
TEST_TIME 3 4503
TEST_TIME 4 4368
TEST_TIME 5 3835
TEST_TIME 6 3876
TEST_TIME 7 4125
TEST_TIME 8 4707
TEST_TIME 9 5323
TEST_TIME 10 5973
TEST_TIME 11 6206
TEST_TIME 12 6674
TEST_TIME 13 7132
TEST_TIME 14 7251
TEST_TIME 15 7610
TEST_TIME 16 7955
TEST_TIME 17 8182
TEST_TIME 18 8452
TEST_TIME 19 8716
TEST_TIME 20 8863
TEST_TIME 21 8834
TEST_TIME 22 9058
TEST_TIME 23 9195
TEST_TIME 24 9321
TEST_TIME 25 9403
TEST_TIME 26 9442
TEST_TIME 27 9266
TEST_TIME 28 9606
TEST_TIME 29 10215
TEST_TIME 30 10570
TEST_TIME 31 10630
TEST_TIME 32 10744
TEST_TIME 33 11620
TEST_TIME 34 11404
TEST_TIME 35 11224
TEST_TIME 36 11052
TEST_TIME 37 10929
TEST_TIME 38 10758
TEST_TIME 39 10612
TEST_TIME 40 10483
TEST_TIME 41 10458
TEST_TIME 42 10368
TEST_TIME 43 10345
TEST_TIME 44 10231
TEST_TIME 45 10184
TEST_TIME 46 10199
TEST_TIME 47 10172
TEST_TIME 48 10009
TEST_TIME 49 9946
TEST_TIME 50 9838
TEST_TIME 51 9966
TEST_TIME 52 9881
TEST_TIME 53 9802
TEST_TIME 54 9664
TEST_TIME 55 9593
TEST_TIME 56 9455
TEST_TIME 57 9651
TEST_TIME 58 9668
TEST_TIME 59 9597
TEST_TIME 60 9531
TEST_TIME 61 9556
TEST_TIME 62 9490
TEST_TIME 63 9422
TEST_TIME 64 9377
TEST_TIME 65 9405
TEST_TIME 66 9377
TEST_TIME 67 9324
TEST_TIME 68 9369
TEST_TIME 69 9420
TEST_TIME 70 9398
TEST_TIME 71 9442
TEST_TIME 72 9434
TEST_TIME 73 9438
TEST_TIME 74 9487
TEST_TIME 75 9494
TEST_TIME 76 9460
TEST_TIME 77 9478
TEST_TIME 78 9468
TEST_TIME 79 9398
TEST_TIME 80 9341
TEST_TIME 81 9342
TEST_TIME 82 9307
TEST_TIME 83 9274
TEST_TIME 84 9234
TEST_TIME 85 9186
TEST_TIME 86 9361
TEST_TIME 87 9345
TEST_TIME 88 9306
TEST_TIME 89 9326
TEST_TIME 90 9303
TEST_TIME 91 9298
TEST_TIME 92 9340
TEST_TIME 93 9424
TEST_TIME 94 9400
TEST_TIME 95 9400
TEST_TIME 96 9403
TEST_TIME 97 9488
TEST_TIME 98 9495
TEST_TIME 99 9457
TEST_TIME 100 9430
TEST_TIME 101 9378
TEST_TIME 102 9373
TEST_TIME 103 9338
TEST_TIME 104 9370
TEST_TIME 105 9316
TEST_TIME 106 9297
TEST_TIME 107 9266
TEST_TIME 108 9216
TEST_TIME 109 9160
TEST_TIME 110 9218
TEST_TIME 111 9259
TEST_TIME 112 9275
TEST_TIME 113 9283
TEST_TIME 114 9263
TEST_TIME 115 9288
TEST_TIME 116 9306
TEST_TIME 117 9281
TEST_TIME 118 9259
TEST_TIME 119 9267
TEST_TIME 120 9331

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-11-2021 01:39:06 to 01-11-2021 01:39:09.

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 1556602 1556602 0 0.00 0 16254 6 0 0
doc_list_biud 1556601 1556601 0 0.00 0 61094 29 0 0
sp_add_invoice_to_stock 5197 5097 100 1.92 62 148059 18219 0 100
sp_cancel_adding_invoice 5674 5674 0 0.00 4 51172 853 0 0
sp_cancel_client_order 1784 1784 0 0.00 4 14009 399 0 0
sp_cancel_customer_reserve 558868 558868 0 0.00 1 61097 63 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 6715 98 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 3802 89 0 0
sp_cancel_supplier_invoice 6216 6209 7 0.11 1 14452 834 0 7
sp_cancel_supplier_order 389 389 0 0.00 6 1201172 115023 0 0
sp_cancel_write_off 10136 10136 0 0.00 1 14352 115 0 0
sp_client_order 21111 21111 0 0.00 3 28412 373 0 0
sp_customer_reserve 472636 472636 0 0.00 0 40340 179 0 0
sp_fill_shopping_cart 32423 32423 0 0.00 1 47252 51 0 0
sp_fill_shopping_cart_clo_res 472632 472632 0 0.00 0 19667 14 0 0
sp_get_clo_for_invoice 5093 5093 0 0.00 0 33146 350 0 0
sp_kill_cost_storno 16886 16886 0 0.00 0 8364 59 0 0
sp_kill_qstorno_ret_qs2qd 1134470 1134470 0 0.00 0 61091 34 0 0
sp_kill_qty_storno 598435 598435 0 0.00 0 61094 74 0 0
sp_lock_selected_doc 21167 21167 0 0.00 0 8381 4 0 0
sp_make_cost_storno 28621 28621 0 0.00 0 15044 56 0 0
sp_multiply_rows_for_pdistr 28621 28621 0 0.00 0 9158 31 0 0
sp_multiply_rows_for_qdistr 21111 21111 0 0.00 0 14396 158 0 0
sp_pay_from_customer 7329 7329 0 0.00 4 12403 123 0 0
sp_pay_to_supplier 5916 5916 0 0.00 4 21521 130 0 0
sp_payment_common 13245 13245 0 0.00 1 13982 27 0 0
sp_qd_handle_on_cancel_clo 1784 1784 0 0.00 0 9951 357 0 0
sp_qd_handle_on_invoice_upd_sts 10767 10767 0 0.00 0 23839 376 0 0
sp_qd_handle_on_reserve_upd_sts 10282 10282 0 0.00 0 22237 64 0 0
sp_reserve_write_off 10283 10283 0 0.00 6 22263 195 0 0
sp_supplier_invoice 5550 5550 0 0.00 6 91413 3847 0 0
sp_supplier_order 5778 5778 0 0.00 5 36288 1317 0 0
srv_aggregate_perf_data 97 97 0 0.00 2898 277747 22619 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1073 145946 44526 0 0
srv_find_qd_qs_mism 1070366 1070366 0 0.00 0 16254 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 28 68138 1386 0 0
srv_make_money_saldo 840 840 0 0.00 1 25033 78 0 0
srv_recalc_idx_stat 4 4 0 0.00 134277 422845 311359 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5097 5097 0 0.00 0 22566 210 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 12136 161 0 0
t$perf-norm:sp_cancel_client_order 1784 1784 0 0.00 0 4035 20 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 14175 44 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 3279 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 222 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 894 18 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 27185 1957 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 2789 9 0 0
t$perf-norm:sp_client_order 21111 21111 0 0.00 0 15031 24 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 11149 24 0 0
t$perf-norm:sp_pay_from_customer 7329 7329 0 0.00 0 7806 17 0 0
t$perf-norm:sp_pay_to_supplier 5916 5916 0 0.00 0 9654 19 0 0
t$perf-norm:sp_reserve_write_off 10283 10283 0 0.00 0 6717 12 0 0
t$perf-norm:sp_supplier_invoice 5550 5550 0 0.00 0 6228 18 0 0
t$perf-norm:sp_supplier_order 5778 5778 0 0.00 0 7839 19 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 275 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1679 26 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 931 19 0 0
t$perf-norm:srv_make_money_saldo 840 840 0 0.00 0 1912 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 10380 10180 200 1.92 0 16705 23 0 200
v_all_customers 38186 38186 0 0.00 0 24 1 0 0
v_all_suppliers 31000 31000 0 0.00 0 39 1 0 0
v_all_wares 42220 42220 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 2160 10 0 0
v_cancel_client_order 3568 3568 0 0.00 0 12777 14 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 2702 9 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 17171 26 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 2996 10 0 0
v_cancel_supplier_invoice 798 784 14 1.75 0 2148 17 0 14
v_cancel_supplier_order 778 778 0 0.00 0 1252 6 0 0
v_cancel_write_off 2070 2070 0 0.00 0 7521 19 0 0
v_max_id_clo_ord 5778 5778 0 0.00 0 4567 4 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 1497 13 0 0
v_max_id_ord_sup 5535 5535 0 0.00 0 11038 11 0 0
v_max_non_paid_invoice 1729 1729 0 0.00 0 1387 3 0 0
v_max_non_paid_realizn 4092 4092 0 0.00 0 4302 4 0 0
v_min_id_clo_ord 5778 5778 0 0.00 0 10622 10 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 2742 7 0 0
v_min_id_ord_sup 5535 5535 0 0.00 0 8655 10 0 0
v_min_non_paid_invoice 5916 5916 0 0.00 0 13978 13 0 0
v_min_non_paid_realizn 7329 7329 0 0.00 0 12388 24 0 0
v_our_firm 10508 10508 0 0.00 0 45 1 0 0
v_reserve_write_off 20564 20564 0 0.00 0 21499 13 0 0
x_lock_depdocs_on_canc_invoice 11491 11491 0 0.00 0 15820 125 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 16219 415 0 0
x_make_qstorno_1000_1200 5778 5778 0 0.00 1 36264 1218 0 0
x_make_qstorno_1200_2000 5550 5550 0 0.00 2 91197 3656 0 0
x_make_qstorno_2100_3300 472636 472636 0 0.00 0 40339 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-11-2021 01:39:10 to 01-11-2021 01:39:14.

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:01 11 8192000000 8805938384 8819806208 10610912 0.129 4 1 2 0 10770816 71328 88576
23:09:01 25677 8192000000 9910499696 10223493120 996328656 10.843 100 88 176 1 1025997552 6072496 23596400
23:10:33 19758 8192000000 9958907824 10283892736 1039295696 11.258 100 91 182 0 1071058656 8133632 23629328
23:11:54 23661 8192000000 10005917888 10345299968 1086679200 11.711 100 81 162 0 1116469552 9349792 20440560
23:13:21 15349 8192000000 10046886816 10396610560 1128334752 12.106 100 75 149 1 1157210752 11364128 17511872
23:14:39 8352 8192000000 10096211328 10460495872 1183080512 12.619 100 79 158 0 1213782528 12687392 18014624
23:15:51 6742 8192000000 10133288176 10509983744 1223236112 12.992 100 77 154 2 1253209264 12748880 17224272
23:17:00 4103 8192000000 10165803072 10543636480 1250334576 13.241 100 91 181 0 1283986848 13847920 19804352
23:18:05 1252 8192000000 10195529184 10578391040 1278648720 13.501 100 89 178 0 1312218944 13555280 20014944
23:19:08 1613 8192000000 10214680656 10600001536 1294892464 13.649 100 93 184 2 1329375536 12548928 21934144
23:20:11 21330 8192000000 10200013072 10606419968 1303399072 13.726 100 42 84 1 1319864048 6117264 10347712
23:21:34 6517 8192000000 10192126928 10610769920 1310705952 13.792 100 27 52 1 1323786736 6485920 6594864
23:22:43 6929 8192000000 10198266576 10616606720 1314935776 13.831 100 23 46 1 1327282128 6239968 6106384
23:23:53 14381 8192000000 10203736160 10625175552 1320450960 13.881 100 26 50 2 1332232432 5677056 6104416
23:25:08 13081 8192000000 10218593152 10640535552 1324332944 13.916 100 42 84 0 1341224864 7690144 9201776
23:26:22 4573 8192000000 10222913024 10640474112 1325765216 13.929 100 31 62 1 1340440416 7024080 7651120
23:27:28 6715 8192000000 10196738208 10617614336 1282659712 13.537 97 30 59 3 1294641536 5695360 6286464
23:28:40 8714 8192000000 10191998096 10607276032 1291452000 13.617 99 20 40 1 1301996880 6184736 4360144
23:29:49 42481 8192000000 10169917216 10578358272 1258022512 13.312 98 34 68 0 1272777600 6756256 7998832
23:31:38 6079 8192000000 10136904160 10535501824 1217541856 12.939 97 22 45 1 1236957744 14498928 4916960
23:32:46 6410 8192000000 10151879200 10550403072 1241853984 13.163 99 37 74 0 1257132256 6098896 9179376
23:33:56 8469 8192000000 10178549872 10579820544 1280458544 13.517 100 16 32 2 1288896544 4777344 3660656
23:35:05 47518 8192000000 10176550112 10590756864 1288877248 13.594 100 39 78 1 1304161712 5480208 9804256
23:36:56 8249 8192000000 10186222128 10601205760 1296932976 13.667 100 22 44 1 1308105200 5453376 5718848
23:38:08 11346 8192000000 10222832560 10626408448 1312328528 13.807 100 43 86 0 1328251472 5884912 10038032
23:39:27 5109 8192000000 10252678992 10664099840 1338185760 14.041 100 49 97 0 1360255424 11487744 10581920
23:40:35 128348 8192000000 10280800176 10708303872 1368471280 14.313 100 38 76 2 1394616768 16685248 9460240
23:43:49 19421 8192000000 10297432368 10720641024 1381279696 14.428 100 47 92 0 1411309216 19209136 10820384
23:45:17 1072 8192000000 10317233456 10723282944 1379352384 14.411 99 86 170 0 1421828464 23512016 18964064
23:46:21 11273 8192000000 10313711504 10730954752 1388372320 14.491 100 59 118 0 1429061712 26441232 14248160
23:47:37 3308 8192000000 10328653568 10747523072 1398437856 14.581 100 78 156 0 1442483712 25139152 18906704
23:48:44 8560 8192000000 10328300768 10746400768 1389215456 14.499 100 66 132 0 1433395728 28291968 15888304
23:50:11 3790 8192000000 10319085536 10731581440 1379709680 14.414 100 64 124 1 1424460912 29456448 15294784
23:51:18 4831 8192000000 10328573344 10741882880 1384490992 14.457 100 75 150 1 1435090144 31603744 18995408
23:52:29 2865 8192000000 10350788080 10762969088 1400377040 14.598 100 84 168 0 1453545440 32566336 20602064
23:53:35 6908 8192000000 10338266592 10755969024 1398703456 14.583 100 58 116 2 1442978576 29159376 15115744
23:54:51 5600 8192000000 10326299952 10757832704 1394498240 14.546 100 45 90 1 1437440752 31573376 11369136
23:56:03 12514 8192000000 10323368656 10757099520 1381463088 14.430 99 38 75 0 1424625776 34037072 9125616
23:57:23 9256 8192000000 10314078768 10734542848 1365168256 14.284 100 51 102 1 1416483344 38209104 13105984
23:58:39 3019 8192000000 10323809408 10730582016 1356398112 14.205 100 91 181 2 1417761072 38221488 23141472
23:59:49 4867 8192000000 10333521824 10745610240 1376847040 14.388 100 69 138 3 1436319408 42272928 17199440
00:01:25 11402 8192000000 10323607664 10749423616 1368167952 14.311 99 31 62 1 1422831024 45903312 8759760
00:02:44 7377 8192000000 10350123168 10771312640 1391923872 14.523 100 46 90 0 1448672768 44764416 11984480
00:03:57 13445 8192000000 10359694560 10786541568 1399546000 14.591 100 39 78 0 1464215056 53704720 10964336
00:05:14 14694 8192000000 10377511424 10814808064 1414801328 14.727 100 34 68 0 1488051376 63521712 9728336
00:06:37 32572 8192000000 10372628848 10840924160 1411828016 14.700 100 29 58 0 1484972576 63926272 9218288
00:08:33 78746 8192000000 10332524336 10775953408 1377518112 14.394 100 28 56 1 1440096336 54869792 7708432
00:10:54 70746 8192000000 10288534608 10728337408 1357687360 14.217 99 15 30 0 1378269520 17825616 2756544
00:13:07 52216 8192000000 10304271952 10739515392 1373710080 14.360 99 17 33 1 1384377024 7192736 3474208
00:15:51 89274 8192000000 10293320224 10738712576 1354663152 14.189 98 32 64 2 1372813664 10650208 7500304
00:18:28 145946 8192000000 10302057808 10731728896 1316658224 13.846 95 26 52 1 1332202864 8946240 6598400
00:21:54 66931 8192000000 10299756576 10750500864 1372282800 14.347 99 37 74 1 1395620864 14397056 8941008
00:24:16 43171 8192000000 10277530032 10736164864 1374551392 14.368 99 14 28 0 1387082080 9181264 3349424
00:26:01 36980 8192000000 10302712432 10750500864 1375199600 14.374 99 29 58 0 1390744544 8681552 6863392
00:27:40 30561 8192000000 10313134000 10756501504 1387478352 14.483 100 44 87 0 1412255888 12939168 11838368
00:29:13 30022 8192000000 10307091616 10766917632 1392814080 14.531 100 19 38 1 1412959104 15926304 4218720
00:30:53 63444 8192000000 10299681504 10761302016 1364912784 14.281 97 9 18 0 1383901008 16795488 2192736
00:33:05 70994 8192000000 10282434784 10740453376 1358882176 14.227 99 21 43 0 1385816032 21252000 5681856
00:35:18 54362 8192000000 10261129232 10718339072 1330146288 13.968 97 21 43 0 1349443904 14459184 4838432
00:37:21 52888 8192000000 10291169104 10742198272 1367085696 14.301 99 38 75 2 1390358160 14848688 8423776
00:39:14 38550 8192000000 10267101168 10728382464 1367882112 14.308 99 8 16 0 1377349920 7893632 1574176
00:40:55 45276 8192000000 10267417072 10734817280 1373157664 14.355 100 9 18 0 1381295904 6331600 1806640
00:42:43 77937 8192000000 10264860752 10725306368 1309487616 13.781 95 6 12 0 1322189856 11767024 935216
00:45:01 18368 8192000000 10288104224 10735009792 1359895952 14.236 99 51 101 1 1384828144 13847536 11084656
00:46:26 74421 8192000000 10263334224 10713608192 1317468640 13.854 96 18 34 3 1334028464 12637952 3921872
00:48:51 70809 8192000000 10264650640 10708213760 1288462240 13.590 95 20 40 1 1304697616 11961904 4273472
00:51:06 66330 8192000000 10263873008 10701971456 1302327552 13.716 96 11 22 0 1317831120 13021360 2482208
00:53:16 23777 8192000000 10290631984 10727763968 1371577968 14.341 100 37 72 3 1389351856 10134512 7639376
00:54:43 46769 8192000000 10284501984 10725072896 1342894160 14.083 98 31 62 1 1361107216 11692896 6520160
00:56:30 75102 8192000000 10273359440 10723319808 1357555072 14.215 99 27 54 0 1371314192 7702352 6056768
00:58:47 48235 8192000000 10257094880 10708713472 1356355248 14.205 99 31 62 0 1378128928 14468688 7304992
01:01:49 65906 8192000000 10279674336 10730426368 1347229776 14.123 98 14 28 1 1364700544 14148304 3322464
01:04:05 75886 8192000000 10271284080 10713325568 1315170000 13.833 96 22 44 2 1330859776 10329408 5360368
01:06:21 63645 8192000000 10278124512 10725384192 1356764896 14.208 99 23 46 1 1373096080 10971600 5359584
01:08:32 49772 8192000000 10298482320 10746138624 1375665232 14.378 99 28 55 0 1392879680 11266272 5948176
01:10:23 99958 8192000000 10283315312 10738200576 1353234064 14.177 98 8 16 0 1367765920 13078288 1453568
01:13:05 35940 8192000000 10304619168 10751549440 1378969584 14.407 99 18 36 0 1393020064 10052624 3997856
01:14:41 93057 8192000000 10300643760 10758586368 1360680544 14.243 98 12 22 2 1375498560 11654912 3163104
01:17:15 81475 8192000000 10290035616 10741202944 1371894912 14.344 99 33 66 2 1389617712 9512544 8210256
01:19:37 16814 8192000000 10269877296 10729836544 1355191104 14.194 99 13 26 1 1372145552 14204048 2750400
01:20:54 63333 8192000000 10268451280 10740928512 1365400736 14.286 99 13 26 0 1384087520 15847648 2839136
01:22:58 69100 8192000000 10278597648 10735288320 1343193760 14.086 97 17 34 0 1357341008 10249504 3897744
01:25:10 83568 8192000000 10299790368 10755977216 1375735552 14.378 99 14 28 0 1394098736 15057024 3306160
01:27:34 30710 8192000000 10289153136 10750894080 1364632352 14.279 98 19 38 2 1387407376 18689040 4085984
01:29:05 58182 8192000000 10270241728 10725089280 1347246880 14.123 97 19 38 0 1362884048 11609584 4027584
01:31:09 47218 8192000000 10279404224 10722529280 1357063392 14.211 98 27 54 1 1372618752 9258624 6296736
01:33:02 70557 8192000000 10252301728 10701369344 1325046672 13.922 98 19 38 0 1339155728 10084304 4024752
01:35:26 39325 8192000000 10283229264 10721775616 1348106896 14.130 99 44 88 1 1376934000 18736688 10090416
01:37:07 56125 8192000000 9567869200 9909444608 693013584 7.799 51 7 14 2 718966848 24387984 1565280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-11-2021 01:39:16 to 01-11-2021 01:39:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-11-2021 01:39:32 to 01-11-2021 01:39:34.

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          241243
MON$OLDEST_ACTIVE               242869
MON$OLDEST_SNAPSHOT             242869
MON$NEXT_TRANSACTION            242869
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                       2061856
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33525 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33525 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33525 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33525 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 ---
:141: 
:142: mscs-test-161.budzdorov.ru    Mon Nov  1 01:38:06 2021
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:144: 
:145: 
:146: mscs-test-161.budzdorov.ru    Mon Nov  1 01:38:19 2021
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:148: 
:149: 
:150: mscs-test-161.budzdorov.ru    Mon Nov  1 01:38:19 2021
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:152: 
--- end of diff output ---

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

01.11.21 01:39:35. 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
2021-11-01 01:39:37 No severe PSQL-related problems occured
01.11.21 01:39:38. End of report.