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

Hardware and OS info

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

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-05 13:37:08
DTS_END                         2021-12-05 13:37:08.7730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34282
TRN_ID                          233001
ATT_ID                          665
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: 9464.38 24468 1135725
customer order: creation 167.53 439 20104
customer order: refuse 14.22 319 1706
order to supplier: creation 44.45 1380 5334
order to supplier: removal 3.68 104481 442
invoice (draft): creation 45.15 3474 5418
invoice (draft): removal 56.08 815 6729
invoice accept: apply 40.97 18321 4916
invoice accept: cancel 50.32 833 6038
customer reserve: creation 3817.68 179 458122
customer reserve: removal 4928.08 62 591369
realization accept: apply 81.42 208 9770
realization accept: cancel 87.18 125 10461
payment from customer: creation 58.18 131 6981
payment from customer: removal 5.73 87 687
payment to supplier: creation 48.96 162 5875
payment to supplier: removal 3.53 70 423
service: total inventory turnovers 4.11 3188 493
service: total monetary turnovers 6.24 522 749
service: aggregate perf. data 0.86 19889 103
service: refresh index statistics 0.04 334682 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-12-2021 13:37:51 to 05-12-2021 13:37:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5131
TEST_TIME 2 4285
TEST_TIME 3 3832
TEST_TIME 4 3866
TEST_TIME 5 4065
TEST_TIME 6 4392
TEST_TIME 7 4778
TEST_TIME 8 5034
TEST_TIME 9 5799
TEST_TIME 10 6215
TEST_TIME 11 7138
TEST_TIME 12 7025
TEST_TIME 13 7377
TEST_TIME 14 7361
TEST_TIME 15 7830
TEST_TIME 16 7934
TEST_TIME 17 7953
TEST_TIME 18 8231
TEST_TIME 19 8458
TEST_TIME 20 8709
TEST_TIME 21 9074
TEST_TIME 22 9164
TEST_TIME 23 9235
TEST_TIME 24 9199
TEST_TIME 25 9782
TEST_TIME 26 9591
TEST_TIME 27 10605
TEST_TIME 28 10907
TEST_TIME 29 10769
TEST_TIME 30 10696
TEST_TIME 31 10692
TEST_TIME 32 10741
TEST_TIME 33 10648
TEST_TIME 34 10589
TEST_TIME 35 10454
TEST_TIME 36 10231
TEST_TIME 37 10137
TEST_TIME 38 10195
TEST_TIME 39 10072
TEST_TIME 40 9964
TEST_TIME 41 9863
TEST_TIME 42 9852
TEST_TIME 43 9837
TEST_TIME 44 9750
TEST_TIME 45 9804
TEST_TIME 46 9765
TEST_TIME 47 9873
TEST_TIME 48 9786
TEST_TIME 49 9800
TEST_TIME 50 9846
TEST_TIME 51 9853
TEST_TIME 52 9862
TEST_TIME 53 9923
TEST_TIME 54 10004
TEST_TIME 55 9962
TEST_TIME 56 10081
TEST_TIME 57 10024
TEST_TIME 58 9950
TEST_TIME 59 9795
TEST_TIME 60 9872
TEST_TIME 61 9773
TEST_TIME 62 9736
TEST_TIME 63 9637
TEST_TIME 64 9571
TEST_TIME 65 9632
TEST_TIME 66 9647
TEST_TIME 67 9647
TEST_TIME 68 9642
TEST_TIME 69 9674
TEST_TIME 70 9714
TEST_TIME 71 9740
TEST_TIME 72 9714
TEST_TIME 73 9676
TEST_TIME 74 9696
TEST_TIME 75 9739
TEST_TIME 76 9782
TEST_TIME 77 9771
TEST_TIME 78 9757
TEST_TIME 79 9777
TEST_TIME 80 9668
TEST_TIME 81 9655
TEST_TIME 82 9574
TEST_TIME 83 9568
TEST_TIME 84 9509
TEST_TIME 85 9451
TEST_TIME 86 9400
TEST_TIME 87 9331
TEST_TIME 88 9341
TEST_TIME 89 9342
TEST_TIME 90 9389
TEST_TIME 91 9363
TEST_TIME 92 9385
TEST_TIME 93 9377
TEST_TIME 94 9454
TEST_TIME 95 9418
TEST_TIME 96 9416
TEST_TIME 97 9447
TEST_TIME 98 9469
TEST_TIME 99 9514
TEST_TIME 100 9495
TEST_TIME 101 9556
TEST_TIME 102 9643
TEST_TIME 103 9625
TEST_TIME 104 9580
TEST_TIME 105 9508
TEST_TIME 106 9485
TEST_TIME 107 9519
TEST_TIME 108 9478
TEST_TIME 109 9443
TEST_TIME 110 9429
TEST_TIME 111 9448
TEST_TIME 112 9438
TEST_TIME 113 9397
TEST_TIME 114 9373
TEST_TIME 115 9368
TEST_TIME 116 9338
TEST_TIME 117 9490
TEST_TIME 118 9523
TEST_TIME 119 9526
TEST_TIME 120 9466

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-12-2021 13:37:55 to 05-12-2021 13:37:58.

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 1561005 1561005 0 0.00 0 28525 7 0 0
doc_list_biud 1561005 1561005 0 0.00 0 50573 29 0 0
sp_add_invoice_to_stock 4999 4916 83 1.66 48 217204 18322 0 83
sp_cancel_adding_invoice 6038 6038 0 0.00 5 76156 833 0 0
sp_cancel_client_order 1706 1706 0 0.00 5 7578 319 0 0
sp_cancel_customer_reserve 591369 591369 0 0.00 1 47432 62 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 5163 88 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 3231 70 0 0
sp_cancel_supplier_invoice 6737 6729 8 0.11 1 50580 815 0 8
sp_cancel_supplier_order 442 442 0 0.00 7 994996 104482 0 0
sp_cancel_write_off 10461 10461 0 0.00 1 24195 126 0 0
sp_client_order 20104 20104 0 0.00 4 41649 439 0 0
sp_customer_reserve 458122 458122 0 0.00 0 72843 179 0 0
sp_fill_shopping_cart 30850 30850 0 0.00 1 18624 43 0 0
sp_fill_shopping_cart_clo_res 458118 458118 0 0.00 0 15473 15 0 0
sp_get_clo_for_invoice 4912 4912 0 0.00 0 32877 368 0 0
sp_kill_cost_storno 17609 17609 0 0.00 0 9519 57 0 0
sp_kill_qstorno_ret_qs2qd 1200370 1200370 0 0.00 0 50572 33 0 0
sp_kill_qty_storno 631427 631427 0 0.00 0 50573 72 0 0
sp_lock_selected_doc 20410 20410 0 0.00 0 9712 7 0 0
sp_make_cost_storno 27538 27538 0 0.00 0 12511 64 0 0
sp_multiply_rows_for_pdistr 27538 27538 0 0.00 0 10688 31 0 0
sp_multiply_rows_for_qdistr 20104 20104 0 0.00 0 33307 223 0 0
sp_pay_from_customer 6981 6981 0 0.00 4 26815 131 0 0
sp_pay_to_supplier 5875 5875 0 0.00 5 26610 162 0 0
sp_payment_common 12856 12856 0 0.00 1 10707 31 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 0 6533 284 0 0
sp_qd_handle_on_invoice_upd_sts 10950 10950 0 0.00 0 30635 339 0 0
sp_qd_handle_on_reserve_upd_sts 9770 9770 0 0.00 0 18477 54 0 0
sp_reserve_write_off 9770 9770 0 0.00 6 28532 209 0 0
sp_supplier_invoice 5418 5418 0 0.00 6 91319 3475 0 0
sp_supplier_order 5334 5334 0 0.00 6 40010 1380 0 0
srv_aggregate_perf_data 103 103 0 0.00 2894 255671 19890 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1791 158423 49104 0 0
srv_find_qd_qs_mism 1089237 1089237 0 0.00 0 28524 6 0 0
srv_make_invnt_saldo 493 493 0 0.00 60 91222 3189 0 0
srv_make_money_saldo 749 749 0 0.00 1 34648 522 0 0
srv_recalc_idx_stat 5 5 0 0.00 233733 514574 334682 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4916 4916 0 0.00 0 12183 167 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 7600 102 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 7486 17 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 4447 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 12827 41 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 2819 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 461 10 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 23732 1456 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 3135 8 0 0
t$perf-norm:sp_client_order 20104 20104 0 0.00 0 23299 21 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 5285 10 0 0
t$perf-norm:sp_pay_from_customer 6981 6981 0 0.00 0 27812 15 0 0
t$perf-norm:sp_pay_to_supplier 5875 5875 0 0.00 0 6478 8 0 0
t$perf-norm:sp_reserve_write_off 9770 9770 0 0.00 0 27815 17 0 0
t$perf-norm:sp_supplier_invoice 5418 5418 0 0.00 0 11600 23 0 0
t$perf-norm:sp_supplier_order 5334 5334 0 0.00 0 6837 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 127 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 98 2 0 0
t$perf-norm:srv_make_invnt_saldo 493 493 0 0.00 0 7405 22 0 0
t$perf-norm:srv_make_money_saldo 749 749 0 0.00 0 3584 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9988 9822 166 1.66 0 10782 19 0 166
v_all_customers 36876 36876 0 0.00 0 19 1 0 0
v_all_suppliers 30346 30346 0 0.00 0 21 1 0 0
v_all_wares 40208 40208 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 3413 11 0 0
v_cancel_client_order 3412 3412 0 0.00 0 3349 6 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 1209 6 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 3677 11 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1132 5 0 0
v_cancel_supplier_invoice 822 806 16 1.94 0 1617 15 0 16
v_cancel_supplier_order 884 884 0 0.00 0 2169 6 0 0
v_cancel_write_off 1976 1976 0 0.00 0 3291 14 0 0
v_max_id_clo_ord 5332 5332 0 0.00 0 1527 2 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 1709 12 0 0
v_max_id_ord_sup 5413 5413 0 0.00 0 5138 5 0 0
v_max_non_paid_invoice 1447 1447 0 0.00 0 10349 9 0 0
v_max_non_paid_realizn 3592 3592 0 0.00 0 6868 8 0 0
v_min_id_clo_ord 5332 5332 0 0.00 0 10311 9 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 8064 13 0 0
v_min_id_ord_sup 5413 5413 0 0.00 0 7320 7 0 0
v_min_non_paid_invoice 5875 5875 0 0.00 0 8182 19 0 0
v_min_non_paid_realizn 6981 6981 0 0.00 0 9058 20 0 0
v_our_firm 10110 10110 0 0.00 0 15 1 0 0
v_reserve_write_off 19538 19538 0 0.00 0 28319 9 0 0
x_lock_depdocs_on_canc_invoice 12364 12364 0 0.00 0 24346 112 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 20498 406 0 0
x_make_qstorno_1000_1200 5334 5334 0 0.00 2 40002 1299 0 0
x_make_qstorno_1200_2000 5418 5418 0 0.00 3 83953 3314 0 0
x_make_qstorno_2100_3300 458122 458122 0 0.00 0 72842 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-12-2021 13:37:59 to 05-12-2021 13:38:03.

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
11:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 11732 8192000000 9704249088 9998999552 789335888 8.788 100 87 174 0 818256208 7483088 21437232
11:09:33 17600 8192000000 9745642288 10048630784 817557408 9.074 100 85 170 0 849302864 10775584 20969872
11:11:00 24717 8192000000 9797294496 10111152128 856128592 9.461 100 88 176 0 896734880 19687344 20918944
11:12:32 11864 8192000000 9856060208 10188132352 911725280 10.014 100 76 154 0 962263328 34405600 16132448
11:13:53 13211 8192000000 9913778960 10256695296 960569712 10.495 100 76 151 0 1032790112 56470192 15750208
11:15:10 5054 8192000000 9961625696 10316578816 991296864 10.794 100 79 157 0 1073454560 66431488 15726208
11:16:17 4044 8192000000 10009483632 10370142208 1017978368 11.052 100 80 159 2 1120083296 85943776 16161152
11:17:23 3132 8192000000 10008612224 10375835648 1042443840 11.288 100 78 154 4 1115695072 58208272 15042960
11:18:28 1591 8192000000 10040376912 10409623552 1060348864 11.460 100 97 193 0 1138680400 58671184 19660352
11:19:31 9030 8192000000 9992556048 10388324352 1064215632 11.497 100 34 66 1 1110311952 38963856 7132464
11:20:41 3633 8192000000 10009238000 10406854656 1072228720 11.573 100 36 71 1 1130296784 51050208 7017856
11:21:47 6784 8192000000 9990655888 10389106688 1078625744 11.634 100 39 77 2 1115394448 29014176 7754528
11:22:57 46705 8192000000 9992559584 10402607104 1082574640 11.672 100 34 68 1 1112653648 22734720 7344288
11:24:49 5865 8192000000 10004675280 10399657984 1089635648 11.739 100 39 75 2 1121456080 23574464 8245968
11:25:56 3122 8192000000 9993541760 10394165248 1082805936 11.674 99 52 105 2 1107667360 13110336 11751088
11:27:01 6294 8192000000 10022416032 10424213504 1097303392 11.812 100 40 80 0 1130530256 22967680 10259184
11:28:07 72456 8192000000 9977938160 10383896576 1010006336 10.975 94 29 58 1 1030983296 14924816 6052144
11:30:21 4298 8192000000 9947921728 10326396928 1010672512 10.982 96 48 96 1 1031257280 9856080 10728688
11:31:26 7606 8192000000 9942684800 10322632704 1003012496 10.908 97 26 52 1 1029334944 21034208 5288240
11:32:34 8123 8192000000 9945919392 10334113792 1013077568 11.005 98 35 65 1 1036088864 15211264 7800032
11:33:48 41824 8192000000 9948060176 10355658752 1010279056 10.978 97 34 68 0 1050175184 32732448 7163680
11:35:30 5257 8192000000 9962434528 10356617216 1053379344 11.393 100 40 81 2 1074092624 12087392 8625888
11:36:38 9011 8192000000 9980773344 10383507456 1063827024 11.493 100 46 94 0 1087436528 12860320 10749184
11:37:51 28884 8192000000 10043570016 10436489216 1094429904 11.785 100 56 108 1 1138550624 32560416 11560304
11:39:31 84474 8192000000 10075982512 10497679360 1109895520 11.931 100 48 95 1 1176712272 56245184 10571568
11:42:02 4909 8192000000 10096545104 10500980736 1121756448 12.044 100 45 90 0 1203617040 72411728 9448864
11:43:10 15752 8192000000 10148304944 10560462848 1142693392 12.241 100 47 94 1 1259056656 106536320 9826944
11:44:32 10565 8192000000 10194419728 10605191168 1148136752 12.292 100 54 109 0 1297407824 137261936 12009136
11:45:48 9795 8192000000 10192212000 10617548800 1157669152 12.381 100 59 119 0 1313885712 143328752 12887808
11:47:08 10259 8192000000 10188698496 10606780416 1161207712 12.415 100 56 112 2 1300966896 127031984 12727200
11:48:20 2076 8192000000 10229052400 10629689344 1153002752 12.338 100 78 153 2 1328758080 158801280 16954048
11:49:23 6248 8192000000 10205646656 10615230464 1165058784 12.451 100 61 119 0 1306371872 128512560 12800528
11:50:35 3240 8192000000 10271314368 10683260928 1181024416 12.600 100 71 144 2 1377983536 181363072 15596048
11:51:46 5695 8192000000 10282546832 10695323648 1175243936 12.546 100 70 139 0 1381281584 190243424 15794224
11:52:56 4063 8192000000 10301341024 10716069888 1170588064 12.502 100 67 133 0 1405197936 219066000 15543872
11:54:08 1789 8192000000 10319484576 10734751744 1163630848 12.437 100 86 172 0 1415921744 232685712 19605184
11:55:14 1949 8192000000 10330541536 10741067776 1151411232 12.323 99 77 154 0 1426039024 256486032 18141760
11:56:20 8141 8192000000 10327051184 10762141696 1127641008 12.099 98 60 119 0 1414119792 271933600 14545184
11:57:36 8775 8192000000 10381793328 10795995136 1138263008 12.199 99 58 116 1 1483783024 331283296 14236720
11:58:53 5152 8192000000 10336820160 10797080576 1124697456 12.071 99 45 88 2 1433004080 297464752 10841872
12:00:17 26015 8192000000 10292057968 10743922688 1111155424 11.943 99 26 52 1 1384226480 266329728 6741328
12:01:45 11792 8192000000 10307084352 10735513600 1124166528 12.066 100 30 60 0 1395552384 263803904 7581952
12:03:07 45921 8192000000 10272399792 10719690752 1097178432 11.811 99 14 28 0 1352416336 251119488 4118416
12:05:46 43491 8192000000 10071592272 10508713984 1099227792 11.830 99 18 36 1 1151747536 48750224 3769520
12:07:30 52986 8192000000 10051476640 10494410752 1088103504 11.725 97 17 34 0 1132133456 40610160 3419792
12:09:28 117249 8192000000 10074276048 10510970880 1099453504 11.832 97 10 19 0 1130283312 28863504 1966304
12:12:26 88767 8192000000 10071587152 10500833280 1106697952 11.901 98 31 60 1 1154823856 42175760 5950144
12:14:59 98234 8192000000 10084872768 10522804224 1131886752 12.139 100 35 70 0 1210162384 70891552 7384080
12:17:39 30499 8192000000 10126655888 10563477504 1129097632 12.113 99 29 56 0 1215474368 80140352 6236384
12:19:16 48490 8192000000 10151385184 10588610560 1120327120 12.030 98 30 62 0 1237469664 110095408 7047136
12:21:38 70044 8192000000 10134108240 10622185472 1139990416 12.215 100 14 28 0 1244187840 100989680 3207744
12:24:05 63979 8192000000 10067282160 10505011200 1112867808 11.960 98 17 34 1 1141347344 25126800 3352736
12:26:14 18228 8192000000 10129467168 10584092672 1142049792 12.235 100 58 116 0 1244469424 89319280 13100352
12:27:32 67377 8192000000 10121955584 10569093120 1127971808 12.102 98 20 40 1 1199533248 67376944 4184496
12:30:11 86228 8192000000 10119508384 10585513984 1145511328 12.267 99 18 36 1 1216955712 67801520 3642864
12:32:37 75899 8192000000 10122226512 10594897920 1127908224 12.102 97 10 20 1 1181056480 51206160 1942096
12:34:59 20979 8192000000 10115375152 10562584576 1156808320 12.373 100 29 58 0 1207163728 44899264 5456144
12:36:22 112194 8192000000 10076951136 10548625408 1138612624 12.202 98 43 87 0 1228143456 80209392 9321440
12:39:16 48188 8192000000 10120310784 10576982016 1136863232 12.186 99 34 66 1 1223595184 80137632 6594320
12:41:30 43020 8192000000 10150048240 10611101696 1152600768 12.334 100 14 28 0 1264777888 109102128 3074992
12:43:30 40389 8192000000 10127780656 10605408256 1135468928 12.173 98 14 28 0 1217284800 78522560 3293312
12:45:30 32064 8192000000 10091286384 10528423936 1067135328 11.525 93 21 40 2 1126603264 55135328 4332608
12:47:16 39916 8192000000 10061681488 10511515648 1105341872 11.888 98 16 32 1 1152483040 44045152 3096016
12:48:57 39820 8192000000 10046354256 10479652864 1082399024 11.670 97 30 60 1 1118222304 29547488 6275792
12:50:37 66227 8192000000 10082420048 10521726976 1081490656 11.662 96 43 86 0 1149914192 59812992 8610544
12:52:45 81891 8192000000 10044833936 10484768768 1042360624 11.287 93 20 40 0 1088328272 42177840 3789808
12:55:07 14883 8192000000 10063183776 10471141376 1093352192 11.775 99 48 96 1 1139571104 36152592 10066320
12:56:25 46013 8192000000 10071050176 10538782720 1071533504 11.567 97 36 72 0 1149895952 71328832 7033616
12:58:46 54744 8192000000 10054529920 10488676352 1105846832 11.893 100 32 64 1 1147294720 35188336 6259552
13:00:41 41155 8192000000 10046331488 10488832000 1114920560 11.979 100 24 46 0 1148068848 28537552 4610736
13:02:24 100162 8192000000 10139019280 10595921920 1132651552 12.146 100 20 40 0 1207149584 69791856 4706176
13:05:05 70471 8192000000 10105453616 10566971392 1114229872 11.972 99 18 36 0 1202348624 84251472 3867280
13:07:21 91940 8192000000 10081251728 10540748800 1111301952 11.945 98 22 47 0 1173211504 56751680 5157872
13:10:01 66899 8192000000 10100042464 10579705856 1089264512 11.736 96 14 29 0 1157311728 65144192 2903024
13:12:37 50094 8192000000 10078556128 10532528128 1129295664 12.115 99 22 44 0 1182602880 48963488 4343728
13:14:29 119494 8192000000 10081962976 10581950464 1097192528 11.811 96 9 18 0 1133763504 35137504 1433472
13:17:29 68556 8192000000 10153570672 10655539200 1137845424 12.195 99 13 26 0 1221383872 80904176 2634272
13:19:38 158423 8192000000 10111582256 10576351232 1142237744 12.237 99 16 32 1 1191612688 45788384 3586560
13:23:18 115665 8192000000 10098092720 10602328064 1144176352 12.255 99 5 10 0 1218458880 73399408 883120
13:26:15 34244 8192000000 10116744320 10561363968 1142141296 12.236 98 35 73 2 1192752048 42575344 8035408
13:28:10 90756 8192000000 10070706144 10544902144 1126407088 12.087 97 9 18 0 1179214320 50957120 1850112
13:31:24 84784 8192000000 10183404768 10653462528 1140933952 12.224 97 24 48 0 1243997216 97587872 5475392
13:33:53 60532 8192000000 10114847584 10633121792 1139410368 12.210 99 18 37 0 1255321264 111656720 4254176
13:36:38 44761 8192000000 9014461072 9232355328 162436704 1.944 15 3 6 0 193004112 29999248 568160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-12-2021 13:38:05 to 05-12-2021 13:38:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 05-12-2021 13:38:21 to 05-12-2021 13:38:22.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          233436
MON$OLDEST_ACTIVE               233437
MON$OLDEST_SNAPSHOT             233437
MON$NEXT_TRANSACTION            233437
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-30 07:30:08.6030 GMT
MON$PAGES                       2072160
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2675 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2675 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2675 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2675 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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-oltp40 according to config parameter 'remove_isql_logs'

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