Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.09.2021 22: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 16112188 kB
MemAvailable 30784736 kB
Buffers 0 kB
Cached 15548796 kB
SwapCached 416 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-09-17 22:37:58
DTS_END                         2021-09-17 22:37:52.7710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44604
TRN_ID                          244456
ATT_ID                          274
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: 9422.05 21431 1130646
customer order: creation 174.08 328 20889
customer order: refuse 14.90 297 1788
order to supplier: creation 46.46 1133 5575
order to supplier: removal 3.56 102105 427
invoice (draft): creation 47.67 3269 5720
invoice (draft): removal 52.53 814 6303
invoice accept: apply 42.97 17759 5156
invoice accept: cancel 47.53 774 5704
customer reserve: creation 4004.19 174 480503
customer reserve: removal 4688.82 61 562658
realization accept: apply 86.00 171 10320
realization accept: cancel 81.24 110 9749
payment from customer: creation 59.97 115 7196
payment from customer: removal 5.81 101 697
payment to supplier: creation 50.33 142 6039
payment to supplier: removal 3.50 99 420
service: total inventory turnovers 4.63 1205 555
service: total monetary turnovers 7.05 37 846
service: aggregate perf. data 0.81 21202 97
service: refresh index statistics 0.03 278721 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-09-2021 22:39:00 to 17-09-2021 22:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1396
TEST_TIME 2 2794
TEST_TIME 3 3377
TEST_TIME 4 3876
TEST_TIME 5 4253
TEST_TIME 6 4408
TEST_TIME 7 4189
TEST_TIME 8 4589
TEST_TIME 9 4949
TEST_TIME 10 5021
TEST_TIME 11 5509
TEST_TIME 12 5873
TEST_TIME 13 6598
TEST_TIME 14 6799
TEST_TIME 15 6989
TEST_TIME 16 7424
TEST_TIME 17 7809
TEST_TIME 18 7969
TEST_TIME 19 7794
TEST_TIME 20 7847
TEST_TIME 21 8336
TEST_TIME 22 8533
TEST_TIME 23 9140
TEST_TIME 24 9194
TEST_TIME 25 9817
TEST_TIME 26 9871
TEST_TIME 27 10197
TEST_TIME 28 11114
TEST_TIME 29 10969
TEST_TIME 30 10919
TEST_TIME 31 10733
TEST_TIME 32 10545
TEST_TIME 33 10506
TEST_TIME 34 10413
TEST_TIME 35 10463
TEST_TIME 36 10382
TEST_TIME 37 10302
TEST_TIME 38 10145
TEST_TIME 39 10197
TEST_TIME 40 10141
TEST_TIME 41 10384
TEST_TIME 42 10261
TEST_TIME 43 10196
TEST_TIME 44 10148
TEST_TIME 45 10010
TEST_TIME 46 9973
TEST_TIME 47 9889
TEST_TIME 48 9716
TEST_TIME 49 9763
TEST_TIME 50 9701
TEST_TIME 51 9733
TEST_TIME 52 9729
TEST_TIME 53 9672
TEST_TIME 54 9627
TEST_TIME 55 9670
TEST_TIME 56 9866
TEST_TIME 57 9809
TEST_TIME 58 9799
TEST_TIME 59 9800
TEST_TIME 60 9762
TEST_TIME 61 9954
TEST_TIME 62 9974
TEST_TIME 63 9883
TEST_TIME 64 9802
TEST_TIME 65 9788
TEST_TIME 66 9694
TEST_TIME 67 9728
TEST_TIME 68 9643
TEST_TIME 69 9560
TEST_TIME 70 9614
TEST_TIME 71 9673
TEST_TIME 72 9641
TEST_TIME 73 9620
TEST_TIME 74 9649
TEST_TIME 75 9681
TEST_TIME 76 9696
TEST_TIME 77 9683
TEST_TIME 78 9671
TEST_TIME 79 9739
TEST_TIME 80 9718
TEST_TIME 81 9775
TEST_TIME 82 9747
TEST_TIME 83 9779
TEST_TIME 84 9687
TEST_TIME 85 9685
TEST_TIME 86 9610
TEST_TIME 87 9657
TEST_TIME 88 9620
TEST_TIME 89 9567
TEST_TIME 90 9530
TEST_TIME 91 9476
TEST_TIME 92 9453
TEST_TIME 93 9431
TEST_TIME 94 9429
TEST_TIME 95 9505
TEST_TIME 96 9612
TEST_TIME 97 9572
TEST_TIME 98 9544
TEST_TIME 99 9594
TEST_TIME 100 9602
TEST_TIME 101 9565
TEST_TIME 102 9577
TEST_TIME 103 9568
TEST_TIME 104 9574
TEST_TIME 105 9689
TEST_TIME 106 9662
TEST_TIME 107 9658
TEST_TIME 108 9590
TEST_TIME 109 9606
TEST_TIME 110 9557
TEST_TIME 111 9548
TEST_TIME 112 9505
TEST_TIME 113 9505
TEST_TIME 114 9471
TEST_TIME 115 9493
TEST_TIME 116 9466
TEST_TIME 117 9434
TEST_TIME 118 9449
TEST_TIME 119 9425
TEST_TIME 120 9423

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-09-2021 22:39:04 to 17-09-2021 22:39:07.

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 1574174 1574174 0 0.00 0 17393 6 0 0
doc_list_biud 1574174 1574174 0 0.00 0 75548 27 0 0
sp_add_invoice_to_stock 5250 5156 94 1.79 28 195815 17759 0 94
sp_cancel_adding_invoice 5704 5704 0 0.00 5 62655 775 0 0
sp_cancel_client_order 1788 1788 0 0.00 4 7621 297 0 0
sp_cancel_customer_reserve 562658 562658 0 0.00 1 75548 61 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 10837 102 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 7718 100 0 0
sp_cancel_supplier_invoice 6312 6303 9 0.14 1 17586 815 0 9
sp_cancel_supplier_order 427 427 0 0.00 7 1041149 102105 0 0
sp_cancel_write_off 9749 9749 0 0.00 1 8976 111 0 0
sp_client_order 20889 20889 0 0.00 4 40489 328 0 0
sp_customer_reserve 480503 480503 0 0.00 0 137490 174 0 0
sp_fill_shopping_cart 32184 32184 0 0.00 1 15132 38 0 0
sp_fill_shopping_cart_clo_res 480503 480503 0 0.00 0 24921 13 0 0
sp_get_clo_for_invoice 5155 5155 0 0.00 0 45148 289 0 0
sp_kill_cost_storno 16570 16570 0 0.00 0 5897 55 0 0
sp_kill_qstorno_ret_qs2qd 1141795 1141795 0 0.00 0 75546 33 0 0
sp_kill_qty_storno 602104 602104 0 0.00 0 75547 70 0 0
sp_lock_selected_doc 21342 21342 0 0.00 0 9622 9 0 0
sp_make_cost_storno 28710 28710 0 0.00 0 17159 59 0 0
sp_multiply_rows_for_pdistr 28710 28710 0 0.00 0 6337 28 0 0
sp_multiply_rows_for_qdistr 20889 20889 0 0.00 0 34704 161 0 0
sp_pay_from_customer 7196 7196 0 0.00 4 16530 115 0 0
sp_pay_to_supplier 6039 6039 0 0.00 4 17780 142 0 0
sp_payment_common 13235 13235 0 0.00 1 17681 36 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 4387 257 0 0
sp_qd_handle_on_invoice_upd_sts 10859 10859 0 0.00 0 39516 301 0 0
sp_qd_handle_on_reserve_upd_sts 10320 10320 0 0.00 0 9743 46 0 0
sp_reserve_write_off 10320 10320 0 0.00 7 17205 172 0 0
sp_supplier_invoice 5720 5720 0 0.00 6 99770 3270 0 0
sp_supplier_order 5575 5575 0 0.00 5 30077 1133 0 0
srv_aggregate_perf_data 98 97 1 1.02 2643 251543 21203 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1599 189974 47258 0 0
srv_find_qd_qs_mism 1080963 1080963 0 0.00 0 10009 5 0 0
srv_make_invnt_saldo 555 555 0 0.00 41 124381 1205 0 0
srv_make_money_saldo 846 846 0 0.00 1 13092 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 166222 395316 278722 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5156 5156 0 0.00 0 11436 179 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 1 8913 106 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 4525 12 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 2813 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 9234 29 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 2948 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 436 12 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 22881 1717 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 4185 10 0 0
t$perf-norm:sp_client_order 20889 20889 0 0.00 0 11674 18 0 0
t$perf-norm:sp_customer_reserve 1057 1057 0 0.00 0 3609 8 0 0
t$perf-norm:sp_pay_from_customer 7196 7196 0 0.00 0 13682 14 0 0
t$perf-norm:sp_pay_to_supplier 6039 6039 0 0.00 0 9875 15 0 0
t$perf-norm:sp_reserve_write_off 10320 10320 0 0.00 0 10467 14 0 0
t$perf-norm:sp_supplier_invoice 5720 5720 0 0.00 0 7571 21 0 0
t$perf-norm:sp_supplier_order 5575 5575 0 0.00 0 15694 19 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 132 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 323 10 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 705 25 0 0
t$perf-norm:srv_make_money_saldo 846 846 0 0.00 0 2050 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10496 10308 188 1.79 0 7369 21 0 188
v_all_customers 37462 37462 0 0.00 0 18 1 0 0
v_all_suppliers 31384 31384 0 0.00 0 55 1 0 0
v_all_wares 41778 41778 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1895 8 0 0
v_cancel_client_order 3576 3576 0 0.00 0 3331 6 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 976 4 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 2453 5 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 4729 14 0 0
v_cancel_supplier_invoice 810 792 18 2.22 0 4540 22 0 18
v_cancel_supplier_order 854 854 0 0.00 0 5277 12 0 0
v_cancel_write_off 2048 2048 0 0.00 0 3110 16 0 0
v_max_id_clo_ord 5575 5575 0 0.00 0 159 1 0 0
v_max_id_clo_res 1057 1057 0 0.00 0 2687 13 0 0
v_max_id_ord_sup 5720 5720 0 0.00 0 6041 6 0 0
v_max_non_paid_invoice 1642 1642 0 0.00 0 3225 4 0 0
v_max_non_paid_realizn 4028 4028 0 0.00 0 2482 4 0 0
v_min_id_clo_ord 5575 5575 0 0.00 0 3430 7 0 0
v_min_id_clo_res 1057 1057 0 0.00 0 3203 10 0 0
v_min_id_ord_sup 5720 5720 0 0.00 0 7256 9 0 0
v_min_non_paid_invoice 6039 6039 0 0.00 0 11385 25 0 0
v_min_non_paid_realizn 7196 7196 0 0.00 0 12192 21 0 0
v_our_firm 10652 10652 0 0.00 0 21 1 0 0
v_reserve_write_off 20640 20640 0 0.00 0 3919 5 0 0
x_lock_depdocs_on_canc_invoice 11611 11611 0 0.00 0 15614 125 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 11027 378 0 0
x_make_qstorno_1000_1200 5575 5575 0 0.00 2 30068 1058 0 0
x_make_qstorno_1200_2000 5720 5720 0 0.00 3 99706 3135 0 0
x_make_qstorno_2100_3300 480503 480503 0 0.00 0 137488 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-09-2021 22:39:08 to 17-09-2021 22:39:12.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:56 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:57 31418 8192000000 10373545344 10694381568 1421240816 14.784 100 87 174 0 1455165040 5906528 28017696
20:10:35 17127 8192000000 10445891472 10775343104 1495259440 15.435 100 89 178 0 1531536096 8015872 28260784
20:12:02 19479 8192000000 10472328864 10817470464 1532942960 15.763 100 81 162 0 1567664448 9969440 24752048
20:13:27 7196 8192000000 10544519888 10901831680 1608634192 16.413 100 77 154 1 1642048512 10542192 22872128
20:14:39 8925 8192000000 10628511568 10997948416 1695574832 17.148 100 78 156 0 1730320864 12433936 22312096
20:15:50 6569 8192000000 10700405056 11081789440 1764676368 17.723 100 68 135 2 1795328096 12313504 18338224
20:16:59 2847 8192000000 10764773136 11149971456 1829046848 18.252 100 82 165 0 1864709616 12588384 23074384
20:18:03 1925 8192000000 10800772224 11188580352 1862541152 18.524 100 89 176 0 1900382400 13104816 24736432
20:19:08 6626 8192000000 10815217344 11209015296 1880442944 18.669 100 79 154 1 1912833040 10586080 21804016
20:20:16 6950 8192000000 10798896976 11219189760 1881949200 18.681 100 35 70 1 1899488512 7894608 9644704
20:21:24 8260 8192000000 10796048448 11228246016 1889327024 18.740 100 22 44 0 1911016128 15109024 6580080
20:22:33 59557 8192000000 10844571184 11254566912 1902090272 18.843 100 38 79 2 1918998064 5250496 11657296
20:24:36 4126 8192000000 10819687984 11256188928 1908384432 18.894 100 28 56 1 1923900048 6323040 9192576
20:25:41 8792 8192000000 10815039296 11256963072 1905415328 18.870 100 18 36 0 1916628224 5992000 5220896
20:26:51 58891 8192000000 10821464368 11239972864 1860464448 18.507 98 37 71 3 1875336848 5316496 9555904
20:28:50 6210 8192000000 10751875200 11177095168 1822417584 18.197 99 32 63 1 1837574784 6666720 8490480
20:29:58 6225 8192000000 10694983088 11105427456 1736871120 17.493 97 27 54 1 1751392240 6187344 8333776
20:31:05 9058 8192000000 10706854000 11117535232 1753563104 17.631 97 24 47 1 1765905104 5136192 7205808
20:32:14 38762 8192000000 10737267472 11152986112 1773162672 17.793 97 36 72 1 1791040112 6468944 11408496
20:33:59 5190 8192000000 10769983856 11194671104 1851096400 18.431 100 24 45 1 1862976544 5168368 6711776
20:35:07 8971 8192000000 10786534128 11214454784 1874684176 18.622 100 26 52 1 1887601312 5698192 7218944
20:36:17 64611 8192000000 10812832064 11239665664 1888253056 18.732 100 33 65 0 1903422992 6110032 9059904
20:38:27 53412 8192000000 10846285712 11273338880 1910139392 18.908 99 41 80 2 1929015840 7743952 11132496
20:40:23 15825 8192000000 10889720112 11322105856 1956057600 19.275 100 46 91 1 1982230464 13108944 13063920
20:41:46 38848 8192000000 10900744400 11341467648 1970409952 19.389 100 36 72 0 1997837072 15838912 11588208
20:43:30 12125 8192000000 10937858464 11369820160 1992805424 19.566 100 44 88 0 2024532000 18055888 13670688
20:44:46 6189 8192000000 10961599216 11388559360 2006354880 19.673 100 55 110 0 2045047888 22257120 16435888
20:46:07 9411 8192000000 10977140000 11409866752 2021543072 19.792 100 56 109 2 2058553088 21313888 15696128
20:47:24 19438 8192000000 10992210512 11423670272 2021909344 19.795 100 68 135 0 2076910368 34399008 20602016
20:48:49 3759 8192000000 11006699888 11432779776 2031203536 19.868 100 61 122 0 2088885392 38115600 19566256
20:49:58 4274 8192000000 10967147232 11389665280 1975221280 19.427 99 77 154 0 2039538224 40526592 23790352
20:51:09 4221 8192000000 10955445616 11377254400 1971850848 19.400 100 69 138 1 2032980544 40096288 21033408
20:52:18 5433 8192000000 10955979232 11373092864 1967313776 19.364 100 80 158 2 2037067456 45791584 23962096
20:53:28 1598 8192000000 10991618400 11407077376 1993657024 19.573 100 91 183 0 2073694864 52048032 27989808
20:54:35 3436 8192000000 10991868576 11405922304 1973392704 19.412 99 90 180 1 2057687424 56190064 28104656
20:55:44 15101 8192000000 10983684816 11422167040 2004387920 19.657 100 43 86 0 2067155856 48703808 14064128
20:57:10 11270 8192000000 10974466272 11412045824 1984521536 19.500 100 49 96 0 2057084288 57000432 15562320
20:58:24 18834 8192000000 10944907312 11391696896 1931947696 19.082 98 33 66 0 2008064064 64368656 11747712
20:59:45 25083 8192000000 10899794128 11340918784 1882388736 18.684 96 40 81 0 1949841920 54195072 13258112
21:01:13 12450 8192000000 10939968736 11385159680 1962546768 19.326 100 29 56 3 2020691584 48517344 9627472
21:02:30 25111 8192000000 10907098752 11363975168 1938190704 19.132 100 23 46 0 1997689520 51349872 8148944
21:04:12 34395 8192000000 10873394256 11332960256 1901571728 18.839 99 15 30 0 1954938576 47570784 5796064
21:05:51 10466 8192000000 10872612560 11305385984 1909093520 18.899 99 56 110 3 1930061584 6052560 14915504
21:07:03 46267 8192000000 10840210608 11290341376 1835487808 18.304 95 30 56 0 1851211648 7657120 8066720
21:08:50 134339 8192000000 10871423888 11327541248 1882978688 18.689 97 32 64 1 1899073104 6850528 9243888
21:12:06 189974 8192000000 10902333024 11358228480 1873859200 18.615 96 17 34 0 1893455504 15105328 4490976
21:16:17 34070 8192000000 10896851664 11356549120 1949710656 19.224 99 41 82 0 1979799328 18016976 12071696
21:18:07 59143 8192000000 10892392704 11372359680 1959831328 19.305 100 9 18 0 1984907456 22774320 2301808
21:20:06 63652 8192000000 10904060496 11373441024 1973822064 19.416 100 10 20 1 1985530576 8649184 3059328
21:22:10 63684 8192000000 10893439440 11369959424 1962469760 19.326 99 11 22 0 1972714784 7583536 2661488
21:25:22 104555 8192000000 10912755760 11400679424 1994641632 19.580 100 16 31 2 2010919424 11434672 4843120
21:28:08 74572 8192000000 10914309664 11390132224 1959954816 19.306 98 23 45 0 1975134096 9158752 6020528
21:30:29 18189 8192000000 10945514880 11412148224 2007500816 19.682 100 29 58 0 2026139840 10217952 8421072
21:31:47 82827 8192000000 10929949792 11418529792 1987842176 19.527 99 18 36 0 2012882272 19403248 5636848
21:34:15 39766 8192000000 10906296304 11371954176 1927164704 19.044 97 21 42 0 1941868768 8601648 6102416
21:35:58 19589 8192000000 10905679792 11355979776 1945440944 19.190 99 38 75 1 1969179040 13006416 10731680
21:37:26 85585 8192000000 10901157664 11379810304 1923344512 19.014 97 9 18 0 1942235088 16573088 2317488
21:39:54 132821 8192000000 10892237568 11362017280 1904090064 18.859 96 33 66 1 1923771680 9968560 9713056
21:43:08 5552 8192000000 10910309184 11374174208 1976333664 19.436 100 36 72 1 1995882032 9303232 10245136
21:44:15 80608 8192000000 10840190144 11318276096 1911795392 18.921 98 17 34 2 1928339152 11847024 4696736
21:46:35 54744 8192000000 10877579472 11360747520 1923684208 19.016 98 12 24 0 1942605664 14953760 3967696
21:49:40 33376 8192000000 10873290192 11328495616 1861562944 18.516 95 36 72 0 1881047200 9688416 9795840
21:51:19 92861 8192000000 10861621408 11341881344 1881810304 18.680 97 14 28 1 1906119168 19576720 4732144
21:53:59 40140 8192000000 10882676944 11332214784 1900482176 18.830 97 33 66 0 1918915008 9554864 8877968
21:55:54 23284 8192000000 10892525376 11341701120 1928889488 19.058 99 49 98 1 1958625440 15919136 13816816
21:57:20 36398 8192000000 10879033952 11338485760 1933551104 19.095 100 32 64 0 1955213728 12663840 8998784
21:59:18 39309 8192000000 10887663024 11336282112 1906576576 18.879 98 31 62 3 1924594576 9190528 8827472
22:01:00 94236 8192000000 10868732576 11337072640 1925320256 19.029 99 33 65 0 1942917888 8235136 9362496
22:03:51 17765 8192000000 10895975216 11346968576 1951945216 19.242 100 43 86 0 1975747024 11295312 12506496
22:05:13 54683 8192000000 10856476704 11337936896 1933288000 19.093 99 10 20 0 1944783712 8777408 2718304
22:07:08 67062 8192000000 10869058464 11353587712 1938108720 19.132 99 12 24 0 1948675216 7486288 3080208
22:09:40 57511 8192000000 10915955328 11383398400 1944247104 19.181 98 24 44 1 1956938912 6336768 6355040
22:12:28 59340 8192000000 10910475392 11394158592 1964711504 19.343 99 17 34 0 1981956464 11770144 5474816
22:14:43 29727 8192000000 10913890176 11379462144 1966934512 19.361 99 37 72 1 1984274304 7079856 10259936
22:16:13 54673 8192000000 10888144240 11368157184 1950786480 19.233 99 20 40 1 1967394288 11066944 5540864
22:18:11 34657 8192000000 10926322672 11376799744 1948657872 19.216 98 43 84 1 1969254912 8904224 11692816
22:19:51 60254 8192000000 10903297952 11367325696 1916442352 18.958 97 33 66 0 1938646992 13174016 9030624
22:21:57 91035 8192000000 10881747568 11349925888 1899968464 18.826 96 9 18 1 1912460976 10126368 2366144
22:24:37 107526 8192000000 10839392720 11314241536 1888462608 18.733 97 23 46 1 1904069264 9258928 6347728
22:27:33 69231 8192000000 10850074160 11341250560 1909105968 18.899 98 7 14 0 1926723520 15372848 2244704
22:29:59 83202 8192000000 10864652480 11338674176 1930917584 19.074 99 15 30 0 1946308288 10833280 4557424
22:32:22 80227 8192000000 10872501472 11354763264 1951928352 19.242 100 12 25 2 1964589952 9329136 3332464
22:34:43 63113 8192000000 10867969264 11351060480 1949132928 19.220 100 12 24 1 1961862816 8849280 3880608
22:36:54 19740 8192000000 10830932608 11275997184 1847790288 18.404 95 24 48 0 1860237648 5947808 6499552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-09-2021 22:39:14 to 17-09-2021 22:39:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 94 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 17-09-2021 22:39:29 to 17-09-2021 22:39:31.

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          248263
MON$OLDEST_ACTIVE               248264
MON$OLDEST_SNAPSHOT             248264
MON$NEXT_TRANSACTION            248264
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                       2024928
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.33498 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33498 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33498 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33498 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 ---
--- end of diff output ---

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

17.09.21 22:39:32. 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-09-17 22:39:35 No severe PSQL-related problems occured
17.09.21 22:39:36. End of report.