Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.11.2020 13: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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16815732 kB
MemAvailable 31519152 kB
Buffers 0 kB
Cached 14875916 kB
SwapCached 2900 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.0
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 2020-11-01 13:26:49
DTS_END                         2020-11-01 13:26:49.6370
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/45804
TRN_ID                          241335
ATT_ID                          692
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: 9016.23 24903 1081947
customer order: creation 171.76 432 20611
customer order: refuse 15.30 321 1836
order to supplier: creation 47.02 1426 5642
order to supplier: removal 3.47 109199 416
invoice (draft): creation 47.58 3484 5710
invoice (draft): removal 50.30 805 6036
invoice accept: apply 42.60 17969 5112
invoice accept: cancel 45.13 942 5416
customer reserve: creation 3892.45 177 467094
customer reserve: removal 4406.91 70 528829
realization accept: apply 84.33 182 10119
realization accept: cancel 77.76 126 9331
payment from customer: creation 60.68 122 7282
payment from customer: removal 6.30 102 756
payment to supplier: creation 49.68 131 5962
payment to supplier: removal 3.78 101 453
service: total inventory turnovers 4.13 3203 496
service: total monetary turnovers 6.23 530 748
service: aggregate perf. data 0.78 21693 93
service: refresh index statistics 0.04 337051 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-11-2020 13:29:39 to 01-11-2020 13:29:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7321
TEST_TIME 2 5620
TEST_TIME 3 6129
TEST_TIME 4 5629
TEST_TIME 5 5076
TEST_TIME 6 5471
TEST_TIME 7 5622
TEST_TIME 8 6142
TEST_TIME 9 6283
TEST_TIME 10 6445
TEST_TIME 11 6382
TEST_TIME 12 6962
TEST_TIME 13 6935
TEST_TIME 14 7014
TEST_TIME 15 7140
TEST_TIME 16 7383
TEST_TIME 17 7516
TEST_TIME 18 7817
TEST_TIME 19 8060
TEST_TIME 20 8507
TEST_TIME 21 8472
TEST_TIME 22 9149
TEST_TIME 23 9072
TEST_TIME 24 9252
TEST_TIME 25 9407
TEST_TIME 26 9437
TEST_TIME 27 9762
TEST_TIME 28 10159
TEST_TIME 29 10669
TEST_TIME 30 10669
TEST_TIME 31 10511
TEST_TIME 32 10509
TEST_TIME 33 10540
TEST_TIME 34 10414
TEST_TIME 35 10467
TEST_TIME 36 10407
TEST_TIME 37 10491
TEST_TIME 38 10291
TEST_TIME 39 10115
TEST_TIME 40 9961
TEST_TIME 41 9850
TEST_TIME 42 9746
TEST_TIME 43 9757
TEST_TIME 44 9657
TEST_TIME 45 9663
TEST_TIME 46 9550
TEST_TIME 47 9582
TEST_TIME 48 9504
TEST_TIME 49 9437
TEST_TIME 50 9741
TEST_TIME 51 9722
TEST_TIME 52 9694
TEST_TIME 53 9723
TEST_TIME 54 9662
TEST_TIME 55 9742
TEST_TIME 56 9884
TEST_TIME 57 9904
TEST_TIME 58 9917
TEST_TIME 59 9931
TEST_TIME 60 9929
TEST_TIME 61 10011
TEST_TIME 62 9949
TEST_TIME 63 9805
TEST_TIME 64 9681
TEST_TIME 65 9623
TEST_TIME 66 9550
TEST_TIME 67 9466
TEST_TIME 68 9445
TEST_TIME 69 9383
TEST_TIME 70 9386
TEST_TIME 71 9340
TEST_TIME 72 9319
TEST_TIME 73 9306
TEST_TIME 74 9298
TEST_TIME 75 9285
TEST_TIME 76 9394
TEST_TIME 77 9451
TEST_TIME 78 9448
TEST_TIME 79 9501
TEST_TIME 80 9513
TEST_TIME 81 9499
TEST_TIME 82 9523
TEST_TIME 83 9509
TEST_TIME 84 9413
TEST_TIME 85 9372
TEST_TIME 86 9320
TEST_TIME 87 9284
TEST_TIME 88 9235
TEST_TIME 89 9227
TEST_TIME 90 9214
TEST_TIME 91 9202
TEST_TIME 92 9227
TEST_TIME 93 9241
TEST_TIME 94 9249
TEST_TIME 95 9296
TEST_TIME 96 9254
TEST_TIME 97 9312
TEST_TIME 98 9339
TEST_TIME 99 9293
TEST_TIME 100 9257
TEST_TIME 101 9208
TEST_TIME 102 9156
TEST_TIME 103 9135
TEST_TIME 104 9106
TEST_TIME 105 9090
TEST_TIME 106 9104
TEST_TIME 107 9080
TEST_TIME 108 9105
TEST_TIME 109 9080
TEST_TIME 110 9081
TEST_TIME 111 9118
TEST_TIME 112 9103
TEST_TIME 113 9121
TEST_TIME 114 9154
TEST_TIME 115 9135
TEST_TIME 116 9106
TEST_TIME 117 9145
TEST_TIME 118 9138
TEST_TIME 119 9070
TEST_TIME 120 9024

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-11-2020 13:29:43 to 01-11-2020 13:29:46.

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 1514658 1514658 0 0.00 0 19625 7 0 0
doc_list_biud 1514654 1514654 0 0.00 0 26327 30 0 0
sp_add_invoice_to_stock 5203 5112 91 1.74 51 182328 17969 0 91
sp_cancel_adding_invoice 5416 5416 0 0.00 4 64999 942 0 0
sp_cancel_client_order 1836 1836 0 0.00 4 9599 321 0 0
sp_cancel_customer_reserve 528829 528829 0 0.00 1 25783 70 0 0
sp_cancel_pay_from_customer 756 756 0 0.00 2 5724 102 0 0
sp_cancel_pay_to_supplier 453 453 0 0.00 3 5318 101 0 0
sp_cancel_supplier_invoice 6043 6036 7 0.11 2 16929 806 0 7
sp_cancel_supplier_order 416 416 0 0.00 6 942903 109199 0 0
sp_cancel_write_off 9331 9331 0 0.00 1 9090 126 0 0
sp_client_order 20611 20611 0 0.00 4 14981 432 0 0
sp_customer_reserve 467094 467094 0 0.00 0 64232 177 0 0
sp_fill_shopping_cart 31944 31944 0 0.00 1 29067 45 0 0
sp_fill_shopping_cart_clo_res 467088 467088 0 0.00 0 17877 19 0 0
sp_get_clo_for_invoice 5103 5103 0 0.00 0 53139 368 0 0
sp_kill_cost_storno 15956 15956 0 0.00 0 7235 60 0 0
sp_kill_qstorno_ret_qs2qd 1073441 1073441 0 0.00 0 26070 37 0 0
sp_kill_qty_storno 567083 567083 0 0.00 0 26327 80 0 0
sp_lock_selected_doc 21157 21157 0 0.00 0 7311 6 0 0
sp_make_cost_storno 28466 28466 0 0.00 0 11528 61 0 0
sp_multiply_rows_for_pdistr 28464 28464 0 0.00 0 12336 34 0 0
sp_multiply_rows_for_qdistr 20611 20611 0 0.00 0 14510 235 0 0
sp_pay_from_customer 7282 7282 0 0.00 4 17572 123 0 0
sp_pay_to_supplier 5962 5962 0 0.00 5 14521 131 0 0
sp_payment_common 13244 13244 0 0.00 1 9938 21 0 0
sp_qd_handle_on_cancel_clo 1836 1836 0 0.00 0 9555 286 0 0
sp_qd_handle_on_invoice_upd_sts 10516 10516 0 0.00 0 26327 383 0 0
sp_qd_handle_on_reserve_upd_sts 10119 10119 0 0.00 0 11123 44 0 0
sp_reserve_write_off 10119 10119 0 0.00 7 14457 182 0 0
sp_supplier_invoice 5710 5710 0 0.00 7 89171 3485 0 0
sp_supplier_order 5642 5642 0 0.00 6 27902 1427 0 0
srv_aggregate_perf_data 93 93 0 0.00 3211 300150 21694 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1363 126997 43919 0 0
srv_find_qd_qs_mism 1033652 1033652 0 0.00 0 19625 5 0 0
srv_make_invnt_saldo 496 496 0 0.00 54 89764 3204 0 0
srv_make_money_saldo 748 748 0 0.00 1 17882 531 0 0
srv_recalc_idx_stat 5 5 0 0.00 107503 535024 337051 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5112 5112 0 0.00 0 16349 188 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 9876 127 0 0
t$perf-norm:sp_cancel_client_order 1836 1836 0 0.00 0 2580 15 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 1930 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 756 756 0 0.00 0 4634 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 453 453 0 0.00 0 453 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 3535 31 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 21683 1456 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 9700 23 0 0
t$perf-norm:sp_client_order 20611 20611 0 0.00 0 10376 20 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 2781 10 0 0
t$perf-norm:sp_pay_from_customer 7282 7282 0 0.00 0 6612 9 0 0
t$perf-norm:sp_pay_to_supplier 5962 5962 0 0.00 0 6137 13 0 0
t$perf-norm:sp_reserve_write_off 10119 10119 0 0.00 0 7004 12 0 0
t$perf-norm:sp_supplier_invoice 5710 5710 0 0.00 0 11968 24 0 0
t$perf-norm:sp_supplier_order 5642 5642 0 0.00 0 5884 17 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 121 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 58 2 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 3630 28 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 3084 12 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 17 6 0 0
v_add_invoice_to_stock 10374 10192 182 1.75 0 7114 17 0 182
v_all_customers 37606 37606 0 0.00 0 19 1 0 0
v_all_suppliers 30954 30954 0 0.00 0 17 1 0 0
v_all_wares 41220 41220 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 1696 10 0 0
v_cancel_client_order 3672 3672 0 0.00 0 4625 5 0 0
v_cancel_customer_prepayment 1512 1512 0 0.00 0 1211 5 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 1166 6 0 0
v_cancel_payment_to_supplier 906 906 0 0.00 0 3029 16 0 0
v_cancel_supplier_invoice 836 822 14 1.67 0 4236 19 0 14
v_cancel_supplier_order 832 832 0 0.00 0 622 5 0 0
v_cancel_write_off 1944 1944 0 0.00 0 1428 14 0 0
v_max_id_clo_ord 5638 5638 0 0.00 0 6332 3 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 4792 12 0 0
v_max_id_ord_sup 5695 5695 0 0.00 0 3843 4 0 0
v_max_non_paid_invoice 1818 1818 0 0.00 0 2654 4 0 0
v_max_non_paid_realizn 3924 3924 0 0.00 0 2412 4 0 0
v_min_id_clo_ord 5638 5638 0 0.00 0 2234 8 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 9605 17 0 0
v_min_id_ord_sup 5695 5695 0 0.00 0 7551 11 0 0
v_min_non_paid_invoice 5962 5962 0 0.00 0 5568 11 0 0
v_min_non_paid_realizn 7282 7282 0 0.00 0 9911 15 0 0
v_our_firm 10330 10330 0 0.00 0 17 1 0 0
v_reserve_write_off 20238 20238 0 0.00 0 4367 6 0 0
x_lock_depdocs_on_canc_invoice 11041 11041 0 0.00 0 15742 119 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 11952 373 0 0
x_make_qstorno_1000_1200 5642 5642 0 0.00 2 27895 1335 0 0
x_make_qstorno_1200_2000 5710 5710 0 0.00 3 77420 3321 0 0
x_make_qstorno_2100_3300 467093 467093 0 0.00 0 64231 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-11-2020 13:29:47 to 01-11-2020 13:29:51.

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
10:56:53 1 8192000000 8796633136 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
10:57:55 33492 8192000000 9715498320 10009956352 781046256 8.704 100 83 167 1 809519984 7510688 20963040
10:59:31 26006 8192000000 9763905040 10067832832 834309520 9.243 100 88 176 0 867982752 12212464 21460768
11:01:06 24826 8192000000 9808984880 10125963264 864097600 9.541 100 80 160 0 907735488 25157392 18480496
11:02:33 11378 8192000000 9873765024 10210037760 928968832 10.184 100 80 159 0 985357296 39662336 16726128
11:03:47 10512 8192000000 9930134096 10277928960 964861456 10.537 100 79 158 3 1044735744 62971216 16903072
11:04:59 5675 8192000000 9962494032 10321465344 987233664 10.755 100 69 138 1 1074047360 72986208 13827488
11:06:08 3229 8192000000 9990541200 10355277824 1017520576 11.048 100 78 156 1 1113666944 80413392 15732976
11:07:13 1586 8192000000 9988101264 10362449920 1041351984 11.278 100 74 148 0 1109663232 53640240 14671008
11:08:17 2078 8192000000 10034890896 10412834816 1057194016 11.430 100 80 159 0 1144220976 70717312 16309648
11:09:20 14498 8192000000 10013720800 10409345024 1065289904 11.507 100 46 92 1 1144071568 69854608 8927056
11:10:35 5196 8192000000 10001270704 10392813568 1071417424 11.566 100 43 84 4 1112785280 32332336 9035520
11:11:41 4738 8192000000 9994628144 10395205632 1078481088 11.633 100 31 61 1 1104938176 19559072 6898016
11:12:47 53359 8192000000 10001200208 10400292864 1085243376 11.697 100 23 46 1 1118953600 29094864 4615360
11:14:41 6985 8192000000 10020612576 10421325824 1096317872 11.803 100 46 92 1 1121010448 15548448 9144128
11:15:51 6611 8192000000 10000691024 10401218560 1089171168 11.735 99 32 65 0 1112083280 15648432 7263680
11:17:00 9001 8192000000 9994646160 10409136128 1073115504 11.582 99 23 47 2 1101285072 23173488 4996080
11:18:11 48705 8192000000 9939043680 10332286976 1015192416 11.026 95 32 63 1 1043056016 21013568 6850032
11:20:02 14309 8192000000 9937907552 10332127232 984192656 10.725 95 24 49 2 1010220944 20604816 5423472
11:21:17 4626 8192000000 9931850256 10308116480 987097920 10.753 97 49 95 3 1023587472 24875600 11613952
11:22:26 8401 8192000000 9925589248 10312974336 1006064464 10.937 98 27 54 2 1025735520 13569328 6101728
11:23:35 45569 8192000000 9961116720 10356252672 1032938704 11.197 99 33 66 3 1059933248 20347904 6646640
11:25:21 7418 8192000000 9980711408 10366611456 1058430880 11.441 100 51 102 0 1084171408 14215360 11525168
11:26:30 11095 8192000000 9977545776 10380017664 1065599120 11.510 100 30 60 1 1088991408 16304576 7087712
11:27:45 13855 8192000000 10022111600 10412527616 1082320192 11.670 100 35 67 1 1123446496 34092416 7033888
11:29:01 38654 8192000000 10044768704 10444935168 1094148080 11.782 100 43 84 4 1147291968 42852608 10291280
11:30:49 18387 8192000000 10061636656 10463608832 1108691872 11.920 100 48 96 5 1169377904 50737232 9948800
11:32:11 27363 8192000000 10092425632 10503045120 1114451616 11.975 100 45 89 2 1209343152 85623360 9268176
11:33:43 7324 8192000000 10127466256 10532536320 1131471136 12.135 100 48 96 2 1233850448 91175088 11204224
11:34:55 2825 8192000000 10121008768 10529234944 1138153904 12.198 100 57 114 2 1228707168 77769552 12783712
11:36:03 9157 8192000000 10174278832 10578841600 1150250400 12.312 100 63 123 2 1280410112 116151600 14008112
11:37:16 3209 8192000000 10221400528 10625867776 1160806304 12.411 100 73 145 0 1320327600 143434464 16086832
11:38:24 3339 8192000000 10201045872 10605129728 1167073664 12.469 100 61 122 3 1303270144 122872240 13324240
11:39:37 4883 8192000000 10215326032 10621771776 1155246240 12.359 100 63 122 1 1317569936 149601664 12722032
11:40:45 4354 8192000000 10252231056 10649411584 1160193408 12.405 100 79 156 0 1352350304 174250016 17906880
11:41:54 2257 8192000000 10301957904 10705244160 1162185728 12.424 100 88 174 0 1398268176 216760816 19321632
11:43:02 1360 8192000000 10282083040 10674282496 1150408048 12.313 99 91 180 0 1387824048 217086736 20329264
11:44:09 2722 8192000000 10324750192 10718531584 1138724944 12.204 100 78 153 2 1419319856 261999088 18595824
11:45:15 2005 8192000000 10337874368 10735165440 1150329776 12.313 100 86 171 0 1434431520 264179808 19921936
11:46:22 12926 8192000000 10294614784 10709336064 1150186960 12.311 100 39 78 1 1407155648 247662704 9305984
11:47:43 2229 8192000000 10358466368 10765840384 1147866688 12.289 100 87 173 0 1454044592 285862128 20315776
11:48:52 10857 8192000000 10287180208 10721005568 1155545296 12.362 100 41 82 1 1396285888 230229040 10511552
11:50:13 6230 8192000000 10325140400 10736193536 1143262240 12.246 100 42 84 1 1417303344 263966976 10074128
11:51:26 15658 8192000000 10349844448 10798813184 1146319792 12.275 100 46 92 0 1442032272 284860784 10851696
11:53:05 13228 8192000000 10304708576 10745696256 1139101616 12.207 100 25 50 0 1405808672 260571536 6135520
11:54:26 26701 8192000000 10175295568 10690068480 1115372016 11.983 99 17 34 2 1305931872 186543024 4016832
11:55:54 65687 8192000000 10095106368 10563944448 1104445536 11.880 99 9 18 0 1175150464 68674080 2030848
11:58:39 44297 8192000000 10076727024 10511585280 1109551568 11.928 99 19 38 0 1166512400 52324848 4635984
12:00:59 59516 8192000000 10048275712 10490097664 1096221424 11.802 97 7 14 1 1122927424 25661904 1044096
12:03:03 113637 8192000000 10022166896 10439761920 1019693744 11.069 91 23 43 2 1051050576 27003808 4353024
12:06:44 50593 8192000000 10044699872 10492366848 1086704096 11.711 98 19 38 1 1115585440 25245360 3635984
12:08:39 33664 8192000000 10109267824 10541400064 1117749248 12.006 99 46 90 5 1202675904 75307024 9619632
12:10:19 45220 8192000000 10132498992 10571829248 1106855888 11.903 98 23 46 2 1187538304 75316928 5365488
12:12:07 88243 8192000000 10192592240 10658971648 1135253424 12.171 99 24 48 0 1279703712 138872048 5578240
12:14:36 64043 8192000000 10166203488 10629599232 1111625488 11.948 97 16 32 0 1254995312 139518112 3851712
12:16:40 31026 8192000000 10073820576 10509283328 1125619824 12.080 99 27 57 2 1161185264 27966208 7599232
12:18:27 36113 8192000000 10102581488 10534666240 1109983184 11.932 97 19 38 0 1167804320 53527472 4293664
12:20:10 55303 8192000000 10178401040 10621493248 1137826912 12.195 99 25 49 1 1254638480 111347408 5464160
12:22:06 44157 8192000000 10120421200 10603487232 1131476752 12.135 99 16 32 1 1236750944 101597008 3677184
12:23:52 42193 8192000000 10103652480 10553868288 1082481504 11.671 95 21 42 1 1155192272 67795760 4915008
12:25:35 44575 8192000000 10110467088 10555195392 1129467424 12.116 100 29 58 1 1178575888 42325376 6783088
12:27:28 92686 8192000000 10059887184 10513924096 1110790256 11.940 98 19 38 0 1166232496 51589984 3852256
12:30:07 87194 8192000000 10069481344 10524823552 1133331456 12.153 100 20 40 0 1185675856 48212608 4131792
12:32:39 58243 8192000000 10100852960 10564259840 1134316208 12.162 100 8 17 0 1193521360 57573152 1632000
12:34:46 63471 8192000000 10072343152 10514608128 1095590048 11.796 97 15 30 2 1150644080 51610048 3443984
12:38:06 54166 8192000000 10082806048 10533912576 1113191456 11.963 99 25 50 3 1158825744 40049936 5584352
12:40:04 56005 8192000000 10142989392 10582310912 1100932352 11.846 97 21 42 0 1175261584 69589344 4739888
12:42:02 75977 8192000000 10066441568 10495709184 1095972480 11.799 98 20 40 1 1175460816 75548992 3939344
12:44:21 60355 8192000000 10088036768 10531856384 1106173280 11.896 98 24 48 0 1182866480 71828320 4864880
12:47:22 51157 8192000000 10099275936 10525093888 1120108736 12.028 99 39 78 1 1179553248 50806352 8638160
12:49:13 126997 8192000000 10067941808 10506989568 1106853616 11.903 98 30 60 0 1169383664 56620976 5909072
12:52:21 49049 8192000000 10090616880 10550329344 1123516720 12.060 100 10 20 1 1186208080 60321136 2370224
12:55:06 63325 8192000000 10068912288 10533339136 1114812992 11.978 98 19 38 1 1167068944 48139568 4116384
12:57:10 74214 8192000000 10109653856 10553851904 1093517568 11.776 97 18 36 1 1169810272 71899024 4393680
13:00:24 54466 8192000000 10098010704 10544476160 1117762432 12.006 99 21 42 0 1183834752 61346432 4725888
13:02:19 35983 8192000000 10114998752 10541408256 1120101744 12.028 99 41 82 1 1155505808 27064704 8339360
13:03:55 84658 8192000000 10064151408 10539626496 1094531296 11.786 97 24 48 0 1131657360 32154944 4971120
13:06:19 118684 8192000000 10088265008 10565103616 1129649712 12.118 100 26 52 0 1204699360 69552048 5497600
13:09:55 51062 8192000000 10060441312 10547023872 1100341136 11.841 98 5 10 0 1145365232 43985248 1038848
13:11:47 70947 8192000000 10069017120 10589331456 1128297824 12.105 100 12 24 0 1174980240 44553968 2128448
13:13:59 67631 8192000000 10069746704 10534756352 1109696336 11.930 98 22 44 2 1162590112 48405040 4488736
13:16:07 59668 8192000000 10084315792 10536947712 1125284048 12.077 99 20 40 1 1193995904 64603216 4108640
13:18:12 43964 8192000000 10093685920 10539802624 1117738944 12.006 99 17 33 2 1174858544 53257200 3862400
13:20:08 33687 8192000000 10091736016 10547232768 1116544304 11.994 99 36 70 0 1183813056 60396128 6872624
13:21:47 44706 8192000000 10083066960 10509946880 1111512112 11.947 99 25 51 1 1144716624 28524624 4679888
13:23:35 65688 8192000000 10072616848 10513879040 1106552912 11.900 98 19 38 0 1165112912 54073280 4486720
13:25:43 75037 8192000000 9416724864 9740283904 536507600 6.146 48 14 28 1 572874768 33235680 3131488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-11-2020 13:29:53 to 01-11-2020 13:30:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 91 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-11-2020 13:30:09 to 01-11-2020 13:30:11.

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          240646
MON$OLDEST_ACTIVE               241777
MON$OLDEST_SNAPSHOT             241777
MON$NEXT_TRANSACTION            241777
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                       2104896
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             800
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1/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 ---
:635: 
:636: mscs-test-161.budzdorov.ru    Sun Nov  1 13:27:26 2020
:637:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:638: 
:639: 
:640: mscs-test-161.budzdorov.ru    Sun Nov  1 13:27:26 2020
:641:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:642: 
--- end of diff output ---

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

01.11.20 13:30:12. 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
2020-11-01 13:30:14 No severe PSQL-related problems occured
01.11.20 13:30:16. End of report.