Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.06.2021 10:29
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 16731160 kB
MemAvailable 31454652 kB
Buffers 0 kB
Cached 14957132 kB
SwapCached 16 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-06-13 10:26:57
DTS_END                         2021-06-13 10:26:57.1080
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/58340
TRN_ID                          223986
ATT_ID                          572
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: 8783.03 24431 1053964
customer order: creation 161.72 434 19406
customer order: refuse 13.73 394 1648
order to supplier: creation 43.43 1485 5212
order to supplier: removal 3.41 122607 409
invoice (draft): creation 42.91 3934 5149
invoice (draft): removal 50.88 1011 6105
invoice accept: apply 38.57 20219 4628
invoice accept: cancel 45.50 1035 5460
customer reserve: creation 3637.43 193 436491
customer reserve: removal 4462.67 74 535520
realization accept: apply 78.13 194 9375
realization accept: cancel 85.07 128 10208
payment from customer: creation 55.44 119 6653
payment from customer: removal 5.09 103 611
payment to supplier: creation 45.30 150 5436
payment to supplier: removal 3.30 98 396
service: total inventory turnovers 3.48 3807 417
service: total monetary turnovers 6.19 559 743
service: aggregate perf. data 0.77 17502 92
service: refresh index statistics 0.04 314580 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-06-2021 10:30:02 to 13-06-2021 10:30:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2502
TEST_TIME 2 3131
TEST_TIME 3 2847
TEST_TIME 4 3753
TEST_TIME 5 3687
TEST_TIME 6 3490
TEST_TIME 7 3839
TEST_TIME 8 4502
TEST_TIME 9 4878
TEST_TIME 10 5112
TEST_TIME 11 5242
TEST_TIME 12 5397
TEST_TIME 13 5604
TEST_TIME 14 5516
TEST_TIME 15 6018
TEST_TIME 16 6326
TEST_TIME 17 6622
TEST_TIME 18 6737
TEST_TIME 19 6872
TEST_TIME 20 7115
TEST_TIME 21 7239
TEST_TIME 22 7472
TEST_TIME 23 8162
TEST_TIME 24 8439
TEST_TIME 25 8356
TEST_TIME 26 8645
TEST_TIME 27 8864
TEST_TIME 28 9520
TEST_TIME 29 9787
TEST_TIME 30 10176
TEST_TIME 31 10039
TEST_TIME 32 9812
TEST_TIME 33 9932
TEST_TIME 34 10035
TEST_TIME 35 10106
TEST_TIME 36 10054
TEST_TIME 37 9903
TEST_TIME 38 9702
TEST_TIME 39 9494
TEST_TIME 40 9470
TEST_TIME 41 9432
TEST_TIME 42 9376
TEST_TIME 43 9362
TEST_TIME 44 9291
TEST_TIME 45 9352
TEST_TIME 46 9395
TEST_TIME 47 9351
TEST_TIME 48 9221
TEST_TIME 49 9082
TEST_TIME 50 9003
TEST_TIME 51 9097
TEST_TIME 52 9228
TEST_TIME 53 9233
TEST_TIME 54 9260
TEST_TIME 55 9246
TEST_TIME 56 9221
TEST_TIME 57 9172
TEST_TIME 58 9320
TEST_TIME 59 9400
TEST_TIME 60 9462
TEST_TIME 61 9455
TEST_TIME 62 9428
TEST_TIME 63 9468
TEST_TIME 64 9394
TEST_TIME 65 9318
TEST_TIME 66 9271
TEST_TIME 67 9242
TEST_TIME 68 9162
TEST_TIME 69 9067
TEST_TIME 70 9100
TEST_TIME 71 9080
TEST_TIME 72 9064
TEST_TIME 73 9010
TEST_TIME 74 8992
TEST_TIME 75 9034
TEST_TIME 76 9135
TEST_TIME 77 9255
TEST_TIME 78 9278
TEST_TIME 79 9234
TEST_TIME 80 9200
TEST_TIME 81 9206
TEST_TIME 82 9210
TEST_TIME 83 9173
TEST_TIME 84 9097
TEST_TIME 85 9040
TEST_TIME 86 8994
TEST_TIME 87 8914
TEST_TIME 88 8924
TEST_TIME 89 8874
TEST_TIME 90 8864
TEST_TIME 91 8838
TEST_TIME 92 8835
TEST_TIME 93 8847
TEST_TIME 94 8960
TEST_TIME 95 8959
TEST_TIME 96 8958
TEST_TIME 97 9023
TEST_TIME 98 9062
TEST_TIME 99 9086
TEST_TIME 100 9030
TEST_TIME 101 8980
TEST_TIME 102 8920
TEST_TIME 103 8909
TEST_TIME 104 8853
TEST_TIME 105 8825
TEST_TIME 106 8789
TEST_TIME 107 8788
TEST_TIME 108 8764
TEST_TIME 109 8731
TEST_TIME 110 8722
TEST_TIME 111 8749
TEST_TIME 112 8754
TEST_TIME 113 8746
TEST_TIME 114 8713
TEST_TIME 115 8713
TEST_TIME 116 8839
TEST_TIME 117 8820
TEST_TIME 118 8843
TEST_TIME 119 8853
TEST_TIME 120 8785
TEST_TIME 121 8712

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-06-2021 10:30:06 to 13-06-2021 10:30:09.

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 1456860 1456860 0 0.00 0 31722 7 0 0
doc_list_biud 1456860 1456860 0 0.00 0 53173 35 0 0
sp_add_invoice_to_stock 4727 4628 99 2.09 39 231048 20219 0 99
sp_cancel_adding_invoice 5460 5460 0 0.00 5 79877 1036 0 0
sp_cancel_client_order 1648 1648 0 0.00 4 12834 395 0 0
sp_cancel_customer_reserve 535520 535520 0 0.00 1 34254 75 0 0
sp_cancel_pay_from_customer 611 611 0 0.00 3 5691 103 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 8424 99 0 0
sp_cancel_supplier_invoice 6107 6105 2 0.03 2 53192 1012 0 2
sp_cancel_supplier_order 409 409 0 0.00 6 1157348 122607 0 0
sp_cancel_write_off 10208 10208 0 0.00 1 19376 128 0 0
sp_client_order 19406 19406 0 0.00 4 18560 434 0 0
sp_customer_reserve 436491 436491 0 0.00 0 95356 193 0 0
sp_fill_shopping_cart 29754 29754 0 0.00 1 11299 45 0 0
sp_fill_shopping_cart_clo_res 436490 436490 0 0.00 0 19999 20 0 0
sp_get_clo_for_invoice 4626 4626 0 0.00 0 44365 445 0 0
sp_kill_cost_storno 16675 16675 0 0.00 0 8471 66 0 0
sp_kill_qstorno_ret_qs2qd 1087762 1087762 0 0.00 0 53172 40 0 0
sp_kill_qty_storno 573351 573351 0 0.00 0 53173 87 0 0
sp_lock_selected_doc 19475 19475 0 0.00 0 11735 7 0 0
sp_make_cost_storno 26090 26090 0 0.00 0 15336 56 0 0
sp_multiply_rows_for_pdistr 26090 26090 0 0.00 0 11736 38 0 0
sp_multiply_rows_for_qdistr 19406 19406 0 0.00 0 18552 245 0 0
sp_pay_from_customer 6653 6653 0 0.00 4 12545 119 0 0
sp_pay_to_supplier 5436 5436 0 0.00 5 17139 150 0 0
sp_payment_common 12089 12089 0 0.00 1 14329 29 0 0
sp_qd_handle_on_cancel_clo 1648 1648 0 0.00 0 11592 355 0 0
sp_qd_handle_on_invoice_upd_sts 10086 10086 0 0.00 0 46316 443 0 0
sp_qd_handle_on_reserve_upd_sts 9375 9375 0 0.00 0 9232 44 0 0
sp_reserve_write_off 9375 9375 0 0.00 7 14740 195 0 0
sp_supplier_invoice 5149 5149 0 0.00 6 102904 3935 0 0
sp_supplier_order 5212 5212 0 0.00 6 32233 1485 0 0
srv_aggregate_perf_data 92 92 0 0.00 2196 234586 17502 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1650 171138 50183 0 0
srv_find_qd_qs_mism 1008564 1008564 0 0.00 0 31721 6 0 0
srv_make_invnt_saldo 417 417 0 0.00 35 136536 3807 0 0
srv_make_money_saldo 743 743 0 0.00 0 15669 560 0 0
srv_recalc_idx_stat 5 5 0 0.00 204636 458215 314581 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4628 4628 0 0.00 0 17942 204 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 3319 108 0 0
t$perf-norm:sp_cancel_client_order 1648 1648 0 0.00 0 1875 15 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 1559 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 611 611 0 0.00 0 1070 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 235 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1406 20 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 23577 1513 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 481 4 0 0
t$perf-norm:sp_client_order 19406 19406 0 0.00 0 8357 19 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 5188 14 0 0
t$perf-norm:sp_pay_from_customer 6653 6653 0 0.00 0 8208 12 0 0
t$perf-norm:sp_pay_to_supplier 5436 5436 0 0.00 0 5714 12 0 0
t$perf-norm:sp_reserve_write_off 9375 9375 0 0.00 0 7862 10 0 0
t$perf-norm:sp_supplier_invoice 5149 5149 0 0.00 0 8888 29 0 0
t$perf-norm:sp_supplier_order 5212 5212 0 0.00 0 3592 21 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 249 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 150 7 0 0
t$perf-norm:srv_make_invnt_saldo 417 417 0 0.00 0 1015 12 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 793 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9450 9252 198 2.09 0 7104 16 0 198
v_all_customers 35726 35726 0 0.00 0 75 1 0 0
v_all_suppliers 28936 28936 0 0.00 0 81 1 0 0
v_all_wares 38812 38812 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 942 9 0 0
v_cancel_client_order 3296 3296 0 0.00 0 12503 12 0 0
v_cancel_customer_prepayment 1222 1222 0 0.00 0 5619 11 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 2944 7 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 1718 6 0 0
v_cancel_supplier_invoice 818 814 4 0.48 0 1529 16 0 4
v_cancel_supplier_order 818 818 0 0.00 0 377 4 0 0
v_cancel_write_off 1896 1896 0 0.00 0 8573 27 0 0
v_max_id_clo_ord 5212 5212 0 0.00 0 464 1 0 0
v_max_id_clo_res 976 976 0 0.00 0 4988 23 0 0
v_max_id_ord_sup 5136 5136 0 0.00 0 2964 4 0 0
v_max_non_paid_invoice 1316 1316 0 0.00 0 253 2 0 0
v_max_non_paid_realizn 3414 3414 0 0.00 0 4007 6 0 0
v_min_id_clo_ord 5212 5212 0 0.00 0 5293 7 0 0
v_min_id_clo_res 976 976 0 0.00 0 7640 12 0 0
v_min_id_ord_sup 5136 5136 0 0.00 0 8935 9 0 0
v_min_non_paid_invoice 5436 5436 0 0.00 0 14325 23 0 0
v_min_non_paid_realizn 6653 6653 0 0.00 0 8958 19 0 0
v_our_firm 9564 9564 0 0.00 0 14 1 0 0
v_reserve_write_off 18750 18750 0 0.00 0 12503 10 0 0
x_lock_depdocs_on_canc_invoice 11158 11158 0 0.00 0 16493 119 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 20317 372 0 0
x_make_qstorno_1000_1200 5212 5212 0 0.00 1 32185 1396 0 0
x_make_qstorno_1200_2000 5149 5149 0 0.00 3 102885 3767 0 0
x_make_qstorno_2100_3300 436491 436491 0 0.00 0 95356 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-06-2021 10:30:10 to 13-06-2021 10:30:14.

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
07:57:00 4 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
07:58:01 25242 8192000000 9712255968 10007027712 787797232 8.772 100 85 169 0 816151504 7694048 20660224
07:59:38 22520 8192000000 9758342816 10062671872 831788256 9.217 100 93 186 0 866831104 12632656 22410192
08:01:10 20672 8192000000 9809113712 10127179776 871097376 9.611 100 87 174 0 916671792 25018416 20556000
08:02:37 14030 8192000000 9862961248 10197028864 915830016 10.055 100 78 155 2 975629952 42717408 17082528
08:03:56 11014 8192000000 9910078048 10256515072 956794928 10.458 100 76 152 2 1023209760 50579872 15834960
08:05:12 10827 8192000000 9953140736 10309484544 993757168 10.818 100 77 154 0 1068476096 60002880 14716048
08:06:25 3986 8192000000 10009568128 10377064448 1029510720 11.164 100 80 160 1 1129117184 82942112 16664352
08:07:30 2606 8192000000 10019381216 10392158208 1048039264 11.342 100 84 168 0 1142085104 77028256 17017584
08:08:35 1738 8192000000 10028944272 10408259584 1068277504 11.536 100 76 152 0 1139909728 56613728 15018496
08:09:37 5906 8192000000 9995600544 10395844608 1067006672 11.523 100 38 75 4 1115316832 41275456 7034704
08:10:43 8559 8192000000 9990318176 10395582464 1078479504 11.633 100 37 72 3 1107252224 20118192 8654528
08:11:55 6020 8192000000 9997044128 10389716992 1092857408 11.770 100 32 64 1 1117371280 16666192 7847680
08:13:02 51709 8192000000 10002723920 10407329792 1092785120 11.769 100 33 64 1 1113260160 11348288 9126752
08:14:54 2755 8192000000 10035794992 10433847296 1102456928 11.861 100 65 124 4 1138795584 22151616 14187040
08:15:59 6532 8192000000 10028429952 10451746816 1099639712 11.834 100 26 52 2 1143801664 38700944 5461008
08:17:09 7962 8192000000 10014531536 10420150272 1089331696 11.736 99 47 94 2 1113502848 13535008 10636144
08:18:21 32835 8192000000 10013581344 10403876864 1082939088 11.675 99 63 127 2 1108634464 12029408 13665968
08:19:54 4769 8192000000 10015514848 10411053056 1062036784 11.476 99 54 111 1 1119717952 46175840 11505328
08:21:02 5030 8192000000 9950996448 10337361920 1008484880 10.961 99 48 95 2 1055473520 36707600 10281040
08:22:08 6703 8192000000 9938295152 10323374080 998545584 10.864 97 37 70 2 1017848752 10956224 8346944
08:23:15 48471 8192000000 9954026544 10338037760 1029286320 11.162 99 37 73 3 1054406416 17472256 7647840
08:25:04 5831 8192000000 9988344064 10379083776 1052839616 11.388 100 48 96 0 1085836976 22426784 10570576
08:26:10 7919 8192000000 9984108432 10383843328 1068119168 11.534 100 40 80 0 1093972816 16946832 8906816
08:27:25 9157 8192000000 10025888928 10412560384 1092517456 11.767 100 68 133 3 1122556928 16261104 13778368
08:28:38 115824 8192000000 10071547200 10481008640 1110243808 11.935 100 43 86 2 1159766448 40049264 9473376
08:32:02 13229 8192000000 10100342928 10508537856 1129229664 12.114 100 39 77 2 1209501824 72395568 7876592
08:33:27 12215 8192000000 10139230480 10553176064 1139747872 12.213 100 46 92 0 1255388032 105189104 10451056
08:34:46 15485 8192000000 10153930032 10567254016 1150665056 12.316 100 46 92 0 1258762928 97855168 10242704
08:36:06 11059 8192000000 10206326800 10621980672 1163796816 12.439 100 54 108 2 1311784480 136272256 11715408
08:37:21 6489 8192000000 10210934624 10634096640 1169467760 12.492 100 53 106 2 1315081392 133811616 11802016
08:38:31 3940 8192000000 10246032768 10661502976 1170753440 12.504 100 80 157 2 1351964640 163147408 18063792
08:39:48 1777 8192000000 10276909312 10698584064 1188295456 12.667 100 61 122 1 1385257520 183179312 13782752
08:40:59 2689 8192000000 10369146928 10787602432 1198717904 12.764 100 91 177 1 1464196608 245560048 19918656
08:42:08 3928 8192000000 10384281104 10802528256 1202617888 12.801 100 85 168 1 1484184528 262641840 18924800
08:43:19 2456 8192000000 10401260336 10816581632 1201801040 12.793 100 86 174 0 1495577952 274155648 19621264
08:44:27 1649 8192000000 10425310896 10843107328 1191104800 12.694 100 84 167 1 1527523056 316286864 20131392
08:45:35 6144 8192000000 10417262032 10840272896 1166697696 12.466 100 78 155 0 1509411456 324626368 18087392
08:46:48 3125 8192000000 10445676192 10858098688 1168290192 12.481 100 86 170 2 1540175776 352132816 19752768
08:47:58 3816 8192000000 10410929248 10838945792 1149615008 12.306 99 79 159 0 1513694400 345471584 18607808
08:49:09 5240 8192000000 10424042832 10837753856 1141993664 12.234 99 65 129 2 1522570448 365087936 15488848
08:50:19 14266 8192000000 10343309120 10795999232 1138790448 12.204 99 53 106 1 1476698976 324895248 13013280
08:51:35 18337 8192000000 10374787744 10804256768 1126480848 12.088 99 33 62 2 1459725152 325025984 8218320
08:52:57 25571 8192000000 10335644448 10791022592 1101669440 11.853 99 23 46 0 1420131728 311897472 6564816
08:54:32 55014 8192000000 10134357648 10591936512 1104765504 11.883 99 19 38 1 1284078048 174381760 4930784
08:56:29 49792 8192000000 10053575280 10473431040 1087762528 11.721 97 18 36 1 1116133408 24645520 3725360
08:58:20 77130 8192000000 10084916016 10536730624 1110499504 11.937 99 15 30 0 1166966880 53247760 3219616
09:00:53 70246 8192000000 10062995680 10505760768 1094980576 11.790 98 25 49 0 1134584528 35158400 4445552
09:03:12 99280 8192000000 10060544480 10508464128 1104941904 11.885 98 29 58 1 1143392112 31935280 6514928
09:05:54 50202 8192000000 10051753712 10510598144 1088737088 11.731 97 18 36 0 1136229136 43982832 3509216
09:07:46 48608 8192000000 10080850176 10509565952 1121052480 12.037 100 35 69 1 1192503680 63521296 7929904
09:09:56 55660 8192000000 10139961456 10597621760 1133593136 12.155 100 18 36 1 1221564688 83812224 4159328
09:12:28 39362 8192000000 10065207952 10524393472 1129751744 12.119 100 15 30 0 1162353328 29794448 2807136
09:14:17 63423 8192000000 10121089760 10569768960 1137222368 12.189 100 18 36 1 1194875184 53712352 3940464
09:16:53 63664 8192000000 10108880320 10602151936 1146064864 12.273 100 12 24 0 1264034800 114849216 3120720
09:18:58 73809 8192000000 10066705360 10523537408 1138150912 12.198 100 9 18 0 1187369872 47604272 1614688
09:21:18 55877 8192000000 10080637872 10522468352 1138517360 12.202 100 19 38 1 1167402544 25128992 3756192
09:23:24 26272 8192000000 10168518736 10618679296 1155069792 12.357 100 42 82 1 1250625216 86861712 8693712
09:25:09 71814 8192000000 10089633552 10602364928 1137718432 12.194 99 12 24 0 1218054112 77554384 2781296
09:27:22 64933 8192000000 10101225696 10543910912 1092532416 11.767 95 30 60 1 1144350688 45817728 6000544
09:29:29 171137 8192000000 10081832688 10548916224 1130903536 12.130 100 22 44 1 1178850048 43535264 4411248
09:33:29 82068 8192000000 10064262848 10523668480 1080266448 11.650 95 8 16 0 1129134304 47167744 1700112
09:35:59 67745 8192000000 10091787488 10597892096 1112896736 11.960 98 11 22 0 1162394288 47325536 2172016
09:38:08 60735 8192000000 10127198992 10563985408 1113809600 11.968 98 25 50 1 1163874016 44975840 5088576
09:40:14 53012 8192000000 10184374688 10641661952 1139342272 12.209 99 31 62 1 1274118080 127954080 6821728
09:42:11 73623 8192000000 10101962672 10599718912 1120122096 12.028 99 13 26 0 1240291520 117208752 2960672
09:44:28 58200 8192000000 10040150288 10485530624 1071257248 11.564 96 19 38 2 1113804576 38595872 3951456
09:46:28 12330 8192000000 10096148096 10510733312 1109592256 11.929 99 68 134 1 1176817968 53496768 13728944
09:47:41 16678 8192000000 10101805184 10525339648 1106724560 11.901 99 56 111 2 1189267488 71777696 10765232
09:49:01 99883 8192000000 10035828016 10514632704 1107611632 11.910 99 30 61 0 1179269680 65003408 6654640
09:51:45 43863 8192000000 10034294144 10492993536 1110558880 11.938 100 8 16 1 1138386512 25972336 1855296
09:55:14 83124 8192000000 10092388384 10554355712 1090271440 11.745 96 16 32 0 1154623856 61081776 3270640
09:57:53 111805 8192000000 10171901408 10695344128 1124978320 12.074 99 16 32 0 1233733024 105155744 3598960
10:01:40 80038 8192000000 10108410560 10566475776 1112893776 11.960 97 21 42 0 1155243344 38302048 4047520
10:04:07 109310 8192000000 10079142208 10594934784 1083361856 11.679 95 6 12 0 1167933920 83371632 1200432
10:07:05 102778 8192000000 10113864944 10552766464 1133675712 12.156 99 59 121 0 1180339504 34224688 12439104
10:12:23 57614 8192000000 10081910864 10537103360 1142631680 12.240 99 10 20 0 1172224080 27667200 1925200
10:14:25 46668 8192000000 10088891216 10531106816 1146522304 12.277 99 23 46 1 1181153424 30079376 4551744
10:16:15 91375 8192000000 10116257216 10606231552 1153547232 12.343 99 23 46 1 1224344848 65604432 5193184
10:18:47 66208 8192000000 10143158976 10601156608 1141815616 12.233 98 24 48 0 1207490736 60647136 5027984
10:20:55 54303 8192000000 10187900032 10628923392 1143046400 12.244 99 32 63 0 1255650176 105313344 7290432
10:23:10 71337 8192000000 10102440640 10565304320 1140846560 12.223 99 23 46 1 1164931216 19525760 4558896
10:25:26 105379 8192000000 9865128992 10342260736 957376032 10.463 83 12 22 1 1021058192 61025520 2656640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-06-2021 10:30:16 to 13-06-2021 10:30:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-06-2021 10:30:31 to 13-06-2021 10:30:33.

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          224368
MON$OLDEST_ACTIVE               224369
MON$OLDEST_SNAPSHOT             224369
MON$NEXT_TRANSACTION            224369
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                       2105664
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2516 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2516 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2516 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2516 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Sun Jun 13 10:27:45 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

13.06.21 10:30:34. 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-06-13 10:30:37 No severe PSQL-related problems occured
13.06.21 10:30:38. End of report.