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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-18 16:37:54
DTS_END                         2021-10-18 16:37:54.9400
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/45582
TRN_ID                          232973
ATT_ID                          642
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: 9550.28 20145 1146033
customer order: creation 171.41 365 20569
customer order: refuse 14.57 331 1748
order to supplier: creation 43.98 1180 5277
order to supplier: removal 3.79 91219 455
invoice (draft): creation 45.95 2868 5514
invoice (draft): removal 56.23 689 6748
invoice accept: apply 41.13 17947 4936
invoice accept: cancel 50.59 694 6071
customer reserve: creation 3843.03 176 461164
customer reserve: removal 4984.02 54 598083
realization accept: apply 82.38 165 9885
realization accept: cancel 86.58 106 10390
payment from customer: creation 58.08 102 6970
payment from customer: removal 5.66 60 679
payment to supplier: creation 47.75 107 5730
payment to supplier: removal 3.44 63 413
service: total inventory turnovers 4.61 1165 553
service: total monetary turnovers 6.23 29 748
service: aggregate perf. data 0.80 20547 96
service: refresh index statistics 0.03 265042 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2021 16:38:34 to 18-10-2021 16:38:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3563
TEST_TIME 2 5507
TEST_TIME 3 5732
TEST_TIME 4 5984
TEST_TIME 5 5700
TEST_TIME 6 5507
TEST_TIME 7 5393
TEST_TIME 8 5493
TEST_TIME 9 6396
TEST_TIME 10 6331
TEST_TIME 11 6221
TEST_TIME 12 6561
TEST_TIME 13 6570
TEST_TIME 14 6709
TEST_TIME 15 7129
TEST_TIME 16 7198
TEST_TIME 17 7374
TEST_TIME 18 8381
TEST_TIME 19 8688
TEST_TIME 20 8740
TEST_TIME 21 8859
TEST_TIME 22 8848
TEST_TIME 23 9553
TEST_TIME 24 9951
TEST_TIME 25 9987
TEST_TIME 26 10140
TEST_TIME 27 10807
TEST_TIME 28 10944
TEST_TIME 29 10799
TEST_TIME 30 11151
TEST_TIME 31 10991
TEST_TIME 32 10892
TEST_TIME 33 10685
TEST_TIME 34 11024
TEST_TIME 35 11028
TEST_TIME 36 10787
TEST_TIME 37 10524
TEST_TIME 38 10535
TEST_TIME 39 10383
TEST_TIME 40 10243
TEST_TIME 41 10235
TEST_TIME 42 10259
TEST_TIME 43 10280
TEST_TIME 44 10104
TEST_TIME 45 10027
TEST_TIME 46 10165
TEST_TIME 47 10032
TEST_TIME 48 9952
TEST_TIME 49 9850
TEST_TIME 50 9830
TEST_TIME 51 9749
TEST_TIME 52 9739
TEST_TIME 53 9708
TEST_TIME 54 9633
TEST_TIME 55 9502
TEST_TIME 56 9430
TEST_TIME 57 9396
TEST_TIME 58 9257
TEST_TIME 59 9329
TEST_TIME 60 9358
TEST_TIME 61 9426
TEST_TIME 62 9390
TEST_TIME 63 9520
TEST_TIME 64 9521
TEST_TIME 65 9443
TEST_TIME 66 9555
TEST_TIME 67 9608
TEST_TIME 68 9629
TEST_TIME 69 9583
TEST_TIME 70 9649
TEST_TIME 71 9754
TEST_TIME 72 9796
TEST_TIME 73 9816
TEST_TIME 74 9723
TEST_TIME 75 9654
TEST_TIME 76 9638
TEST_TIME 77 9561
TEST_TIME 78 9525
TEST_TIME 79 9514
TEST_TIME 80 9487
TEST_TIME 81 9502
TEST_TIME 82 9451
TEST_TIME 83 9416
TEST_TIME 84 9400
TEST_TIME 85 9401
TEST_TIME 86 9410
TEST_TIME 87 9405
TEST_TIME 88 9448
TEST_TIME 89 9421
TEST_TIME 90 9426
TEST_TIME 91 9452
TEST_TIME 92 9554
TEST_TIME 93 9612
TEST_TIME 94 9602
TEST_TIME 95 9645
TEST_TIME 96 9620
TEST_TIME 97 9700
TEST_TIME 98 9633
TEST_TIME 99 9567
TEST_TIME 100 9525
TEST_TIME 101 9513
TEST_TIME 102 9495
TEST_TIME 103 9475
TEST_TIME 104 9449
TEST_TIME 105 9426
TEST_TIME 106 9415
TEST_TIME 107 9370
TEST_TIME 108 9339
TEST_TIME 109 9402
TEST_TIME 110 9397
TEST_TIME 111 9357
TEST_TIME 112 9425
TEST_TIME 113 9412
TEST_TIME 114 9423
TEST_TIME 115 9438
TEST_TIME 116 9415
TEST_TIME 117 9430
TEST_TIME 118 9462
TEST_TIME 119 9497
TEST_TIME 120 9553

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-10-2021 16:38:37 to 18-10-2021 16:38:41.

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 1574062 1574062 0 0.00 0 27601 6 0 0
doc_list_biud 1574062 1574062 0 0.00 0 52231 26 0 0
sp_add_invoice_to_stock 5001 4936 65 1.29 52 156423 17947 0 65
sp_cancel_adding_invoice 6071 6071 0 0.00 5 42735 695 0 0
sp_cancel_client_order 1748 1748 0 0.00 4 29914 331 0 0
sp_cancel_customer_reserve 598083 598083 0 0.00 1 52233 55 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 1689 60 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 2 4090 63 0 0
sp_cancel_supplier_invoice 6752 6748 4 0.05 2 12965 690 0 4
sp_cancel_supplier_order 455 455 0 0.00 6 1055872 91220 0 0
sp_cancel_write_off 10390 10390 0 0.00 1 6194 106 0 0
sp_client_order 20569 20569 0 0.00 3 31574 366 0 0
sp_customer_reserve 461164 461164 0 0.00 0 85208 177 0 0
sp_fill_shopping_cart 31359 31359 0 0.00 1 11007 34 0 0
sp_fill_shopping_cart_clo_res 461155 461155 0 0.00 0 12267 12 0 0
sp_get_clo_for_invoice 4927 4927 0 0.00 0 23692 281 0 0
sp_kill_cost_storno 17553 17553 0 0.00 0 9845 55 0 0
sp_kill_qstorno_ret_qs2qd 1213759 1213759 0 0.00 0 51980 30 0 0
sp_kill_qty_storno 638303 638303 0 0.00 0 52230 64 0 0
sp_lock_selected_doc 20561 20561 0 0.00 0 6985 4 0 0
sp_make_cost_storno 27508 27508 0 0.00 0 13096 52 0 0
sp_multiply_rows_for_pdistr 27508 27508 0 0.00 0 11805 25 0 0
sp_multiply_rows_for_qdistr 20569 20569 0 0.00 0 15156 173 0 0
sp_pay_from_customer 6970 6970 0 0.00 4 13102 103 0 0
sp_pay_to_supplier 5730 5730 0 0.00 4 17099 107 0 0
sp_payment_common 12700 12700 0 0.00 1 9439 26 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 0 29895 310 0 0
sp_qd_handle_on_invoice_upd_sts 10994 10994 0 0.00 0 16118 297 0 0
sp_qd_handle_on_reserve_upd_sts 9885 9885 0 0.00 0 20716 55 0 0
sp_reserve_write_off 9885 9885 0 0.00 6 20727 165 0 0
sp_supplier_invoice 5514 5514 0 0.00 7 68225 2869 0 0
sp_supplier_order 5277 5277 0 0.00 5 34812 1180 0 0
srv_aggregate_perf_data 96 96 0 0.00 2887 384727 20547 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2577 123182 47579 0 0
srv_find_qd_qs_mism 1099290 1099290 0 0.00 0 27599 5 0 0
srv_make_invnt_saldo 553 553 0 0.00 47 37495 1165 0 0
srv_make_money_saldo 748 748 0 0.00 1 4223 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 117754 494478 265042 0 0
t$perf-abend:sp_add_invoice_to_stock 65 65 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4936 4936 0 0.00 0 33819 177 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 4905 87 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 34992 33 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 2606 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 537 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 1764 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1038 18 0 0
t$perf-norm:sp_cancel_supplier_order 455 455 0 0.00 0 34774 1439 0 0
t$perf-norm:sp_cancel_write_off 945 945 0 0.00 0 4291 8 0 0
t$perf-norm:sp_client_order 20569 20569 0 0.00 0 32729 18 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 9244 13 0 0
t$perf-norm:sp_pay_from_customer 6970 6970 0 0.00 0 4297 7 0 0
t$perf-norm:sp_pay_to_supplier 5730 5730 0 0.00 0 32841 16 0 0
t$perf-norm:sp_reserve_write_off 9885 9885 0 0.00 0 6589 12 0 0
t$perf-norm:sp_supplier_invoice 5514 5514 0 0.00 0 4689 18 0 0
t$perf-norm:sp_supplier_order 5277 5277 0 0.00 0 33351 20 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 178 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 6 1 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 1493 23 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 2781 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 9968 9838 130 1.30 0 7472 20 0 130
v_all_customers 37508 37508 0 0.00 0 31 1 0 0
v_all_suppliers 29956 29956 0 0.00 0 15 1 0 0
v_all_wares 41138 41138 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 1379 7 0 0
v_cancel_client_order 3496 3496 0 0.00 0 2161 5 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 1667 5 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 1174 5 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 844 5 0 0
v_cancel_supplier_invoice 806 798 8 0.99 0 2308 17 0 8
v_cancel_supplier_order 910 910 0 0.00 0 5137 12 0 0
v_cancel_write_off 1890 1890 0 0.00 0 3633 16 0 0
v_max_id_clo_ord 5277 5277 0 0.00 0 274 1 0 0
v_max_id_clo_res 956 956 0 0.00 0 3349 16 0 0
v_max_id_ord_sup 5513 5513 0 0.00 0 7461 5 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 664 3 0 0
v_max_non_paid_realizn 3692 3692 0 0.00 0 4856 6 0 0
v_min_id_clo_ord 5277 5277 0 0.00 0 2110 4 0 0
v_min_id_clo_res 956 956 0 0.00 0 1531 6 0 0
v_min_id_ord_sup 5513 5513 0 0.00 0 5056 6 0 0
v_min_non_paid_invoice 5730 5730 0 0.00 0 5407 12 0 0
v_min_non_paid_realizn 6970 6970 0 0.00 0 4697 22 0 0
v_our_firm 10186 10186 0 0.00 0 207 1 0 0
v_reserve_write_off 19770 19770 0 0.00 0 5065 5 0 0
x_lock_depdocs_on_canc_invoice 12420 12420 0 0.00 0 16671 115 0 0
x_lock_depdocs_on_canc_sup_ord 455 455 0 0.00 0 6642 315 0 0
x_make_qstorno_1000_1200 5277 5277 0 0.00 2 34806 1113 0 0
x_make_qstorno_1200_2000 5514 5514 0 0.00 3 68218 2747 0 0
x_make_qstorno_2100_3300 461164 461164 0 0.00 0 85206 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2021 16:38:42 to 18-10-2021 16:38:46.

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 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:08:59 21603 8192000000 9881789312 10190655488 964899872 10.537 100 87 174 0 994520640 5534768 24086000
14:10:27 31156 8192000000 9947425536 10268110848 1021385552 11.085 100 90 180 1 1052508112 7326672 23795888
14:12:01 12208 8192000000 9976955472 10308411392 1061115872 11.467 100 88 176 0 1092092752 8830928 22145952
14:13:15 7900 8192000000 10035858544 10384764928 1122884272 12.054 100 77 155 0 1151811392 10552512 18374608
14:14:27 4906 8192000000 10094392384 10456358912 1183477632 12.623 100 78 156 1 1214414000 12958688 17977680
14:15:35 4321 8192000000 10137353312 10507677696 1222854176 12.988 100 78 156 2 1253335168 12423792 18057200
14:16:41 2661 8192000000 10165898240 10543276032 1248705776 13.226 100 91 178 1 1281979552 14028976 19244800
14:17:45 2835 8192000000 10190641152 10572701696 1273732112 13.456 100 84 168 1 1305604240 12275680 19596448
14:18:50 2419 8192000000 10218258896 10602070016 1298729264 13.684 100 90 182 2 1332397232 13024592 20643376
14:19:54 1864 8192000000 10225884464 10611961856 1304034224 13.732 100 93 186 0 1336013488 11506352 20472912
14:20:57 3975 8192000000 10211472704 10619199488 1314853216 13.830 100 38 77 0 1331854944 8057664 8944064
14:22:01 19437 8192000000 10236338896 10631405568 1321903792 13.894 100 87 172 5 1348141168 6170400 20066976
14:23:21 6370 8192000000 10218561344 10634792960 1326578864 13.936 100 31 62 0 1340791136 7497040 6715232
14:24:28 6239 8192000000 10213960960 10635997184 1329839984 13.966 100 21 42 0 1340678272 5512400 5325888
14:25:42 5610 8192000000 10223704416 10643734528 1335200416 14.014 100 20 40 1 1345959872 5593392 5166064
14:26:48 64518 8192000000 10213512144 10636312576 1305308816 13.743 98 34 69 1 1319333584 6166512 7858256
14:28:54 6414 8192000000 10201841040 10614378496 1297510640 13.673 99 22 44 0 1308356400 6057808 4787952
14:30:03 11768 8192000000 10144436752 10550767616 1202928880 12.804 94 27 53 2 1214931504 5658896 6343728
14:31:16 51375 8192000000 10158215856 10546372608 1162988720 12.431 92 41 77 5 1181547216 8719760 9838736
14:33:08 7203 8192000000 10151523504 10552107008 1253711824 13.272 100 31 62 2 1267835568 6932400 7191344
14:34:17 4891 8192000000 10176739824 10584817664 1283719552 13.547 100 37 73 2 1299196960 6987872 8489536
14:35:24 8195 8192000000 10193723952 10600005632 1297230848 13.670 100 32 64 1 1309568752 4266640 8071264
14:36:35 64496 8192000000 10213020976 10619006976 1305668464 13.747 100 27 53 0 1317911904 5430528 6812912
14:38:46 10071 8192000000 10245552960 10656034816 1337510944 14.035 100 49 96 0 1355861552 7500544 10850064
14:40:00 32233 8192000000 10268786800 10689269760 1355048688 14.193 100 51 102 2 1377559104 11157984 11352432
14:41:35 6101 8192000000 10286018560 10705207296 1372025136 14.345 100 50 101 0 1397664464 14255024 11384304
14:42:44 11609 8192000000 10294291152 10713743360 1380631392 14.422 100 52 103 2 1406687584 13545024 12511168
14:44:02 28609 8192000000 10296248192 10724638720 1389677264 14.503 100 49 96 0 1416772576 15619584 11475728
14:45:36 2820 8192000000 10324008944 10740379648 1396178032 14.561 100 57 114 1 1431153568 21565376 13410160
14:46:45 12313 8192000000 10329759264 10754703360 1404769024 14.637 100 53 106 1 1440318256 22651168 12898064
14:48:06 9827 8192000000 10347657360 10773200896 1419960304 14.772 100 48 96 1 1458959600 26056800 12942496
14:49:24 6196 8192000000 10373924656 10794086400 1430605104 14.867 100 76 149 2 1482181424 32656464 18919856
14:50:36 6418 8192000000 10374210912 10796756992 1428919504 14.852 100 66 132 2 1482452112 37061824 16470784
14:51:50 8729 8192000000 10374584496 10793127936 1407727152 14.664 99 78 155 0 1474174064 46825616 19621296
14:53:07 2576 8192000000 10346406384 10762354688 1379396416 14.411 98 72 143 2 1444610208 45892016 19321776
14:54:13 3668 8192000000 10373992128 10792296448 1410307760 14.687 100 82 164 2 1479569872 47768800 21493312
14:55:23 8630 8192000000 10356890832 10781966336 1380483824 14.421 98 59 118 1 1446336688 50716528 15136336
14:56:34 19837 8192000000 10319112384 10751954944 1356376960 14.205 98 31 62 0 1408421312 43086800 8957552
14:57:58 18801 8192000000 10315961136 10746560512 1366643056 14.297 99 35 68 2 1418994656 43828864 8522736
14:59:30 17779 8192000000 10354201808 10789535744 1397658672 14.574 100 33 66 1 1456710112 49319744 9731696
15:00:52 11768 8192000000 10310638976 10750316544 1354165120 14.185 98 27 54 4 1395746624 33596400 7985104
15:02:07 33283 8192000000 10305236240 10738069504 1370616448 14.333 100 22 44 0 1404947856 28140816 6190592
15:03:43 30889 8192000000 10288211632 10728804352 1358119488 14.220 100 20 40 0 1392584944 29239728 5225728
15:05:25 78557 8192000000 10290678176 10734702592 1308044880 13.768 97 10 20 2 1338357040 28121424 2190736
15:07:46 65803 8192000000 10266140240 10690637824 1282551024 13.536 94 24 49 0 1301389296 13108592 5729680
15:09:56 63711 8192000000 10277146640 10715590656 1342223392 14.077 98 21 42 0 1361255008 14280592 4751024
15:12:23 108019 8192000000 10250893168 10693451776 1318876592 13.867 97 17 34 1 1332943280 10778496 3288192
15:15:20 118803 8192000000 10281915712 10731741184 1351870400 14.164 99 24 47 0 1371829616 14187664 5771552
15:18:36 77312 8192000000 10280565632 10745901056 1354918736 14.192 98 9 18 0 1374526144 17166400 2441008
15:20:54 72572 8192000000 10311510800 10753212416 1391729872 14.521 100 22 44 0 1409210576 12452384 5028320
15:23:15 53263 8192000000 10286176544 10758656000 1384598080 14.458 100 6 12 0 1397799632 11736816 1464736
15:25:15 38194 8192000000 10298543744 10759548928 1388691600 14.494 100 15 30 1 1403784096 11513104 3579392
15:27:00 123182 8192000000 10294711120 10765496320 1387602672 14.484 99 17 34 1 1409970464 18916352 3451440
15:30:04 59507 8192000000 10299043120 10765901824 1403501584 14.626 100 12 24 0 1415541168 9347376 2692208
15:32:04 46637 8192000000 10295391088 10762919936 1390870832 14.514 99 10 20 0 1402997872 9484448 2642592
15:34:00 44732 8192000000 10287877824 10754486272 1375808256 14.379 98 15 30 0 1389296208 10066096 3421856
15:35:46 96522 8192000000 10311396752 10792247296 1391708816 14.521 99 14 28 0 1416820832 21443632 3668384
15:38:46 49193 8192000000 10302095328 10768334848 1378108784 14.400 98 10 20 2 1400315328 19919184 2287360
15:40:43 24498 8192000000 10311745184 10763022336 1376696736 14.387 98 25 49 1 1389153504 6928832 5527936
15:42:09 24826 8192000000 10330869808 10773757952 1388545392 14.493 99 31 62 0 1407174368 11118880 7510096
15:43:37 84084 8192000000 10278734016 10746585088 1335448656 14.016 96 9 18 0 1352375392 15108960 1817776
15:46:02 58093 8192000000 10307545152 10759184384 1378706800 14.405 99 31 61 0 1399121248 13596896 6817552
15:48:04 39783 8192000000 10299393872 10763378688 1377930944 14.398 99 35 70 0 1402641872 16132384 8578544
15:49:47 62623 8192000000 10285999552 10740461568 1335405200 14.016 96 14 28 1 1349166752 10851776 2909776
15:51:53 102449 8192000000 10248744000 10699526144 1286699664 13.574 94 27 54 0 1299660336 7042512 5918160
15:54:46 43044 8192000000 10265914528 10716889088 1341693360 14.073 99 31 59 1 1361445200 13762048 5989792
15:56:46 41415 8192000000 10253146256 10721001472 1346253008 14.114 99 9 18 1 1362676592 14383664 2039920
15:58:28 70232 8192000000 10240621056 10706636800 1317494144 13.854 96 17 34 0 1331179232 9217600 4467488
16:00:40 88395 8192000000 10266300912 10733187072 1330872336 13.975 97 11 22 0 1342579904 9244224 2463344
16:03:10 36054 8192000000 10249127008 10694877184 1310728464 13.793 97 25 49 0 1327121424 10212304 6180656
16:04:46 52853 8192000000 10263597424 10714222592 1350824400 14.155 100 25 50 0 1367161024 10672352 5664272
16:06:40 40366 8192000000 10256175600 10693849088 1318653808 13.865 97 33 66 1 1338148160 12509952 6984400
16:08:42 60047 8192000000 10291880528 10749636608 1377389120 14.393 100 15 30 0 1395797104 15185472 3222512
16:10:53 93534 8192000000 10301064864 10751492096 1375708256 14.378 99 19 38 0 1395194608 15098016 4388336
16:13:55 51521 8192000000 10318147808 10773614592 1383176480 14.445 99 16 30 1 1408412864 21812064 3424320
16:15:52 64455 8192000000 10278948960 10732642304 1364605600 14.279 99 9 18 0 1388369184 21578400 2185184
16:18:01 12951 8192000000 10284099760 10739458048 1382531616 14.439 100 35 70 1 1399269504 9216864 7521024
16:19:36 48452 8192000000 10289405760 10758565888 1395382496 14.554 100 5 11 0 1406740880 10065376 1293008
16:21:35 65920 8192000000 10291957936 10745311232 1369090832 14.319 98 19 38 1 1381250720 8255632 3904256
16:23:48 73128 8192000000 10305141200 10768891904 1401871248 14.612 100 15 30 0 1415853680 10132832 3849600
16:26:08 90379 8192000000 10282424064 10750259200 1323724992 13.910 95 7 14 0 1336417872 11413984 1278896
16:28:41 86726 8192000000 10264138128 10726551552 1284063984 13.550 93 17 34 0 1299355664 11452832 3838848
16:31:08 50404 8192000000 10276939504 10727395328 1355800736 14.200 99 31 62 1 1375295856 12537136 6957984
16:32:59 34575 8192000000 10277578544 10717683712 1336577936 14.027 97 35 70 2 1356767872 12369104 7820832
16:34:39 45252 8192000000 10268798880 10720174080 1334126704 14.004 97 13 26 0 1349572592 12591136 2854752
16:36:24 73162 8192000000 10283139968 10748497920 1354791248 14.191 98 11 22 0 1370531200 13338976 2400976

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-10-2021 16:38:48 to 18-10-2021 16:39:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 65 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          232147
MON$OLDEST_ACTIVE               233085
MON$OLDEST_SNAPSHOT             233085
MON$NEXT_TRANSACTION            233085
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                       2039008
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:518: 
:519: mscs-test-161.budzdorov.ru    Mon Oct 18 16:37:56 2021
:520:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:521: 
:522: 
:523: mscs-test-161.budzdorov.ru    Mon Oct 18 16:38:01 2021
:524:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:525: 
:526: 
:527: mscs-test-161.budzdorov.ru    Mon Oct 18 16:38:01 2021
:528:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:529: 
:530: 
:531: mscs-test-161.budzdorov.ru    Mon Oct 18 16:38:03 2021
:532:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:533: 
:534: 
:535: mscs-test-161.budzdorov.ru    Mon Oct 18 16:38:06 2021
:536:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:537: 
:538: 
:539: mscs-test-161.budzdorov.ru    Mon Oct 18 16:38:06 2021
:540:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:541: 
:542: 
:543: mscs-test-161.budzdorov.ru    Mon Oct 18 16:38:06 2021
:544:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:545: 
:546: 
:547: mscs-test-161.budzdorov.ru    Mon Oct 18 16:38:06 2021
:548:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:549: 
:550: 
:551: mscs-test-161.budzdorov.ru    Mon Oct 18 16:38:06 2021
:552:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:553: 
:554: 
:555: mscs-test-161.budzdorov.ru    Mon Oct 18 16:38:06 2021
:556:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:557: 
:558: 
:559: mscs-test-161.budzdorov.ru    Mon Oct 18 16:38:06 2021
:560:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:561: 
--- end of diff output ---

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

18.10.21 16:39:06. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-10-18 16:39:09 No severe PSQL-related problems occured
18.10.21 16:39:10. End of report.