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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16891112 kB
MemAvailable 31642004 kB
Buffers 0 kB
Cached 14821952 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-15 22:37:56
DTS_END                         2021-11-15 22:37:56.7110
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/50200
TRN_ID                          235595
ATT_ID                          685
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: 9016.33 23764 1081959
customer order: creation 168.82 390 20258
customer order: refuse 14.18 371 1701
order to supplier: creation 45.09 1415 5411
order to supplier: removal 3.42 100066 410
invoice (draft): creation 45.34 4183 5441
invoice (draft): removal 51.23 847 6147
invoice accept: apply 40.20 18496 4824
invoice accept: cancel 46.14 921 5537
customer reserve: creation 3790.53 179 454864
customer reserve: removal 4524.12 59 542894
realization accept: apply 82.15 201 9858
realization accept: cancel 77.17 115 9260
payment from customer: creation 58.97 112 7076
payment from customer: removal 5.90 93 708
payment to supplier: creation 47.75 142 5730
payment to supplier: removal 3.50 66 420
service: total inventory turnovers 4.79 1105 575
service: total monetary turnovers 6.22 34 746
service: aggregate perf. data 0.79 22492 95
service: refresh index statistics 0.03 323985 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-11-2021 22:40:25 to 15-11-2021 22:40:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2480
TEST_TIME 2 3085
TEST_TIME 3 4161
TEST_TIME 4 3620
TEST_TIME 5 5321
TEST_TIME 6 4977
TEST_TIME 7 5000
TEST_TIME 8 5260
TEST_TIME 9 5194
TEST_TIME 10 5506
TEST_TIME 11 5465
TEST_TIME 12 5598
TEST_TIME 13 5767
TEST_TIME 14 5940
TEST_TIME 15 6485
TEST_TIME 16 6688
TEST_TIME 17 6922
TEST_TIME 18 7001
TEST_TIME 19 7088
TEST_TIME 20 7056
TEST_TIME 21 7350
TEST_TIME 22 7748
TEST_TIME 23 7574
TEST_TIME 24 7866
TEST_TIME 25 8520
TEST_TIME 26 8699
TEST_TIME 27 8900
TEST_TIME 28 8837
TEST_TIME 29 9099
TEST_TIME 30 9386
TEST_TIME 31 9819
TEST_TIME 32 9767
TEST_TIME 33 9498
TEST_TIME 34 9459
TEST_TIME 35 9308
TEST_TIME 36 9271
TEST_TIME 37 9238
TEST_TIME 38 9314
TEST_TIME 39 9239
TEST_TIME 40 9195
TEST_TIME 41 9135
TEST_TIME 42 9039
TEST_TIME 43 8927
TEST_TIME 44 8853
TEST_TIME 45 8920
TEST_TIME 46 8851
TEST_TIME 47 8754
TEST_TIME 48 8794
TEST_TIME 49 8759
TEST_TIME 50 8657
TEST_TIME 51 8558
TEST_TIME 52 8501
TEST_TIME 53 8474
TEST_TIME 54 8429
TEST_TIME 55 8391
TEST_TIME 56 8414
TEST_TIME 57 8499
TEST_TIME 58 8582
TEST_TIME 59 8720
TEST_TIME 60 8749
TEST_TIME 61 8756
TEST_TIME 62 8819
TEST_TIME 63 8876
TEST_TIME 64 9030
TEST_TIME 65 9042
TEST_TIME 66 9028
TEST_TIME 67 9086
TEST_TIME 68 9146
TEST_TIME 69 9045
TEST_TIME 70 8976
TEST_TIME 71 8952
TEST_TIME 72 8958
TEST_TIME 73 8887
TEST_TIME 74 8892
TEST_TIME 75 8835
TEST_TIME 76 8781
TEST_TIME 77 8776
TEST_TIME 78 8804
TEST_TIME 79 8865
TEST_TIME 80 8853
TEST_TIME 81 8854
TEST_TIME 82 8909
TEST_TIME 83 8914
TEST_TIME 84 8960
TEST_TIME 85 8971
TEST_TIME 86 8956
TEST_TIME 87 9076
TEST_TIME 88 9072
TEST_TIME 89 9103
TEST_TIME 90 9112
TEST_TIME 91 9025
TEST_TIME 92 8987
TEST_TIME 93 9069
TEST_TIME 94 9012
TEST_TIME 95 8981
TEST_TIME 96 8945
TEST_TIME 97 8930
TEST_TIME 98 8922
TEST_TIME 99 8896
TEST_TIME 100 8962
TEST_TIME 101 8961
TEST_TIME 102 8990
TEST_TIME 103 9002
TEST_TIME 104 9072
TEST_TIME 105 9034
TEST_TIME 106 9055
TEST_TIME 107 9104
TEST_TIME 108 9190
TEST_TIME 109 9184
TEST_TIME 110 9158
TEST_TIME 111 9215
TEST_TIME 112 9151
TEST_TIME 113 9114
TEST_TIME 114 9111
TEST_TIME 115 9087
TEST_TIME 116 9098
TEST_TIME 117 9092
TEST_TIME 118 9061
TEST_TIME 119 9050
TEST_TIME 120 9023

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-11-2021 22:40:29 to 15-11-2021 22:40:32.

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 1501940 1501940 0 0.00 0 26136 6 0 0
doc_list_biud 1501939 1501939 0 0.00 0 40339 28 0 0
sp_add_invoice_to_stock 4956 4824 132 2.66 32 194254 18496 0 132
sp_cancel_adding_invoice 5537 5537 0 0.00 5 62488 921 0 0
sp_cancel_client_order 1701 1701 0 0.00 4 8569 372 0 0
sp_cancel_customer_reserve 542894 542894 0 0.00 1 32861 59 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 3 6060 94 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 2 3043 66 0 0
sp_cancel_supplier_invoice 6159 6147 12 0.19 1 40344 847 0 12
sp_cancel_supplier_order 410 410 0 0.00 5 1029877 100067 0 0
sp_cancel_write_off 9260 9260 0 0.00 1 22594 116 0 0
sp_client_order 20258 20258 0 0.00 3 18886 391 0 0
sp_customer_reserve 454864 454864 0 0.00 0 63348 180 0 0
sp_fill_shopping_cart 31109 31109 0 0.00 1 16772 39 0 0
sp_fill_shopping_cart_clo_res 454859 454859 0 0.00 0 18780 14 0 0
sp_get_clo_for_invoice 4815 4815 0 0.00 0 18303 306 0 0
sp_kill_cost_storno 15925 15925 0 0.00 0 22569 59 0 0
sp_kill_qstorno_ret_qs2qd 1101605 1101605 0 0.00 0 40337 32 0 0
sp_kill_qty_storno 580622 580622 0 0.00 0 40338 70 0 0
sp_lock_selected_doc 20430 20430 0 0.00 0 8694 6 0 0
sp_make_cost_storno 27479 27479 0 0.00 0 26102 62 0 0
sp_multiply_rows_for_pdistr 27479 27479 0 0.00 0 25667 39 0 0
sp_multiply_rows_for_qdistr 20258 20258 0 0.00 0 14718 177 0 0
sp_pay_from_customer 7076 7076 0 0.00 4 23321 112 0 0
sp_pay_to_supplier 5730 5730 0 0.00 5 24310 142 0 0
sp_payment_common 12806 12806 0 0.00 1 24101 36 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 8258 340 0 0
sp_qd_handle_on_invoice_upd_sts 10352 10352 0 0.00 0 23056 354 0 0
sp_qd_handle_on_reserve_upd_sts 9858 9858 0 0.00 0 10337 55 0 0
sp_reserve_write_off 9858 9858 0 0.00 6 26156 201 0 0
sp_supplier_invoice 5441 5441 0 0.00 7 96990 4184 0 0
sp_supplier_order 5411 5411 0 0.00 5 40056 1415 0 0
srv_aggregate_perf_data 95 95 0 0.00 3177 239233 22492 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1792 97118 43909 0 0
srv_find_qd_qs_mism 1034316 1034316 0 0.00 0 11763 5 0 0
srv_make_invnt_saldo 575 575 0 0.00 34 65624 1105 0 0
srv_make_money_saldo 746 746 0 0.00 1 8023 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 230723 402798 323986 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4824 4824 0 0.00 0 18475 179 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 6412 138 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 9388 14 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 1777 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 4145 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 6476 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 606 12 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 24668 1505 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 4537 17 0 0
t$perf-norm:sp_client_order 20258 20258 0 0.00 0 10147 18 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 1798 7 0 0
t$perf-norm:sp_pay_from_customer 7076 7076 0 0.00 0 9388 10 0 0
t$perf-norm:sp_pay_to_supplier 5730 5730 0 0.00 0 7634 9 0 0
t$perf-norm:sp_reserve_write_off 9858 9858 0 0.00 0 10069 12 0 0
t$perf-norm:sp_supplier_invoice 5441 5441 0 0.00 0 6500 19 0 0
t$perf-norm:sp_supplier_order 5411 5411 0 0.00 0 3106 14 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 62 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 358 9 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 1314 17 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 1460 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 20 11 0 0
v_add_invoice_to_stock 9894 9630 264 2.66 0 8435 20 0 264
v_all_customers 36636 36636 0 0.00 0 16 0 0 0
v_all_suppliers 29884 29884 0 0.00 0 26 1 0 0
v_all_wares 40516 40516 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1728 9 0 0
v_cancel_client_order 3402 3402 0 0.00 0 7939 8 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 3127 8 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 1651 6 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 1135 5 0 0
v_cancel_supplier_invoice 878 854 24 2.73 0 5187 21 0 24
v_cancel_supplier_order 820 820 0 0.00 0 1346 7 0 0
v_cancel_write_off 2006 2006 0 0.00 0 2360 14 0 0
v_max_id_clo_ord 5410 5410 0 0.00 0 2305 2 0 0
v_max_id_clo_res 941 941 0 0.00 0 5312 27 0 0
v_max_id_ord_sup 5440 5440 0 0.00 0 4628 5 0 0
v_max_non_paid_invoice 1638 1638 0 0.00 0 401 2 0 0
v_max_non_paid_realizn 3951 3951 0 0.00 0 3798 5 0 0
v_min_id_clo_ord 5410 5410 0 0.00 0 2806 7 0 0
v_min_id_clo_res 941 941 0 0.00 0 7314 15 0 0
v_min_id_ord_sup 5440 5440 0 0.00 0 5394 6 0 0
v_min_non_paid_invoice 5730 5730 0 0.00 0 23966 35 0 0
v_min_non_paid_realizn 7076 7076 0 0.00 0 22978 25 0 0
v_our_firm 10130 10130 0 0.00 0 15 1 0 0
v_reserve_write_off 19716 19716 0 0.00 0 3212 6 0 0
x_lock_depdocs_on_canc_invoice 11257 11257 0 0.00 0 29880 126 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 15014 423 0 0
x_make_qstorno_1000_1200 5411 5411 0 0.00 1 40006 1330 0 0
x_make_qstorno_1200_2000 5441 5441 0 0.00 4 96154 4048 0 0
x_make_qstorno_2100_3300 454863 454863 0 0.00 0 63343 164 0 0

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

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:08:00 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:01 38823 8192000000 9912043008 10230079488 996011232 10.840 100 89 177 0 1025981744 6759936 23210576
20:10:54 6238 8192000000 9953882912 10276433920 1035236592 11.219 100 94 188 0 1068252176 9170560 23845024
20:12:06 22464 8192000000 9973891360 10309091328 1058172320 11.439 100 81 162 1 1088691344 10758368 19760656
20:13:35 12071 8192000000 10029523600 10376921088 1116853248 11.997 100 84 169 1 1148647040 11033024 20760768
20:14:51 9607 8192000000 10080977296 10439196672 1172024064 12.516 100 73 146 0 1200409008 11652784 16732160
20:16:04 4637 8192000000 10135916640 10508492800 1224037344 12.999 100 81 162 1 1255247088 12748224 18461520
20:17:11 3158 8192000000 10174693968 10550820864 1259118224 13.322 100 87 172 1 1292187776 14724560 18344992
20:18:16 1704 8192000000 10204965328 10588237824 1285347440 13.562 100 90 181 0 1319924368 14532192 20044736
20:19:18 6868 8192000000 10199688112 10597408768 1295629584 13.655 100 61 122 1 1321316016 12379616 13306816
20:20:26 17798 8192000000 10188792784 10602475520 1302525952 13.718 100 27 54 0 1315275168 6179440 6569776
20:21:48 11682 8192000000 10196552032 10615123968 1312256768 13.807 100 28 57 1 1328677520 9855296 6565456
20:23:02 11219 8192000000 10202592848 10620325888 1318000736 13.859 100 16 32 1 1327891808 6286064 3605008
20:24:15 5724 8192000000 10207288000 10627158016 1321046976 13.886 100 21 42 0 1331748160 6555776 4145408
20:25:23 35060 8192000000 10217786704 10637688832 1328510608 13.954 100 30 60 0 1341040304 5751664 6778032
20:27:01 6020 8192000000 10212292784 10623029248 1305839696 13.748 99 35 70 0 1320493488 6485616 8168176
20:28:07 6787 8192000000 10201245184 10616320000 1292333232 13.625 98 19 38 2 1302938880 5818992 4786656
20:29:16 13134 8192000000 10172452448 10585214976 1257088672 13.303 97 13 26 0 1265162672 4710816 3363184
20:30:29 32233 8192000000 10152700800 10562912256 1204758592 12.821 94 27 54 0 1216363136 5010320 6594224
20:32:02 4628 8192000000 10110461152 10500534272 1183337568 12.621 95 34 68 1 1196430032 5321040 7771424
20:33:09 6805 8192000000 10151378560 10542096384 1248712400 13.226 100 38 76 0 1263298160 5664944 8920816
20:34:18 62302 8192000000 10160720320 10567323648 1262036704 13.349 99 39 76 2 1277224848 5303552 9884592
20:36:23 6073 8192000000 10183634944 10596270080 1290780208 13.611 100 30 60 1 1303345232 6048176 6516848
20:37:32 6963 8192000000 10198905936 10610376704 1304651952 13.738 100 29 57 1 1316928688 5615152 6661584
20:38:48 9068 8192000000 10228917456 10634874880 1329493216 13.963 100 34 65 0 1343321728 6044944 7783568
20:40:14 24759 8192000000 10253469536 10666475520 1346906080 14.120 100 35 69 0 1364348800 8913520 8529200
20:41:48 50212 8192000000 10253613728 10685804544 1353402752 14.178 100 28 56 3 1371701632 11728992 6569888
20:43:45 4153 8192000000 10294061296 10701873152 1357451328 14.214 99 59 116 3 1385793472 15599472 12742672
20:45:07 6570 8192000000 10290173552 10709753856 1380712528 14.423 100 45 89 1 1403356048 11882736 10760784
20:46:17 12739 8192000000 10295474224 10711130112 1374241744 14.365 99 53 106 0 1401559232 15154928 12162560
20:47:35 8993 8192000000 10312912736 10734727168 1392993328 14.533 100 66 133 1 1428729520 19159968 16576224
20:48:49 4413 8192000000 10338869504 10756268032 1407874640 14.665 100 68 136 1 1445230752 21069456 16286656
20:49:56 3801 8192000000 10341046224 10754125824 1397094976 14.569 99 76 148 1 1439204304 24422544 17686784
20:51:03 4840 8192000000 10339362912 10755739648 1403443920 14.626 100 75 150 1 1447311072 24855552 19011600
20:52:17 1791 8192000000 10361397968 10771460096 1407355024 14.660 100 91 181 1 1461948320 31565840 23027456
20:53:27 6932 8192000000 10342611856 10763845632 1401560512 14.609 99 66 132 3 1445551264 27469856 16520896
20:54:38 7412 8192000000 10346465008 10776248320 1415758240 14.735 100 65 130 3 1460564336 27675200 17130896
20:55:51 5611 8192000000 10348110960 10763550720 1406412464 14.652 100 73 146 1 1451913296 27345856 18154976
20:57:07 4823 8192000000 10330664784 10746396672 1392831488 14.531 100 58 116 1 1436704256 29143200 14729568
20:58:16 7810 8192000000 10327417680 10745090048 1384703136 14.459 100 67 133 2 1433640144 32427744 16509264
20:59:33 25154 8192000000 10289740512 10716282880 1348148352 14.131 98 42 84 2 1395124896 35672432 11304112
21:01:03 6791 8192000000 10312813696 10735570944 1354454976 14.188 99 54 108 3 1415331424 46385632 14490816
21:02:16 16221 8192000000 10305841568 10740346880 1363043920 14.265 100 32 64 2 1413866160 42195568 8626672
21:04:00 23988 8192000000 10292025920 10723151872 1360751264 14.244 100 29 58 1 1397705712 28898880 8055568
21:05:54 18427 8192000000 10295273760 10727362560 1350635088 14.153 99 40 80 4 1398503520 37349808 10518624
21:07:13 36779 8192000000 10247708976 10682408960 1282358816 13.535 95 12 24 0 1315616192 29392080 3865296
21:08:51 29016 8192000000 10267831520 10692739072 1320662656 13.883 97 47 93 1 1343418640 12534848 10221136
21:10:24 95173 8192000000 10247534752 10692136960 1308792528 13.775 96 47 94 0 1333341360 13651680 10897152
21:13:26 70410 8192000000 10278651664 10720096256 1360300240 14.240 100 26 51 2 1384520320 18486400 5733680
21:15:38 72794 8192000000 10261321520 10709405696 1343393568 14.088 98 50 99 1 1362822032 8651472 10776992
21:17:52 29644 8192000000 10268142336 10730831872 1374696496 14.369 100 6 12 1 1386662672 10687088 1279088
21:19:25 89758 8192000000 10288159168 10733252608 1387033216 14.479 100 27 54 0 1404399472 10579152 6787104
21:22:51 75616 8192000000 10274739072 10744619008 1362057856 14.256 98 13 26 0 1379442032 13916960 3467216
21:25:07 75739 8192000000 10279646352 10740367360 1378267376 14.401 99 22 44 2 1392458176 9385280 4805520
21:27:23 50111 8192000000 10275984720 10733191168 1385694976 14.467 100 27 55 0 1398226864 6751680 5780208
21:29:23 51600 8192000000 10280503392 10748850176 1384532976 14.457 99 10 20 0 1394010224 7134592 2342656
21:31:58 34567 8192000000 10303024784 10750693376 1389216160 14.499 99 39 78 0 1408371136 10384848 8770128
21:33:42 49970 8192000000 10302658464 10768801792 1404418752 14.634 100 20 40 0 1425313280 15733200 5161328
21:35:34 53123 8192000000 10308525200 10761945088 1390329280 14.509 99 18 36 0 1415813120 20975968 4507872
21:37:34 97118 8192000000 10306344640 10763018240 1350713648 14.154 96 14 28 0 1369869520 16009312 3146560
21:40:11 51082 8192000000 10292611200 10753843200 1370187264 14.329 98 24 48 0 1394841168 19159680 5494224
21:42:11 78271 8192000000 10301897568 10742730752 1341136400 14.068 96 23 44 1 1357682832 11576576 4969856
21:44:30 57716 8192000000 10290844192 10760798208 1392065600 14.524 100 25 50 3 1422695424 25262800 5367024
21:46:28 81373 8192000000 10287777488 10738089984 1349709520 14.145 97 24 48 1 1367700544 12442560 5548464
21:48:50 47696 8192000000 10270965392 10726899712 1356392400 14.205 98 29 58 3 1370136512 7075568 6668544
21:50:48 58525 8192000000 10278408992 10733703168 1363425168 14.268 99 10 19 1 1380354800 14449104 2480528
21:53:24 54193 8192000000 10272547008 10722717696 1343425728 14.088 98 29 59 1 1362629056 12697024 6506304
21:55:49 78972 8192000000 10244224368 10700349440 1265799552 13.383 93 14 28 0 1281882640 13167056 2916032
21:58:22 55654 8192000000 10271038928 10724229120 1361708960 14.253 100 24 48 1 1380787008 13443536 5634512
22:00:18 67782 8192000000 10274727488 10718760960 1335966240 14.021 98 17 34 0 1354930688 15039488 3924960
22:02:27 77212 8192000000 10285433232 10734329856 1369227824 14.320 100 20 40 2 1387356720 13209664 4919232
22:04:45 85718 8192000000 10265683728 10704678912 1295241152 13.652 95 12 24 0 1314583072 16739792 2602128
22:07:17 46210 8192000000 10260679520 10706362368 1357135856 14.212 100 14 28 0 1371325088 11103248 3085984
22:09:33 49966 8192000000 10278476704 10731933696 1357857520 14.218 99 19 38 1 1383717120 21306576 4553024
22:11:37 30795 8192000000 10257677904 10711969792 1359996608 14.237 99 14 28 2 1371510960 8153104 3361248
22:13:18 71280 8192000000 10257882752 10711179264 1321799808 13.893 96 15 28 1 1332253232 7182448 3270976
22:15:35 58260 8192000000 10298683072 10736877568 1344743392 14.100 97 25 49 0 1360130592 9644368 5742832
22:17:34 31754 8192000000 10288777904 10744979456 1375375376 14.375 99 19 38 1 1389628208 9316848 4935984
22:19:06 48836 8192000000 10293724704 10746413056 1360341808 14.240 98 18 36 1 1373565968 8882112 4342048
22:20:55 91010 8192000000 10307471168 10759815168 1377652400 14.396 99 13 26 0 1397752800 17166736 2933664
22:23:28 59704 8192000000 10328726144 10786533376 1394304944 14.544 99 23 46 2 1417691008 18207616 5178448
22:25:28 95860 8192000000 10314472976 10763698176 1338003936 14.039 95 16 32 0 1360455680 18922560 3529184
22:28:05 39861 8192000000 10310405968 10768158720 1385640832 14.467 99 25 50 0 1405358096 14375632 5341632
22:30:54 65834 8192000000 10296084304 10740006912 1362513264 14.260 97 27 54 0 1376956144 8615392 5827488
22:33:03 24813 8192000000 10309215888 10762358784 1405275776 14.642 100 38 75 1 1422975680 9685280 8014624
22:34:28 32258 8192000000 10281891952 10744479744 1392114160 14.525 100 12 24 0 1401483472 6908928 2460384
22:36:02 59642 8192000000 10292924128 10752507904 1369019440 14.318 98 12 24 0 1379290272 6965632 3305200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 15-11-2021 22:40:55 to 15-11-2021 22:40:56.

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          234532
MON$OLDEST_ACTIVE               236697
MON$OLDEST_SNAPSHOT             236697
MON$NEXT_TRANSACTION            236697
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                       2032032
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:388: 
:389: mscs-test-161.budzdorov.ru    Mon Nov 15 22:38:21 2021
:390:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:391: 
:392: 
:393: mscs-test-161.budzdorov.ru    Mon Nov 15 22:38:23 2021
:394:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:395: 
:396: 
:397: mscs-test-161.budzdorov.ru    Mon Nov 15 22:38:23 2021
:398:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:399: 
--- end of diff output ---

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

15.11.21 22:40:58. 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-15 22:41:00 No severe PSQL-related problems occured
15.11.21 22:41:01. End of report.