Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.11.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 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16783084 kB
MemAvailable 31493048 kB
Buffers 0 kB
Cached 14859560 kB
SwapCached 0 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-30 19:37:54
DTS_END                         2021-11-30 19:37:54.0780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57534
TRN_ID                          240913
ATT_ID                          653
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: 9323.11 19690 1118773
customer order: creation 174.05 373 20886
customer order: refuse 14.60 356 1752
order to supplier: creation 46.90 1274 5628
order to supplier: removal 3.54 112740 425
invoice (draft): creation 44.78 3532 5374
invoice (draft): removal 55.39 856 6647
invoice accept: apply 40.63 18206 4875
invoice accept: cancel 49.79 813 5975
customer reserve: creation 3715.72 181 445886
customer reserve: removal 4869.78 63 584374
realization accept: apply 83.93 199 10071
realization accept: cancel 97.52 109 11702
payment from customer: creation 57.83 128 6939
payment from customer: removal 5.66 84 679
payment to supplier: creation 47.93 141 5752
payment to supplier: removal 3.39 101 407
service: total inventory turnovers 4.44 1403 533
service: total monetary turnovers 6.38 24 765
service: aggregate perf. data 0.83 18925 99
service: refresh index statistics 0.03 234285 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-11-2021 19:38:53 to 30-11-2021 19:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2704
TEST_TIME 2 2152
TEST_TIME 3 2871
TEST_TIME 4 3784
TEST_TIME 5 4760
TEST_TIME 6 4866
TEST_TIME 7 4662
TEST_TIME 8 4540
TEST_TIME 9 4823
TEST_TIME 10 5001
TEST_TIME 11 4958
TEST_TIME 12 5383
TEST_TIME 13 5684
TEST_TIME 14 5795
TEST_TIME 15 6002
TEST_TIME 16 6344
TEST_TIME 17 6669
TEST_TIME 18 6899
TEST_TIME 19 7061
TEST_TIME 20 7264
TEST_TIME 21 7730
TEST_TIME 22 8095
TEST_TIME 23 8203
TEST_TIME 24 8265
TEST_TIME 25 8480
TEST_TIME 26 9400
TEST_TIME 27 9485
TEST_TIME 28 9831
TEST_TIME 29 10938
TEST_TIME 30 10688
TEST_TIME 31 10523
TEST_TIME 32 10278
TEST_TIME 33 10139
TEST_TIME 34 10129
TEST_TIME 35 10006
TEST_TIME 36 9842
TEST_TIME 37 9832
TEST_TIME 38 9733
TEST_TIME 39 9815
TEST_TIME 40 9641
TEST_TIME 41 9603
TEST_TIME 42 9464
TEST_TIME 43 9393
TEST_TIME 44 9218
TEST_TIME 45 9182
TEST_TIME 46 9301
TEST_TIME 47 9406
TEST_TIME 48 9439
TEST_TIME 49 9433
TEST_TIME 50 9398
TEST_TIME 51 9436
TEST_TIME 52 9367
TEST_TIME 53 9600
TEST_TIME 54 9632
TEST_TIME 55 9618
TEST_TIME 56 9624
TEST_TIME 57 9596
TEST_TIME 58 9562
TEST_TIME 59 9433
TEST_TIME 60 9418
TEST_TIME 61 9410
TEST_TIME 62 9387
TEST_TIME 63 9375
TEST_TIME 64 9272
TEST_TIME 65 9255
TEST_TIME 66 9234
TEST_TIME 67 9295
TEST_TIME 68 9294
TEST_TIME 69 9279
TEST_TIME 70 9270
TEST_TIME 71 9306
TEST_TIME 72 9293
TEST_TIME 73 9241
TEST_TIME 74 9232
TEST_TIME 75 9386
TEST_TIME 76 9408
TEST_TIME 77 9408
TEST_TIME 78 9420
TEST_TIME 79 9388
TEST_TIME 80 9311
TEST_TIME 81 9322
TEST_TIME 82 9256
TEST_TIME 83 9287
TEST_TIME 84 9233
TEST_TIME 85 9250
TEST_TIME 86 9154
TEST_TIME 87 9220
TEST_TIME 88 9179
TEST_TIME 89 9175
TEST_TIME 90 9198
TEST_TIME 91 9272
TEST_TIME 92 9261
TEST_TIME 93 9295
TEST_TIME 94 9267
TEST_TIME 95 9294
TEST_TIME 96 9325
TEST_TIME 97 9400
TEST_TIME 98 9399
TEST_TIME 99 9396
TEST_TIME 100 9379
TEST_TIME 101 9414
TEST_TIME 102 9483
TEST_TIME 103 9462
TEST_TIME 104 9399
TEST_TIME 105 9407
TEST_TIME 106 9389
TEST_TIME 107 9381
TEST_TIME 108 9342
TEST_TIME 109 9348
TEST_TIME 110 9327
TEST_TIME 111 9373
TEST_TIME 112 9348
TEST_TIME 113 9319
TEST_TIME 114 9244
TEST_TIME 115 9259
TEST_TIME 116 9285
TEST_TIME 117 9289
TEST_TIME 118 9278
TEST_TIME 119 9338
TEST_TIME 120 9325

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-11-2021 19:38:57 to 30-11-2021 19:39:00.

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 1533757 1533757 0 0.00 0 32707 6 0 0
doc_list_biud 1533757 1533757 0 0.00 0 73312 31 0 0
sp_add_invoice_to_stock 5076 4875 201 3.95 24 224153 18207 0 201
sp_cancel_adding_invoice 5975 5975 0 0.00 5 86611 813 0 0
sp_cancel_client_order 1752 1752 0 0.00 4 12316 357 0 0
sp_cancel_customer_reserve 584374 584374 0 0.00 1 73315 64 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 11585 85 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 11813 101 0 0
sp_cancel_supplier_invoice 6651 6647 4 0.06 2 14893 857 0 4
sp_cancel_supplier_order 425 425 0 0.00 6 1164823 112741 0 0
sp_cancel_write_off 11702 11702 0 0.00 1 17276 110 0 0
sp_client_order 20886 20886 0 0.00 3 29586 373 0 0
sp_customer_reserve 445886 445886 0 0.00 0 77967 182 0 0
sp_fill_shopping_cart 31888 31888 0 0.00 1 19607 39 0 0
sp_fill_shopping_cart_clo_res 445881 445881 0 0.00 0 22430 14 0 0
sp_get_clo_for_invoice 4870 4870 0 0.00 0 23175 288 0 0
sp_kill_cost_storno 18763 18763 0 0.00 0 14643 62 0 0
sp_kill_qstorno_ret_qs2qd 1187522 1187522 0 0.00 0 73237 35 0 0
sp_kill_qty_storno 625816 625816 0 0.00 0 73312 75 0 0
sp_lock_selected_doc 20614 20614 0 0.00 0 7537 5 0 0
sp_make_cost_storno 27632 27632 0 0.00 0 32704 59 0 0
sp_multiply_rows_for_pdistr 27632 27632 0 0.00 0 14767 32 0 0
sp_multiply_rows_for_qdistr 20886 20886 0 0.00 0 17770 152 0 0
sp_pay_from_customer 6939 6939 0 0.00 4 46127 129 0 0
sp_pay_to_supplier 5752 5752 0 0.00 5 32776 141 0 0
sp_payment_common 12691 12691 0 0.00 1 46119 34 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 12305 324 0 0
sp_qd_handle_on_invoice_upd_sts 10845 10845 0 0.00 0 30778 349 0 0
sp_qd_handle_on_reserve_upd_sts 10071 10071 0 0.00 0 21547 70 0 0
sp_reserve_write_off 10071 10071 0 0.00 6 21566 200 0 0
sp_supplier_invoice 5374 5374 0 0.00 6 98604 3533 0 0
sp_supplier_order 5628 5628 0 0.00 4 28299 1274 0 0
srv_aggregate_perf_data 100 99 1 1.00 2731 245211 18926 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1399 150781 45151 0 0
srv_find_qd_qs_mism 1072898 1072898 0 0.00 0 18814 5 0 0
srv_make_invnt_saldo 533 533 0 0.00 41 152138 1403 0 0
srv_make_money_saldo 765 765 0 0.00 1 2147 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 54552 381974 234286 0 0
t$perf-abend:sp_add_invoice_to_stock 201 201 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 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 4875 4875 0 0.00 0 34363 237 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 4181 92 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 11388 28 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 1147 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 11833 48 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 2335 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 1470 18 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 50484 1736 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 8013 12 0 0
t$perf-norm:sp_client_order 20886 20886 0 0.00 0 14143 24 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 17713 35 0 0
t$perf-norm:sp_pay_from_customer 6939 6939 0 0.00 0 15541 22 0 0
t$perf-norm:sp_pay_to_supplier 5753 5753 0 0.00 0 8315 15 0 0
t$perf-norm:sp_reserve_write_off 10071 10071 0 0.00 0 25503 25 0 0
t$perf-norm:sp_supplier_invoice 5374 5374 0 0.00 0 16010 29 0 0
t$perf-norm:sp_supplier_order 5629 5629 0 0.00 0 6543 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 337 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 257 5 0 0
t$perf-norm:srv_make_invnt_saldo 533 533 0 0.00 0 8423 43 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 1668 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10142 9740 402 3.96 0 10968 22 0 402
v_all_customers 38054 38054 0 0.00 0 25 0 0 0
v_all_suppliers 30650 30650 0 0.00 0 211 1 0 0
v_all_wares 41772 41772 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 882 7 0 0
v_cancel_client_order 3504 3504 0 0.00 0 3552 7 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 11575 13 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 14094 28 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 407 4 0 0
v_cancel_supplier_invoice 754 746 8 1.06 0 1298 16 0 8
v_cancel_supplier_order 850 850 0 0.00 0 6205 21 0 0
v_cancel_write_off 1904 1904 0 0.00 0 10967 25 0 0
v_max_id_clo_ord 5628 5628 0 0.00 0 896 1 0 0
v_max_id_clo_res 990 990 0 0.00 0 1178 10 0 0
v_max_id_ord_sup 5374 5374 0 0.00 0 5853 6 0 0
v_max_non_paid_invoice 1429 1429 0 0.00 0 1439 3 0 0
v_max_non_paid_realizn 3622 3622 0 0.00 0 46114 16 0 0
v_min_id_clo_ord 5628 5628 0 0.00 0 17224 13 0 0
v_min_id_clo_res 990 990 0 0.00 0 1645 4 0 0
v_min_id_ord_sup 5374 5374 0 0.00 0 5062 10 0 0
v_min_non_paid_invoice 5752 5752 0 0.00 0 9897 19 0 0
v_min_non_paid_realizn 6939 6939 0 0.00 0 14563 28 0 0
v_our_firm 10352 10352 0 0.00 0 23 1 0 0
v_reserve_write_off 20142 20142 0 0.00 0 16486 11 0 0
x_lock_depdocs_on_canc_invoice 12249 12249 0 0.00 0 25933 131 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 6859 393 0 0
x_make_qstorno_1000_1200 5628 5628 0 0.00 1 28287 1186 0 0
x_make_qstorno_1200_2000 5374 5374 0 0.00 3 98580 3399 0 0
x_make_qstorno_2100_3300 445886 445886 0 0.00 0 77965 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-11-2021 19:39:01 to 30-11-2021 19:39:05.

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:57 2 8192000000 8799003072 8810430464 5409808 0.065 2 1 2 0 5509440 56848 42784
17:08:58 33503 8192000000 9903817152 10218283008 976727936 10.652 100 85 168 1 1005519184 6695600 22095648
17:10:37 18889 8192000000 9949417536 10274934784 1031796784 11.186 100 92 184 0 1064100800 8715120 23588896
17:12:03 13229 8192000000 9987534832 10323828736 1074702064 11.597 100 86 172 0 1106016096 9496624 21817408
17:13:26 8744 8192000000 10037825648 10388140032 1125729552 12.081 100 84 169 1 1156689856 10990880 19969424
17:14:44 9596 8192000000 10078943808 10438516736 1165904288 12.459 100 79 160 1 1197181136 13012496 18264352
17:15:57 6852 8192000000 10121965616 10490445824 1210173072 12.871 100 80 159 1 1240475248 12490544 17811632
17:17:06 7226 8192000000 10153911296 10533486592 1249337904 13.232 100 71 142 2 1277273520 12872080 15063536
17:18:15 2082 8192000000 10203639904 10584010752 1282729840 13.538 100 89 181 1 1317315024 14704304 19880880
17:19:18 1495 8192000000 10220571728 10604511232 1298281248 13.680 100 97 191 2 1331926464 12166240 21478976
17:20:20 11507 8192000000 10212628976 10617135104 1305201872 13.743 100 41 82 0 1325495392 10951936 9341584
17:21:34 12088 8192000000 10199830320 10617712640 1314914240 13.831 100 21 42 0 1325603968 5708752 4980976
17:22:47 10194 8192000000 10212777184 10628481024 1320921504 13.885 100 20 39 1 1331697552 6022512 4753536
17:23:57 9463 8192000000 10210254720 10629124096 1326000448 13.931 100 29 56 0 1339971104 6764992 7205664
17:25:08 8217 8192000000 10247345232 10644971520 1328680928 13.955 100 77 155 1 1353109040 5449696 18978416
17:26:16 5768 8192000000 10220085840 10633437184 1317056592 13.850 99 34 69 1 1329852864 5130752 7665520
17:27:24 8362 8192000000 10203156144 10619625472 1271100224 13.432 96 18 36 0 1281063360 4958864 5004272
17:28:33 63822 8192000000 10154692368 10553880576 1210488256 12.874 93 27 51 3 1221628736 4777568 6362912
17:30:42 6906 8192000000 10153855296 10558091264 1234027888 13.091 97 24 48 1 1244323808 5053952 5241968
17:31:49 8908 8192000000 10137388912 10541453312 1221774144 12.978 97 30 60 0 1234684848 6063280 6847424
17:32:58 7750 8192000000 10164847888 10568818688 1269230928 13.415 100 21 42 1 1279309952 5383808 4695216
17:34:08 31442 8192000000 10184478208 10589630464 1263154752 13.359 98 23 46 1 1273880112 4981936 5743424
17:35:40 7304 8192000000 10199875664 10602754048 1297396336 13.672 100 42 85 1 1317204672 9639040 10169296
17:36:48 13756 8192000000 10200196384 10618449920 1308588928 13.773 100 20 40 1 1322457088 9448368 4419792
17:38:04 33634 8192000000 10253459472 10658742272 1337362880 14.034 100 63 126 0 1359641888 6532368 15746640
17:39:41 44312 8192000000 10275867568 10687229952 1358977936 14.228 100 61 121 0 1386626720 13098256 14550528
17:41:29 7166 8192000000 10280278480 10705338368 1370414768 14.331 100 40 80 1 1396493744 16883520 9195456
17:42:41 14241 8192000000 10296610432 10719502336 1369928976 14.326 99 53 106 1 1401904864 19813584 12162304
17:44:11 5373 8192000000 10310789824 10734350336 1390822816 14.513 100 46 93 0 1420761200 19183856 10754528
17:45:24 27382 8192000000 10320976048 10748506112 1398078896 14.578 100 46 92 1 1433499392 24161600 11258896
17:46:53 19554 8192000000 10338475008 10768543744 1401292416 14.607 99 45 90 3 1443806992 30957616 11556960
17:48:18 9729 8192000000 10354202576 10776592384 1408234144 14.668 99 45 90 1 1457650080 38140432 11275504
17:49:31 2490 8192000000 10375499440 10787110912 1414412992 14.723 100 87 170 3 1479708384 44230128 21065264
17:50:38 4495 8192000000 10380106896 10791211008 1414126592 14.721 100 76 152 1 1476431136 43751984 18552560
17:51:47 4903 8192000000 10374540048 10778746880 1406803136 14.656 100 81 161 1 1473608592 47352032 19453424
17:52:59 5384 8192000000 10362622800 10769633280 1374505152 14.367 97 69 135 1 1442663008 51412176 16745680
17:54:09 1397 8192000000 10377784448 10782384128 1399100352 14.587 100 94 185 0 1479445328 56280912 24064064
17:55:14 10832 8192000000 10361331712 10775048192 1399188128 14.588 100 63 122 0 1461525376 46133520 16203728
17:56:34 8080 8192000000 10379128528 10794041344 1402859712 14.620 100 60 120 1 1480769152 61768368 16141072
17:57:45 10887 8192000000 10355708272 10781757440 1387844272 14.487 99 50 100 2 1467755664 65492304 14419088
17:59:04 9532 8192000000 10350703456 10767605760 1367020736 14.300 98 43 86 0 1435950320 57129792 11799792
18:00:50 10725 8192000000 10339826128 10758541312 1368445120 14.313 99 55 110 0 1438182224 54319040 15418064
18:02:12 5168 8192000000 10350486192 10772578304 1373848064 14.362 100 36 72 1 1456904592 72583344 10473184
18:03:22 16845 8192000000 10354859744 10799452160 1377202848 14.392 100 32 64 1 1464660256 78256480 9200928
18:04:43 23441 8192000000 10306106272 10753122304 1348920912 14.138 100 21 42 0 1412844800 56951984 6971904
18:06:23 25052 8192000000 10271129760 10717097984 1346268368 14.114 99 15 29 2 1370328048 20494848 3564832
18:07:55 16519 8192000000 10293686704 10729922560 1375180352 14.373 100 51 100 2 1396241488 9694112 11367024
18:09:12 150781 8192000000 10281310384 10740527104 1366439280 14.295 99 21 43 1 1382644272 11530000 4674992
18:12:49 27507 8192000000 10286146736 10740064256 1384918528 14.461 100 21 41 0 1396814480 7300704 4595248
18:14:18 61730 8192000000 10301226640 10754772992 1375187312 14.373 99 29 57 1 1398558304 16685296 6685696
18:16:22 56769 8192000000 10283048752 10731446272 1312256704 13.807 95 12 24 0 1332232880 17251728 2724448
18:18:21 92447 8192000000 10292044256 10749464576 1380672000 14.423 100 13 26 0 1399420368 15521808 3226560
18:20:59 61528 8192000000 10293490096 10743070720 1360239648 14.240 98 21 42 0 1386256160 20778352 5238160
18:23:31 42071 8192000000 10275130288 10722295808 1354080048 14.184 99 9 18 0 1374645568 18552128 2013392
18:25:18 64932 8192000000 10264843200 10719916032 1324717312 13.919 97 25 50 1 1348263360 16966560 6579488
18:27:23 65552 8192000000 10285885584 10745171968 1369578752 14.323 99 17 34 0 1386452080 13068320 3805008
18:29:39 44029 8192000000 10312726128 10768306176 1394459456 14.546 100 29 58 0 1419365152 17671024 7234672
18:32:03 21594 8192000000 10330147744 10769367040 1403152384 14.623 100 37 74 2 1420154064 8310144 8691536
18:33:28 59847 8192000000 10306818256 10752135168 1379107040 14.409 99 21 42 0 1392851392 8815488 4928864
18:35:33 30431 8192000000 10311779392 10752884736 1375347056 14.375 99 36 72 1 1397934336 14544176 8043104
18:37:16 74398 8192000000 10283520096 10739249152 1332587248 13.991 96 35 70 2 1353671008 12742768 8340992
18:39:46 10584 8192000000 10257850032 10718568448 1364009856 14.273 99 6 11 1 1368484240 3290992 1183392
18:40:57 38709 8192000000 10256877120 10716766208 1338177184 14.041 97 9 18 0 1345946448 5730496 2038768
18:42:42 48733 8192000000 10270554720 10729164800 1335736704 14.019 97 8 16 0 1347898064 10540112 1621248
18:44:34 87380 8192000000 10255476016 10709114880 1317369600 13.853 96 13 26 1 1330374640 10100736 2904304
18:47:19 34960 8192000000 10267590256 10711318528 1336915216 14.030 98 33 67 0 1357715648 13602784 7197648
18:48:57 41262 8192000000 10261198464 10702508032 1304532624 13.736 96 28 56 2 1319896176 9501296 5862256
18:50:40 8919 8192000000 10270096512 10699214848 1340356608 14.061 99 53 105 1 1361970208 9728528 11885072
18:51:54 76688 8192000000 10268535872 10727030784 1338927184 14.048 98 21 42 0 1364344496 20605984 4811328
18:54:22 138960 8192000000 10275444288 10726686720 1295955728 13.658 94 16 32 0 1312536336 12358256 4222352
18:57:41 86437 8192000000 10274725056 10726019072 1303121568 13.724 95 14 28 0 1315604960 9243344 3240048
19:00:19 47090 8192000000 10278719648 10739191808 1379486592 14.412 100 27 54 1 1401997168 16094816 6415760
19:02:18 73518 8192000000 10278635488 10745094144 1359600672 14.234 99 11 22 0 1382964528 20725104 2638752
19:04:31 45337 8192000000 10274115664 10748194816 1376447472 14.385 100 3 6 0 1389785152 12815648 522032
19:06:17 50341 8192000000 10276730288 10736283648 1345354192 14.106 97 10 20 0 1361588640 13738096 2496352
19:08:08 68558 8192000000 10260854368 10708213760 1319246304 13.870 96 17 34 2 1334213072 11677632 3289136
19:10:17 43423 8192000000 10291412544 10746814464 1367254944 14.302 99 16 32 1 1382609776 11977328 3377504
19:12:04 54500 8192000000 10296175136 10747281408 1355480848 14.197 97 21 42 0 1374075248 14074592 4519808
19:13:59 62123 8192000000 10283743296 10747469824 1345432880 14.106 97 15 30 1 1361813312 13011856 3368576
19:16:02 98947 8192000000 10287840528 10736898048 1313562800 13.818 95 13 26 0 1331506704 15445456 2498448
19:18:42 89376 8192000000 10287569696 10746503168 1342022880 14.076 97 5 8 1 1361120928 18018944 1079104
19:21:11 105979 8192000000 10293457504 10751512576 1374892032 14.371 99 40 80 0 1398042192 13623328 9526832
19:24:08 59621 8192000000 10281312960 10736738304 1347745840 14.127 97 24 47 1 1363507456 10595552 5166064
19:26:08 102563 8192000000 10290474288 10754686976 1357330688 14.213 98 12 24 1 1385770240 25196016 3243536
19:28:59 65341 8192000000 10264474816 10721308672 1325730880 13.929 96 13 26 0 1340837696 11932128 3174688
19:31:08 77037 8192000000 10276976160 10736414720 1347938176 14.129 97 19 37 0 1366854592 14839664 4076752
19:33:30 66475 8192000000 10270629040 10726633472 1337607536 14.036 98 16 31 1 1350301712 9125280 3568896
19:35:36 39860 8192000000 10237474400 10682621952 1267909136 13.402 94 18 36 0 1287170784 15207296 4054352
19:37:16 41241 8192000000 8975436400 9174228992 143009088 1.715 11 2 4 0 143978992 853664 116240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-11-2021 19:39:07 to 30-11-2021 19:39:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 201 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-11-2021 19:39:23 to 30-11-2021 19:39:25.

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          241221
MON$OLDEST_ACTIVE               241222
MON$OLDEST_SNAPSHOT             241222
MON$NEXT_TRANSACTION            241222
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                       2020416
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
--- end of diff output ---

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

30.11.21 19:39:26. 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-30 19:39:28 No severe PSQL-related problems occured
30.11.21 19:39:29. End of report.