Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.08.2021 19: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 16049164 kB
MemAvailable 30947540 kB
Buffers 0 kB
Cached 15588820 kB
SwapCached 2600 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-18 19:37:46
DTS_END                         2021-08-18 19:37:46.8710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52318
TRN_ID                          243457
ATT_ID                          714
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: 9302.46 20017 1116295
customer order: creation 172.49 326 20699
customer order: refuse 14.78 344 1774
order to supplier: creation 46.59 1170 5591
order to supplier: removal 3.61 109049 433
invoice (draft): creation 44.58 3158 5350
invoice (draft): removal 53.83 900 6460
invoice accept: apply 41.63 16999 4995
invoice accept: cancel 48.78 780 5854
customer reserve: creation 3839.88 168 460786
customer reserve: removal 4730.46 65 567655
realization accept: apply 85.32 174 10238
realization accept: cancel 90.45 104 10854
payment from customer: creation 60.11 94 7213
payment from customer: removal 5.62 67 674
payment to supplier: creation 48.69 115 5843
payment to supplier: removal 3.26 78 391
service: total inventory turnovers 4.85 1377 582
service: total monetary turnovers 6.72 36 806
service: aggregate perf. data 0.78 22612 93
service: refresh index statistics 0.03 242726 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-08-2021 19:38:47 to 18-08-2021 19:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3563
TEST_TIME 2 4342
TEST_TIME 3 4532
TEST_TIME 4 4815
TEST_TIME 5 4487
TEST_TIME 6 4767
TEST_TIME 7 4985
TEST_TIME 8 5081
TEST_TIME 9 5005
TEST_TIME 10 5138
TEST_TIME 11 5383
TEST_TIME 12 5574
TEST_TIME 13 6167
TEST_TIME 14 6495
TEST_TIME 15 6638
TEST_TIME 16 6899
TEST_TIME 17 7188
TEST_TIME 18 7871
TEST_TIME 19 7948
TEST_TIME 20 7802
TEST_TIME 21 7823
TEST_TIME 22 8181
TEST_TIME 23 8767
TEST_TIME 24 8658
TEST_TIME 25 8662
TEST_TIME 26 8779
TEST_TIME 27 9085
TEST_TIME 28 9125
TEST_TIME 29 9694
TEST_TIME 30 10848
TEST_TIME 31 11295
TEST_TIME 32 11154
TEST_TIME 33 11125
TEST_TIME 34 11128
TEST_TIME 35 11147
TEST_TIME 36 10994
TEST_TIME 37 10800
TEST_TIME 38 10616
TEST_TIME 39 10571
TEST_TIME 40 10560
TEST_TIME 41 10584
TEST_TIME 42 10426
TEST_TIME 43 10488
TEST_TIME 44 10277
TEST_TIME 45 10239
TEST_TIME 46 10163
TEST_TIME 47 10052
TEST_TIME 48 10266
TEST_TIME 49 10204
TEST_TIME 50 10143
TEST_TIME 51 10092
TEST_TIME 52 10043
TEST_TIME 53 9949
TEST_TIME 54 9869
TEST_TIME 55 9893
TEST_TIME 56 9848
TEST_TIME 57 9771
TEST_TIME 58 9695
TEST_TIME 59 9638
TEST_TIME 60 9545
TEST_TIME 61 9521
TEST_TIME 62 9527
TEST_TIME 63 9519
TEST_TIME 64 9563
TEST_TIME 65 9577
TEST_TIME 66 9646
TEST_TIME 67 9766
TEST_TIME 68 9776
TEST_TIME 69 9736
TEST_TIME 70 9722
TEST_TIME 71 9708
TEST_TIME 72 9779
TEST_TIME 73 9769
TEST_TIME 74 9757
TEST_TIME 75 9865
TEST_TIME 76 9817
TEST_TIME 77 9721
TEST_TIME 78 9716
TEST_TIME 79 9654
TEST_TIME 80 9628
TEST_TIME 81 9573
TEST_TIME 82 9500
TEST_TIME 83 9514
TEST_TIME 84 9451
TEST_TIME 85 9400
TEST_TIME 86 9406
TEST_TIME 87 9376
TEST_TIME 88 9404
TEST_TIME 89 9363
TEST_TIME 90 9347
TEST_TIME 91 9345
TEST_TIME 92 9483
TEST_TIME 93 9465
TEST_TIME 94 9501
TEST_TIME 95 9537
TEST_TIME 96 9531
TEST_TIME 97 9577
TEST_TIME 98 9548
TEST_TIME 99 9572
TEST_TIME 100 9576
TEST_TIME 101 9616
TEST_TIME 102 9611
TEST_TIME 103 9532
TEST_TIME 104 9484
TEST_TIME 105 9459
TEST_TIME 106 9435
TEST_TIME 107 9392
TEST_TIME 108 9397
TEST_TIME 109 9361
TEST_TIME 110 9339
TEST_TIME 111 9287
TEST_TIME 112 9284
TEST_TIME 113 9271
TEST_TIME 114 9284
TEST_TIME 115 9259
TEST_TIME 116 9272
TEST_TIME 117 9331
TEST_TIME 118 9325
TEST_TIME 119 9321
TEST_TIME 120 9306

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-08-2021 19:38:50 to 18-08-2021 19:38: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 1544245 1544245 0 0.00 0 12569 6 0 0
doc_list_biud 1544245 1544245 0 0.00 0 21797 30 0 0
sp_add_invoice_to_stock 5179 4995 184 3.55 38 174624 17000 0 184
sp_cancel_adding_invoice 5854 5854 0 0.00 3 47225 781 0 0
sp_cancel_client_order 1774 1774 0 0.00 4 10727 345 0 0
sp_cancel_customer_reserve 567655 567655 0 0.00 1 17340 66 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 2 1919 68 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 4422 78 0 0
sp_cancel_supplier_invoice 6473 6460 13 0.20 1 21805 900 0 13
sp_cancel_supplier_order 433 433 0 0.00 7 996034 109049 0 0
sp_cancel_write_off 10854 10854 0 0.00 1 10206 104 0 0
sp_client_order 20699 20699 0 0.00 3 13254 326 0 0
sp_customer_reserve 460786 460786 0 0.00 0 62885 168 0 0
sp_fill_shopping_cart 31631 31631 0 0.00 1 22972 38 0 0
sp_fill_shopping_cart_clo_res 460774 460774 0 0.00 0 10385 13 0 0
sp_get_clo_for_invoice 4983 4983 0 0.00 0 18298 281 0 0
sp_kill_cost_storno 17773 17773 0 0.00 0 7260 54 0 0
sp_kill_qstorno_ret_qs2qd 1153057 1153057 0 0.00 0 21793 36 0 0
sp_kill_qty_storno 608250 608250 0 0.00 0 21795 76 0 0
sp_lock_selected_doc 21028 21028 0 0.00 0 8021 5 0 0
sp_make_cost_storno 28276 28276 0 0.00 0 12559 44 0 0
sp_multiply_rows_for_pdistr 28276 28276 0 0.00 0 11608 29 0 0
sp_multiply_rows_for_qdistr 20699 20699 0 0.00 0 12873 162 0 0
sp_pay_from_customer 7213 7213 0 0.00 4 11613 95 0 0
sp_pay_to_supplier 5843 5843 0 0.00 5 11879 116 0 0
sp_payment_common 13056 13056 0 0.00 1 8314 22 0 0
sp_qd_handle_on_cancel_clo 1774 1774 0 0.00 0 10716 308 0 0
sp_qd_handle_on_invoice_upd_sts 10836 10836 0 0.00 0 13169 323 0 0
sp_qd_handle_on_reserve_upd_sts 10238 10238 0 0.00 0 15631 63 0 0
sp_reserve_write_off 10238 10238 0 0.00 6 24658 175 0 0
sp_supplier_invoice 5350 5350 0 0.00 6 107688 3159 0 0
sp_supplier_order 5591 5591 0 0.00 5 44052 1170 0 0
srv_aggregate_perf_data 94 93 1 1.06 3153 212921 22613 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1908 148229 45812 0 0
srv_find_qd_qs_mism 1069189 1069189 0 0.00 0 12275 5 0 0
srv_make_invnt_saldo 582 582 0 0.00 56 208353 1378 0 0
srv_make_money_saldo 806 806 0 0.00 1 10278 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 175250 347074 242727 0 0
t$perf-abend:sp_add_invoice_to_stock 184 184 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4995 4995 0 0.00 0 6765 153 0 0
t$perf-norm:sp_cancel_adding_invoice 455 455 0 0.00 0 4284 97 0 0
t$perf-norm:sp_cancel_client_order 1774 1774 0 0.00 0 2477 11 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 2554 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 503 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 2321 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 1238 16 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 15636 1439 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 613 4 0 0
t$perf-norm:sp_client_order 20699 20699 0 0.00 0 13080 17 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 7768 10 0 0
t$perf-norm:sp_pay_from_customer 7213 7213 0 0.00 0 5090 10 0 0
t$perf-norm:sp_pay_to_supplier 5843 5843 0 0.00 0 7558 8 0 0
t$perf-norm:sp_reserve_write_off 10238 10238 0 0.00 0 18875 13 0 0
t$perf-norm:sp_supplier_invoice 5350 5350 0 0.00 0 5922 20 0 0
t$perf-norm:sp_supplier_order 5591 5591 0 0.00 0 6443 16 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 564 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2299 37 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 1277 18 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 1904 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10332 9964 368 3.56 0 6748 20 0 368
v_all_customers 37866 37866 0 0.00 0 106 1 0 0
v_all_suppliers 30330 30330 0 0.00 0 75 1 0 0
v_all_wares 41396 41396 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 910 910 0 0.00 0 3185 10 0 0
v_cancel_client_order 3548 3548 0 0.00 0 7472 7 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 931 6 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 3970 8 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 4410 15 0 0
v_cancel_supplier_invoice 798 772 26 3.25 0 4806 25 0 26
v_cancel_supplier_order 866 866 0 0.00 0 154 3 0 0
v_cancel_write_off 2014 2014 0 0.00 0 3608 18 0 0
v_max_id_clo_ord 5588 5588 0 0.00 0 375 1 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 2187 12 0 0
v_max_id_ord_sup 5345 5345 0 0.00 0 6236 6 0 0
v_max_non_paid_invoice 1611 1611 0 0.00 0 1637 3 0 0
v_max_non_paid_realizn 3701 3701 0 0.00 0 1037 4 0 0
v_min_id_clo_ord 5588 5588 0 0.00 0 4058 6 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 2720 6 0 0
v_min_id_ord_sup 5345 5345 0 0.00 0 5737 8 0 0
v_min_non_paid_invoice 5843 5843 0 0.00 0 5287 13 0 0
v_min_non_paid_realizn 7213 7213 0 0.00 0 8310 13 0 0
v_our_firm 10554 10554 0 0.00 0 21 1 0 0
v_reserve_write_off 20476 20476 0 0.00 0 7751 6 0 0
x_lock_depdocs_on_canc_invoice 11928 11928 0 0.00 0 20836 114 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 19770 357 0 0
x_make_qstorno_1000_1200 5591 5591 0 0.00 2 44046 1096 0 0
x_make_qstorno_1200_2000 5350 5350 0 0.00 3 107615 3010 0 0
x_make_qstorno_2100_3300 460786 460786 0 0.00 0 62881 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-08-2021 19:38:55 to 18-08-2021 19:38: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
17:07:50 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:51 31043 8192000000 10371246304 10694541312 1416856336 14.745 100 87 174 0 1451901440 6112864 28932240
17:10:28 20263 8192000000 10434782832 10767360000 1490635536 15.394 100 87 174 0 1527587728 8382768 28569424
17:11:52 12515 8192000000 10501272528 10843824128 1555939584 15.961 100 83 166 0 1592193776 9502752 26751440
17:13:08 13225 8192000000 10549549648 10902286336 1605645936 16.388 100 77 153 1 1638977088 10219840 23111312
17:14:24 9580 8192000000 10637290336 11005644800 1699323280 17.179 100 76 152 3 1732682064 11259120 22099664
17:15:36 3609 8192000000 10708737696 11083870208 1768508176 17.755 100 89 178 3 1807063536 14094016 24461344
17:16:43 9742 8192000000 10751576752 11137474560 1809960640 18.096 100 78 153 1 1844553136 14084064 20508432
17:17:54 2114 8192000000 10810960064 11196579840 1860221024 18.505 100 93 186 0 1900454496 12990480 27242992
17:18:57 17049 8192000000 10813889936 11208097792 1875055600 18.625 100 81 161 1 1907840432 9160912 23623920
17:20:15 7895 8192000000 10787296704 11208548352 1873141536 18.610 100 31 59 3 1889553040 7335456 9076048
17:21:23 13296 8192000000 10799131728 11229552640 1888953168 18.737 100 24 46 1 1901110560 5988288 6169104
17:22:38 17744 8192000000 10825247344 11248132096 1907829072 18.889 100 34 68 3 1923804176 5901792 10073312
17:24:03 7640 8192000000 10823013632 11257315328 1908852608 18.897 100 36 72 0 1927688816 8611264 10224944
17:25:12 4800 8192000000 10833452816 11264782336 1916945696 18.962 100 26 52 1 1931201152 6609424 7646032
17:26:18 8757 8192000000 10835074784 11262894080 1900023424 18.826 99 20 40 1 1911562976 6089744 5449808
17:27:27 16771 8192000000 10759205952 11179032576 1769193328 17.760 93 18 36 1 1780089504 4890080 6006096
17:28:45 23301 8192000000 10738508080 11164889088 1740879808 17.526 93 23 46 1 1753476352 5760720 6835824
17:30:13 5610 8192000000 10703217536 11103879168 1675037504 16.976 93 25 49 0 1687711968 5407408 7267056
17:31:19 9335 8192000000 10724525296 11128586240 1739724480 17.516 96 28 56 0 1753838656 5670656 8443520
17:32:33 46181 8192000000 10765074128 11179008000 1839496096 18.337 100 21 42 4 1851407200 4970176 6940928
17:34:20 8125 8192000000 10786272064 11202514944 1861278240 18.514 100 30 60 1 1876331776 6688096 8365440
17:35:31 9188 8192000000 10782968464 11216556032 1872801616 18.607 100 23 46 4 1885888688 5467280 7619792
17:36:41 15146 8192000000 10783674480 11224457216 1879181408 18.658 100 14 29 0 1888268304 4979760 4107136
17:37:59 61090 8192000000 10834610064 11266842624 1915550096 18.951 100 41 80 0 1933726208 6661120 11514992
17:40:05 6253 8192000000 10858576960 11292073984 1938194128 19.132 100 32 64 1 1955628576 7925632 9508816
17:41:17 8165 8192000000 10895818000 11330048000 1961403696 19.317 100 51 102 2 1994460272 18712768 14343808
17:42:28 11067 8192000000 10914144832 11349082112 1976332368 19.436 100 44 87 0 2009872176 19268224 14271584
17:43:59 19290 8192000000 10938221952 11378737152 2004912656 19.661 100 56 112 0 2039185696 17076992 17196048
17:45:25 11389 8192000000 10957696896 11383771136 2005472576 19.666 100 65 129 0 2043873840 19366992 19034272
17:46:42 10709 8192000000 10976876800 11408359424 2021621600 19.793 100 59 118 2 2062595952 23757376 17216976
17:47:58 11847 8192000000 10967893216 11402006528 2005501552 19.666 99 51 103 1 2048291408 27061408 15728448
17:49:14 8960 8192000000 10961460496 11383971840 1993614208 19.572 100 68 133 1 2046134528 32316688 20203632
17:50:29 2465 8192000000 10955203200 11374268416 1965527440 19.350 98 78 157 0 2019512096 30570560 23414096
17:51:38 1905 8192000000 10988689712 11408429056 2013055680 19.726 100 82 162 3 2073898992 36362336 24480976
17:52:44 11165 8192000000 10928152256 11349602304 1933094080 19.092 97 54 108 1 1985990720 35857296 17039344
17:54:03 3177 8192000000 10939107040 11356893184 1941356272 19.158 99 65 130 1 2006792688 44186976 21249440
17:55:11 3788 8192000000 10968101072 11381141504 1972437824 19.405 100 79 158 0 2047283056 50281632 24563600
17:56:19 5559 8192000000 10912021968 11340304384 1936637824 19.120 99 54 108 1 1986660720 33603824 16419072
17:57:35 6761 8192000000 10935154128 11357630464 1948476080 19.214 99 63 124 1 2011357712 42414544 20467088
17:58:50 4329 8192000000 10986864688 11411361792 1998176912 19.608 100 62 124 2 2070852304 51545952 21129440
18:00:02 8871 8192000000 10969177488 11410644992 1989346096 19.539 100 50 100 2 2060059232 53107600 17605536
18:01:18 4153 8192000000 10958645616 11393245184 1981622864 19.478 100 39 76 1 2038216320 43293904 13299552
18:02:28 17597 8192000000 10980319088 11424268288 1996952272 19.599 100 39 77 1 2061662528 51464432 13245824
18:03:49 18373 8192000000 10921918096 11359821824 1915655952 18.952 97 34 68 0 1990937920 63260320 12021648
18:05:12 15170 8192000000 10941985552 11390324736 1954097056 19.259 100 25 50 1 2021129488 58053152 8979280
18:06:28 28493 8192000000 10923817872 11391696896 1940305696 19.149 100 21 42 1 2016753120 67805504 8641920
18:07:59 58878 8192000000 10864850064 11328241664 1897330704 18.805 98 10 20 0 1935945264 35620816 2993744
18:09:58 71937 8192000000 10864652064 11315945472 1874280496 18.619 96 14 28 1 1888931456 11035296 3615664
18:12:19 63663 8192000000 10891562176 11338240000 1939752656 19.145 99 18 36 3 1953190656 7654544 5783456
18:14:34 101265 8192000000 10908114896 11366834176 1976737968 19.439 100 42 84 0 1999721216 10700784 12282464
18:17:20 128096 8192000000 10919641728 11382845440 1971742816 19.399 99 26 52 1 1988457152 10061024 6653312
18:20:29 51382 8192000000 10907669296 11380502528 1973551328 19.414 99 8 16 1 1983290400 7772976 1966096
18:22:46 53348 8192000000 10927476048 11398283264 1982395472 19.484 99 19 38 0 2000687152 13215824 5075856
18:24:59 41943 8192000000 10945389136 11400650752 1986650784 19.517 99 30 60 0 2013869648 17266112 9952752
18:26:48 23003 8192000000 10919862208 11391483904 1999387200 19.618 100 23 45 2 2014287072 8058800 6841072
18:28:11 36764 8192000000 10920959568 11405811712 1988558688 19.532 99 9 18 0 2001293088 10231440 2502960
18:29:50 114044 8192000000 10920348112 11409616896 2005027616 19.662 100 14 28 0 2021624800 12494768 4102416
18:33:16 82532 8192000000 10867984608 11342155776 1839120368 18.334 92 15 30 0 1854088768 10844064 4124336
18:35:39 68936 8192000000 10868815632 11349737472 1922277344 19.005 98 8 16 0 1930821184 6362464 2181376
18:37:51 46001 8192000000 10877490656 11349835776 1920767040 18.993 98 11 22 0 1931892688 8063680 3061968
18:39:49 51922 8192000000 10871558096 11347271680 1854516384 18.459 94 22 44 1 1874577568 13227328 6833856
18:41:42 79993 8192000000 10875038704 11356614656 1887374576 18.725 96 19 38 1 1911302928 18193184 5735168
18:44:06 71323 8192000000 10877681968 11348865024 1893636480 18.775 97 11 22 0 1906783456 10049216 3097760
18:46:18 44873 8192000000 10898356336 11353313280 1923565232 19.015 98 28 56 0 1942250016 10690096 7994688
18:48:09 19674 8192000000 10884188448 11343761408 1936058064 19.115 99 32 64 0 1955566592 10729536 8778992
18:49:34 49451 8192000000 10871846224 11351011328 1862715904 18.525 95 27 54 4 1884345312 14185936 7443472
18:51:24 56757 8192000000 10880204320 11335729152 1897424064 18.806 98 23 46 3 1917675024 13268352 6982608
18:53:24 94489 8192000000 10858717088 11323662336 1917586976 18.968 99 24 47 1 1939325328 14923328 6815024
18:56:05 30652 8192000000 10872530416 11344490496 1937572608 19.127 100 20 40 0 1964260304 21703440 4984256
18:57:42 54837 8192000000 10860056240 11334029312 1922266432 19.005 99 8 16 0 1937086304 12778080 2041792
18:59:37 48140 8192000000 10861715568 11339059200 1945072560 19.187 100 13 27 2 1958193040 9311888 3808592
19:01:35 95273 8192000000 10848150416 11330170880 1895515600 18.790 98 11 22 0 1907790320 9861808 2412912
19:05:34 40015 8192000000 10855490320 11325644800 1906235888 18.876 98 14 28 0 1917475984 7272720 3967376
19:07:17 28690 8192000000 10907130992 11372797952 1969005168 19.378 100 31 63 0 1990449664 13053504 8390992
19:08:47 126971 8192000000 10909088768 11371376640 1917561680 18.967 97 9 18 0 1933100160 13437024 2101456
19:11:54 112365 8192000000 10893912368 11372457984 1918789952 18.977 97 7 14 0 1929763856 9457232 1516672
19:14:52 74469 8192000000 10914857856 11395964928 1986922832 19.519 100 18 36 1 2005763664 14199600 4641232
19:17:07 86113 8192000000 10914917456 11401392128 1972101632 19.402 99 11 22 0 1986088128 11072736 2913760
19:19:43 148228 8192000000 10908508160 11394629632 1954956496 19.266 98 19 38 2 1973728064 13473664 5297904
19:23:16 41829 8192000000 10866796240 11340136448 1921950656 19.002 97 14 28 2 1937839408 12108160 3780592
19:25:01 43021 8192000000 10868339904 11357745152 1907038064 18.883 97 8 16 0 1923827888 14538432 2251392
19:26:44 57728 8192000000 10853613648 11325845504 1894620176 18.783 97 22 44 2 1910330128 9019584 6690368
19:28:42 75115 8192000000 10875683328 11364356096 1924328704 19.022 98 8 16 0 1939944064 13361856 2253504
19:31:04 40750 8192000000 10867723296 11343745024 1928809536 19.057 98 42 84 1 1950976192 10246608 11920048
19:32:54 55137 8192000000 10833618064 11315941376 1861301184 18.514 96 10 20 0 1876542528 12306144 2935200
19:34:55 43506 8192000000 10865782320 11328040960 1913237584 18.933 99 22 44 3 1928470464 8916720 6316160
19:36:47 38227 8192000000 10872505632 11328954368 1870799968 18.591 96 43 86 1 1894795840 11975024 12020848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 184 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 18-08-2021 19:39:17 to 18-08-2021 19:39:18.

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          241641
MON$OLDEST_ACTIVE               243581
MON$OLDEST_SNAPSHOT             243581
MON$NEXT_TRANSACTION            243581
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                       2052768
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:35: 
:36: mscs-test-161.budzdorov.ru    Wed Aug 18 19:38:00 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:38: 
--- end of diff output ---

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

18.08.21 19:39:19. 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-18 19:39:22 No severe PSQL-related problems occured
18.08.21 19:39:23. End of report.