Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.08.2021 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 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 16288700 kB
MemAvailable 31017004 kB
Buffers 0 kB
Cached 15416864 kB
SwapCached 2688 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-08-17 01:37:46
DTS_END                         2021-08-17 01:37:46.3270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53094
TRN_ID                          232980
ATT_ID                          686
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: 9470.78 18659 1136494
customer order: creation 163.55 399 19626
customer order: refuse 14.33 377 1720
order to supplier: creation 42.75 1303 5130
order to supplier: removal 3.58 100097 430
invoice (draft): creation 43.59 3806 5231
invoice (draft): removal 56.80 782 6816
invoice accept: apply 40.63 16360 4876
invoice accept: cancel 52.23 766 6268
customer reserve: creation 3685.61 163 442273
customer reserve: removal 5078.09 55 609371
realization accept: apply 81.37 197 9764
realization accept: cancel 84.98 113 10197
payment from customer: creation 56.12 122 6734
payment from customer: removal 5.35 87 642
payment to supplier: creation 46.66 173 5599
payment to supplier: removal 3.40 75 408
service: total inventory turnovers 4.47 1292 536
service: total monetary turnovers 6.38 39 766
service: aggregate perf. data 0.86 23790 103
service: refresh index statistics 0.03 223178 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-08-2021 01:39:47 to 17-08-2021 01:39:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3251
TEST_TIME 2 3584
TEST_TIME 3 2994
TEST_TIME 4 3083
TEST_TIME 5 3295
TEST_TIME 6 3504
TEST_TIME 7 3928
TEST_TIME 8 4247
TEST_TIME 9 4342
TEST_TIME 10 4889
TEST_TIME 11 5276
TEST_TIME 12 5332
TEST_TIME 13 5643
TEST_TIME 14 5910
TEST_TIME 15 5950
TEST_TIME 16 6780
TEST_TIME 17 7386
TEST_TIME 18 7499
TEST_TIME 19 7665
TEST_TIME 20 7507
TEST_TIME 21 8066
TEST_TIME 22 9289
TEST_TIME 23 9470
TEST_TIME 24 9447
TEST_TIME 25 10026
TEST_TIME 26 10082
TEST_TIME 27 11133
TEST_TIME 28 11033
TEST_TIME 29 10980
TEST_TIME 30 10737
TEST_TIME 31 10609
TEST_TIME 32 10611
TEST_TIME 33 10476
TEST_TIME 34 10438
TEST_TIME 35 10321
TEST_TIME 36 10262
TEST_TIME 37 10128
TEST_TIME 38 10084
TEST_TIME 39 10099
TEST_TIME 40 10105
TEST_TIME 41 9955
TEST_TIME 42 9845
TEST_TIME 43 9869
TEST_TIME 44 9787
TEST_TIME 45 9835
TEST_TIME 46 9765
TEST_TIME 47 9617
TEST_TIME 48 9554
TEST_TIME 49 9465
TEST_TIME 50 9437
TEST_TIME 51 9574
TEST_TIME 52 9489
TEST_TIME 53 9444
TEST_TIME 54 9538
TEST_TIME 55 9601
TEST_TIME 56 9580
TEST_TIME 57 9545
TEST_TIME 58 9462
TEST_TIME 59 9493
TEST_TIME 60 9745
TEST_TIME 61 9780
TEST_TIME 62 9788
TEST_TIME 63 9763
TEST_TIME 64 9872
TEST_TIME 65 9894
TEST_TIME 66 9825
TEST_TIME 67 9713
TEST_TIME 68 9762
TEST_TIME 69 9674
TEST_TIME 70 9651
TEST_TIME 71 9616
TEST_TIME 72 9550
TEST_TIME 73 9573
TEST_TIME 74 9485
TEST_TIME 75 9444
TEST_TIME 76 9471
TEST_TIME 77 9407
TEST_TIME 78 9372
TEST_TIME 79 9401
TEST_TIME 80 9352
TEST_TIME 81 9346
TEST_TIME 82 9404
TEST_TIME 83 9476
TEST_TIME 84 9594
TEST_TIME 85 9568
TEST_TIME 86 9528
TEST_TIME 87 9592
TEST_TIME 88 9605
TEST_TIME 89 9651
TEST_TIME 90 9676
TEST_TIME 91 9578
TEST_TIME 92 9547
TEST_TIME 93 9526
TEST_TIME 94 9580
TEST_TIME 95 9525
TEST_TIME 96 9478
TEST_TIME 97 9465
TEST_TIME 98 9445
TEST_TIME 99 9494
TEST_TIME 100 9473
TEST_TIME 101 9450
TEST_TIME 102 9461
TEST_TIME 103 9432
TEST_TIME 104 9433
TEST_TIME 105 9569
TEST_TIME 106 9585
TEST_TIME 107 9623
TEST_TIME 108 9625
TEST_TIME 109 9655
TEST_TIME 110 9634
TEST_TIME 111 9679
TEST_TIME 112 9662
TEST_TIME 113 9589
TEST_TIME 114 9570
TEST_TIME 115 9525
TEST_TIME 116 9487
TEST_TIME 117 9520
TEST_TIME 118 9522
TEST_TIME 119 9497
TEST_TIME 120 9476

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-08-2021 01:39:51 to 17-08-2021 01:39:54.

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 1547244 1547244 0 0.00 0 27676 6 0 0
doc_list_biud 1547223 1547223 0 0.00 0 36022 28 0 0
sp_add_invoice_to_stock 4996 4876 120 2.40 68 201460 16360 0 120
sp_cancel_adding_invoice 6268 6268 0 0.00 4 69672 767 0 0
sp_cancel_client_order 1720 1720 0 0.00 4 15167 377 0 0
sp_cancel_customer_reserve 609371 609371 0 0.00 1 20852 55 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 3 5005 87 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 8886 76 0 0
sp_cancel_supplier_invoice 6823 6816 7 0.10 1 20385 783 0 7
sp_cancel_supplier_order 430 430 0 0.00 9 1184848 100097 0 0
sp_cancel_write_off 10197 10197 0 0.00 1 8613 114 0 0
sp_client_order 19626 19626 0 0.00 3 21215 399 0 0
sp_customer_reserve 442273 442273 0 0.00 0 35142 163 0 0
sp_fill_shopping_cart 29987 29987 0 0.00 1 17757 42 0 0
sp_fill_shopping_cart_clo_res 442270 442270 0 0.00 0 11258 13 0 0
sp_get_clo_for_invoice 4873 4873 0 0.00 0 28585 286 0 0
sp_kill_cost_storno 17515 17515 0 0.00 0 23584 56 0 0
sp_kill_qstorno_ret_qs2qd 1236185 1236185 0 0.00 0 20847 30 0 0
sp_kill_qty_storno 649421 649421 0 0.00 0 36022 65 0 0
sp_lock_selected_doc 20240 20240 0 0.00 0 9577 10 0 0
sp_make_cost_storno 26970 26970 0 0.00 0 22991 62 0 0
sp_multiply_rows_for_pdistr 26959 26959 0 0.00 0 27657 37 0 0
sp_multiply_rows_for_qdistr 19626 19626 0 0.00 0 14835 182 0 0
sp_pay_from_customer 6734 6734 0 0.00 4 10839 123 0 0
sp_pay_to_supplier 5599 5599 0 0.00 5 27681 174 0 0
sp_payment_common 12330 12330 0 0.00 1 17822 34 0 0
sp_qd_handle_on_cancel_clo 1720 1720 0 0.00 0 15161 338 0 0
sp_qd_handle_on_invoice_upd_sts 11134 11134 0 0.00 0 36021 326 0 0
sp_qd_handle_on_reserve_upd_sts 9753 9753 0 0.00 0 19538 55 0 0
sp_reserve_write_off 9764 9764 0 0.00 7 23059 197 0 0
sp_supplier_invoice 5231 5231 0 0.00 6 138705 3807 0 0
sp_supplier_order 5130 5130 0 0.00 5 37871 1303 0 0
srv_aggregate_perf_data 103 103 0 0.00 3035 273418 23790 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2082 126041 44696 0 0
srv_find_qd_qs_mism 1091632 1091632 0 0.00 0 16304 4 0 0
srv_make_invnt_saldo 536 536 0 0.00 23 109386 1293 0 0
srv_make_money_saldo 766 766 0 0.00 1 8290 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 77490 285838 223179 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 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 4878 4878 0 0.00 0 16305 184 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 7338 113 0 0
t$perf-norm:sp_cancel_client_order 1720 1720 0 0.00 0 5194 26 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 1784 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 3079 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 281 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 350 350 0 0.00 0 1952 21 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 29215 1685 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 2988 15 0 0
t$perf-norm:sp_client_order 19626 19626 0 0.00 0 13161 18 0 0
t$perf-norm:sp_customer_reserve 922 922 0 0.00 0 3383 13 0 0
t$perf-norm:sp_pay_from_customer 6734 6734 0 0.00 0 4968 11 0 0
t$perf-norm:sp_pay_to_supplier 5599 5599 0 0.00 0 8197 14 0 0
t$perf-norm:sp_reserve_write_off 9764 9764 0 0.00 0 7132 14 0 0
t$perf-norm:sp_supplier_invoice 5231 5231 0 0.00 0 11035 21 0 0
t$perf-norm:sp_supplier_order 5130 5130 0 0.00 0 9200 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 413 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 27 1 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 4609 32 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 845 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9972 9732 240 2.40 0 8881 24 0 240
v_all_customers 35958 35958 0 0.00 0 19 1 0 0
v_all_suppliers 28726 28726 0 0.00 0 32 1 0 0
v_all_wares 39252 39252 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 4244 10 0 0
v_cancel_client_order 3440 3440 0 0.00 0 2882 5 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 1489 6 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 2332 5 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 422 3 0 0
v_cancel_supplier_invoice 714 700 14 1.96 0 988 17 0 14
v_cancel_supplier_order 860 860 0 0.00 0 8316 14 0 0
v_cancel_write_off 1968 1968 0 0.00 0 7759 21 0 0
v_max_id_clo_ord 5130 5130 0 0.00 0 1750 2 0 0
v_max_id_clo_res 922 922 0 0.00 0 2162 19 0 0
v_max_id_ord_sup 5231 5231 0 0.00 0 6378 3 0 0
v_max_non_paid_invoice 1593 1593 0 0.00 0 394 2 0 0
v_max_non_paid_realizn 3584 3584 0 0.00 0 5011 5 0 0
v_min_id_clo_ord 5130 5130 0 0.00 0 2813 7 0 0
v_min_id_clo_res 922 922 0 0.00 0 3550 13 0 0
v_min_id_ord_sup 5231 5231 0 0.00 0 6888 15 0 0
v_min_non_paid_invoice 5594 5594 0 0.00 0 5205 21 0 0
v_min_non_paid_realizn 6734 6734 0 0.00 0 8390 19 0 0
v_our_firm 9594 9594 0 0.00 0 20 1 0 0
v_reserve_write_off 19504 19504 0 0.00 0 8939 10 0 0
x_lock_depdocs_on_canc_invoice 12734 12734 0 0.00 0 14656 106 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 14010 428 0 0
x_make_qstorno_1000_1200 5130 5130 0 0.00 2 37857 1229 0 0
x_make_qstorno_1200_2000 5231 5231 0 0.00 3 138691 3648 0 0
x_make_qstorno_2100_3300 442273 442273 0 0.00 0 35141 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-08-2021 01:39:55 to 17-08-2021 01:39:59.

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:07:50 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:50 27711 8192000000 10372559248 10690949120 1414574128 14.725 100 86 172 0 1449566640 6160800 28831712
23:10:26 12902 8192000000 10434875072 10764541952 1489961712 15.389 100 87 174 0 1526835120 8349312 28524096
23:11:45 22084 8192000000 10490732176 10831859712 1537393072 15.801 100 85 170 2 1573689456 9556992 26739392
23:13:21 11328 8192000000 10571888800 10926927872 1634065936 16.629 100 83 166 0 1670294688 11304096 24924656
23:14:39 9478 8192000000 10658253472 11026530304 1713371472 17.297 100 78 156 1 1747854304 12489344 21993488
23:15:52 4979 8192000000 10713585248 11090227200 1774570928 17.805 100 78 156 0 1809219648 13011872 21636848
23:17:00 3214 8192000000 10762424448 11147665408 1820688928 18.183 100 89 172 1 1858015344 14217008 23109408
23:18:06 1925 8192000000 10803087920 11193131008 1856518720 18.475 100 87 174 1 1893562096 12944960 24098416
23:19:10 1390 8192000000 10818262032 11216683008 1880868688 18.672 100 87 174 0 1919135040 13713344 24553008
23:20:12 5804 8192000000 10824759568 11222134784 1886089072 18.714 100 85 169 1 1920451936 10400832 23962032
23:21:19 5803 8192000000 10809312080 11228684288 1889912976 18.745 100 36 72 0 1908763504 8552864 10297664
23:22:28 6467 8192000000 10794976368 11234316288 1898887360 18.817 100 16 32 1 1909656160 6244544 4524256
23:23:35 5792 8192000000 10846862112 11249524736 1911960688 18.922 100 26 52 1 1925691920 6531072 7200160
23:24:42 23945 8192000000 10875383520 11276812288 1925111408 19.028 100 66 133 3 1956647840 10992080 20544352
23:26:07 4758 8192000000 10842491888 11268235264 1913217680 18.932 99 48 97 3 1933574384 7247760 13108944
23:27:17 3988 8192000000 10813321216 11240861696 1882636320 18.686 99 31 61 1 1897424752 5955024 8833408
23:28:21 61582 8192000000 10778801536 11203969024 1771006496 17.775 94 30 54 1 1784487568 4475680 9005392
23:30:24 7823 8192000000 10729467232 11147399168 1747797312 17.583 95 33 66 2 1762419760 4651232 9971216
23:31:34 8515 8192000000 10734950768 11157327872 1788227776 17.917 97 27 54 0 1802406624 6714592 7464256
23:32:43 9552 8192000000 10737200512 11152003072 1784384400 17.886 98 26 52 0 1797760176 5956320 7419456
23:33:54 33099 8192000000 10749065360 11183763456 1840087504 18.342 100 16 32 1 1849885776 5069776 4728496
23:35:29 5610 8192000000 10784692624 11216617472 1874704032 18.622 100 42 86 3 1893511744 6516016 12291696
23:36:35 7002 8192000000 10810070352 11230490624 1884931024 18.705 100 29 56 1 1896581488 4046000 7604464
23:37:43 28600 8192000000 10837194224 11265003520 1917799152 18.969 100 34 65 3 1932500432 5528608 9172672
23:39:20 10240 8192000000 10868723024 11291271168 1933578096 19.095 100 22 41 3 1950401984 10791568 6032320
23:40:36 35425 8192000000 10889442560 11322945536 1958169248 19.291 100 31 63 0 1979465616 12558112 8738256
23:42:18 12636 8192000000 10926037056 11356205056 1981229424 19.474 100 43 86 4 2013898512 20149760 12519328
23:43:33 19181 8192000000 10941709328 11378614272 1997410016 19.602 100 40 80 1 2035220672 25158160 12652496
23:44:55 6058 8192000000 10965329056 11395788800 2013763632 19.731 100 71 140 2 2061507440 26351088 21392720
23:46:11 11660 8192000000 10988722848 11423809536 2021850112 19.795 100 62 121 2 2075919120 34463568 19605440
23:47:28 2115 8192000000 10996669136 11427307520 2027313328 19.838 100 86 167 3 2093449248 39979776 26156144
23:48:37 4111 8192000000 11005380176 11436150784 2027494752 19.839 100 67 135 1 2086456704 37758512 21203440
23:49:45 10856 8192000000 10976531808 11406946304 2010022304 19.702 100 68 135 0 2070374240 40548288 19803648
23:50:58 7980 8192000000 11001935728 11434614784 2008958400 19.693 99 76 152 0 2080931952 49186656 22786896
23:52:14 5128 8192000000 10992779408 11422683136 1980481280 19.469 98 65 128 1 2055794752 54926880 20386592
23:53:21 2079 8192000000 11010076768 11430522880 2010068512 19.702 100 69 138 3 2090059920 58098816 21892592
23:54:28 13085 8192000000 10985816624 11428458496 2011100352 19.710 100 46 92 3 2078661744 52484000 15077392
23:55:43 11278 8192000000 10942189648 11384573952 1944407328 19.182 98 39 78 1 2013267184 55530896 13328960
23:56:59 8197 8192000000 10933216448 11363143680 1918439024 18.974 98 41 83 3 1986525392 53698816 14387552
23:58:15 4799 8192000000 10964534560 11389112320 1956606064 19.279 100 72 141 1 2044202000 65523264 22072672
23:59:24 13553 8192000000 10923403840 11383611392 1934911664 19.106 99 31 62 1 2005880800 59945200 11023936
00:00:51 24012 8192000000 10886530320 11328249856 1910190944 18.908 99 29 57 1 1962633968 43234432 9208592
00:02:22 39699 8192000000 10897698896 11354419200 1941365808 19.158 100 17 34 2 1980504080 33095712 6042560
00:04:02 30971 8192000000 10868420848 11335172096 1912499616 18.927 99 14 28 1 1953735808 36212992 5023200
00:05:52 31596 8192000000 10858451680 11331555328 1902336896 18.845 98 11 22 1 1916813024 11659472 2816656
00:07:39 47817 8192000000 10861492768 11321577472 1916082288 18.955 98 23 45 2 1931786128 10058432 5645408
00:09:28 42378 8192000000 10880155088 11347288064 1918073888 18.971 98 19 35 2 1937013424 14328208 4611328
00:11:11 68285 8192000000 10892352832 11349258240 1927518528 19.047 98 18 36 1 1948596960 16258368 4820064
00:13:22 114977 8192000000 10920477456 11377065984 1965348944 19.349 100 32 64 1 1991405552 16103696 9952912
00:16:26 34469 8192000000 10915691344 11372670976 1983117440 19.489 100 50 99 1 2004474000 7226560 14130000
00:18:03 86060 8192000000 10882545024 11330174976 1878432784 18.652 95 9 18 1 1892467312 11312512 2722016
00:20:30 19897 8192000000 10865456480 11330138112 1907950000 18.890 97 29 55 0 1923243232 7517376 7775856
00:21:56 38445 8192000000 10876239376 11348684800 1944090144 19.179 99 45 86 3 1964750880 8569904 12090832
00:23:34 56567 8192000000 10904871472 11377577984 1956985424 19.282 99 9 18 0 1976543584 17176944 2381216
00:25:31 46689 8192000000 10911188848 11380674560 1979864176 19.464 100 21 42 4 2004441072 19063072 5513824
00:27:18 62339 8192000000 10905680416 11389313024 1966310432 19.356 99 27 54 1 1995262528 20309968 8642128
00:29:21 48447 8192000000 10935105920 11404636160 1991465472 19.555 100 10 20 0 2007084144 12905840 2712832
00:31:17 77897 8192000000 10908583280 11383025664 1930710768 19.073 97 9 18 1 1948393072 15101616 2580688
00:33:40 48822 8192000000 10934257392 11394576384 1970764880 19.392 99 25 50 0 1991184576 13093984 7325712
00:35:30 68746 8192000000 10932363776 11406106624 1978601568 19.454 99 15 30 1 1998945328 16107488 4236272
00:37:39 107466 8192000000 10907636480 11376406528 1874431168 18.620 94 17 34 1 1891531328 12542400 4557760
00:40:29 74074 8192000000 10898457424 11381710848 1910757792 18.913 96 26 52 0 1931535856 14050544 6727520
00:42:43 21119 8192000000 10885470864 11334197248 1906378640 18.878 97 53 104 1 1936799696 14684896 15736160
00:44:11 76452 8192000000 10866498448 11334127616 1898194448 18.812 97 32 64 2 1919205888 12032816 8978624
00:46:36 13350 8192000000 10884173600 11344203776 1949046224 19.219 100 35 70 0 1969575696 9621744 10907728
00:47:51 48608 8192000000 10866121392 11342438400 1928483680 19.055 99 12 24 0 1952682576 21050224 3148672
00:49:42 44711 8192000000 10855372944 11324129280 1920069152 18.987 99 13 26 2 1934593616 10629296 3895168
00:51:27 36247 8192000000 10866122400 11342159872 1944379536 19.182 99 15 30 1 1956790208 8347200 4063472
00:53:51 46347 8192000000 10875674944 11357155328 1957339200 19.285 100 4 9 1 1964975648 6583216 1053232
00:55:38 43522 8192000000 10886860000 11354832896 1945955200 19.194 99 21 42 1 1961352832 9164656 6232976
00:57:24 31591 8192000000 10889461008 11357298688 1916829744 18.961 97 31 61 2 1937491792 12052720 8609328
00:58:56 76836 8192000000 10900231408 11377778688 1940621648 19.152 99 18 37 0 1963305360 16852592 5831120
01:01:14 45681 8192000000 10868660112 11344736256 1916014736 18.955 98 7 12 0 1927341120 9220256 2106128
01:02:59 22738 8192000000 10905159568 11352903680 1938136768 19.132 99 44 85 1 1963023264 13109360 11777136
01:04:22 35363 8192000000 10869416480 11335987200 1937865664 19.130 99 26 52 1 1959029504 13899168 7264672
01:06:05 117611 8192000000 10853522208 11327852544 1830720992 18.265 94 6 12 1 1847394096 15257920 1415184
01:09:10 90078 8192000000 10885117520 11366662144 1947085760 19.203 100 20 40 1 1981748208 28664512 5997936
01:11:42 56330 8192000000 10885662352 11370971136 1946957632 19.202 99 11 22 0 1965630832 15750656 2922544
01:13:38 126040 8192000000 10878237568 11358932992 1922619584 19.008 98 16 32 1 1942453936 14558352 5276000
01:17:46 35545 8192000000 10904265216 11379097600 1978780784 19.455 100 31 61 1 1995803696 8425744 8597168
01:19:23 40853 8192000000 10911769136 11392606208 1985452400 19.508 100 22 44 2 2001148896 9378656 6317840
01:21:04 67699 8192000000 10922164128 11409375232 1959667008 19.303 98 17 34 1 1989227296 23904576 5655712
01:23:17 54048 8192000000 10889299216 11374379008 1926767936 19.041 98 14 28 1 1951582848 20705232 4109680
01:25:11 76469 8192000000 10918828592 11396157440 1937118480 19.124 98 16 32 1 1955390464 13896992 4374992
01:27:33 120831 8192000000 10925612912 11414523904 1969962912 19.385 99 19 38 1 1998078720 22803344 5312464
01:30:35 86690 8192000000 10865981952 11338174464 1883601984 18.694 95 29 57 1 1899034480 7644720 7787776
01:33:05 49054 8192000000 10916000528 11370086400 1940978032 19.155 98 47 94 0 1967942208 13503232 13460944
01:34:57 60322 8192000000 10869827024 11353985024 1927601952 19.048 99 11 22 0 1942893632 12003104 3288576
01:37:00 49080 8192000000 10713540880 11182030848 1789456912 17.927 91 11 22 1 1799140256 6756736 2926608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-08-2021 01:40:01 to 17-08-2021 01:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 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 17-08-2021 01:40:17 to 17-08-2021 01:40:19.

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          232191
MON$OLDEST_ACTIVE               233187
MON$OLDEST_SNAPSHOT             233187
MON$NEXT_TRANSACTION            233187
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                       2030112
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
:373: 
:374: mscs-test-161.budzdorov.ru    Tue Aug 17 01:37:59 2021
:375:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:376: 
:377: 
:378: mscs-test-161.budzdorov.ru    Tue Aug 17 01:38:01 2021
:379:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:380: 
--- end of diff output ---

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

17.08.21 01:40:20. 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-08-17 01:40:22 No severe PSQL-related problems occured
17.08.21 01:40:23. End of report.