Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.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 15970172 kB
MemAvailable 30637124 kB
Buffers 0 kB
Cached 15714020 kB
SwapCached 3776 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-16 19:37:54
DTS_END                         2021-10-16 19:37:54.5660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44842
TRN_ID                          236455
ATT_ID                          670
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: 9293.87 22026 1115264
customer order: creation 169.85 371 20382
customer order: refuse 14.08 368 1689
order to supplier: creation 44.83 1212 5380
order to supplier: removal 3.46 102572 415
invoice (draft): creation 45.23 3322 5427
invoice (draft): removal 53.67 756 6440
invoice accept: apply 41.45 17785 4974
invoice accept: cancel 48.01 743 5761
customer reserve: creation 3841.61 175 460993
customer reserve: removal 4736.80 60 568416
realization accept: apply 81.49 194 9779
realization accept: cancel 86.59 107 10391
payment from customer: creation 57.61 135 6913
payment from customer: removal 5.55 84 666
payment to supplier: creation 48.38 145 5805
payment to supplier: removal 3.47 75 416
service: total inventory turnovers 4.64 1092 557
service: total monetary turnovers 6.43 38 771
service: aggregate perf. data 0.71 22912 85
service: refresh index statistics 0.03 288368 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2021 19:38:33 to 16-10-2021 19:38:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3166
TEST_TIME 2 3674
TEST_TIME 3 2890
TEST_TIME 4 3352
TEST_TIME 5 3654
TEST_TIME 6 4161
TEST_TIME 7 4078
TEST_TIME 8 4171
TEST_TIME 9 4613
TEST_TIME 10 4735
TEST_TIME 11 5254
TEST_TIME 12 5417
TEST_TIME 13 5744
TEST_TIME 14 6500
TEST_TIME 15 6673
TEST_TIME 16 6512
TEST_TIME 17 6593
TEST_TIME 18 7163
TEST_TIME 19 7492
TEST_TIME 20 7566
TEST_TIME 21 8014
TEST_TIME 22 8179
TEST_TIME 23 8432
TEST_TIME 24 8767
TEST_TIME 25 9203
TEST_TIME 26 9165
TEST_TIME 27 9913
TEST_TIME 28 10980
TEST_TIME 29 10899
TEST_TIME 30 10546
TEST_TIME 31 10374
TEST_TIME 32 10107
TEST_TIME 33 10167
TEST_TIME 34 10271
TEST_TIME 35 10121
TEST_TIME 36 9964
TEST_TIME 37 9888
TEST_TIME 38 9737
TEST_TIME 39 9872
TEST_TIME 40 9777
TEST_TIME 41 9769
TEST_TIME 42 9607
TEST_TIME 43 9532
TEST_TIME 44 9572
TEST_TIME 45 9430
TEST_TIME 46 9325
TEST_TIME 47 9314
TEST_TIME 48 9292
TEST_TIME 49 9220
TEST_TIME 50 9191
TEST_TIME 51 9227
TEST_TIME 52 9250
TEST_TIME 53 9406
TEST_TIME 54 9476
TEST_TIME 55 9458
TEST_TIME 56 9393
TEST_TIME 57 9389
TEST_TIME 58 9511
TEST_TIME 59 9557
TEST_TIME 60 9548
TEST_TIME 61 9613
TEST_TIME 62 9522
TEST_TIME 63 9400
TEST_TIME 64 9344
TEST_TIME 65 9329
TEST_TIME 66 9295
TEST_TIME 67 9254
TEST_TIME 68 9213
TEST_TIME 69 9167
TEST_TIME 70 9309
TEST_TIME 71 9330
TEST_TIME 72 9335
TEST_TIME 73 9325
TEST_TIME 74 9388
TEST_TIME 75 9313
TEST_TIME 76 9401
TEST_TIME 77 9351
TEST_TIME 78 9422
TEST_TIME 79 9507
TEST_TIME 80 9432
TEST_TIME 81 9377
TEST_TIME 82 9339
TEST_TIME 83 9316
TEST_TIME 84 9319
TEST_TIME 85 9298
TEST_TIME 86 9238
TEST_TIME 87 9258
TEST_TIME 88 9246
TEST_TIME 89 9201
TEST_TIME 90 9177
TEST_TIME 91 9234
TEST_TIME 92 9270
TEST_TIME 93 9239
TEST_TIME 94 9211
TEST_TIME 95 9302
TEST_TIME 96 9357
TEST_TIME 97 9361
TEST_TIME 98 9369
TEST_TIME 99 9367
TEST_TIME 100 9407
TEST_TIME 101 9421
TEST_TIME 102 9420
TEST_TIME 103 9384
TEST_TIME 104 9331
TEST_TIME 105 9310
TEST_TIME 106 9281
TEST_TIME 107 9280
TEST_TIME 108 9243
TEST_TIME 109 9206
TEST_TIME 110 9170
TEST_TIME 111 9164
TEST_TIME 112 9226
TEST_TIME 113 9202
TEST_TIME 114 9199
TEST_TIME 115 9250
TEST_TIME 116 9220
TEST_TIME 117 9241
TEST_TIME 118 9250
TEST_TIME 119 9268
TEST_TIME 120 9300

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-10-2021 19:38:36 to 16-10-2021 19:38:40.

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 1542100 1542100 0 0.00 0 28923 6 0 0
doc_list_biud 1542098 1542098 0 0.00 0 48627 28 0 0
sp_add_invoice_to_stock 5043 4974 69 1.36 32 194876 17786 0 69
sp_cancel_adding_invoice 5761 5761 0 0.00 4 63852 743 0 0
sp_cancel_client_order 1689 1689 0 0.00 4 10932 369 0 0
sp_cancel_customer_reserve 568416 568416 0 0.00 1 43441 60 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 8904 84 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 3291 75 0 0
sp_cancel_supplier_invoice 6446 6440 6 0.09 2 48637 756 0 6
sp_cancel_supplier_order 415 415 0 0.00 5 1035909 102572 0 0
sp_cancel_write_off 10391 10391 0 0.00 1 24564 108 0 0
sp_client_order 20382 20382 0 0.00 4 27890 371 0 0
sp_customer_reserve 460993 460993 0 0.00 0 52660 176 0 0
sp_fill_shopping_cart 31173 31173 0 0.00 1 10263 38 0 0
sp_fill_shopping_cart_clo_res 460979 460979 0 0.00 0 23696 15 0 0
sp_get_clo_for_invoice 4959 4959 0 0.00 0 39834 323 0 0
sp_kill_cost_storno 17234 17234 0 0.00 0 24457 55 0 0
sp_kill_qstorno_ret_qs2qd 1154078 1154078 0 0.00 0 48619 32 0 0
sp_kill_qty_storno 607850 607850 0 0.00 0 48623 69 0 0
sp_lock_selected_doc 20310 20310 0 0.00 0 10445 6 0 0
sp_make_cost_storno 27456 27456 0 0.00 0 28281 59 0 0
sp_multiply_rows_for_pdistr 27455 27455 0 0.00 0 28242 32 0 0
sp_multiply_rows_for_qdistr 20382 20382 0 0.00 0 27882 179 0 0
sp_pay_from_customer 6913 6913 0 0.00 3 25451 135 0 0
sp_pay_to_supplier 5805 5805 0 0.00 4 25234 145 0 0
sp_payment_common 12717 12717 0 0.00 1 25438 51 0 0
sp_qd_handle_on_cancel_clo 1689 1689 0 0.00 0 10925 337 0 0
sp_qd_handle_on_invoice_upd_sts 10720 10720 0 0.00 0 47969 310 0 0
sp_qd_handle_on_reserve_upd_sts 9779 9779 0 0.00 0 17449 55 0 0
sp_reserve_write_off 9779 9779 0 0.00 6 29341 195 0 0
sp_supplier_invoice 5427 5427 0 0.00 7 97987 3323 0 0
sp_supplier_order 5380 5380 0 0.00 5 33746 1212 0 0
srv_aggregate_perf_data 85 85 0 0.00 2788 499336 22913 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2673 116633 45943 0 0
srv_find_qd_qs_mism 1068086 1068086 0 0.00 0 19208 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 40 65025 1093 0 0
srv_make_money_saldo 771 771 0 0.00 1 12680 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 65081 379577 288368 0 0
t$perf-abend:sp_add_invoice_to_stock 69 69 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4974 4974 0 0.00 0 12557 163 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 2365 80 0 0
t$perf-norm:sp_cancel_client_order 1689 1689 0 0.00 0 4227 16 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 3712 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 1861 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 2129 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 380 9 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 22106 1411 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 13773 28 0 0
t$perf-norm:sp_client_order 20382 20382 0 0.00 0 12180 18 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 2374 10 0 0
t$perf-norm:sp_pay_from_customer 6913 6913 0 0.00 0 9033 13 0 0
t$perf-norm:sp_pay_to_supplier 5805 5805 0 0.00 0 10233 14 0 0
t$perf-norm:sp_reserve_write_off 9779 9779 0 0.00 0 14013 13 0 0
t$perf-norm:sp_supplier_invoice 5427 5427 0 0.00 0 12782 17 0 0
t$perf-norm:sp_supplier_order 5380 5380 0 0.00 0 5962 12 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 180 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 13 1 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 1194 14 0 0
t$perf-norm:srv_make_money_saldo 771 771 0 0.00 0 2002 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10056 9918 138 1.37 0 7269 17 0 138
v_all_customers 37258 37258 0 0.00 0 21 0 0 0
v_all_suppliers 30170 30170 0 0.00 0 56 1 0 0
v_all_wares 40764 40764 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 2229 9 0 0
v_cancel_client_order 3378 3378 0 0.00 0 5869 8 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 5197 10 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 3383 10 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 829 6 0 0
v_cancel_supplier_invoice 788 776 12 1.52 0 1775 19 0 12
v_cancel_supplier_order 830 830 0 0.00 0 1277 5 0 0
v_cancel_write_off 1920 1920 0 0.00 0 4626 16 0 0
v_max_id_clo_ord 5371 5371 0 0.00 0 2069 2 0 0
v_max_id_clo_res 959 959 0 0.00 0 8178 35 0 0
v_max_id_ord_sup 5419 5419 0 0.00 0 3704 4 0 0
v_max_non_paid_invoice 1509 1509 0 0.00 0 2804 4 0 0
v_max_non_paid_realizn 3475 3475 0 0.00 0 3516 5 0 0
v_min_id_clo_ord 5371 5371 0 0.00 0 1721 5 0 0
v_min_id_clo_res 959 959 0 0.00 0 2486 6 0 0
v_min_id_ord_sup 5419 5419 0 0.00 0 3298 6 0 0
v_min_non_paid_invoice 5804 5804 0 0.00 0 24647 40 0 0
v_min_non_paid_realizn 6913 6913 0 0.00 0 25326 48 0 0
v_our_firm 10382 10382 0 0.00 0 67 1 0 0
v_reserve_write_off 19558 19558 0 0.00 0 7820 7 0 0
x_lock_depdocs_on_canc_invoice 11813 11813 0 0.00 0 20129 115 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 19574 389 0 0
x_make_qstorno_1000_1200 5380 5380 0 0.00 1 33739 1142 0 0
x_make_qstorno_1200_2000 5427 5427 0 0.00 3 97960 3183 0 0
x_make_qstorno_2100_3300 460993 460993 0 0.00 0 52640 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2021 19:38:41 to 16-10-2021 19:38:45.

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:58 16 8192000000 8809816128 8824918016 12027904 0.146 3 3 4 0 12213248 78240 107104
17:08:59 18238 8192000000 9879704496 10187702272 965819680 10.546 100 88 176 0 994826704 5520800 23486224
17:10:25 27760 8192000000 9936019456 10254454784 1015124544 11.025 100 91 182 0 1046745920 7558480 24062896
17:11:56 15913 8192000000 9976716368 10312019968 1064705680 11.501 100 79 158 0 1093551472 8807600 20038192
17:13:15 11494 8192000000 10040534816 10386825216 1125846960 12.082 100 79 158 0 1154269728 9400448 19022320
17:14:30 9571 8192000000 10077696032 10439602176 1165699824 12.457 100 71 142 1 1192192224 10287328 16205072
17:15:44 6316 8192000000 10127842464 10499612672 1219818304 12.960 100 79 158 0 1250271984 12276176 18177504
17:16:53 3100 8192000000 10159374784 10536251392 1253371088 13.269 100 84 167 1 1286212416 14618976 18222352
17:17:58 2111 8192000000 10193249168 10573766656 1284097424 13.550 100 90 180 2 1317052656 13180592 19774640
17:19:01 1426 8192000000 10223180528 10609315840 1305616416 13.746 100 91 181 1 1339543184 13808832 20117936
17:20:04 42435 8192000000 10229486512 10624438272 1316400192 13.844 100 63 128 3 1337784464 6899392 14484880
17:21:48 5318 8192000000 10214882800 10625429504 1321837520 13.893 100 40 80 1 1339050320 8033488 9179312
17:22:54 8045 8192000000 10213255472 10635116544 1328428576 13.953 100 25 50 0 1340681408 6403984 5848848
17:24:07 58149 8192000000 10221096832 10644803584 1331224704 13.978 100 11 22 0 1339526448 5642144 2659600
17:26:06 7241 8192000000 10226686544 10649972736 1339210336 14.050 100 20 40 1 1349358624 5030192 5118096
17:27:14 8800 8192000000 10226060064 10655080448 1342804208 14.083 100 19 38 0 1353372656 6141120 4427328
17:28:24 5473 8192000000 10199040752 10612195328 1277292080 13.488 97 30 60 2 1290959392 6496640 7170672
17:29:30 25433 8192000000 10173440464 10591703040 1244775424 13.190 95 32 64 5 1258807936 5983584 8048928
17:30:56 6252 8192000000 10157920928 10556571648 1232683856 13.079 97 29 58 0 1244081936 4810224 6587856
17:32:02 6941 8192000000 10113960912 10513952768 1209047680 12.860 98 17 34 0 1218146976 5182608 3916688
17:33:10 13219 8192000000 10137195616 10544037888 1253638912 13.272 99 18 36 0 1263674672 5808240 4227520
17:34:24 25663 8192000000 10156825312 10566078464 1261897008 13.347 99 46 92 0 1279185136 5773120 11515008
17:35:53 10308 8192000000 10184934096 10595717120 1293030960 13.632 100 15 28 0 1301656192 5162640 3462592
17:37:04 5336 8192000000 10205779296 10612858880 1306491920 13.754 100 28 54 1 1317911072 4233952 7185200
17:38:15 67549 8192000000 10245517008 10657632256 1338858656 14.047 100 44 90 3 1359596944 10357216 10381072
17:40:25 44961 8192000000 10271905648 10690220032 1358965872 14.228 100 51 101 0 1387288704 16368816 11954016
17:42:14 28829 8192000000 10282165648 10706587648 1371452496 14.340 100 50 100 3 1401964832 18894448 11617888
17:43:48 12553 8192000000 10300834880 10721599488 1384536112 14.457 100 49 98 3 1416603872 19872336 12195424
17:45:19 15738 8192000000 10315521888 10734919680 1393493184 14.537 100 55 110 0 1429594864 22889040 13212640
17:46:40 12615 8192000000 10330938720 10748895232 1396827264 14.567 100 57 112 2 1433276624 23651472 12797888
17:47:58 2672 8192000000 10351112416 10768687104 1412169712 14.703 100 67 134 0 1460649680 32736144 15743824
17:49:06 4185 8192000000 10371129568 10797023232 1433017856 14.888 100 58 116 2 1484059088 36401712 14639520
17:50:16 3275 8192000000 10374129856 10795225088 1413757328 14.717 99 77 153 0 1479043936 46910032 18376576
17:51:24 3078 8192000000 10399388768 10817523712 1430129328 14.862 100 84 169 2 1505023552 53550784 21343440
17:52:29 8449 8192000000 10364511456 10792644608 1408031744 14.666 99 40 79 2 1461987344 43074912 10880688
17:53:43 9016 8192000000 10374947536 10799472640 1419924992 14.772 100 47 94 1 1480243120 47893360 12424768
17:54:57 8040 8192000000 10385443824 10811944960 1420611008 14.778 100 45 90 2 1492492032 58329008 13552016
17:56:11 8197 8192000000 10324099488 10751545344 1348016176 14.130 97 33 66 1 1411478240 54035200 9426864
17:57:24 8595 8192000000 10320612528 10739720192 1349813520 14.146 99 46 92 1 1419054688 56373840 12867328
17:58:36 10700 8192000000 10317081952 10745614336 1367455376 14.304 100 34 68 1 1427212608 50359808 9397424
18:00:12 21492 8192000000 10332597744 10768363520 1375914592 14.380 100 35 68 1 1438030256 52463232 9652432
18:01:42 27914 8192000000 10323830016 10759684096 1344486672 14.098 98 32 64 4 1407926224 53625216 9814336
18:03:23 22563 8192000000 10321815136 10759110656 1369486080 14.322 100 26 52 1 1427927072 50651104 7789888
18:04:55 27313 8192000000 10308127904 10758008832 1347218560 14.122 99 16 32 1 1402575552 49879856 5477136
18:06:58 76412 8192000000 10256083440 10679730176 1310241120 13.788 97 46 88 0 1329615664 7587760 11786784
18:09:23 86772 8192000000 10268501600 10722918400 1342120480 14.077 98 31 60 0 1366545184 17025744 7398960
18:11:51 35081 8192000000 10270674912 10722213888 1352383648 14.169 99 17 35 0 1368700640 12675952 3641040
18:13:27 91084 8192000000 10272649600 10724700160 1334115136 14.004 97 17 33 0 1348784608 11023888 3645584
18:16:00 44459 8192000000 10271729520 10726187008 1361660592 14.252 99 11 22 1 1380843760 16480752 2702416
18:17:52 34047 8192000000 10262695872 10722504704 1366729904 14.298 100 12 25 0 1383762992 13822096 3210992
18:19:31 78280 8192000000 10284253808 10733387776 1365785440 14.289 99 21 42 1 1384317648 13980256 4551952
18:21:54 43477 8192000000 10270056336 10744340480 1374761920 14.370 100 7 14 0 1390847536 14620288 1465328
18:23:57 40351 8192000000 10273118864 10725052416 1372697312 14.351 99 19 37 3 1386209488 9301744 4210432
18:25:39 52470 8192000000 10264650944 10727038976 1365894448 14.290 99 26 52 1 1378407968 6953136 5560384
18:27:36 55259 8192000000 10273248672 10747424768 1357533840 14.215 98 8 16 0 1371962928 12497968 1931120
18:29:32 85997 8192000000 10289484448 10758422528 1375861696 14.380 99 24 48 2 1398799568 17213632 5724240
18:32:03 107617 8192000000 10299584736 10750803968 1371589616 14.341 99 21 42 1 1387112832 10726464 4796752
18:35:10 35468 8192000000 10292966928 10761240576 1378637136 14.404 99 22 44 1 1402487312 18311280 5538896
18:37:08 64625 8192000000 10301101024 10754572288 1377103520 14.391 99 22 44 1 1391871040 9462480 5305040
18:39:39 111254 8192000000 10287251568 10748076032 1378934848 14.407 99 41 82 2 1398146272 9517968 9693456
18:43:32 71655 8192000000 10293227856 10756317184 1379336800 14.411 99 8 16 0 1391997536 10583088 2077648
18:46:20 31176 8192000000 10313416832 10754887680 1335871488 14.020 95 46 90 1 1365136560 18344944 10920128
18:47:53 93000 8192000000 10277170928 10743783424 1290029744 13.604 93 13 26 0 1307636912 14689616 2917552
18:50:26 58495 8192000000 10277673840 10729811968 1336648048 14.027 97 23 46 0 1354071936 12566128 4857760
18:52:25 52352 8192000000 10268452464 10717511680 1287457408 13.581 94 37 74 1 1316374352 20361408 8555536
18:54:19 17338 8192000000 10275732400 10706583552 1334829728 14.011 98 38 73 1 1353904592 10957040 8117824
18:55:39 63228 8192000000 10259544464 10707939328 1308323616 13.771 97 23 46 1 1329554992 16168880 5062496
18:57:46 116633 8192000000 10264060736 10712985600 1345790848 14.110 99 21 42 0 1357824384 7488560 4544976
19:00:44 50760 8192000000 10258724880 10716545024 1353310960 14.177 99 11 22 0 1367574080 11518800 2744320
19:02:43 45221 8192000000 10243905872 10691481600 1314898640 13.830 97 6 12 1 1328525408 12296240 1330528
19:04:29 77144 8192000000 10233689392 10686746624 1310953584 13.795 97 6 12 0 1319287056 6828560 1504912
19:06:59 63987 8192000000 10259808000 10712895488 1344621456 14.099 98 18 36 1 1360000368 10688416 4690496
19:10:01 36983 8192000000 10267535344 10706210816 1318382224 13.862 96 22 42 1 1333538272 9989760 5166288
19:11:44 54765 8192000000 10275620608 10722795520 1361902032 14.254 100 21 42 2 1381321712 14805712 4613968
19:13:47 37372 8192000000 10280379936 10727587840 1357917712 14.219 99 19 38 1 1371154896 9066560 4170624
19:15:34 47787 8192000000 10282134608 10720657408 1335295776 14.015 97 28 56 1 1351427264 10249584 5881904
19:17:23 53739 8192000000 10286236352 10750701568 1377779424 14.397 100 16 32 0 1397617232 16301760 3536048
19:19:17 87289 8192000000 10284749808 10726572032 1350483200 14.152 98 19 38 0 1363850080 9500624 3866256
19:21:45 79296 8192000000 10279031760 10730651648 1354092064 14.184 98 28 56 0 1367867792 7667856 6107872
19:24:12 29949 8192000000 10287022272 10745884672 1385118448 14.462 100 25 50 2 1398287232 7842304 5326480
19:25:58 71134 8192000000 10289060032 10744991744 1376758768 14.388 99 15 32 2 1390641952 10147664 3735520
19:28:11 76408 8192000000 10280614640 10757505024 1382219920 14.436 100 21 42 0 1400530272 13412800 4897552
19:30:37 32541 8192000000 10282683520 10728083456 1351511792 14.161 97 23 46 2 1366545296 9276192 5757312
19:32:11 43301 8192000000 10257671984 10706931712 1338788560 14.046 97 11 22 0 1348913056 7943104 2181392
19:34:01 10238 8192000000 10267143120 10708381696 1357030768 14.211 100 49 96 2 1380015776 11152608 11832400
19:35:15 17569 8192000000 10275672048 10717839360 1365581744 14.287 100 36 72 0 1382368400 9353600 7433056
19:36:34 84590 8192000000 9090722704 9326305280 242524864 2.875 18 5 10 0 247170704 3521600 1124240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-10-2021 19:38:47 to 16-10-2021 19:39:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 69 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-10-2021 19:39:03 to 16-10-2021 19:39:05.

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          236775
MON$OLDEST_ACTIVE               236776
MON$OLDEST_SNAPSHOT             236776
MON$NEXT_TRANSACTION            236776
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                       2022528
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:443: 
:444: mscs-test-161.budzdorov.ru    Sat Oct 16 19:38:01 2021
:445:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:446: 
--- end of diff output ---

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

16.10.21 19:39:06. 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-16 19:39:08 No severe PSQL-related problems occured
16.10.21 19:39:09. End of report.