Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.11.2021 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16173524 kB
MemAvailable 30818116 kB
Buffers 0 kB
Cached 15558996 kB
SwapCached 2456 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-07 22:37:56
DTS_END                         2021-11-07 22:37:56.1480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50878
TRN_ID                          235404
ATT_ID                          636
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: 9112.19 21478 1093463
customer order: creation 171.07 389 20528
customer order: refuse 14.78 350 1774
order to supplier: creation 46.22 1324 5546
order to supplier: removal 3.54 88594 425
invoice (draft): creation 45.18 3374 5422
invoice (draft): removal 52.36 745 6283
invoice accept: apply 41.29 18794 4955
invoice accept: cancel 47.18 782 5662
customer reserve: creation 3777.51 188 453301
customer reserve: removal 4622.53 53 554704
realization accept: apply 83.71 181 10045
realization accept: cancel 76.79 103 9215
payment from customer: creation 59.07 113 7088
payment from customer: removal 5.78 93 693
payment to supplier: creation 48.97 134 5876
payment to supplier: removal 3.61 77 433
service: total inventory turnovers 4.99 960 599
service: total monetary turnovers 6.78 21 813
service: aggregate perf. data 0.81 21423 97
service: refresh index statistics 0.03 291861 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-11-2021 22:39:52 to 07-11-2021 22:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3229
TEST_TIME 2 5889
TEST_TIME 3 5677
TEST_TIME 4 5147
TEST_TIME 5 4940
TEST_TIME 6 5127
TEST_TIME 7 5059
TEST_TIME 8 4823
TEST_TIME 9 5404
TEST_TIME 10 5772
TEST_TIME 11 5888
TEST_TIME 12 5983
TEST_TIME 13 6216
TEST_TIME 14 6299
TEST_TIME 15 6957
TEST_TIME 16 7429
TEST_TIME 17 7796
TEST_TIME 18 7786
TEST_TIME 19 8115
TEST_TIME 20 8071
TEST_TIME 21 8709
TEST_TIME 22 8998
TEST_TIME 23 9165
TEST_TIME 24 9183
TEST_TIME 25 9781
TEST_TIME 26 10008
TEST_TIME 27 10120
TEST_TIME 28 10121
TEST_TIME 29 10504
TEST_TIME 30 10229
TEST_TIME 31 9971
TEST_TIME 32 9894
TEST_TIME 33 10028
TEST_TIME 34 9934
TEST_TIME 35 9857
TEST_TIME 36 9697
TEST_TIME 37 9622
TEST_TIME 38 9465
TEST_TIME 39 9494
TEST_TIME 40 9371
TEST_TIME 41 9483
TEST_TIME 42 9353
TEST_TIME 43 9255
TEST_TIME 44 9181
TEST_TIME 45 9235
TEST_TIME 46 9265
TEST_TIME 47 9164
TEST_TIME 48 9137
TEST_TIME 49 9029
TEST_TIME 50 8925
TEST_TIME 51 8892
TEST_TIME 52 8852
TEST_TIME 53 9052
TEST_TIME 54 9045
TEST_TIME 55 9004
TEST_TIME 56 9074
TEST_TIME 57 9055
TEST_TIME 58 9110
TEST_TIME 59 9098
TEST_TIME 60 9111
TEST_TIME 61 9111
TEST_TIME 62 9249
TEST_TIME 63 9378
TEST_TIME 64 9352
TEST_TIME 65 9421
TEST_TIME 66 9343
TEST_TIME 67 9297
TEST_TIME 68 9275
TEST_TIME 69 9282
TEST_TIME 70 9302
TEST_TIME 71 9240
TEST_TIME 72 9184
TEST_TIME 73 9167
TEST_TIME 74 9148
TEST_TIME 75 9046
TEST_TIME 76 9016
TEST_TIME 77 9084
TEST_TIME 78 9081
TEST_TIME 79 9075
TEST_TIME 80 9134
TEST_TIME 81 9201
TEST_TIME 82 9206
TEST_TIME 83 9202
TEST_TIME 84 9186
TEST_TIME 85 9254
TEST_TIME 86 9316
TEST_TIME 87 9303
TEST_TIME 88 9314
TEST_TIME 89 9359
TEST_TIME 90 9284
TEST_TIME 91 9187
TEST_TIME 92 9237
TEST_TIME 93 9180
TEST_TIME 94 9110
TEST_TIME 95 9113
TEST_TIME 96 9132
TEST_TIME 97 9176
TEST_TIME 98 9177
TEST_TIME 99 9156
TEST_TIME 100 9153
TEST_TIME 101 9143
TEST_TIME 102 9312
TEST_TIME 103 9276
TEST_TIME 104 9332
TEST_TIME 105 9312
TEST_TIME 106 9329
TEST_TIME 107 9313
TEST_TIME 108 9325
TEST_TIME 109 9348
TEST_TIME 110 9314
TEST_TIME 111 9255
TEST_TIME 112 9248
TEST_TIME 113 9194
TEST_TIME 114 9197
TEST_TIME 115 9165
TEST_TIME 116 9199
TEST_TIME 117 9186
TEST_TIME 118 9159
TEST_TIME 119 9118
TEST_TIME 120 9120

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-11-2021 22:39:56 to 07-11-2021 22:39:59.

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 1513004 1513004 0 0.00 0 21319 6 0 0
doc_list_biud 1513003 1513003 0 0.00 0 22340 25 0 0
sp_add_invoice_to_stock 5091 4955 136 2.67 45 148306 18795 0 136
sp_cancel_adding_invoice 5662 5662 0 0.00 5 67700 783 0 0
sp_cancel_client_order 1774 1774 0 0.00 4 13717 350 0 0
sp_cancel_customer_reserve 554704 554704 0 0.00 1 21590 53 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 2 6031 93 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 2 5996 77 0 0
sp_cancel_supplier_invoice 6292 6283 9 0.14 1 22357 745 0 9
sp_cancel_supplier_order 425 425 0 0.00 6 1008950 88595 0 0
sp_cancel_write_off 9215 9215 0 0.00 1 20692 104 0 0
sp_client_order 20528 20528 0 0.00 3 21432 389 0 0
sp_customer_reserve 453301 453301 0 0.00 0 70663 189 0 0
sp_fill_shopping_cart 31494 31494 0 0.00 1 20398 41 0 0
sp_fill_shopping_cart_clo_res 453290 453290 0 0.00 0 16054 14 0 0
sp_get_clo_for_invoice 4944 4944 0 0.00 0 21057 312 0 0
sp_kill_cost_storno 16003 16003 0 0.00 0 16050 56 0 0
sp_kill_qstorno_ret_qs2qd 1125331 1125331 0 0.00 0 22331 29 0 0
sp_kill_qty_storno 593051 593051 0 0.00 0 22337 64 0 0
sp_lock_selected_doc 20805 20805 0 0.00 0 5951 5 0 0
sp_make_cost_storno 27953 27953 0 0.00 0 16827 48 0 0
sp_multiply_rows_for_pdistr 27953 27953 0 0.00 0 11307 30 0 0
sp_multiply_rows_for_qdistr 20528 20528 0 0.00 0 16900 155 0 0
sp_pay_from_customer 7088 7088 0 0.00 4 15563 113 0 0
sp_pay_to_supplier 5876 5876 0 0.00 4 17576 134 0 0
sp_payment_common 12964 12964 0 0.00 2 13379 24 0 0
sp_qd_handle_on_cancel_clo 1774 1774 0 0.00 0 13702 318 0 0
sp_qd_handle_on_invoice_upd_sts 10605 10605 0 0.00 0 15916 325 0 0
sp_qd_handle_on_reserve_upd_sts 10045 10045 0 0.00 0 16866 71 0 0
sp_reserve_write_off 10045 10045 0 0.00 6 21355 181 0 0
sp_supplier_invoice 5422 5422 0 0.00 7 96366 3375 0 0
sp_supplier_order 5546 5546 0 0.00 5 24098 1324 0 0
srv_aggregate_perf_data 97 97 0 0.00 2847 264395 21423 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2719 121091 48921 0 0
srv_find_qd_qs_mism 1045985 1045985 0 0.00 0 15497 5 0 0
srv_make_invnt_saldo 599 599 0 0.00 41 32857 961 0 0
srv_make_money_saldo 813 813 0 0.00 0 604 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 163419 444787 291861 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4956 4956 0 0.00 0 8532 161 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 3731 119 0 0
t$perf-norm:sp_cancel_client_order 1774 1774 0 0.00 0 6581 22 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 8862 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 3543 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 230 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 781 19 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 26800 1589 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 9814 18 0 0
t$perf-norm:sp_client_order 20528 20528 0 0.00 0 14492 19 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 1618 5 0 0
t$perf-norm:sp_pay_from_customer 7088 7088 0 0.00 0 6554 9 0 0
t$perf-norm:sp_pay_to_supplier 5876 5876 0 0.00 0 7659 14 0 0
t$perf-norm:sp_reserve_write_off 10045 10045 0 0.00 0 10534 11 0 0
t$perf-norm:sp_supplier_invoice 5422 5422 0 0.00 0 6218 23 0 0
t$perf-norm:sp_supplier_order 5546 5546 0 0.00 0 7128 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 533 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 150 4 0 0
t$perf-norm:srv_make_invnt_saldo 599 599 0 0.00 0 814 24 0 0
t$perf-norm:srv_make_money_saldo 813 813 0 0.00 0 913 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 85 23 0 0
v_add_invoice_to_stock 10158 9886 272 2.67 0 4862 20 0 272
v_all_customers 37168 37168 0 0.00 0 23 1 0 0
v_all_suppliers 30258 30258 0 0.00 0 18 1 0 0
v_all_wares 41056 41056 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 451 6 0 0
v_cancel_client_order 3548 3548 0 0.00 0 6173 6 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 3054 8 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 1556 7 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 5889 12 0 0
v_cancel_supplier_invoice 792 774 18 2.27 0 1425 15 0 18
v_cancel_supplier_order 850 850 0 0.00 0 735 4 0 0
v_cancel_write_off 2054 2054 0 0.00 0 1832 12 0 0
v_max_id_clo_ord 5546 5546 0 0.00 0 2600 2 0 0
v_max_id_clo_res 976 976 0 0.00 0 4576 15 0 0
v_max_id_ord_sup 5420 5420 0 0.00 0 2818 4 0 0
v_max_non_paid_invoice 1713 1713 0 0.00 0 3719 5 0 0
v_max_non_paid_realizn 3903 3903 0 0.00 0 9324 9 0 0
v_min_id_clo_ord 5546 5546 0 0.00 0 11386 11 0 0
v_min_id_clo_res 976 976 0 0.00 0 1321 4 0 0
v_min_id_ord_sup 5420 5420 0 0.00 0 9113 9 0 0
v_min_non_paid_invoice 5876 5876 0 0.00 0 4678 14 0 0
v_min_non_paid_realizn 7088 7088 0 0.00 0 9845 17 0 0
v_our_firm 10258 10258 0 0.00 0 14 1 0 0
v_reserve_write_off 20090 20090 0 0.00 0 6732 8 0 0
x_lock_depdocs_on_canc_invoice 11558 11558 0 0.00 0 19422 119 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 12813 338 0 0
x_make_qstorno_1000_1200 5546 5546 0 0.00 1 24080 1239 0 0
x_make_qstorno_1200_2000 5422 5422 0 0.00 3 87633 3224 0 0
x_make_qstorno_2100_3300 453301 453301 0 0.00 0 70660 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-11-2021 22:40:00 to 07-11-2021 22:40:04.

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
20:07:59 7 8192000000 8804220480 8817246208 8989280 0.109 3 2 2 0 9094352 62288 42784
20:09:01 21939 8192000000 9897469824 10208358400 980025024 10.684 100 91 181 1 1010123696 6010288 24088384
20:10:27 22152 8192000000 9945452176 10268360704 1025057952 11.121 100 88 176 1 1055997072 8086544 22852576
20:11:52 10932 8192000000 9985683728 10315325440 1067174720 11.525 100 91 180 1 1099521232 8921984 23424528
20:13:10 16087 8192000000 10037962880 10387066880 1116818864 11.997 100 73 146 0 1144454240 10183328 17452048
20:14:31 11693 8192000000 10090222096 10451861504 1176819200 12.561 100 80 160 3 1207162304 12028224 18314880
20:15:45 3822 8192000000 10135114352 10504671232 1222562576 12.985 100 83 168 0 1254471568 13866688 18042304
20:16:50 8560 8192000000 10170357936 10546044928 1252655392 13.263 100 84 167 1 1285759248 15096928 18006928
20:18:00 1133 8192000000 10205280992 10584653824 1281147136 13.523 100 94 188 0 1317138912 14637472 21354304
20:19:03 34088 8192000000 10205599568 10602770432 1298314352 13.680 100 60 116 2 1321357520 9111120 13932048
20:20:38 7503 8192000000 10206231760 10611531776 1307521568 13.764 100 32 62 2 1321761952 7594864 6645520
20:21:45 8574 8192000000 10203792464 10622889984 1319543424 13.873 100 15 30 1 1329312576 6620560 3148592
20:22:54 46470 8192000000 10235907808 10642800640 1330384064 13.971 100 47 95 3 1347489344 5205920 11899360
20:24:41 8176 8192000000 10237576160 10650013696 1336679648 14.027 100 35 69 1 1355593136 10953920 7959568
20:25:51 7649 8192000000 10235860592 10656456704 1340597760 14.063 100 24 48 0 1356539360 10340192 5601408
20:27:03 56656 8192000000 10223672864 10647474176 1330358912 13.970 99 24 48 1 1341339856 5163632 5817312
20:29:01 11705 8192000000 10172353120 10578083840 1202064768 12.796 92 29 58 3 1214221760 5400768 6756224
20:30:18 7147 8192000000 10152743920 10553204736 1222373008 12.984 96 24 46 2 1233883776 6093696 5417072
20:31:25 54876 8192000000 10150949600 10549604352 1244019696 13.183 99 31 62 0 1256406112 4842672 7543744
20:33:22 5806 8192000000 10137325248 10538381312 1237815312 13.126 99 29 55 2 1250561264 6590384 6155568
20:34:32 14626 8192000000 10172118688 10578706432 1275283472 13.470 100 12 24 0 1283569616 5414640 2871504
20:35:47 9643 8192000000 10176453200 10590326784 1287197856 13.579 100 14 28 2 1296137264 5838704 3100704
20:37:01 41173 8192000000 10194715408 10605510656 1295358192 13.653 100 30 60 1 1309540768 7129536 7053040
20:38:47 18345 8192000000 10228906864 10634125312 1320110528 13.878 100 57 114 1 1343120256 10454208 12555520
20:40:14 15625 8192000000 10254178288 10665709568 1344285952 14.096 100 53 103 2 1366790752 11353088 11151712
20:41:35 5316 8192000000 10280046384 10694094848 1361667472 14.252 100 52 104 0 1392030176 18407392 11955312
20:42:44 14709 8192000000 10289962256 10706255872 1373483984 14.358 100 58 116 0 1404671808 16807152 14380672
20:44:09 29310 8192000000 10298307232 10726318080 1387007136 14.479 100 30 58 2 1409948928 15834896 7106896
20:45:45 17908 8192000000 10323897680 10747899904 1403640800 14.627 100 48 96 0 1439317120 23195200 12481120
20:47:07 8961 8192000000 10352806880 10763554816 1411506144 14.697 100 77 154 3 1455393376 25903840 17983392
20:48:19 6448 8192000000 10331884144 10754682880 1407904288 14.665 100 56 111 1 1446078528 24880400 13293840
20:49:30 8969 8192000000 10342092112 10760245248 1401200848 14.606 99 61 122 0 1442354512 26427936 14725728
20:50:55 7984 8192000000 10329286544 10748915712 1394520800 14.546 100 54 108 1 1441427280 32471040 14435440
20:52:11 7326 8192000000 10351227008 10770821120 1407029008 14.658 100 69 138 0 1459410288 35083376 17297904
20:53:25 6675 8192000000 10338717120 10747777024 1382474512 14.439 100 82 161 0 1436378480 34616880 19287088
20:54:37 2719 8192000000 10317810576 10729172992 1366238320 14.293 99 73 146 2 1415065248 31194464 17632464
20:55:45 3200 8192000000 10314495664 10725785600 1370771616 14.334 100 79 157 1 1425861088 36482672 18606800
20:56:55 6142 8192000000 10290418032 10703089664 1333495312 13.999 99 64 125 2 1383153808 34277632 15380864
20:58:10 3014 8192000000 10309615904 10719621120 1346638672 14.117 99 67 134 2 1406416016 42664112 17113232
20:59:19 22089 8192000000 10298198496 10720202752 1353675136 14.181 99 32 64 0 1386236672 24650096 7911440
21:00:45 12934 8192000000 10295482576 10725531648 1369256912 14.320 100 35 69 1 1402843744 24613056 8973776
21:02:01 19359 8192000000 10296447616 10730131456 1351788240 14.164 99 26 52 2 1396727840 37461584 7478016
21:03:22 22119 8192000000 10269794016 10714644480 1341112352 14.067 99 19 39 1 1373449056 27465792 4870912
21:04:48 72835 8192000000 10254702720 10692722688 1298763360 13.684 96 25 51 1 1325825840 20567536 6494944
21:07:31 98770 8192000000 10302709216 10728050688 1361422544 14.250 100 43 86 0 1380568912 8420176 10726192
21:11:05 38641 8192000000 10277346032 10721857536 1369336528 14.321 99 17 35 0 1380066656 7070064 3660064
21:13:01 88465 8192000000 10283514992 10730139648 1376270512 14.383 99 14 27 2 1387514336 8122176 3121648
21:15:52 73795 8192000000 10295240432 10755391488 1395818240 14.558 100 24 48 0 1416681376 14961984 5901152
21:18:09 80478 8192000000 10301804000 10758012928 1400642704 14.601 100 20 40 0 1415421808 10457136 4321968
21:20:33 39439 8192000000 10297006944 10753945600 1376354336 14.384 98 20 40 0 1393431232 12632832 4444064
21:22:13 34364 8192000000 10299939360 10763964416 1388357120 14.491 99 20 40 1 1409096208 16287616 4451472
21:23:52 57406 8192000000 10288217696 10744016896 1372631664 14.351 98 14 28 1 1383499664 7377552 3490448
21:25:58 39306 8192000000 10290762016 10760359936 1402932016 14.621 100 16 32 0 1414497584 7866544 3699024
21:27:51 31246 8192000000 10293479712 10759413760 1389377840 14.500 99 22 44 1 1404371248 10196048 4797360
21:29:23 74904 8192000000 10298274032 10776363008 1362632112 14.261 97 9 18 0 1389376240 24300016 2444112
21:31:39 82310 8192000000 10300954304 10754600960 1378120688 14.400 99 16 32 2 1390776688 9172448 3483552
21:34:01 35228 8192000000 10294209232 10741903360 1366213808 14.293 98 33 66 2 1383050768 9433472 7403488
21:35:37 61467 8192000000 10286930592 10749423616 1359036368 14.229 98 11 22 0 1371153008 9755280 2361360
21:37:41 50953 8192000000 10286383152 10720129024 1312355200 13.807 95 28 54 1 1330788016 12651296 5781520
21:39:36 105092 8192000000 10276653616 10731851776 1272153840 13.441 92 9 18 0 1290021104 16174304 1692960
21:42:32 98662 8192000000 10259334720 10705035264 1293655104 13.638 95 18 36 1 1311741456 13787392 4298960
21:45:13 98300 8192000000 10262743632 10715623424 1319877248 13.876 97 21 41 1 1342779008 18332304 4569456
21:47:58 44481 8192000000 10242100848 10695139328 1331171840 13.978 98 8 16 0 1339511088 6675696 1663552
21:49:44 59249 8192000000 10237451728 10692329472 1346110528 14.112 99 18 36 0 1358417504 8294064 4012912
21:51:53 74384 8192000000 10274834384 10719805440 1357835840 14.218 99 19 38 2 1373310416 11183168 4291408
21:54:19 69519 8192000000 10262486640 10723319808 1356253472 14.204 99 15 30 0 1375500768 16081584 3165712
21:56:30 44784 8192000000 10282431616 10727096320 1359789216 14.235 99 36 73 1 1377727536 9212672 8725648
21:58:23 99083 8192000000 10274975536 10719891456 1311476032 13.799 96 19 39 0 1331250944 15468464 4306448
22:01:02 20312 8192000000 10287302304 10724941824 1365864464 14.290 100 53 106 0 1389693008 12179504 11649040
22:02:33 121072 8192000000 10280386544 10730930176 1337499360 14.035 97 10 20 0 1355684528 15389904 2795264
22:05:35 42971 8192000000 10301714656 10747039744 1371466816 14.340 99 38 76 1 1392350000 12357792 8525392
22:07:42 97130 8192000000 10307516784 10756104192 1396434912 14.563 100 26 52 1 1416285136 14090176 5760048
22:10:28 54983 8192000000 10307040736 10769485824 1396004752 14.559 99 10 20 0 1416882368 18686320 2191296
22:12:46 79219 8192000000 10305494784 10766987264 1382325280 14.437 99 8 16 0 1402432400 18420176 1686944
22:15:05 68766 8192000000 10318404272 10773884928 1378919328 14.407 98 14 28 0 1394713696 12459680 3334688
22:17:17 45697 8192000000 10325447104 10773979136 1385504688 14.466 98 29 58 2 1406557744 13793184 7259872
22:19:17 83757 8192000000 10319360624 10787217408 1399548208 14.591 100 5 10 0 1414931152 14361888 1021056
22:21:41 43512 8192000000 10304351696 10761269248 1388355040 14.491 99 20 40 0 1403449024 10028080 5065904
22:23:32 45077 8192000000 10299764624 10732711936 1335883856 14.020 96 31 62 0 1354694960 11635824 7175280
22:25:20 109873 8192000000 10299799568 10738122752 1331114784 13.977 96 28 55 0 1351251120 14246656 5889680
22:28:34 90041 8192000000 10265356560 10710134784 1281750224 13.529 93 20 40 1 1294662112 8502160 4409728
22:31:18 34023 8192000000 10256857696 10699735040 1316058192 13.841 97 35 69 1 1343299328 19390384 7850752
22:33:56 79501 8192000000 10274544800 10722951168 1355200560 14.194 99 19 38 1 1368668848 9156528 4311760
22:36:26 75821 8192000000 10286162192 10740330496 1363946480 14.273 99 15 30 0 1378081776 10640096 3495200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-11-2021 22:40:06 to 07-11-2021 22:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-11-2021 22:40:22 to 07-11-2021 22:40:24.

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          235389
MON$OLDEST_ACTIVE               236851
MON$OLDEST_SNAPSHOT             236851
MON$NEXT_TRANSACTION            236851
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                       2017152
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.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:211: 
:212: mscs-test-161.budzdorov.ru    Sun Nov  7 22:38:08 2021
:213:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:214: 
--- end of diff output ---

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

07.11.21 22:40:25. 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-07 22:40:27 No severe PSQL-related problems occured
07.11.21 22:40:29. End of report.