Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.11.2021 16: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 16889820 kB
MemAvailable 31586900 kB
Buffers 0 kB
Cached 14759620 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-14 16:37:54
DTS_END                         2021-11-14 16:37:54.8990
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/59032
TRN_ID                          245349
ATT_ID                          706
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: 9183.09 20375 1101971
customer order: creation 181.83 334 21819
customer order: refuse 15.18 273 1821
order to supplier: creation 48.11 1187 5773
order to supplier: removal 3.47 95111 416
invoice (draft): creation 48.55 2976 5826
invoice (draft): removal 49.68 746 5962
invoice accept: apply 43.44 18165 5213
invoice accept: cancel 44.62 769 5354
customer reserve: creation 4066.04 179 487925
customer reserve: removal 4377.69 60 525323
realization accept: apply 86.34 152 10361
realization accept: cancel 82.27 106 9872
payment from customer: creation 62.21 95 7465
payment from customer: removal 6.11 70 733
payment to supplier: creation 51.22 102 6146
payment to supplier: removal 3.42 58 410
service: total inventory turnovers 5.10 986 612
service: total monetary turnovers 6.95 31 834
service: aggregate perf. data 0.85 16613 102
service: refresh index statistics 0.03 269486 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-11-2021 16:39:04 to 14-11-2021 16:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2584
TEST_TIME 2 3836
TEST_TIME 3 3969
TEST_TIME 4 5048
TEST_TIME 5 5129
TEST_TIME 6 5619
TEST_TIME 7 5291
TEST_TIME 8 5386
TEST_TIME 9 5370
TEST_TIME 10 5579
TEST_TIME 11 5849
TEST_TIME 12 6078
TEST_TIME 13 6327
TEST_TIME 14 6616
TEST_TIME 15 6671
TEST_TIME 16 7328
TEST_TIME 17 7923
TEST_TIME 18 7882
TEST_TIME 19 8147
TEST_TIME 20 8358
TEST_TIME 21 8520
TEST_TIME 22 8528
TEST_TIME 23 8685
TEST_TIME 24 9024
TEST_TIME 25 9190
TEST_TIME 26 9643
TEST_TIME 27 10164
TEST_TIME 28 10657
TEST_TIME 29 10776
TEST_TIME 30 10542
TEST_TIME 31 10404
TEST_TIME 32 10347
TEST_TIME 33 10215
TEST_TIME 34 10231
TEST_TIME 35 10173
TEST_TIME 36 10133
TEST_TIME 37 9955
TEST_TIME 38 9870
TEST_TIME 39 9775
TEST_TIME 40 9812
TEST_TIME 41 9914
TEST_TIME 42 9924
TEST_TIME 43 9921
TEST_TIME 44 9846
TEST_TIME 45 9685
TEST_TIME 46 9671
TEST_TIME 47 9571
TEST_TIME 48 9485
TEST_TIME 49 9478
TEST_TIME 50 9463
TEST_TIME 51 9403
TEST_TIME 52 9481
TEST_TIME 53 9508
TEST_TIME 54 9556
TEST_TIME 55 9556
TEST_TIME 56 9586
TEST_TIME 57 9572
TEST_TIME 58 9661
TEST_TIME 59 9626
TEST_TIME 60 9704
TEST_TIME 61 9792
TEST_TIME 62 9798
TEST_TIME 63 9777
TEST_TIME 64 9670
TEST_TIME 65 9607
TEST_TIME 66 9600
TEST_TIME 67 9528
TEST_TIME 68 9506
TEST_TIME 69 9418
TEST_TIME 70 9337
TEST_TIME 71 9393
TEST_TIME 72 9347
TEST_TIME 73 9442
TEST_TIME 74 9428
TEST_TIME 75 9466
TEST_TIME 76 9445
TEST_TIME 77 9414
TEST_TIME 78 9408
TEST_TIME 79 9465
TEST_TIME 80 9437
TEST_TIME 81 9441
TEST_TIME 82 9511
TEST_TIME 83 9525
TEST_TIME 84 9504
TEST_TIME 85 9439
TEST_TIME 86 9373
TEST_TIME 87 9381
TEST_TIME 88 9340
TEST_TIME 89 9293
TEST_TIME 90 9309
TEST_TIME 91 9264
TEST_TIME 92 9349
TEST_TIME 93 9329
TEST_TIME 94 9337
TEST_TIME 95 9380
TEST_TIME 96 9326
TEST_TIME 97 9437
TEST_TIME 98 9410
TEST_TIME 99 9380
TEST_TIME 100 9419
TEST_TIME 101 9420
TEST_TIME 102 9448
TEST_TIME 103 9428
TEST_TIME 104 9423
TEST_TIME 105 9417
TEST_TIME 106 9460
TEST_TIME 107 9392
TEST_TIME 108 9365
TEST_TIME 109 9325
TEST_TIME 110 9302
TEST_TIME 111 9267
TEST_TIME 112 9235
TEST_TIME 113 9201
TEST_TIME 114 9163
TEST_TIME 115 9180
TEST_TIME 116 9166
TEST_TIME 117 9207
TEST_TIME 118 9194
TEST_TIME 119 9210
TEST_TIME 120 9187

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-11-2021 16:39:07 to 14-11-2021 16:39:11.

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 1552697 1552697 0 0.00 0 14384 6 0 0
doc_list_biud 1552697 1552697 0 0.00 0 31995 26 0 0
sp_add_invoice_to_stock 5301 5213 88 1.66 29 197975 18165 0 88
sp_cancel_adding_invoice 5354 5354 0 0.00 3 76303 769 0 0
sp_cancel_client_order 1821 1821 0 0.00 4 9915 273 0 0
sp_cancel_customer_reserve 525323 525323 0 0.00 1 21941 61 0 0
sp_cancel_pay_from_customer 733 733 0 0.00 3 5279 71 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 2506 58 0 0
sp_cancel_supplier_invoice 5971 5962 9 0.15 2 29790 747 0 9
sp_cancel_supplier_order 416 416 0 0.00 6 922453 95111 0 0
sp_cancel_write_off 9872 9872 0 0.00 1 12960 106 0 0
sp_client_order 21819 21819 0 0.00 3 37688 335 0 0
sp_customer_reserve 487925 487925 0 0.00 0 104536 179 0 0
sp_fill_shopping_cart 33418 33418 0 0.00 1 11604 34 0 0
sp_fill_shopping_cart_clo_res 487920 487920 0 0.00 0 15561 13 0 0
sp_get_clo_for_invoice 5201 5201 0 0.00 0 33764 303 0 0
sp_kill_cost_storno 16369 16369 0 0.00 0 5267 49 0 0
sp_kill_qstorno_ret_qs2qd 1066896 1066896 0 0.00 0 29740 33 0 0
sp_kill_qty_storno 564307 564307 0 0.00 0 31994 70 0 0
sp_lock_selected_doc 21537 21537 0 0.00 0 10647 6 0 0
sp_make_cost_storno 29170 29170 0 0.00 0 9875 41 0 0
sp_multiply_rows_for_pdistr 29170 29170 0 0.00 0 9778 24 0 0
sp_multiply_rows_for_qdistr 21819 21819 0 0.00 0 37666 156 0 0
sp_pay_from_customer 7465 7465 0 0.00 4 21843 95 0 0
sp_pay_to_supplier 6146 6146 0 0.00 5 9880 103 0 0
sp_payment_common 13611 13611 0 0.00 1 21830 22 0 0
sp_qd_handle_on_cancel_clo 1821 1821 0 0.00 0 9900 257 0 0
sp_qd_handle_on_invoice_upd_sts 10552 10552 0 0.00 0 31993 279 0 0
sp_qd_handle_on_reserve_upd_sts 10361 10361 0 0.00 0 22072 57 0 0
sp_reserve_write_off 10361 10361 0 0.00 6 22086 152 0 0
sp_supplier_invoice 5826 5826 0 0.00 6 90737 2977 0 0
sp_supplier_order 5773 5773 0 0.00 5 27325 1187 0 0
srv_aggregate_perf_data 102 102 0 0.00 2821 171089 16614 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1441 182389 45442 0 0
srv_find_qd_qs_mism 1051127 1051127 0 0.00 0 14383 6 0 0
srv_make_invnt_saldo 612 612 0 0.00 30 66901 987 0 0
srv_make_money_saldo 834 834 0 0.00 0 8796 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 141484 364418 269486 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 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 5213 5213 0 0.00 0 11292 144 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 10765 134 0 0
t$perf-norm:sp_cancel_client_order 1821 1821 0 0.00 0 3909 12 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 3412 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 733 733 0 0.00 0 6874 31 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 392 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 2399 23 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 17102 1385 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 5082 9 0 0
t$perf-norm:sp_client_order 21819 21819 0 0.00 0 5193 15 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 4263 10 0 0
t$perf-norm:sp_pay_from_customer 7465 7465 0 0.00 0 7369 10 0 0
t$perf-norm:sp_pay_to_supplier 6146 6146 0 0.00 0 6950 9 0 0
t$perf-norm:sp_reserve_write_off 10361 10361 0 0.00 0 6615 11 0 0
t$perf-norm:sp_supplier_invoice 5826 5826 0 0.00 0 10734 19 0 0
t$perf-norm:sp_supplier_order 5773 5773 0 0.00 0 5074 15 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 6772 82 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 612 612 0 0.00 0 4002 25 0 0
t$perf-norm:srv_make_money_saldo 834 834 0 0.00 0 1631 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10572 10396 176 1.66 0 8812 15 0 176
v_all_customers 39850 39850 0 0.00 0 14 0 0 0
v_all_suppliers 32084 32084 0 0.00 0 112 1 0 0
v_all_wares 43638 43638 0 0.00 0 198 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 2648 14 0 0
v_cancel_client_order 3642 3642 0 0.00 0 1189 4 0 0
v_cancel_customer_prepayment 1466 1466 0 0.00 0 1092 4 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 99 2 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 315 3 0 0
v_cancel_supplier_invoice 874 856 18 2.05 0 13196 27 0 18
v_cancel_supplier_order 832 832 0 0.00 0 2055 6 0 0
v_cancel_write_off 2064 2064 0 0.00 0 2954 14 0 0
v_max_id_clo_ord 5773 5773 0 0.00 0 1312 1 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 4037 20 0 0
v_max_id_ord_sup 5826 5826 0 0.00 0 5260 3 0 0
v_max_non_paid_invoice 1703 1703 0 0.00 0 136 2 0 0
v_max_non_paid_realizn 3968 3968 0 0.00 0 1431 3 0 0
v_min_id_clo_ord 5773 5773 0 0.00 0 2613 5 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 2853 8 0 0
v_min_id_ord_sup 5826 5826 0 0.00 0 6029 6 0 0
v_min_non_paid_invoice 6146 6146 0 0.00 0 5259 10 0 0
v_min_non_paid_realizn 7465 7465 0 0.00 0 21823 20 0 0
v_our_firm 10782 10782 0 0.00 0 13 1 0 0
v_reserve_write_off 20722 20722 0 0.00 0 4982 5 0 0
x_lock_depdocs_on_canc_invoice 10888 10888 0 0.00 0 20043 116 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 15252 328 0 0
x_make_qstorno_1000_1200 5773 5773 0 0.00 1 27316 1113 0 0
x_make_qstorno_1200_2000 5826 5826 0 0.00 3 90712 2857 0 0
x_make_qstorno_2100_3300 487925 487925 0 0.00 0 104534 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-11-2021 16:39:12 to 14-11-2021 16:39:16.

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
14:07:58 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:08:59 24095 8192000000 9922849568 10239639552 1002923472 10.907 100 89 177 3 1033451632 7498512 23029648
14:10:29 23680 8192000000 9962369408 10289205248 1041836864 11.282 100 87 174 1 1073851072 10102960 21911248
14:11:58 16294 8192000000 9991151392 10325913600 1071992032 11.571 100 85 170 0 1104479136 10979376 21507728
14:13:23 11724 8192000000 10033017312 10383323136 1120467008 12.031 100 72 144 0 1149285904 11728320 17090576
14:14:39 9202 8192000000 10083212224 10445709312 1170763824 12.504 100 78 156 0 1201273104 12301104 18208176
14:15:51 4282 8192000000 10129745344 10502430720 1216933232 12.933 100 77 154 1 1247412464 13553344 16925888
14:16:57 3249 8192000000 10175923728 10556383232 1259276432 13.323 100 90 181 0 1292843088 11931584 21635072
14:18:03 2050 8192000000 10210107856 10594312192 1293817392 13.639 100 88 175 0 1326417760 13139648 19460720
14:19:06 18854 8192000000 10185578176 10598227968 1303362512 13.726 100 28 55 1 1317311008 8313632 5634864
14:20:28 6205 8192000000 10201518176 10612916224 1313980576 13.822 100 28 56 0 1326884144 6182512 6721056
14:21:34 7869 8192000000 10216318000 10628907008 1324754400 13.920 100 26 51 2 1338353936 7601776 5997760
14:22:43 69618 8192000000 10216541168 10644488192 1331344928 13.979 100 31 60 0 1345439920 6666256 7428736
14:24:53 8496 8192000000 10229473696 10651992064 1336688400 14.028 100 26 52 3 1348560496 5989840 5882256
14:26:01 7196 8192000000 10238605072 10659332096 1342715568 14.082 100 29 58 1 1355234368 6292176 6226624
14:27:12 66410 8192000000 10203779056 10624622592 1294754048 13.648 97 26 49 2 1305929008 5246704 5928256
14:29:20 4989 8192000000 10183976896 10587099136 1276300576 13.479 99 33 67 0 1289352160 5553056 7498528
14:30:25 8238 8192000000 10142439152 10538061824 1219906480 12.961 97 29 57 2 1232074304 5215600 6952224
14:31:34 7847 8192000000 10150284656 10543366144 1218589392 12.949 97 25 49 3 1229817344 5694016 5533936
14:32:43 35705 8192000000 10144484992 10551836672 1249463184 13.233 99 21 42 0 1259825920 4995008 5367728
14:34:21 4697 8192000000 10185946224 10588340224 1284012704 13.550 100 32 65 0 1302850976 10477296 8360976
14:35:28 10200 8192000000 10194522784 10605932544 1297062864 13.669 100 28 56 1 1308842672 5371200 6408608
14:36:39 20856 8192000000 10195330416 10615721984 1311640608 13.801 100 26 52 2 1324296880 5327568 7328704
14:38:04 36909 8192000000 10239059408 10645082112 1331189312 13.978 100 42 82 0 1347348384 6821440 9337632
14:39:49 14413 8192000000 10246313088 10662871040 1347633376 14.126 100 37 74 2 1363904944 7861456 8410112
14:41:14 19688 8192000000 10278463104 10697375744 1368266720 14.312 100 35 70 0 1387472864 10769872 8436272
14:42:44 24507 8192000000 10301291104 10724716544 1389430768 14.501 100 49 97 0 1413346096 12724624 11190704
14:44:13 8767 8192000000 10311969264 10736361472 1387450144 14.483 99 46 90 3 1417496944 19411888 10634912
14:45:28 4488 8192000000 10324571600 10745843712 1405724400 14.646 100 54 108 3 1437156336 18177712 13254224
14:46:39 4571 8192000000 10344370320 10763841536 1421720832 14.788 100 64 128 0 1460902160 23759744 15421584
14:47:48 10719 8192000000 10339677856 10763931648 1412641280 14.707 100 45 90 3 1450246704 26073088 11532336
14:49:09 11071 8192000000 10332571200 10748620800 1389737024 14.504 99 60 120 1 1431558112 27214880 14606208
14:50:25 1438 8192000000 10362078592 10768932864 1408109344 14.667 100 93 186 1 1458225184 28000384 22115456
14:51:30 3402 8192000000 10363396240 10777272320 1415015200 14.728 100 82 164 0 1468226880 33259360 19952320
14:52:38 9343 8192000000 10371189392 10790256640 1420709280 14.779 100 64 126 0 1475631984 39431760 15490944
14:53:55 11142 8192000000 10345511808 10781036544 1409006176 14.675 100 48 94 3 1467291744 46383152 11902416
14:55:11 4571 8192000000 10319120944 10731945984 1361723152 14.253 99 67 132 1 1415899520 36912272 17264096
14:56:18 6438 8192000000 10307655712 10724417536 1359040464 14.229 100 51 101 1 1418353456 45160512 14152480
14:57:30 12176 8192000000 10339734576 10755829760 1388209168 14.490 100 43 84 3 1443594544 42540224 12845152
14:58:45 19972 8192000000 10317003168 10747944960 1377719376 14.396 100 35 70 1 1428563952 41077648 9766928
15:00:24 6866 8192000000 10315740464 10741313536 1365127760 14.283 100 38 75 0 1418567360 43348752 10090848
15:01:33 18618 8192000000 10304477008 10746384384 1366139056 14.292 100 29 58 0 1424736736 50433872 8163808
15:02:53 14860 8192000000 10309540256 10744881152 1362262416 14.258 100 27 54 0 1413736000 43933824 7539760
15:04:12 23850 8192000000 10315081248 10750406656 1360527968 14.242 99 23 46 4 1406508016 39368560 6611488
15:05:37 62234 8192000000 10252988464 10697773056 1317008208 13.850 97 9 18 0 1333351248 13956224 2386816
15:07:40 33041 8192000000 10288551072 10719293440 1351986480 14.165 98 44 86 0 1369764192 8355760 9421952
15:09:13 79389 8192000000 10278891120 10729832448 1367983696 14.309 99 24 48 1 1382098016 8320832 5793488
15:12:16 182389 8192000000 10296993616 10740121600 1309874704 13.785 95 17 34 1 1324890160 11351856 3663600
15:16:41 2841 8192000000 10331861088 10768629760 1401151056 14.605 100 68 140 1 1434780560 16730336 16899168
15:17:48 37055 8192000000 10308000384 10776764416 1402144352 14.614 100 23 46 1 1430285840 22482608 5658880
15:19:26 21645 8192000000 10294629424 10765127680 1396941248 14.568 100 4 8 0 1411967440 14207312 818880
15:20:58 30850 8192000000 10309239920 10772443136 1403386544 14.625 100 15 30 2 1419378032 12551168 3440320
15:22:32 92375 8192000000 10288261536 10764238848 1377463232 14.394 98 12 24 2 1388721568 8620240 2638096
15:25:05 36943 8192000000 10306407392 10772832256 1406809232 14.656 100 18 34 1 1423495232 12231088 4454912
15:26:44 71373 8192000000 10296991200 10768965632 1392184512 14.525 99 3 6 0 1402855408 10119776 551120
15:29:03 46247 8192000000 10312004704 10781454336 1398219008 14.579 99 17 34 1 1412262256 10163776 3879472
15:30:50 54733 8192000000 10286799568 10757562368 1359985344 14.237 97 9 19 0 1374718000 12554432 2178224
15:32:54 56967 8192000000 10310080480 10766671872 1388320464 14.491 99 28 56 1 1404587264 10171136 6095664
15:34:51 65600 8192000000 10286609024 10743099392 1330894688 13.975 95 8 16 0 1344973680 12346368 1732624
15:37:19 81574 8192000000 10285242512 10749222912 1342739680 14.082 97 17 34 0 1362337888 16263744 3334464
15:39:40 52974 8192000000 10261495920 10706808832 1290859392 13.612 94 21 42 0 1305682992 10224864 4598736
15:41:34 48872 8192000000 10261165088 10702565376 1346947760 14.120 99 40 80 1 1363052016 6843104 9261152
15:43:33 47658 8192000000 10251332544 10688401408 1308006368 13.768 96 39 77 1 1325631456 8590432 9034656
15:45:32 53402 8192000000 10237200224 10688983040 1312387952 13.808 97 19 38 2 1329265872 12552080 4325840
15:47:28 59138 8192000000 10258656368 10703634432 1340159536 14.059 99 18 36 1 1361313312 16512784 4640992
15:50:29 88559 8192000000 10248037328 10707320832 1312837760 13.812 97 12 24 0 1330491072 14783744 2869568
15:52:58 61827 8192000000 10248632032 10689597440 1318778048 13.866 98 10 20 0 1329775552 8822608 2174896
15:55:10 28556 8192000000 10247714864 10691063808 1321680688 13.892 98 47 95 0 1345437328 13334384 10422256
15:56:44 38821 8192000000 10264173024 10698448896 1320809856 13.884 98 47 93 2 1342120128 10834528 10475744
15:58:27 113916 8192000000 10285399424 10733244416 1359507328 14.233 100 32 64 1 1383706128 17563872 6634928
16:01:22 34892 8192000000 10284143520 10725314560 1352691472 14.172 99 41 81 1 1372520656 11063760 8765424
16:02:58 63007 8192000000 10286957408 10734284800 1371632480 14.342 100 31 60 1 1397285312 18833120 6819712
16:05:06 43740 8192000000 10270847136 10732253184 1378413920 14.402 100 6 12 0 1390258064 10492688 1351456
16:06:54 77445 8192000000 10288496320 10748547072 1379288784 14.410 100 11 22 0 1392951024 11307120 2355120
16:09:11 107023 8192000000 10283588352 10739265536 1354195008 14.185 97 13 26 0 1372865632 15629232 3041392
16:12:05 80411 8192000000 10310208160 10759413760 1385367376 14.465 99 19 38 0 1400135504 10010192 4757936
16:14:26 110575 8192000000 10306347488 10757742592 1375574304 14.377 98 11 22 0 1391992816 13831312 2587200
16:17:17 41215 8192000000 10307593536 10763714560 1392499728 14.528 100 33 65 0 1417772672 17335648 7937296
16:19:03 44165 8192000000 10303586496 10760642560 1383775808 14.450 99 15 30 1 1397968176 10960992 3231376
16:20:47 76840 8192000000 10294910400 10741874688 1315305968 13.834 94 13 26 0 1327222752 9142544 2774240
16:23:05 70553 8192000000 10273699808 10729529344 1351847808 14.164 98 13 25 1 1366102960 11239520 3015632
16:25:17 60041 8192000000 10271649520 10726985728 1337496336 14.035 97 17 34 0 1349529616 8232816 3800464
16:28:13 44799 8192000000 10243730992 10704072704 1333111968 13.995 98 9 18 0 1346520160 11309440 2098752
16:30:01 83820 8192000000 10251823152 10705985536 1323786720 13.911 97 13 26 0 1336759712 10157424 2815568
16:32:26 77169 8192000000 10257231792 10715885568 1323900656 13.912 97 11 22 1 1339254784 12558240 2795888
16:34:45 49647 8192000000 10266615952 10711302144 1339141360 14.050 98 19 38 0 1350150432 6949808 4059264
16:36:39 26018 8192000000 10249269632 10694262784 1308350128 13.771 96 21 42 2 1321893584 8739120 4804336

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-11-2021 16:39:17 to 14-11-2021 16:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 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 14-11-2021 16:39:33 to 14-11-2021 16:39:35.

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          245348
MON$OLDEST_ACTIVE               247013
MON$OLDEST_SNAPSHOT             247013
MON$NEXT_TRANSACTION            247013
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                       2024256
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 ---
:282: 
:283: mscs-test-161.budzdorov.ru    Sun Nov 14 16:38:02 2021
:284:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:285: 
:286: 
:287: mscs-test-161.budzdorov.ru    Sun Nov 14 16:38:17 2021
:288:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:289: 
--- end of diff output ---

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

14.11.21 16:39:36. 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-14 16:39:38 No severe PSQL-related problems occured
14.11.21 16:39:40. End of report.