Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.11.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 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 15495596 kB
MemAvailable 30784928 kB
Buffers 0 kB
Cached 16176244 kB
SwapCached 2436 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-11-06 13:37:09
DTS_END                         2021-11-06 13:37:09.3250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36700
TRN_ID                          235468
ATT_ID                          620
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: 9251.20 25685 1110144
customer order: creation 173.43 369 20812
customer order: refuse 14.92 422 1790
order to supplier: creation 46.72 1497 5606
order to supplier: removal 3.33 142736 400
invoice (draft): creation 46.15 3944 5538
invoice (draft): removal 52.95 1108 6354
invoice accept: apply 41.78 20127 5013
invoice accept: cancel 48.32 928 5798
customer reserve: creation 3832.54 198 459905
customer reserve: removal 4685.73 78 562288
realization accept: apply 84.49 206 10139
realization accept: cancel 91.18 133 10942
payment from customer: creation 60.33 122 7239
payment from customer: removal 5.80 88 696
payment to supplier: creation 49.44 136 5933
payment to supplier: removal 3.48 82 418
service: total inventory turnovers 3.54 4643 425
service: total monetary turnovers 6.29 729 755
service: aggregate perf. data 0.74 21668 89
service: refresh index statistics 0.03 314484 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-11-2021 13:37:57 to 06-11-2021 13:37:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6430
TEST_TIME 2 5738
TEST_TIME 3 6527
TEST_TIME 4 7031
TEST_TIME 5 6972
TEST_TIME 6 6417
TEST_TIME 7 6534
TEST_TIME 8 6428
TEST_TIME 9 6415
TEST_TIME 10 6902
TEST_TIME 11 6783
TEST_TIME 12 6947
TEST_TIME 13 6825
TEST_TIME 14 6870
TEST_TIME 15 6676
TEST_TIME 16 6756
TEST_TIME 17 6677
TEST_TIME 18 7038
TEST_TIME 19 7052
TEST_TIME 20 7302
TEST_TIME 21 7445
TEST_TIME 22 7650
TEST_TIME 23 7704
TEST_TIME 24 7726
TEST_TIME 25 8039
TEST_TIME 26 7959
TEST_TIME 27 8142
TEST_TIME 28 8545
TEST_TIME 29 8828
TEST_TIME 30 9133
TEST_TIME 31 10503
TEST_TIME 32 11043
TEST_TIME 33 10842
TEST_TIME 34 10835
TEST_TIME 35 10864
TEST_TIME 36 10868
TEST_TIME 37 10762
TEST_TIME 38 10618
TEST_TIME 39 10452
TEST_TIME 40 10363
TEST_TIME 41 10295
TEST_TIME 42 10451
TEST_TIME 43 10561
TEST_TIME 44 10436
TEST_TIME 45 10367
TEST_TIME 46 10429
TEST_TIME 47 10462
TEST_TIME 48 10407
TEST_TIME 49 10221
TEST_TIME 50 10073
TEST_TIME 51 10108
TEST_TIME 52 10105
TEST_TIME 53 10006
TEST_TIME 54 9881
TEST_TIME 55 9893
TEST_TIME 56 9837
TEST_TIME 57 9740
TEST_TIME 58 9705
TEST_TIME 59 9813
TEST_TIME 60 9807
TEST_TIME 61 9863
TEST_TIME 62 9777
TEST_TIME 63 9680
TEST_TIME 64 9686
TEST_TIME 65 9768
TEST_TIME 66 9818
TEST_TIME 67 9764
TEST_TIME 68 9765
TEST_TIME 69 9797
TEST_TIME 70 9794
TEST_TIME 71 9766
TEST_TIME 72 9759
TEST_TIME 73 9839
TEST_TIME 74 9841
TEST_TIME 75 9840
TEST_TIME 76 9868
TEST_TIME 77 9771
TEST_TIME 78 9669
TEST_TIME 79 9725
TEST_TIME 80 9621
TEST_TIME 81 9663
TEST_TIME 82 9610
TEST_TIME 83 9583
TEST_TIME 84 9508
TEST_TIME 85 9481
TEST_TIME 86 9461
TEST_TIME 87 9481
TEST_TIME 88 9424
TEST_TIME 89 9419
TEST_TIME 90 9409
TEST_TIME 91 9444
TEST_TIME 92 9542
TEST_TIME 93 9521
TEST_TIME 94 9546
TEST_TIME 95 9575
TEST_TIME 96 9535
TEST_TIME 97 9532
TEST_TIME 98 9571
TEST_TIME 99 9533
TEST_TIME 100 9598
TEST_TIME 101 9608
TEST_TIME 102 9526
TEST_TIME 103 9496
TEST_TIME 104 9433
TEST_TIME 105 9426
TEST_TIME 106 9399
TEST_TIME 107 9385
TEST_TIME 108 9358
TEST_TIME 109 9325
TEST_TIME 110 9260
TEST_TIME 111 9269
TEST_TIME 112 9305
TEST_TIME 113 9316
TEST_TIME 114 9293
TEST_TIME 115 9263
TEST_TIME 116 9354
TEST_TIME 117 9321
TEST_TIME 118 9306
TEST_TIME 119 9328
TEST_TIME 120 9253

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-11-2021 13:38:01 to 06-11-2021 13:38:04.

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 1535937 1535937 0 0.00 0 26280 8 0 0
doc_list_biud 1535937 1535937 0 0.00 0 84594 36 0 0
sp_add_invoice_to_stock 5125 5013 112 2.18 44 193124 20128 0 112
sp_cancel_adding_invoice 5798 5798 0 0.00 5 66464 929 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 27125 423 0 0
sp_cancel_customer_reserve 562288 562288 0 0.00 1 64516 78 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 2412 89 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 4011 82 0 0
sp_cancel_supplier_invoice 6363 6354 9 0.14 3 84599 1108 0 9
sp_cancel_supplier_order 400 400 0 0.00 7 1339846 142737 0 0
sp_cancel_write_off 10942 10942 0 0.00 1 28725 134 0 0
sp_client_order 20812 20812 0 0.00 4 25764 370 0 0
sp_customer_reserve 459905 459905 0 0.00 0 48350 199 0 0
sp_fill_shopping_cart 31936 31936 0 0.00 1 23034 49 0 0
sp_fill_shopping_cart_clo_res 459898 459898 0 0.00 0 27333 15 0 0
sp_get_clo_for_invoice 5004 5004 0 0.00 0 41471 369 0 0
sp_kill_cost_storno 17854 17854 0 0.00 0 12220 71 0 0
sp_kill_qstorno_ret_qs2qd 1142272 1142272 0 0.00 0 84593 42 0 0
sp_kill_qty_storno 602713 602713 0 0.00 0 84593 91 0 0
sp_lock_selected_doc 20968 20968 0 0.00 0 9306 5 0 0
sp_make_cost_storno 28313 28313 0 0.00 0 17737 63 0 0
sp_multiply_rows_for_pdistr 28313 28313 0 0.00 0 9557 36 0 0
sp_multiply_rows_for_qdistr 20812 20812 0 0.00 0 10554 157 0 0
sp_pay_from_customer 7239 7239 0 0.00 4 15623 123 0 0
sp_pay_to_supplier 5933 5933 0 0.00 5 19484 136 0 0
sp_payment_common 13172 13172 0 0.00 1 12547 22 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 27115 374 0 0
sp_qd_handle_on_invoice_upd_sts 10800 10800 0 0.00 0 29503 422 0 0
sp_qd_handle_on_reserve_upd_sts 10139 10139 0 0.00 0 22008 63 0 0
sp_reserve_write_off 10139 10139 0 0.00 7 22036 206 0 0
sp_supplier_invoice 5538 5538 0 0.00 7 99704 3944 0 0
sp_supplier_order 5606 5606 0 0.00 6 34131 1497 0 0
srv_aggregate_perf_data 90 89 1 1.11 2479 200387 21669 0 1
srv_fill_mon_cache_memory 68 68 0 0.00 2231 139184 39277 0 0
srv_find_qd_qs_mism 1062195 1062195 0 0.00 0 26277 7 0 0
srv_make_invnt_saldo 425 425 0 0.00 48 139947 4643 0 0
srv_make_money_saldo 755 755 0 0.00 1 17272 729 0 0
srv_recalc_idx_stat 4 4 0 0.00 156486 476592 314485 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 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 5013 5013 0 0.00 0 13714 187 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 3441 92 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 2477 12 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 811 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 5843 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 6027 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 6627 32 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 28540 1926 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 810 6 0 0
t$perf-norm:sp_client_order 20812 20812 0 0.00 0 33503 21 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 603 6 0 0
t$perf-norm:sp_pay_from_customer 7239 7239 0 0.00 0 3730 10 0 0
t$perf-norm:sp_pay_to_supplier 5933 5933 0 0.00 0 5496 11 0 0
t$perf-norm:sp_reserve_write_off 10139 10139 0 0.00 0 5770 11 0 0
t$perf-norm:sp_supplier_invoice 5538 5538 0 0.00 0 5114 19 0 0
t$perf-norm:sp_supplier_order 5606 5606 0 0.00 0 4256 17 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 3235 48 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 253 5 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 996 13 0 0
t$perf-norm:srv_make_money_saldo 755 755 0 0.00 0 22301 43 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 62 19 0 0
v_add_invoice_to_stock 10228 10004 224 2.19 0 8107 21 0 224
v_all_customers 38364 38364 0 0.00 0 21 1 0 0
v_all_suppliers 30930 30930 0 0.00 0 201 1 0 0
v_all_wares 41624 41624 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 3359 15 0 0
v_cancel_client_order 3580 3580 0 0.00 0 5419 7 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 1240 7 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 1057 4 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 3713 9 0 0
v_cancel_supplier_invoice 812 794 18 2.21 0 1524 23 0 18
v_cancel_supplier_order 800 800 0 0.00 0 320 4 0 0
v_cancel_write_off 2002 2002 0 0.00 0 2390 17 0 0
v_max_id_clo_ord 5606 5606 0 0.00 0 2066 2 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 2477 12 0 0
v_max_id_ord_sup 5517 5517 0 0.00 0 2717 3 0 0
v_max_non_paid_invoice 1590 1590 0 0.00 0 1866 4 0 0
v_max_non_paid_realizn 3655 3655 0 0.00 0 1865 4 0 0
v_min_id_clo_ord 5606 5606 0 0.00 0 2558 6 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 474 2 0 0
v_min_id_ord_sup 5517 5517 0 0.00 0 8984 19 0 0
v_min_non_paid_invoice 5933 5933 0 0.00 0 5346 12 0 0
v_min_non_paid_realizn 7239 7239 0 0.00 0 12542 17 0 0
v_our_firm 10428 10428 0 0.00 0 202 1 0 0
v_reserve_write_off 20278 20278 0 0.00 0 5253 8 0 0
x_lock_depdocs_on_canc_invoice 11755 11755 0 0.00 0 10367 128 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 22579 476 0 0
x_make_qstorno_1000_1200 5606 5606 0 0.00 2 34118 1402 0 0
x_make_qstorno_1200_2000 5538 5538 0 0.00 3 89531 3758 0 0
x_make_qstorno_2100_3300 459905 459905 0 0.00 0 48349 181 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-11-2021 13:38:05 to 06-11-2021 13:38:09.

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 7 8192000000 8802936464 8815828992 7761840 0.094 3 2 3 0 7927184 103984 61360
11:08:12 42226 8192000000 9726116816 10024259584 797792016 8.874 100 90 180 0 827327648 7765120 21770512
11:09:58 18094 8192000000 9759378176 10063978496 830956928 9.209 100 93 186 0 866089360 13170960 21961472
11:11:25 19860 8192000000 9799715392 10113835008 856866128 9.469 100 89 178 1 904890336 27458128 20566080
11:12:48 15993 8192000000 9868319536 10197225472 902512544 9.923 100 75 150 0 964458480 45425952 16519984
11:14:06 5179 8192000000 9904168208 10248728576 953351376 10.424 100 75 150 1 1014175152 44980048 15843728
11:15:13 3855 8192000000 9975097680 10328002560 997479536 10.854 100 91 183 0 1091151872 75061312 18611024
11:16:20 3843 8192000000 10010307328 10369236992 1018657840 11.059 100 92 184 1 1123291664 86389056 18244768
11:17:26 4045 8192000000 9987593648 10361430016 1039572624 11.261 100 74 148 0 1110828016 56385568 14869824
11:18:31 1441 8192000000 10063973088 10438750208 1059132832 11.448 100 87 173 0 1176924912 100498576 17293504
11:19:33 31653 8192000000 9973187392 10364379136 1063937472 11.494 100 48 96 2 1132135248 57943904 10253872
11:21:07 4004 8192000000 9988064208 10377072640 1075621520 11.606 100 45 86 2 1093880576 8915744 9343312
11:22:12 8000 8192000000 10000556112 10405801984 1086215040 11.707 100 43 87 0 1127195344 32049520 8930784
11:23:23 65337 8192000000 10021815472 10407542784 1090147312 11.744 100 60 119 3 1122452192 19472880 12832000
11:25:29 4629 8192000000 10018415968 10421747712 1096549040 11.805 100 64 123 5 1136213824 26172784 13492000
11:26:36 7225 8192000000 10016364192 10417958912 1097540976 11.814 100 27 54 1 1120438320 17153760 5743584
11:27:44 63947 8192000000 10001561392 10414600192 1075531552 11.605 99 22 44 1 1102154384 22062912 4559920
11:29:49 3999 8192000000 9959775696 10343718912 1040288512 11.267 99 39 78 1 1061731888 12938032 8505344
11:30:54 5591 8192000000 9938477328 10319482880 1016418752 11.037 98 33 66 0 1053591760 29609248 7563760
11:32:00 11642 8192000000 9930846400 10304741376 962834784 10.517 94 35 70 0 986584912 15422976 8327152
11:33:13 21465 8192000000 9954105472 10357501952 1017454368 11.047 99 18 36 0 1042013904 20773488 3786048
11:34:35 4528 8192000000 9968640400 10351411200 1050166864 11.362 100 51 102 2 1077596912 14018192 13411856
11:35:40 9242 8192000000 9969220528 10365988864 1059814608 11.455 100 39 78 1 1083504496 14357360 9332528
11:36:52 70353 8192000000 10009713984 10403872768 1079659248 11.644 100 46 93 1 1106222432 16843584 9719600
11:39:21 5956 8192000000 10042252592 10435579904 1102944176 11.866 100 45 86 2 1146598672 34674176 8980320
11:40:29 18172 8192000000 10094171248 10496544768 1118406000 12.012 100 60 119 1 1205569376 75077456 12085920
11:41:52 23400 8192000000 10102855392 10513874944 1137654896 12.193 100 52 104 1 1228915376 79220128 12040352
11:43:25 7682 8192000000 10128737552 10529185792 1147745200 12.288 100 54 109 1 1234277968 75026256 11506512
11:44:42 15951 8192000000 10137475760 10551689216 1145236672 12.265 99 44 88 1 1253423344 98634128 9552544
11:46:02 12006 8192000000 10176031744 10585722880 1159632256 12.400 100 63 123 0 1281452768 108676912 13143600
11:47:18 4417 8192000000 10256491088 10662748160 1175942704 12.552 100 75 146 0 1358428368 166869552 15616112
11:48:26 14314 8192000000 10275203920 10688229376 1178079200 12.572 100 63 123 2 1373681488 182499808 13102480
11:49:45 2678 8192000000 10335549824 10743955456 1185627648 12.643 100 75 148 1 1429563184 227674112 16261424
11:50:55 5241 8192000000 10317618096 10754867200 1183374032 12.622 100 57 111 1 1436952896 239988480 13590384
11:52:06 2741 8192000000 10333993488 10745470976 1164839360 12.449 100 61 121 0 1433658912 254599024 14220528
11:53:14 4625 8192000000 10397683024 10807091200 1179602976 12.586 100 66 133 0 1496528848 301022432 15903440
11:54:26 3426 8192000000 10408214768 10811371520 1171214768 12.508 100 68 134 0 1507975360 320958880 15801712
11:55:37 7460 8192000000 10439322352 10844635136 1172786640 12.523 100 70 140 0 1533547136 344777264 15983232
11:56:54 2773 8192000000 10461675952 10859069440 1171661184 12.512 100 80 160 0 1553439616 362029440 19748992
11:58:03 2226 8192000000 10498366656 10897760256 1180044000 12.591 100 89 178 0 1591900400 391358944 20497456
11:59:09 3741 8192000000 10516306256 10924199936 1170789040 12.504 100 68 135 0 1614413920 426973984 16650896
12:00:21 2516 8192000000 10502303104 10933538816 1162472160 12.426 99 73 141 0 1594571536 414137680 17961696
12:01:30 6766 8192000000 10594592768 11024068608 1179971376 12.590 100 60 119 0 1689552736 494259792 15321568
12:02:44 11757 8192000000 10590123680 11050307584 1178799584 12.579 100 49 98 1 1687536576 495856592 12880400
12:04:08 16281 8192000000 10613531920 11082444800 1161621600 12.418 100 46 92 0 1734217408 560396448 12199360
12:05:55 11387 8192000000 10594702544 11057856512 1146539040 12.277 100 36 72 1 1691660000 535269904 9851056
12:07:25 14260 8192000000 10577053456 11030130688 1136451888 12.182 100 35 68 1 1664003616 518738080 8813648
12:08:44 33016 8192000000 10172165936 10691952640 1119911712 12.026 100 18 36 0 1296863104 172704752 4246640
12:10:25 30838 8192000000 10116377760 10540154880 1138833056 12.205 100 25 45 0 1213062016 69431008 4797952
12:11:57 31720 8192000000 10077779200 10587344896 1124095424 12.066 99 12 24 1 1208076336 81527568 2453344
12:13:29 90954 8192000000 10052999712 10507534336 1096134304 11.801 96 8 16 0 1125903296 28011360 1757632
12:16:06 53531 8192000000 10138501968 10575663104 1123845040 12.063 99 14 28 0 1214815504 87758160 3212304
12:18:11 59670 8192000000 10157413472 10626564096 1148115200 12.292 100 29 57 0 1254218864 100146272 5957392
12:20:12 86885 8192000000 10101486832 10581090304 1120237152 12.029 98 22 44 0 1199317872 74200256 4880464
12:22:40 16662 8192000000 10092066400 10525593600 1147106336 12.282 100 42 84 2 1189519344 32589120 9823888
12:23:58 139184 8192000000 10078837872 10539593728 1092253264 11.764 95 9 16 2 1134697760 40646640 1797856
12:27:24 104018 8192000000 10088262992 10559512576 1132082848 12.141 99 19 38 0 1199076528 61756832 5236848
12:30:10 91539 8192000000 10122101584 10553110528 1128124480 12.104 99 26 50 3 1193245552 59341536 5779536
12:32:46 22814 8192000000 10114383856 10554322944 1141785104 12.232 100 29 58 0 1210548896 62188672 6575120
12:34:11 21948 8192000000 10152411008 10572763136 1117978880 12.008 98 32 64 1 1212427088 87291632 7156576
12:35:34 10248 8192000000 10177170320 10617454592 1118322992 12.011 99 51 99 3 1273236432 144158656 10754784
12:36:45 58635 8192000000 10090069200 10535923712 1096637712 11.806 97 8 16 0 1194862368 96309664 1914992
12:38:44 20234 8192000000 10063152448 10511056896 1107514848 11.909 98 16 32 0 1138249856 27845808 2889200
12:40:06 34228 8192000000 10117103248 10571907072 1142553904 12.240 100 8 16 0 1208179184 63978752 1646528
12:41:41 74415 8192000000 10077368160 10526244864 1128911232 12.111 99 10 20 0 1187315408 56470000 1934176
12:43:59 62954 8192000000 10095334816 10536472576 1083474944 11.681 95 19 37 1 1110664272 23508272 3681056
12:46:04 19669 8192000000 10085926560 10520694784 1105654384 11.891 98 45 90 1 1153715456 39131904 8929168
12:47:29 31378 8192000000 10099502912 10543677440 1098826256 11.827 97 39 77 1 1146693296 39251968 8615072
12:49:02 48290 8192000000 10141496224 10573520896 1107682528 11.910 98 27 54 0 1205061952 92333696 5045728
12:51:00 46771 8192000000 10114954544 10555789312 1135145776 12.170 100 25 49 0 1189485840 49386624 4953440
12:53:03 81232 8192000000 10045091488 10463625216 1046963168 11.332 93 24 48 1 1090107760 38172272 4972320
12:56:28 91000 8192000000 10086825904 10529050624 1117754448 12.006 100 18 36 0 1153211776 32029200 3428128
12:59:13 74001 8192000000 10058909216 10531696640 1104623392 11.881 99 20 40 1 1175257168 65808512 4825264
13:01:27 56055 8192000000 10098725152 10564251648 1087225008 11.716 97 18 36 1 1169240736 78065952 3949776
13:03:25 46953 8192000000 10057287664 10540699648 1065241312 11.507 97 14 28 0 1161327072 93311680 2774080
13:05:36 71415 8192000000 10105526944 10570276864 1094637392 11.787 98 27 54 0 1169716512 69159376 5919744
13:08:26 55262 8192000000 10126462672 10611224576 1121857568 12.045 99 27 55 0 1255456128 127667296 5931264
13:10:25 31269 8192000000 10099363408 10543611904 1122921696 12.055 99 31 62 0 1196164064 67038240 6204128
13:11:59 47171 8192000000 10086424800 10576674816 1089962928 11.742 97 37 74 1 1148160624 50903696 7294000
13:13:47 28708 8192000000 10088609168 10520166400 1126085968 12.084 100 36 70 2 1164090656 30356352 7648336
13:15:20 63178 8192000000 10078948032 10547347456 1089427328 11.737 96 15 30 0 1154411104 61783328 3200448
13:17:23 126885 8192000000 10080455360 10563686400 1115538848 11.985 98 16 32 0 1161852752 42972064 3341840
13:21:08 38998 8192000000 10084433136 10528063488 1115688832 11.986 98 21 42 1 1176072336 56162080 4221424
13:22:48 45753 8192000000 10062400928 10515181568 1105860368 11.893 98 20 40 0 1165401376 55236016 4304992
13:24:43 50800 8192000000 10080727936 10567675904 1122091792 12.047 99 14 28 0 1168988080 43884432 3011856
13:26:34 59983 8192000000 10078954768 10532106240 1099972368 11.837 97 17 34 0 1167126320 63434528 3719424
13:28:50 76638 8192000000 10076581328 10583166976 1101877792 11.855 97 11 22 0 1176809824 72391264 2540768
13:31:06 42752 8192000000 10066303008 10519830528 1112846784 11.959 99 34 66 2 1144370224 24448128 7075312
13:32:57 43305 8192000000 10116690832 10594279424 1129807248 12.120 100 16 32 0 1220533904 87284048 3442608
13:35:00 39112 8192000000 10112314544 10547363840 1138880608 12.205 100 37 74 0 1205302880 59124848 7297424
13:36:40 40529 8192000000 9373868656 9707778048 485038800 5.589 44 9 18 0 522770096 36267056 1464240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-11-2021 13:38:11 to 06-11-2021 13:38:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 112 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 06-11-2021 13:38:27 to 06-11-2021 13:38:29.

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          235696
MON$OLDEST_ACTIVE               235697
MON$OLDEST_SNAPSHOT             235697
MON$NEXT_TRANSACTION            235697
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                       2092992
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             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2653 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2653 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2653 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2653 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'

06.11.21 13:38:30. 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-11-06 13:38:32 No severe PSQL-related problems occured
06.11.21 13:38:33. End of report.