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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16667364 kB
MemAvailable 31419928 kB
Buffers 0 kB
Cached 14985896 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.9
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-12-14 01:37:54
DTS_END                         2021-12-14 01:37:54.6680
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/38666
TRN_ID                          241463
ATT_ID                          697
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: 9295.66 21913 1115479
customer order: creation 174.30 359 20916
customer order: refuse 14.68 382 1762
order to supplier: creation 45.70 1363 5484
order to supplier: removal 3.34 97588 401
invoice (draft): creation 45.73 3831 5487
invoice (draft): removal 52.79 782 6335
invoice accept: apply 42.13 18412 5056
invoice accept: cancel 47.76 813 5731
customer reserve: creation 3896.03 181 467523
customer reserve: removal 4667.26 54 560071
realization accept: apply 84.06 194 10087
realization accept: cancel 90.54 101 10865
payment from customer: creation 59.99 123 7199
payment from customer: removal 5.87 65 704
payment to supplier: creation 48.85 133 5862
payment to supplier: removal 3.53 89 423
service: total inventory turnovers 4.93 1095 591
service: total monetary turnovers 7.13 22 855
service: aggregate perf. data 1.03 14071 123
service: refresh index statistics 0.03 298594 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-12-2021 01:38:35 to 14-12-2021 01:38:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1426
TEST_TIME 2 2702
TEST_TIME 3 2768
TEST_TIME 4 3715
TEST_TIME 5 4451
TEST_TIME 6 5135
TEST_TIME 7 5955
TEST_TIME 8 6299
TEST_TIME 9 6626
TEST_TIME 10 6652
TEST_TIME 11 6534
TEST_TIME 12 6685
TEST_TIME 13 6741
TEST_TIME 14 7031
TEST_TIME 15 7605
TEST_TIME 16 7707
TEST_TIME 17 8210
TEST_TIME 18 8509
TEST_TIME 19 8928
TEST_TIME 20 8906
TEST_TIME 21 8876
TEST_TIME 22 8725
TEST_TIME 23 9037
TEST_TIME 24 9169
TEST_TIME 25 9180
TEST_TIME 26 9625
TEST_TIME 27 9891
TEST_TIME 28 10068
TEST_TIME 29 10234
TEST_TIME 30 10199
TEST_TIME 31 10444
TEST_TIME 32 10228
TEST_TIME 33 10462
TEST_TIME 34 10287
TEST_TIME 35 10169
TEST_TIME 36 10035
TEST_TIME 37 10132
TEST_TIME 38 9940
TEST_TIME 39 9820
TEST_TIME 40 9750
TEST_TIME 41 9676
TEST_TIME 42 9800
TEST_TIME 43 9664
TEST_TIME 44 9598
TEST_TIME 45 9644
TEST_TIME 46 9552
TEST_TIME 47 9459
TEST_TIME 48 9443
TEST_TIME 49 9334
TEST_TIME 50 9252
TEST_TIME 51 9228
TEST_TIME 52 9198
TEST_TIME 53 9205
TEST_TIME 54 9127
TEST_TIME 55 9043
TEST_TIME 56 9116
TEST_TIME 57 9080
TEST_TIME 58 9208
TEST_TIME 59 9198
TEST_TIME 60 9160
TEST_TIME 61 9212
TEST_TIME 62 9420
TEST_TIME 63 9414
TEST_TIME 64 9369
TEST_TIME 65 9406
TEST_TIME 66 9466
TEST_TIME 67 9402
TEST_TIME 68 9398
TEST_TIME 69 9353
TEST_TIME 70 9450
TEST_TIME 71 9443
TEST_TIME 72 9334
TEST_TIME 73 9272
TEST_TIME 74 9233
TEST_TIME 75 9237
TEST_TIME 76 9180
TEST_TIME 77 9244
TEST_TIME 78 9189
TEST_TIME 79 9181
TEST_TIME 80 9126
TEST_TIME 81 9060
TEST_TIME 82 8987
TEST_TIME 83 9045
TEST_TIME 84 9071
TEST_TIME 85 9119
TEST_TIME 86 9075
TEST_TIME 87 9106
TEST_TIME 88 9088
TEST_TIME 89 9108
TEST_TIME 90 9168
TEST_TIME 91 9188
TEST_TIME 92 9207
TEST_TIME 93 9210
TEST_TIME 94 9204
TEST_TIME 95 9210
TEST_TIME 96 9230
TEST_TIME 97 9309
TEST_TIME 98 9341
TEST_TIME 99 9390
TEST_TIME 100 9300
TEST_TIME 101 9264
TEST_TIME 102 9251
TEST_TIME 103 9206
TEST_TIME 104 9196
TEST_TIME 105 9179
TEST_TIME 106 9136
TEST_TIME 107 9121
TEST_TIME 108 9062
TEST_TIME 109 9050
TEST_TIME 110 9154
TEST_TIME 111 9138
TEST_TIME 112 9137
TEST_TIME 113 9148
TEST_TIME 114 9196
TEST_TIME 115 9215
TEST_TIME 116 9217
TEST_TIME 117 9231
TEST_TIME 118 9209
TEST_TIME 119 9266
TEST_TIME 120 9300

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-12-2021 01:38:38 to 14-12-2021 01:38:42.

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 1546278 1546278 0 0.00 0 26754 6 0 0
doc_list_biud 1546272 1546272 0 0.00 0 33575 26 0 0
sp_add_invoice_to_stock 5218 5056 162 3.10 57 175693 18412 0 162
sp_cancel_adding_invoice 5731 5731 0 0.00 5 51915 814 0 0
sp_cancel_client_order 1762 1762 0 0.00 4 13264 383 0 0
sp_cancel_customer_reserve 560071 560071 0 0.00 1 24382 54 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 2 4137 66 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 6906 89 0 0
sp_cancel_supplier_invoice 6349 6335 14 0.22 2 17385 782 0 14
sp_cancel_supplier_order 401 401 0 0.00 5 939405 97588 0 0
sp_cancel_write_off 10865 10865 0 0.00 1 13813 102 0 0
sp_client_order 20916 20916 0 0.00 3 15606 359 0 0
sp_customer_reserve 467523 467523 0 0.00 0 115845 182 0 0
sp_fill_shopping_cart 31853 31853 0 0.00 1 20522 41 0 0
sp_fill_shopping_cart_clo_res 467509 467509 0 0.00 0 13541 13 0 0
sp_get_clo_for_invoice 5042 5042 0 0.00 0 25341 320 0 0
sp_kill_cost_storno 17723 17723 0 0.00 0 7944 50 0 0
sp_kill_qstorno_ret_qs2qd 1137743 1137743 0 0.00 0 20376 30 0 0
sp_kill_qty_storno 600294 600294 0 0.00 0 33574 65 0 0
sp_lock_selected_doc 20986 20986 0 0.00 0 7760 6 0 0
sp_make_cost_storno 28190 28190 0 0.00 0 26742 56 0 0
sp_multiply_rows_for_pdistr 28190 28190 0 0.00 0 9951 32 0 0
sp_multiply_rows_for_qdistr 20916 20916 0 0.00 0 14850 152 0 0
sp_pay_from_customer 7199 7199 0 0.00 4 26096 124 0 0
sp_pay_to_supplier 5862 5862 0 0.00 5 26782 133 0 0
sp_payment_common 13061 13061 0 0.00 1 26087 28 0 0
sp_qd_handle_on_cancel_clo 1762 1762 0 0.00 0 13252 355 0 0
sp_qd_handle_on_invoice_upd_sts 10773 10773 0 0.00 0 33572 363 0 0
sp_qd_handle_on_reserve_upd_sts 10087 10087 0 0.00 0 17007 68 0 0
sp_reserve_write_off 10087 10087 0 0.00 6 17034 194 0 0
sp_supplier_invoice 5487 5487 0 0.00 6 98839 3831 0 0
sp_supplier_order 5484 5484 0 0.00 5 29784 1364 0 0
srv_aggregate_perf_data 123 123 0 0.00 2334 85445 14072 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2803 138979 47295 0 0
srv_find_qd_qs_mism 1066209 1066209 0 0.00 0 14508 6 0 0
srv_make_invnt_saldo 591 591 0 0.00 39 107625 1096 0 0
srv_make_money_saldo 855 855 0 0.00 0 1310 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 202959 488365 298595 0 0
t$perf-abend:sp_add_invoice_to_stock 162 162 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5056 5056 0 0.00 0 6806 149 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 7823 103 0 0
t$perf-norm:sp_cancel_client_order 1762 1762 0 0.00 0 5796 18 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 658 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 2786 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 5139 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 2555 13 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 29132 1754 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 1831 8 0 0
t$perf-norm:sp_client_order 20917 20917 0 0.00 0 19236 21 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 2536 9 0 0
t$perf-norm:sp_pay_from_customer 7199 7199 0 0.00 0 8937 11 0 0
t$perf-norm:sp_pay_to_supplier 5862 5862 0 0.00 0 9891 16 0 0
t$perf-norm:sp_reserve_write_off 10087 10087 0 0.00 0 15351 18 0 0
t$perf-norm:sp_supplier_invoice 5487 5487 0 0.00 0 9668 19 0 0
t$perf-norm:sp_supplier_order 5484 5484 0 0.00 0 5550 20 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 410 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 188 5 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 2213 23 0 0
t$perf-norm:srv_make_money_saldo 855 855 0 0.00 0 5026 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10406 10082 324 3.11 0 5209 19 0 324
v_all_customers 38088 38088 0 0.00 0 75 1 0 0
v_all_suppliers 30070 30070 0 0.00 0 22 1 0 0
v_all_wares 41832 41832 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 1095 7 0 0
v_cancel_client_order 3524 3524 0 0.00 0 3701 6 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 2966 5 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 3763 10 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 6899 18 0 0
v_cancel_supplier_invoice 902 874 28 3.10 0 3918 19 0 28
v_cancel_supplier_order 802 802 0 0.00 0 618 6 0 0
v_cancel_write_off 2026 2026 0 0.00 0 6834 17 0 0
v_max_id_clo_ord 5479 5479 0 0.00 0 230 1 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 5811 18 0 0
v_max_id_ord_sup 5458 5458 0 0.00 0 6593 4 0 0
v_max_non_paid_invoice 1764 1764 0 0.00 0 411 2 0 0
v_max_non_paid_realizn 3743 3743 0 0.00 0 7412 6 0 0
v_min_id_clo_ord 5479 5479 0 0.00 0 2034 6 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 1460 6 0 0
v_min_id_ord_sup 5458 5458 0 0.00 0 7399 6 0 0
v_min_non_paid_invoice 5862 5862 0 0.00 0 6480 12 0 0
v_min_non_paid_realizn 7199 7199 0 0.00 0 26080 24 0 0
v_our_firm 10656 10656 0 0.00 0 13 1 0 0
v_reserve_write_off 20174 20174 0 0.00 0 7544 7 0 0
x_lock_depdocs_on_canc_invoice 11629 11629 0 0.00 0 23184 121 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 8583 337 0 0
x_make_qstorno_1000_1200 5484 5484 0 0.00 1 29768 1286 0 0
x_make_qstorno_1200_2000 5487 5487 0 0.00 3 98816 3671 0 0
x_make_qstorno_2100_3300 467523 467523 0 0.00 0 115844 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-12-2021 01:38:43 to 14-12-2021 01:38:47.

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
23:07:58 30 8192000000 8822103232 8841048064 24136128 0.293 6 6 7 1 24457600 108880 212592
23:08:59 30552 8192000000 9918727648 10235047936 995936736 10.839 100 91 182 0 1026844832 6852992 24055104
23:10:35 22567 8192000000 9959179264 10284634112 1039093808 11.256 100 88 176 0 1070978352 9325632 22558912
23:12:07 17366 8192000000 9990184976 10327535616 1076520416 11.614 100 81 162 0 1107725696 10203600 21001680
23:13:27 11062 8192000000 10040681200 10388615168 1123713392 12.062 100 86 170 1 1156068496 12012320 20342784
23:14:44 6851 8192000000 10076254832 10434207744 1164235488 12.443 100 79 158 1 1195639216 13090192 18313536
23:15:53 5253 8192000000 10127401856 10497146880 1212223728 12.890 100 77 152 0 1241343712 11872832 17247152
23:16:59 2205 8192000000 10171893280 10547015680 1252246736 13.259 100 87 175 1 1284838000 13445184 19146080
23:18:03 1310 8192000000 10198580608 10584518656 1284985728 13.559 100 82 164 1 1316592016 12884928 18721360
23:19:05 14007 8192000000 10205343408 10598465536 1297585312 13.673 100 69 136 1 1322150832 9240784 15324736
23:20:19 7860 8192000000 10197023488 10609053696 1308204336 13.770 100 32 64 3 1323838192 8342688 7291168
23:21:28 10576 8192000000 10202840032 10618961920 1317723152 13.856 100 28 56 1 1330879136 6551136 6604848
23:22:39 11572 8192000000 10221857040 10627411968 1327276816 13.943 100 15 30 0 1337257616 6607936 3372864
23:23:52 14368 8192000000 10247532576 10646622208 1332960208 13.994 100 63 128 2 1354801968 5689456 16152304
23:25:07 5793 8192000000 10242481632 10657849344 1337990672 14.039 100 34 68 1 1353454064 8421728 7041664
23:26:16 5397 8192000000 10242091104 10664370176 1342915792 14.084 100 23 46 1 1354042064 6035920 5090352
23:27:21 9250 8192000000 10187774000 10598297600 1267130448 13.395 96 35 70 1 1282411872 5072256 10209168
23:28:33 16271 8192000000 10195775056 10612625408 1274352432 13.461 97 21 42 1 1284101216 4739920 5008864
23:29:53 3964 8192000000 10149948816 10555260928 1258052496 13.312 99 34 65 1 1272886080 7151712 7681872
23:30:58 4804 8192000000 10146582944 10547539968 1254446784 13.279 100 38 75 0 1269775168 7130496 8197888
23:32:09 71014 8192000000 10139705568 10545934336 1240361472 13.150 98 36 72 0 1253424208 5019312 8043424
23:34:20 5299 8192000000 10163146768 10572873728 1272653760 13.446 100 38 77 2 1287340368 6350784 8335824
23:35:29 6540 8192000000 10184795008 10598825984 1296667040 13.665 100 23 46 0 1307853904 6068976 5117888
23:36:36 11324 8192000000 10200680832 10614087680 1303967152 13.731 100 29 58 0 1317590512 6596704 7026656
23:37:50 91468 8192000000 10230613872 10645655552 1324935440 13.921 100 42 81 2 1340098400 5643488 9519472
23:40:24 12101 8192000000 10251004080 10667106304 1348883696 14.137 100 44 88 1 1369184416 9044496 11256224
23:41:41 24441 8192000000 10274164608 10694627328 1364822176 14.281 100 35 70 0 1384290640 11569536 7898928
23:43:13 17263 8192000000 10286612720 10709278720 1373413856 14.358 100 46 90 3 1399456832 15113408 10929568
23:44:33 8619 8192000000 10307331232 10727190528 1386115632 14.471 100 58 116 2 1415864656 16506656 13242368
23:45:46 4512 8192000000 10313200480 10733858816 1394824320 14.549 100 56 112 0 1423884096 15960320 13099456
23:46:55 3983 8192000000 10327838352 10747203584 1403817424 14.629 100 64 123 1 1434136000 15156896 15161680
23:48:04 5080 8192000000 10349098896 10769428480 1413124624 14.712 100 66 129 1 1448839296 20315328 15399344
23:49:14 11494 8192000000 10330945456 10755665920 1402904496 14.621 100 48 96 0 1437294944 21986976 12403472
23:50:39 2802 8192000000 10351777216 10760060928 1405119056 14.641 100 91 182 0 1453965136 26933344 21912736
23:51:45 3738 8192000000 10333156224 10748424192 1392093568 14.525 100 71 141 0 1438601680 28658288 17849824
23:53:00 3329 8192000000 10318631520 10733481984 1377454720 14.394 100 73 146 2 1421106368 25918128 17733520
23:54:08 4559 8192000000 10341135456 10756333568 1389409952 14.501 100 70 136 1 1437453200 31721056 16322192
23:55:19 2890 8192000000 10330298272 10742067200 1379647840 14.413 100 79 155 2 1432159312 33669760 18841712
23:56:25 4034 8192000000 10328005408 10748612608 1380840960 14.424 100 81 158 0 1434820288 34719232 19260096
23:57:32 15427 8192000000 10308236720 10732900352 1374557632 14.368 100 35 70 2 1410945216 27392560 8995024
23:59:04 12161 8192000000 10306339120 10738905088 1375426288 14.376 100 29 58 1 1417518592 34781376 7310928
00:00:35 7897 8192000000 10305229248 10728284160 1366728928 14.298 100 43 84 0 1410902736 32876272 11297536
00:01:48 15034 8192000000 10299506064 10727211008 1356562400 14.206 99 26 52 2 1395533936 32340896 6630640
00:03:06 16646 8192000000 10309199712 10737606656 1361927872 14.255 100 30 60 0 1413142912 42817040 8398000
00:04:26 31305 8192000000 10293585040 10743795712 1360742592 14.244 100 18 36 0 1397991376 31660032 5588752
00:06:00 60643 8192000000 10315715104 10747785216 1372428064 14.349 100 22 44 0 1399062752 21390432 5244256
00:08:08 33828 8192000000 10298318048 10742849536 1359513712 14.233 98 15 30 0 1384088560 21030832 3544016
00:09:43 89076 8192000000 10283305200 10731397120 1369032272 14.318 100 37 73 2 1399648928 22559408 8057248
00:12:37 64261 8192000000 10283969392 10726531072 1369197552 14.320 99 27 52 2 1383844560 8576304 6070704
00:15:37 138978 8192000000 10297810688 10756411392 1361240192 14.248 98 8 16 2 1383626560 20481344 1905024
00:18:56 46909 8192000000 10299273456 10760323072 1390874400 14.514 100 11 21 2 1410132800 16645376 2613024
00:20:50 125174 8192000000 10286006080 10754134016 1356915968 14.210 97 20 39 1 1374552256 13573856 4062432
00:23:58 66749 8192000000 10295535488 10742669312 1388153056 14.489 99 39 78 4 1404024224 6780096 9091072
00:26:06 76042 8192000000 10310715776 10768105472 1407262800 14.660 100 21 42 0 1423658768 11076544 5319424
00:28:24 46797 8192000000 10314030448 10775916544 1402155392 14.614 100 23 45 1 1420429856 13239248 5035216
00:30:12 49924 8192000000 10307498192 10778161152 1410275568 14.686 100 11 22 2 1426957024 13867840 2813616
00:32:08 84095 8192000000 10333320832 10798014464 1416658096 14.743 100 36 70 1 1443949264 18462528 8828640
00:34:53 48547 8192000000 10301225648 10769625088 1377923152 14.398 98 10 20 0 1397426368 16546592 2956624
00:36:56 67001 8192000000 10313329664 10767654912 1340244400 14.060 95 36 73 1 1356642128 8021840 8375888
00:39:43 81112 8192000000 10277319536 10727641088 1276591584 13.482 91 10 20 0 1294205056 15740032 1873440
00:42:04 28016 8192000000 10300691456 10743664640 1357628272 14.216 98 51 102 0 1384162336 14237264 12296800
00:43:37 45364 8192000000 10301123520 10739621888 1337109392 14.031 96 36 72 0 1354744016 8958208 8676416
00:45:26 40028 8192000000 10294132784 10732904448 1316070208 13.841 95 42 84 0 1343464096 18505632 8888256
00:47:09 79151 8192000000 10269555456 10720264192 1279207920 13.506 93 28 56 1 1301945280 16395728 6341632
00:49:29 75942 8192000000 10258346560 10690699264 1277464096 13.490 94 27 54 2 1289648560 6602592 5581872
00:51:46 26380 8192000000 10255092832 10702524416 1324400240 13.917 98 27 54 0 1343831696 12690832 6740624
00:53:13 53699 8192000000 10253107776 10702278656 1327695184 13.946 98 11 22 0 1348644736 18407760 2541792
00:55:14 48441 8192000000 10248074192 10691174400 1289672144 13.601 95 21 42 0 1300662320 6301520 4688656
00:57:04 79682 8192000000 10263325680 10710458368 1337224848 14.032 98 16 31 0 1351163728 10503392 3435488
00:59:30 95575 8192000000 10253904944 10710556672 1337426080 14.034 98 16 32 1 1357087440 15289376 4371984
01:02:36 60761 8192000000 10264610816 10707128320 1360517792 14.242 100 30 59 2 1376339312 8430208 7391312
01:04:40 38673 8192000000 10273731152 10714636288 1338451280 14.043 98 18 36 1 1354085936 11701616 3933040
01:06:22 64935 8192000000 10279973888 10734788608 1362643968 14.261 100 31 62 1 1381433952 11565728 7224256
01:08:27 128018 8192000000 10303216640 10757296128 1386212624 14.472 100 13 26 0 1398862640 10261424 2388592
01:11:45 21006 8192000000 10309283136 10749018112 1375776480 14.379 99 39 78 2 1392731824 8059120 8896224
01:13:06 26293 8192000000 10301162144 10738413568 1362829568 14.263 98 36 71 0 1381479216 10934256 7715392
01:14:33 111385 8192000000 10306997680 10764603392 1379292784 14.410 99 11 22 0 1396628720 15354272 1981664
01:17:33 70058 8192000000 10291792320 10746339328 1382531744 14.439 99 26 52 0 1399376192 10965536 5878912
01:20:01 23654 8192000000 10288775648 10747928576 1390426256 14.510 100 14 28 1 1403799120 10359008 3013856
01:21:26 49327 8192000000 10306253392 10768510976 1393845536 14.540 99 23 46 0 1413640560 14349536 5445488
01:23:28 93623 8192000000 10291006624 10749808640 1359681488 14.234 97 12 24 0 1378745424 16009632 3054304
01:26:02 45902 8192000000 10278994384 10737233920 1343668176 14.090 97 25 50 2 1370298752 21000528 5630048
01:27:55 51884 8192000000 10283108720 10729844736 1355562224 14.197 98 18 36 0 1367374912 7945264 3867424
01:29:54 60488 8192000000 10291435216 10744803328 1365358096 14.285 98 26 52 0 1385701280 13872720 6470464
01:32:05 73107 8192000000 10276984912 10721390592 1328515040 13.954 96 16 32 0 1342340016 10316592 3508384
01:34:21 55244 8192000000 10280086304 10729914368 1338490560 14.044 97 25 50 1 1353676624 9276768 5909296
01:36:21 54967 8192000000 10288171088 10745049088 1348073184 14.130 98 32 64 1 1375563024 20293712 7196128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-12-2021 01:38:49 to 14-12-2021 01:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 162 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-12-2021 01:39:05 to 14-12-2021 01:39:07.

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          240650
MON$OLDEST_ACTIVE               241608
MON$OLDEST_SNAPSHOT             241608
MON$NEXT_TRANSACTION            241608
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                       2026976
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33543 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33543 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33543 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 ---
:157: 
:158: mscs-test-161.budzdorov.ru    Tue Dec 14 01:37:57 2021
:159:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:160: 
:161: 
:162: mscs-test-161.budzdorov.ru    Tue Dec 14 01:38:05 2021
:163:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:164: 
:165: 
:166: mscs-test-161.budzdorov.ru    Tue Dec 14 01:38:05 2021
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:168: 
:169: 
:170: mscs-test-161.budzdorov.ru    Tue Dec 14 01:38:05 2021
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:172: 
--- end of diff output ---

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

14.12.21 01:39:08. 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-12-14 01:39:10 No severe PSQL-related problems occured
14.12.21 01:39:11. End of report.