Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.08.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 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 16174908 kB
MemAvailable 31009724 kB
Buffers 0 kB
Cached 15496440 kB
SwapCached 2632 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-08-13 01:37:46
DTS_END                         2021-08-13 01:37:46.2250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46540
TRN_ID                          248811
ATT_ID                          714
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: 9477.94 17257 1137353
customer order: creation 185.39 330 22247
customer order: refuse 15.43 314 1852
order to supplier: creation 48.80 1185 5856
order to supplier: removal 3.67 92460 440
invoice (draft): creation 48.43 3205 5811
invoice (draft): removal 54.19 760 6503
invoice accept: apply 43.33 16759 5200
invoice accept: cancel 48.75 798 5850
customer reserve: creation 3936.73 169 472407
customer reserve: removal 4775.07 55 573008
realization accept: apply 89.96 204 10795
realization accept: cancel 90.58 111 10870
payment from customer: creation 62.96 132 7555
payment from customer: removal 6.23 63 747
payment to supplier: creation 51.98 159 6237
payment to supplier: removal 3.63 61 436
service: total inventory turnovers 5.00 1243 600
service: total monetary turnovers 6.87 25 824
service: aggregate perf. data 0.93 17875 111
service: refresh index statistics 0.03 209234 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-08-2021 01:39:18 to 13-08-2021 01:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4945
TEST_TIME 2 3614
TEST_TIME 3 3538
TEST_TIME 4 4133
TEST_TIME 5 4145
TEST_TIME 6 4000
TEST_TIME 7 4919
TEST_TIME 8 5061
TEST_TIME 9 5415
TEST_TIME 10 5233
TEST_TIME 11 5348
TEST_TIME 12 5766
TEST_TIME 13 5692
TEST_TIME 14 6159
TEST_TIME 15 6183
TEST_TIME 16 6207
TEST_TIME 17 6334
TEST_TIME 18 6601
TEST_TIME 19 7246
TEST_TIME 20 7348
TEST_TIME 21 7382
TEST_TIME 22 8459
TEST_TIME 23 8403
TEST_TIME 24 9421
TEST_TIME 25 9268
TEST_TIME 26 9369
TEST_TIME 27 10026
TEST_TIME 28 10342
TEST_TIME 29 10189
TEST_TIME 30 10309
TEST_TIME 31 10098
TEST_TIME 32 10088
TEST_TIME 33 10126
TEST_TIME 34 10189
TEST_TIME 35 10233
TEST_TIME 36 10225
TEST_TIME 37 10087
TEST_TIME 38 10165
TEST_TIME 39 10158
TEST_TIME 40 9995
TEST_TIME 41 9808
TEST_TIME 42 9823
TEST_TIME 43 9697
TEST_TIME 44 9748
TEST_TIME 45 9667
TEST_TIME 46 9711
TEST_TIME 47 9669
TEST_TIME 48 9572
TEST_TIME 49 9492
TEST_TIME 50 9433
TEST_TIME 51 9374
TEST_TIME 52 9455
TEST_TIME 53 9573
TEST_TIME 54 9567
TEST_TIME 55 9527
TEST_TIME 56 9487
TEST_TIME 57 9609
TEST_TIME 58 9633
TEST_TIME 59 9736
TEST_TIME 60 9724
TEST_TIME 61 9798
TEST_TIME 62 9750
TEST_TIME 63 9744
TEST_TIME 64 9878
TEST_TIME 65 9894
TEST_TIME 66 9933
TEST_TIME 67 9927
TEST_TIME 68 9990
TEST_TIME 69 9882
TEST_TIME 70 9834
TEST_TIME 71 9757
TEST_TIME 72 9793
TEST_TIME 73 9736
TEST_TIME 74 9710
TEST_TIME 75 9676
TEST_TIME 76 9650
TEST_TIME 77 9652
TEST_TIME 78 9578
TEST_TIME 79 9581
TEST_TIME 80 9569
TEST_TIME 81 9521
TEST_TIME 82 9494
TEST_TIME 83 9476
TEST_TIME 84 9442
TEST_TIME 85 9430
TEST_TIME 86 9467
TEST_TIME 87 9542
TEST_TIME 88 9547
TEST_TIME 89 9551
TEST_TIME 90 9573
TEST_TIME 91 9559
TEST_TIME 92 9656
TEST_TIME 93 9644
TEST_TIME 94 9669
TEST_TIME 95 9703
TEST_TIME 96 9722
TEST_TIME 97 9674
TEST_TIME 98 9583
TEST_TIME 99 9577
TEST_TIME 100 9537
TEST_TIME 101 9555
TEST_TIME 102 9525
TEST_TIME 103 9486
TEST_TIME 104 9463
TEST_TIME 105 9437
TEST_TIME 106 9410
TEST_TIME 107 9377
TEST_TIME 108 9522
TEST_TIME 109 9495
TEST_TIME 110 9538
TEST_TIME 111 9532
TEST_TIME 112 9553
TEST_TIME 113 9544
TEST_TIME 114 9602
TEST_TIME 115 9571
TEST_TIME 116 9548
TEST_TIME 117 9604
TEST_TIME 118 9574
TEST_TIME 119 9535
TEST_TIME 120 9480

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-08-2021 01:39:22 to 13-08-2021 01:39:25.

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 1576464 1576464 0 0.00 0 33602 7 0 0
doc_list_biud 1576464 1576464 0 0.00 0 57953 26 0 0
sp_add_invoice_to_stock 5361 5200 161 3.00 36 165770 16759 0 161
sp_cancel_adding_invoice 5850 5850 0 0.00 5 74977 798 0 0
sp_cancel_client_order 1852 1852 0 0.00 4 8163 315 0 0
sp_cancel_customer_reserve 573008 573008 0 0.00 1 57958 56 0 0
sp_cancel_pay_from_customer 747 747 0 0.00 2 7160 64 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 4457 62 0 0
sp_cancel_supplier_invoice 6514 6503 11 0.16 1 18208 761 0 11
sp_cancel_supplier_order 440 440 0 0.00 7 1113568 92460 0 0
sp_cancel_write_off 10870 10870 0 0.00 1 16681 112 0 0
sp_client_order 22247 22247 0 0.00 4 17483 331 0 0
sp_customer_reserve 472407 472407 0 0.00 0 83445 169 0 0
sp_fill_shopping_cart 33913 33913 0 0.00 1 9743 36 0 0
sp_fill_shopping_cart_clo_res 472403 472403 0 0.00 0 16388 13 0 0
sp_get_clo_for_invoice 5196 5196 0 0.00 0 26650 257 0 0
sp_kill_cost_storno 17903 17903 0 0.00 0 6899 52 0 0
sp_kill_qstorno_ret_qs2qd 1163829 1163829 0 0.00 0 54078 30 0 0
sp_kill_qty_storno 614514 614514 0 0.00 0 57952 66 0 0
sp_lock_selected_doc 22096 22096 0 0.00 0 11220 7 0 0
sp_make_cost_storno 29783 29783 0 0.00 0 33590 56 0 0
sp_multiply_rows_for_pdistr 29783 29783 0 0.00 0 33485 39 0 0
sp_multiply_rows_for_qdistr 22247 22247 0 0.00 0 11575 153 0 0
sp_pay_from_customer 7555 7555 0 0.00 4 33526 133 0 0
sp_pay_to_supplier 6237 6237 0 0.00 4 33509 159 0 0
sp_payment_common 13792 13792 0 0.00 1 33483 54 0 0
sp_qd_handle_on_cancel_clo 1852 1852 0 0.00 0 8155 292 0 0
sp_qd_handle_on_invoice_upd_sts 11046 11046 0 0.00 0 24469 338 0 0
sp_qd_handle_on_reserve_upd_sts 10795 10795 0 0.00 0 22136 61 0 0
sp_reserve_write_off 10795 10795 0 0.00 6 33605 204 0 0
sp_supplier_invoice 5811 5811 0 0.00 7 98016 3205 0 0
sp_supplier_order 5856 5856 0 0.00 5 32322 1185 0 0
srv_aggregate_perf_data 111 111 0 0.00 2995 150177 17875 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1221 160908 49956 0 0
srv_find_qd_qs_mism 1088002 1088002 0 0.00 0 14915 5 0 0
srv_make_invnt_saldo 600 600 0 0.00 50 61836 1244 0 0
srv_make_money_saldo 824 824 0 0.00 1 3467 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 99037 335040 209235 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5201 5201 0 0.00 0 20232 143 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 6466 72 0 0
t$perf-norm:sp_cancel_client_order 1852 1852 0 0.00 0 5458 20 0 0
t$perf-norm:sp_cancel_customer_reserve 757 757 0 0.00 0 1673 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 747 747 0 0.00 0 3346 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 359 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 1579 13 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 19568 1446 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 1290 5 0 0
t$perf-norm:sp_client_order 22247 22247 0 0.00 0 5255 14 0 0
t$perf-norm:sp_customer_reserve 1067 1067 0 0.00 0 6709 17 0 0
t$perf-norm:sp_pay_from_customer 7555 7555 0 0.00 0 9720 9 0 0
t$perf-norm:sp_pay_to_supplier 6237 6237 0 0.00 0 2410 6 0 0
t$perf-norm:sp_reserve_write_off 10795 10795 0 0.00 0 10326 12 0 0
t$perf-norm:sp_supplier_invoice 5811 5811 0 0.00 0 6387 22 0 0
t$perf-norm:sp_supplier_order 5856 5856 0 0.00 0 6200 16 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 780 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 67 2 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 1965 20 0 0
t$perf-norm:srv_make_money_saldo 824 824 0 0.00 0 6528 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10710 10388 322 3.00 0 9548 17 0 322
v_all_customers 40390 40390 0 0.00 0 87 1 0 0
v_all_suppliers 32116 32116 0 0.00 0 19 1 0 0
v_all_wares 44494 44494 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 3352 10 0 0
v_cancel_client_order 3704 3704 0 0.00 0 4366 5 0 0
v_cancel_customer_prepayment 1494 1494 0 0.00 0 5917 9 0 0
v_cancel_customer_reserve 1514 1514 0 0.00 0 1210 4 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 1447 8 0 0
v_cancel_supplier_invoice 876 854 22 2.51 0 3716 20 0 22
v_cancel_supplier_order 880 880 0 0.00 0 606 5 0 0
v_cancel_write_off 2016 2016 0 0.00 0 10344 24 0 0
v_max_id_clo_ord 5856 5856 0 0.00 0 3461 2 0 0
v_max_id_clo_res 1067 1067 0 0.00 0 5012 20 0 0
v_max_id_ord_sup 5810 5810 0 0.00 0 3020 2 0 0
v_max_non_paid_invoice 1845 1845 0 0.00 0 3141 5 0 0
v_max_non_paid_realizn 4015 4015 0 0.00 0 1726 4 0 0
v_min_id_clo_ord 5856 5856 0 0.00 0 5765 9 0 0
v_min_id_clo_res 1067 1067 0 0.00 0 9281 24 0 0
v_min_id_ord_sup 5810 5810 0 0.00 0 5679 11 0 0
v_min_non_paid_invoice 6237 6237 0 0.00 0 26937 47 0 0
v_min_non_paid_realizn 7555 7555 0 0.00 0 33471 39 0 0
v_our_firm 11184 11184 0 0.00 0 87 1 0 0
v_reserve_write_off 21590 21590 0 0.00 0 4555 6 0 0
x_lock_depdocs_on_canc_invoice 11926 11926 0 0.00 0 13983 107 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 7274 278 0 0
x_make_qstorno_1000_1200 5856 5856 0 0.00 1 32314 1108 0 0
x_make_qstorno_1200_2000 5811 5811 0 0.00 4 97997 3073 0 0
x_make_qstorno_2100_3300 472407 472407 0 0.00 0 83443 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-08-2021 01:39:26 to 13-08-2021 01:39:30.

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:50 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:50 19034 8192000000 10372281632 10694631424 1427460096 14.839 100 86 172 0 1461939088 6490208 27988784
23:10:17 26114 8192000000 10443995312 10779033600 1497622800 15.455 100 87 175 0 1534612880 8851408 28138672
23:11:51 21322 8192000000 10495728464 10840399872 1548041600 15.893 100 82 164 1 1584476080 10344304 26090176
23:13:16 11724 8192000000 10560628416 10920579072 1618742464 16.499 100 79 158 2 1654095056 11306416 24046176
23:14:32 11641 8192000000 10629952496 11005517824 1696605440 17.157 100 79 158 1 1732880672 13459824 22815408
23:15:46 7224 8192000000 10701287920 11078778880 1758827744 17.675 100 75 149 0 1791626336 11904992 20893600
23:16:57 2024 8192000000 10756530064 11142369280 1814546416 18.133 100 77 154 2 1849731056 13193104 21991536
23:18:01 2473 8192000000 10783878864 11172708352 1854499104 18.459 100 87 175 0 1891641248 12827888 24314256
23:19:04 1786 8192000000 10820500288 11209527296 1875642864 18.630 100 91 182 1 1914283776 12586672 26054240
23:20:08 7976 8192000000 10808896688 11206643712 1876890368 18.640 100 75 151 2 1910486352 11584336 22011648
23:21:17 22831 8192000000 10801226256 11218350080 1875917648 18.632 100 29 58 1 1894391968 10135504 8338816
23:22:41 5293 8192000000 10803595648 11228999680 1889641520 18.743 100 32 64 1 1905216752 6547712 9027520
23:23:52 5820 8192000000 10815731936 11238006784 1899077280 18.819 100 25 48 0 1912226752 6142464 7007008
23:24:59 8065 8192000000 10811626080 11247104000 1905896544 18.874 100 21 42 0 1918299440 6607984 5794912
23:26:12 57720 8192000000 10849574048 11253403648 1876739872 18.639 98 63 127 0 1902063040 6320240 19002928
23:28:10 9205 8192000000 10815242768 11238404096 1866617872 18.557 98 35 70 0 1882890384 6479248 9793264
23:29:21 41128 8192000000 10695109760 11116593152 1686288304 17.070 91 20 40 1 1697746832 5354192 6104336
23:31:03 9888 8192000000 10695731312 11115118592 1768503552 17.755 98 27 53 2 1782537216 5968752 8064912
23:32:13 9714 8192000000 10715993920 11129741312 1754077696 17.635 97 22 44 3 1765129424 4960784 6090944
23:33:29 39013 8192000000 10755094768 11157393408 1772522912 17.788 97 52 101 1 1793648240 5711472 15413856
23:35:08 4486 8192000000 10759243104 11185037312 1846829344 18.396 100 26 49 1 1859997856 5998816 7169696
23:36:14 6258 8192000000 10781013248 11207966720 1867549536 18.564 100 22 44 2 1879330992 5531808 6249648
23:37:23 13163 8192000000 10814776912 11250741248 1904871696 18.865 100 10 20 2 1916984528 8721328 3391504
23:38:39 80588 8192000000 10860426960 11294789632 1935255792 19.109 100 46 91 2 1957819104 9741360 12821952
23:41:06 7175 8192000000 10895358032 11322269696 1961282992 19.316 100 53 106 0 1989500704 12829392 15388320
23:42:22 60763 8192000000 10908028752 11346907136 1972186576 19.403 100 33 66 1 1997583440 15429600 9967264
23:44:26 14046 8192000000 10923610528 11357417472 1971624336 19.398 99 53 106 1 2005511344 18481152 15405856
23:45:51 12965 8192000000 10953854624 11395801088 2005174640 19.664 100 50 100 0 2050176272 30134448 14867184
23:47:08 8093 8192000000 10966882000 11404574720 2002635344 19.644 99 48 96 1 2044768304 26878832 15254128
23:48:43 12082 8192000000 10970488080 11412037632 2005230544 19.664 99 49 98 1 2056123168 33601904 17290720
23:50:00 6194 8192000000 10979787712 11409850368 2000851872 19.629 99 59 118 1 2050754272 31490880 18411520
23:51:13 4261 8192000000 10992488336 11423911936 2026023648 19.827 100 64 124 1 2077942704 33455392 18463664
23:52:22 6120 8192000000 11008348912 11438850048 2039222144 19.931 100 77 153 0 2098216320 36077296 22916880
23:53:31 4664 8192000000 10996895328 11415932928 2012275488 19.719 100 86 171 0 2075896688 37752144 25869056
23:54:41 1220 8192000000 10983994848 11403423744 1999450800 19.618 100 84 169 2 2068290064 42860208 25979056
23:55:46 4908 8192000000 10970380976 11386998784 1981337200 19.475 100 83 166 1 2054683520 47169008 26177312
23:56:54 12495 8192000000 10948752176 11392237568 1979321040 19.459 100 43 83 2 2038557072 45195920 14040112
23:58:16 12037 8192000000 10929006336 11365015552 1945400480 19.190 99 43 86 0 2013820352 53415936 15003936
23:59:37 22666 8192000000 10933313472 11382980608 1967538800 19.366 100 30 59 1 2023030160 44820768 10670592
00:01:04 7615 8192000000 10951485776 11390058496 1966419168 19.357 100 50 100 2 2036966208 53114096 17432944
00:03:00 18519 8192000000 10904165808 11352973312 1951232576 19.236 100 37 74 2 1995485392 31842432 12410384
00:04:36 17913 8192000000 10906596160 11375906816 1962159856 19.323 100 27 52 4 1998702096 28957840 7584400
00:05:56 53987 8192000000 10876255440 11324698624 1910175472 18.908 98 29 58 0 1929245808 10362240 8708096
00:08:33 77007 8192000000 10895261616 11358781440 1936490656 19.119 98 15 30 1 1953733616 13487296 3755664
00:10:50 92768 8192000000 10904628208 11384225792 1904215680 18.860 96 9 18 1 1920315936 13450320 2649936
00:13:25 71917 8192000000 10921545040 11361148928 1907048672 18.883 96 12 21 2 1918550080 8403424 3097984
00:15:38 97734 8192000000 10910413504 11380391936 1949959024 19.226 99 16 32 0 1966507312 12767136 3781152
00:18:20 81584 8192000000 10908852768 11367436288 1910020368 18.907 96 40 80 0 1930642624 9716784 10905472
00:20:44 32746 8192000000 10897789488 11355471872 1961650272 19.319 100 15 30 1 1973887152 7975248 4261632
00:22:20 26896 8192000000 10939061552 11385139200 1991931584 19.559 100 66 133 1 2019685456 8116928 19636944
00:23:51 19957 8192000000 10925422336 11388280832 1990561200 19.548 100 34 68 0 2009985584 9710480 9713904
00:25:16 46061 8192000000 10910012336 11372158976 1941184832 19.156 97 15 30 1 1962666800 16945056 4536912
00:27:04 56722 8192000000 10927069488 11396952064 1977385024 19.444 99 13 26 1 1999624288 18407088 3832176
00:29:02 47125 8192000000 10880002240 11359453184 1917000784 18.963 98 12 24 0 1942641056 21865056 3775216
00:30:53 66322 8192000000 10875731648 11333423104 1861258704 18.513 95 10 19 1 1874209472 10165824 2784944
00:33:04 67164 8192000000 10877735872 11347546112 1893107792 18.771 96 30 59 2 1918353552 16434912 8810848
00:35:13 47095 8192000000 10889422656 11363639296 1941424048 19.158 99 11 22 3 1956680800 12431632 2825120
00:37:00 84725 8192000000 10890749200 11373850624 1891035024 18.754 96 13 26 1 1911455280 17131296 3288960
00:39:25 45435 8192000000 10896405664 11363995648 1927918064 19.050 98 23 45 2 1946596336 12678928 5999344
00:41:11 63939 8192000000 10903048064 11363336192 1927117616 19.044 98 15 30 1 1942940016 11624992 4197408
00:43:21 71307 8192000000 10914397232 11383824384 1921704656 19.000 97 19 38 0 1946634544 19525600 5404288
00:45:33 99783 8192000000 10896259248 11335823360 1895769856 18.792 96 25 50 3 1919748912 16688304 7290752
00:48:13 50196 8192000000 10896107184 11364163584 1960060064 19.307 100 23 46 1 1978255936 11963056 6232816
00:50:06 6297 8192000000 10899634752 11349553152 1949604400 19.223 100 52 103 1 1974361248 10128896 14627952
00:51:13 72989 8192000000 10880554144 11349467136 1911858688 18.922 98 11 22 1 1929045648 13682944 3504016
00:53:31 53914 8192000000 10878829008 11353141248 1939878400 19.146 99 21 42 0 1953969440 8329008 5762032
00:55:38 99478 8192000000 10894541584 11374202880 1951838592 19.241 99 20 40 1 1968963264 11509136 5615536
00:58:22 67395 8192000000 10874600352 11365912576 1959253344 19.300 100 10 20 0 1971039616 9013408 2772864
01:00:33 36504 8192000000 10888844720 11367534592 1966593824 19.358 100 19 38 1 1979360384 8061728 4704832
01:02:11 32617 8192000000 10910235712 11393159168 1969838528 19.384 99 33 66 0 1997634800 18215808 9580464
01:03:55 79000 8192000000 10883623008 11365380096 1943734192 19.177 98 18 36 1 1966478112 17388464 5355456
01:06:21 98606 8192000000 10889680288 11377496064 1887094016 18.722 95 26 52 0 1906223264 12030432 7098816
01:09:01 102788 8192000000 10932816336 11411607552 1953829936 19.257 98 9 18 0 1969869472 14354416 1685120
01:12:05 58085 8192000000 10965129216 11421732864 2002016048 19.639 100 30 60 1 2023676560 12758912 8901600
01:14:04 160908 8192000000 10885383168 11363737600 1855577872 18.467 93 16 31 1 1869700272 9373504 4748896
01:17:58 85338 8192000000 10865158864 11355869184 1908070688 18.891 97 10 20 0 1922355248 11392016 2892544
01:20:44 53236 8192000000 10904186304 11391365120 1975675216 19.430 100 15 31 1 1990219152 10390144 4153792
01:23:06 69641 8192000000 10891103808 11367768064 1932083440 19.084 98 14 27 2 1959399872 23235744 4080688
01:25:16 57627 8192000000 10863624480 11344547840 1874893936 18.624 96 20 40 3 1897651344 16253648 6503760
01:27:15 45932 8192000000 10882404896 11369218048 1955150336 19.267 100 20 41 0 1976970528 15753360 6066832
01:29:02 61166 8192000000 10880118592 11363360768 1905917376 18.874 97 25 49 2 1928636736 15436784 7282576
01:31:49 46034 8192000000 10873748944 11332866048 1874613968 18.622 96 30 60 1 1892234176 9002864 8617344
01:33:36 27806 8192000000 10888689904 11347517440 1936702928 19.120 100 49 98 2 1971207024 20898992 13605104
01:35:12 117096 8192000000 10846725648 11326308352 1916172032 18.956 99 25 50 0 1934197904 10769424 7256448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-08-2021 01:39:32 to 13-08-2021 01:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-08-2021 01:39:48 to 13-08-2021 01:39:50.

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          247853
MON$OLDEST_ACTIVE               249001
MON$OLDEST_SNAPSHOT             249001
MON$NEXT_TRANSACTION            249001
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                       2042080
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
:197: 
:198: mscs-test-161.budzdorov.ru    Fri Aug 13 01:38:07 2021
:199:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:200: 
--- end of diff output ---

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

13.08.21 01:39:51. 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-08-13 01:39:53 No severe PSQL-related problems occured
13.08.21 01:39:54. End of report.