Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.10.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 15964872 kB
MemAvailable 30824368 kB
Buffers 0 kB
Cached 15710152 kB
SwapCached 4796 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-10-28 19:38:13
DTS_END                         2021-10-28 19:37:56.8570
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33620
TRN_ID                          248596
ATT_ID                          274
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: 9085.40 19277 1090248
customer order: creation 185.08 335 22209
customer order: refuse 15.59 381 1871
order to supplier: creation 48.04 1301 5765
order to supplier: removal 3.07 116943 368
invoice (draft): creation 48.05 3631 5766
invoice (draft): removal 46.83 907 5620
invoice accept: apply 44.62 18862 5354
invoice accept: cancel 42.86 1022 5143
customer reserve: creation 4092.34 188 491081
customer reserve: removal 4255.34 67 510641
realization accept: apply 89.13 194 10695
realization accept: cancel 78.07 126 9368
payment from customer: creation 62.05 121 7446
payment from customer: removal 5.52 85 662
payment to supplier: creation 51.72 137 6206
payment to supplier: removal 3.84 57 461
service: total inventory turnovers 4.96 1218 595
service: total monetary turnovers 7.28 55 873
service: aggregate perf. data 1.00 16748 120
service: refresh index statistics 0.03 223152 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-10-2021 19:38:56 to 28-10-2021 19:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5048
TEST_TIME 2 4025
TEST_TIME 3 4108
TEST_TIME 4 3709
TEST_TIME 5 4201
TEST_TIME 6 4637
TEST_TIME 7 4978
TEST_TIME 8 5388
TEST_TIME 9 5380
TEST_TIME 10 5832
TEST_TIME 11 5942
TEST_TIME 12 6259
TEST_TIME 13 7325
TEST_TIME 14 7460
TEST_TIME 15 7496
TEST_TIME 16 7675
TEST_TIME 17 7818
TEST_TIME 18 8060
TEST_TIME 19 8159
TEST_TIME 20 8254
TEST_TIME 21 8624
TEST_TIME 22 8732
TEST_TIME 23 8860
TEST_TIME 24 9263
TEST_TIME 25 9341
TEST_TIME 26 9582
TEST_TIME 27 9523
TEST_TIME 28 9713
TEST_TIME 29 9923
TEST_TIME 30 10454
TEST_TIME 31 10698
TEST_TIME 32 11376
TEST_TIME 33 11202
TEST_TIME 34 10968
TEST_TIME 35 10930
TEST_TIME 36 10701
TEST_TIME 37 10737
TEST_TIME 38 10552
TEST_TIME 39 10343
TEST_TIME 40 10168
TEST_TIME 41 10081
TEST_TIME 42 9972
TEST_TIME 43 9832
TEST_TIME 44 9765
TEST_TIME 45 9828
TEST_TIME 46 9819
TEST_TIME 47 9815
TEST_TIME 48 9659
TEST_TIME 49 9656
TEST_TIME 50 9561
TEST_TIME 51 9426
TEST_TIME 52 9462
TEST_TIME 53 9332
TEST_TIME 54 9295
TEST_TIME 55 9233
TEST_TIME 56 9140
TEST_TIME 57 9003
TEST_TIME 58 8922
TEST_TIME 59 9125
TEST_TIME 60 9074
TEST_TIME 61 9069
TEST_TIME 62 9095
TEST_TIME 63 9161
TEST_TIME 64 9193
TEST_TIME 65 9182
TEST_TIME 66 9214
TEST_TIME 67 9228
TEST_TIME 68 9262
TEST_TIME 69 9284
TEST_TIME 70 9268
TEST_TIME 71 9244
TEST_TIME 72 9285
TEST_TIME 73 9278
TEST_TIME 74 9306
TEST_TIME 75 9325
TEST_TIME 76 9217
TEST_TIME 77 9283
TEST_TIME 78 9196
TEST_TIME 79 9167
TEST_TIME 80 9192
TEST_TIME 81 9157
TEST_TIME 82 9119
TEST_TIME 83 9068
TEST_TIME 84 9026
TEST_TIME 85 8999
TEST_TIME 86 8995
TEST_TIME 87 8920
TEST_TIME 88 8967
TEST_TIME 89 8980
TEST_TIME 90 8979
TEST_TIME 91 8971
TEST_TIME 92 8994
TEST_TIME 93 9042
TEST_TIME 94 9034
TEST_TIME 95 9040
TEST_TIME 96 9106
TEST_TIME 97 9076
TEST_TIME 98 9078
TEST_TIME 99 9101
TEST_TIME 100 9151
TEST_TIME 101 9166
TEST_TIME 102 9230
TEST_TIME 103 9251
TEST_TIME 104 9246
TEST_TIME 105 9208
TEST_TIME 106 9153
TEST_TIME 107 9141
TEST_TIME 108 9103
TEST_TIME 109 9112
TEST_TIME 110 9126
TEST_TIME 111 9094
TEST_TIME 112 9074
TEST_TIME 113 9016
TEST_TIME 114 9014
TEST_TIME 115 9011
TEST_TIME 116 9012
TEST_TIME 117 9001
TEST_TIME 118 8999
TEST_TIME 119 9014
TEST_TIME 120 9089

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-10-2021 19:39:00 to 28-10-2021 19:39:03.

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 1543117 1543117 0 0.00 0 25940 7 0 0
doc_list_biud 1543112 1543112 0 0.00 0 70465 28 0 0
sp_add_invoice_to_stock 5467 5354 113 2.06 53 196246 18862 0 113
sp_cancel_adding_invoice 5143 5143 0 0.00 4 90671 1022 0 0
sp_cancel_client_order 1871 1871 0 0.00 4 14524 382 0 0
sp_cancel_customer_reserve 510641 510641 0 0.00 1 70470 67 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 2 3962 86 0 0
sp_cancel_pay_to_supplier 461 461 0 0.00 2 1799 57 0 0
sp_cancel_supplier_invoice 5627 5620 7 0.12 1 19880 908 0 7
sp_cancel_supplier_order 368 368 0 0.00 5 1153758 116943 0 0
sp_cancel_write_off 9368 9368 0 0.00 1 14459 127 0 0
sp_client_order 22209 22209 0 0.00 3 66216 335 0 0
sp_customer_reserve 491081 491081 0 0.00 0 32826 188 0 0
sp_fill_shopping_cart 33735 33735 0 0.00 1 34272 46 0 0
sp_fill_shopping_cart_clo_res 491074 491074 0 0.00 0 18239 13 0 0
sp_get_clo_for_invoice 5343 5343 0 0.00 0 34061 311 0 0
sp_kill_cost_storno 15634 15634 0 0.00 0 12324 67 0 0
sp_kill_qstorno_ret_qs2qd 1036638 1036638 0 0.00 0 70456 36 0 0
sp_kill_qty_storno 549047 549047 0 0.00 0 70464 78 0 0
sp_lock_selected_doc 22109 22109 0 0.00 0 6731 4 0 0
sp_make_cost_storno 29690 29690 0 0.00 0 15720 57 0 0
sp_multiply_rows_for_pdistr 29689 29689 0 0.00 0 11185 30 0 0
sp_multiply_rows_for_qdistr 22209 22209 0 0.00 0 66163 138 0 0
sp_pay_from_customer 7446 7446 0 0.00 3 17068 121 0 0
sp_pay_to_supplier 6206 6206 0 0.00 5 10960 138 0 0
sp_payment_common 13652 13652 0 0.00 1 17063 30 0 0
sp_qd_handle_on_cancel_clo 1871 1871 0 0.00 0 14473 344 0 0
sp_qd_handle_on_invoice_upd_sts 10486 10486 0 0.00 0 20752 373 0 0
sp_qd_handle_on_reserve_upd_sts 10693 10693 0 0.00 0 12803 60 0 0
sp_reserve_write_off 10695 10695 0 0.00 6 12847 195 0 0
sp_supplier_invoice 5766 5766 0 0.00 7 70819 3631 0 0
sp_supplier_order 5765 5765 0 0.00 5 45304 1302 0 0
srv_aggregate_perf_data 120 120 0 0.00 2318 207442 16748 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1591 136168 40684 0 0
srv_find_qd_qs_mism 1038698 1038698 0 0.00 0 25940 7 0 0
srv_make_invnt_saldo 595 595 0 0.00 27 140982 1219 0 0
srv_make_money_saldo 873 873 0 0.00 1 23947 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 142841 325554 223153 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 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 5354 5354 0 0.00 0 21818 199 0 0
t$perf-norm:sp_cancel_adding_invoice 469 469 0 0.00 1 7334 136 0 0
t$perf-norm:sp_cancel_client_order 1871 1871 0 0.00 0 5108 16 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 1004 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 1291 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 461 461 0 0.00 0 2086 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 5645 50 0 0
t$perf-norm:sp_cancel_supplier_order 368 368 0 0.00 0 25800 1981 0 0
t$perf-norm:sp_cancel_write_off 1087 1087 0 0.00 0 3544 8 0 0
t$perf-norm:sp_client_order 22209 22209 0 0.00 0 12090 20 0 0
t$perf-norm:sp_customer_reserve 1122 1122 0 0.00 0 5340 16 0 0
t$perf-norm:sp_pay_from_customer 7446 7446 0 0.00 0 7433 11 0 0
t$perf-norm:sp_pay_to_supplier 6206 6206 0 0.00 0 8497 15 0 0
t$perf-norm:sp_reserve_write_off 10695 10695 0 0.00 0 8887 15 0 0
t$perf-norm:sp_supplier_invoice 5766 5766 0 0.00 0 4657 20 0 0
t$perf-norm:sp_supplier_order 5765 5765 0 0.00 0 6782 22 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 157 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 56 2 0 0
t$perf-norm:srv_make_invnt_saldo 595 595 0 0.00 0 1372 25 0 0
t$perf-norm:srv_make_money_saldo 873 873 0 0.00 0 3024 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 20 7 0 0
v_add_invoice_to_stock 10908 10682 226 2.07 0 7982 20 0 226
v_all_customers 40006 40006 0 0.00 0 32 1 0 0
v_all_suppliers 31812 31812 0 0.00 0 20 1 0 0
v_all_wares 44418 44418 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 938 938 0 0.00 0 359 6 0 0
v_cancel_client_order 3742 3742 0 0.00 0 4635 8 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 2612 9 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 4220 10 0 0
v_cancel_payment_to_supplier 922 922 0 0.00 0 1793 6 0 0
v_cancel_supplier_invoice 840 826 14 1.66 0 539 14 0 14
v_cancel_supplier_order 736 736 0 0.00 0 6478 18 0 0
v_cancel_write_off 2174 2174 0 0.00 0 6774 23 0 0
v_max_id_clo_ord 5765 5765 0 0.00 0 3353 2 0 0
v_max_id_clo_res 1122 1122 0 0.00 0 3445 12 0 0
v_max_id_ord_sup 5761 5761 0 0.00 0 5399 4 0 0
v_max_non_paid_invoice 1826 1826 0 0.00 0 3045 5 0 0
v_max_non_paid_realizn 4095 4095 0 0.00 0 770 3 0 0
v_min_id_clo_ord 5765 5765 0 0.00 0 34241 30 0 0
v_min_id_clo_res 1122 1122 0 0.00 0 4347 10 0 0
v_min_id_ord_sup 5761 5761 0 0.00 0 6188 8 0 0
v_min_non_paid_invoice 6206 6206 0 0.00 0 8727 16 0 0
v_min_non_paid_realizn 7446 7446 0 0.00 0 17058 27 0 0
v_our_firm 11114 11114 0 0.00 0 15 1 0 0
v_reserve_write_off 21386 21386 0 0.00 0 8010 9 0 0
x_lock_depdocs_on_canc_invoice 10350 10350 0 0.00 0 13426 133 0 0
x_lock_depdocs_on_canc_sup_ord 368 368 0 0.00 0 8427 387 0 0
x_make_qstorno_1000_1200 5765 5765 0 0.00 2 44659 1189 0 0
x_make_qstorno_1200_2000 5766 5766 0 0.00 2 70807 3479 0 0
x_make_qstorno_2100_3300 491081 491081 0 0.00 0 26594 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2021 19:39:04 to 28-10-2021 19:39:08.

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:08:00 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:01 26599 8192000000 9884452528 10192703488 964597936 10.534 100 81 162 0 992370928 5909248 21863744
17:10:37 28501 8192000000 9932617104 10252046336 1016168832 11.035 100 84 168 0 1046607936 7963520 22475584
17:12:09 21101 8192000000 9971614304 10300375040 1051056448 11.371 100 88 175 1 1082690640 9538384 22095808
17:13:34 13358 8192000000 10029903248 10376957952 1113159664 11.962 100 78 155 1 1142400592 10531408 18709520
17:14:51 9272 8192000000 10068634144 10431418368 1162206320 12.424 100 75 150 0 1191364192 11861728 17296144
17:16:03 5512 8192000000 10126983552 10501709824 1222695024 12.987 100 83 166 0 1254057136 13266752 18095360
17:17:11 3555 8192000000 10170642048 10546622464 1257936416 13.311 100 90 180 0 1292363872 14514448 19913008
17:18:16 1873 8192000000 10194439328 10579140608 1281936512 13.531 100 82 166 1 1312207792 12518528 17752752
17:19:20 1282 8192000000 10213189648 10596175872 1296005696 13.659 100 88 175 1 1329455648 14008752 19441200
17:20:22 14768 8192000000 10194522800 10597339136 1302387808 13.717 100 39 77 0 1317440480 5882880 9169792
17:21:38 7540 8192000000 10200446416 10614665216 1315030416 13.832 100 31 63 0 1328736448 6069472 7636560
17:22:48 11115 8192000000 10205643984 10624614400 1323837344 13.911 100 25 50 3 1335666464 6074960 5754160
17:24:05 7276 8192000000 10214577648 10639216640 1333437184 13.998 100 21 42 0 1345193968 6579072 5177712
17:25:15 29591 8192000000 10224444448 10652430336 1337014864 14.030 100 26 52 0 1349448704 7020976 5412864
17:26:44 5273 8192000000 10231030080 10646536192 1320318272 13.880 99 38 76 0 1336751040 7998448 8434320
17:27:54 5231 8192000000 10200173744 10616012800 1300319152 13.698 98 22 44 0 1311039808 5495152 5225504
17:29:00 14874 8192000000 10171659408 10583052288 1254771760 13.282 97 38 76 4 1268475648 5385888 8318000
17:30:15 9287 8192000000 10159060288 10567700480 1255961328 13.293 98 24 48 0 1267256816 5576544 5718944
17:31:28 6626 8192000000 10143873312 10534735872 1210316240 12.872 97 23 45 0 1220533248 5036512 5180496
17:32:35 11575 8192000000 10146292928 10554306560 1249503264 13.234 99 26 52 0 1261557200 5762928 6291008
17:33:49 72416 8192000000 10159790336 10571304960 1253278592 13.268 98 28 55 0 1264968992 5095168 6595232
17:36:01 7757 8192000000 10198166832 10602061824 1295699008 13.656 100 23 46 0 1306581696 5594288 5288400
17:37:10 8747 8192000000 10201278736 10614857728 1309007296 13.777 100 19 38 0 1319033616 5595312 4431008
17:38:22 9239 8192000000 10255923360 10655227904 1338411744 14.043 100 62 124 0 1361970336 8256976 15301616
17:39:33 69776 8192000000 10263647776 10680508416 1355126544 14.194 100 28 56 0 1371154624 9454176 6573904
17:41:47 30795 8192000000 10269650128 10686865408 1355714288 14.199 99 44 88 0 1378902256 13033488 10154480
17:43:25 22526 8192000000 10296262672 10723577856 1381767552 14.432 100 44 88 1 1410462320 17982432 10712336
17:44:56 6729 8192000000 10296069312 10719383552 1382779280 14.441 100 56 112 1 1411837056 15382080 13675696
17:46:08 12589 8192000000 10318067952 10736857088 1396388176 14.563 100 61 121 1 1428740928 18370224 13982528
17:47:26 2239 8192000000 10337730960 10751275008 1404761408 14.637 100 75 146 2 1444234112 21741264 17731440
17:48:31 4063 8192000000 10342091664 10753044480 1400006336 14.595 100 78 155 0 1449361488 31146624 18208528
17:49:39 4726 8192000000 10354636752 10768519168 1407870896 14.665 100 81 161 3 1461610240 34056128 19683216
17:50:49 16808 8192000000 10318943584 10736123904 1384988816 14.461 100 58 116 0 1426308288 27538736 13780736
17:52:08 1654 8192000000 10325239728 10735210496 1383626384 14.449 100 84 167 4 1430291088 26478448 20186256
17:53:15 8950 8192000000 10328608720 10744066048 1390895056 14.514 100 66 133 0 1437985744 30182896 16907792
17:54:32 4205 8192000000 10325971888 10735140864 1379224912 14.410 100 72 143 0 1430475920 33383392 17867616
17:55:41 2527 8192000000 10333609680 10733056000 1374826496 14.370 99 84 167 1 1432921344 37186496 20908352
17:56:50 1591 8192000000 10348776144 10753667072 1380447504 14.421 100 84 169 1 1444301248 43015168 20838576
17:57:57 3137 8192000000 10350351120 10750980096 1378680304 14.405 99 81 160 1 1448887664 49690896 20516464
17:59:06 10020 8192000000 10335648464 10757660672 1373066576 14.355 99 45 89 1 1431958176 46079632 12811968
18:00:23 5074 8192000000 10330850720 10748837888 1362310224 14.258 98 49 98 2 1418867232 43116368 13440640
18:01:32 9261 8192000000 10355349744 10777358336 1396986672 14.568 100 47 94 0 1460909152 50590832 13331648
18:02:46 21946 8192000000 10349934608 10772652032 1392961936 14.532 100 37 74 0 1450469648 46501008 11006704
18:04:20 12039 8192000000 10346831168 10773110784 1393906768 14.541 100 37 72 0 1449460672 45573120 9980784
18:05:33 24182 8192000000 10355738048 10789367808 1396892688 14.567 100 31 62 3 1456931072 51032256 9006128
18:07:29 15913 8192000000 10325589040 10760638464 1363437952 14.268 100 34 69 1 1430009904 56647616 9924336
18:08:51 17581 8192000000 10325232224 10769227776 1368513184 14.314 99 59 113 4 1429785600 47403744 13868672
18:10:09 46394 8192000000 10295985824 10746433536 1374308528 14.366 99 24 46 0 1391621040 11942256 5370256
18:12:06 72852 8192000000 10295818512 10745147392 1378956992 14.407 99 16 32 0 1396295504 13471808 3866704
18:14:28 101065 8192000000 10284235312 10734059520 1360158128 14.239 98 16 32 0 1371425280 7312768 3954384
18:17:11 19847 8192000000 10302863872 10741092352 1387127216 14.480 100 51 102 0 1407478112 8502656 11848240
18:18:54 45002 8192000000 10304927504 10756931584 1394548848 14.546 100 32 61 0 1415616832 14546144 6521840
18:21:42 29765 8192000000 10309826128 10757283840 1392369072 14.527 100 40 81 3 1419110432 17728016 9013344
18:23:25 40630 8192000000 10308610304 10762317824 1400557200 14.600 100 10 20 1 1413184160 10427968 2198992
18:25:07 61354 8192000000 10324556512 10792005632 1411856320 14.700 100 9 18 1 1431578016 17795792 1925904
18:27:16 136168 8192000000 10297970288 10757255168 1364291232 14.276 97 24 45 0 1376766656 7324880 5150544
18:30:36 39971 8192000000 10288310960 10740932608 1372949696 14.353 99 34 68 0 1394088400 13318432 7820272
18:32:18 61257 8192000000 10269914720 10730110976 1357333168 14.213 98 13 26 0 1370439632 9496272 3610192
18:34:49 63538 8192000000 10285782160 10746642432 1378470736 14.403 99 21 42 0 1398208464 14779040 4958688
18:36:53 67129 8192000000 10271235312 10737377280 1330746752 13.974 96 13 26 0 1347475920 13792704 2936464
18:39:01 83915 8192000000 10278927632 10740645888 1328332000 13.952 96 18 36 1 1353863088 21546864 3984224
18:41:25 48418 8192000000 10295848128 10747539456 1359675200 14.234 98 15 30 1 1371929840 8500896 3753744
18:43:16 49062 8192000000 10288567344 10742816768 1367681968 14.306 99 28 56 0 1383884224 10089936 6112320
18:45:05 36648 8192000000 10277655104 10725892096 1349465712 14.143 98 39 79 0 1368148832 9982256 8700864
18:46:44 57769 8192000000 10276467632 10721349632 1333490000 13.999 97 18 37 0 1346878496 9478544 3909952
18:48:44 41330 8192000000 10297398544 10739523584 1373065648 14.355 99 22 44 0 1388444656 10714448 4664560
18:50:36 59937 8192000000 10300314064 10738327552 1368090976 14.310 99 35 68 5 1390668256 14808064 7769216
18:52:37 95526 8192000000 10273074832 10712612864 1342353616 14.079 98 22 44 0 1362570128 14851824 5364688
18:55:19 35384 8192000000 10268853440 10724421632 1339330608 14.051 98 24 46 1 1356512816 11896656 5285552
18:56:55 59966 8192000000 10255547456 10721071104 1329246224 13.960 98 12 24 0 1351477136 19358784 2872128
18:59:02 26559 8192000000 10261075632 10721013760 1346026096 14.112 99 8 14 0 1360273136 12609296 1637744
19:00:29 62161 8192000000 10270086736 10724610048 1349058608 14.139 98 15 30 0 1360625824 8338992 3228224
19:02:32 28732 8192000000 10292745904 10737991680 1377291664 14.392 100 35 69 4 1397945840 13076416 7577760
19:04:01 79032 8192000000 10281271104 10751238144 1384026608 14.453 100 12 24 0 1399353232 12514960 2811664
19:06:33 55327 8192000000 10286398224 10735501312 1346799488 14.119 97 13 26 0 1358438192 8465680 3173024
19:08:35 38518 8192000000 10292766864 10740957184 1373161776 14.355 99 21 42 0 1388113120 10303120 4648224
19:10:22 25963 8192000000 10291909120 10736050176 1359266416 14.231 98 37 74 0 1381607616 13890752 8450448
19:11:58 18490 8192000000 10302300800 10742669312 1369473808 14.322 99 38 75 0 1390606880 12626128 8506944
19:13:17 72095 8192000000 10298579120 10738757632 1360166656 14.239 98 20 39 0 1375503200 11061824 4274720
19:15:31 12364 8192000000 10308910640 10747895808 1377731280 14.396 99 48 97 1 1395462128 7238848 10492000
19:16:43 69372 8192000000 10290472512 10747719680 1360138816 14.239 98 25 49 2 1376407088 10824000 5444272
19:18:59 100260 8192000000 10298229488 10766155776 1374532912 14.368 99 9 18 0 1389831152 13201408 2096832
19:21:42 68915 8192000000 10282141984 10732748800 1360395168 14.241 98 17 34 0 1374973520 11256576 3321776
19:23:52 58914 8192000000 10278803328 10734608384 1367221296 14.302 99 26 52 2 1383696160 10557584 5917280
19:25:57 43209 8192000000 10290637488 10755923968 1393819072 14.540 100 28 56 3 1411589712 11505744 6264896
19:27:57 53364 8192000000 10263024736 10725892096 1304374848 13.735 94 8 16 0 1315562896 9257136 1930912
19:29:56 59731 8192000000 10260611392 10708869120 1340333072 14.060 98 18 37 1 1350358480 5745168 4280240
19:31:59 61055 8192000000 10262780336 10700546048 1318602176 13.864 97 11 22 0 1327596464 6146640 2847648
19:34:03 58051 8192000000 10246699440 10697973760 1324593440 13.918 98 16 31 0 1339241936 10997392 3651104
19:36:02 63156 8192000000 10266237776 10719363072 1343868512 14.092 99 25 50 0 1367344128 18011904 5463712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-10-2021 19:39:10 to 28-10-2021 19:39:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 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 28-10-2021 19:39:26 to 28-10-2021 19:39:28.

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          260169
MON$OLDEST_ACTIVE               260170
MON$OLDEST_SNAPSHOT             260170
MON$NEXT_TRANSACTION            260170
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                       2046560
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.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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'

28.10.21 19:39:29. 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-10-28 19:39:32 No severe PSQL-related problems occured
28.10.21 19:39:33. End of report.