Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.02.2021 13:27
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 16529160 kB
MemAvailable 31210180 kB
Buffers 0 kB
Cached 15127968 kB
SwapCached 4876 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 2021-02-04 13:26:56
DTS_END                         2021-02-04 13:26:56.3630
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36410
TRN_ID                          232822
ATT_ID                          607
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: 9112.24 21180 1093469
customer order: creation 170.48 412 20458
customer order: refuse 14.38 317 1725
order to supplier: creation 46.03 1312 5523
order to supplier: removal 3.28 126474 394
invoice (draft): creation 45.43 3285 5452
invoice (draft): removal 51.25 970 6150
invoice accept: apply 40.91 17366 4909
invoice accept: cancel 47.07 890 5648
customer reserve: creation 3769.33 170 452319
customer reserve: removal 4635.02 71 556203
realization accept: apply 82.17 172 9860
realization accept: cancel 81.53 123 9783
payment from customer: creation 58.60 109 7032
payment from customer: removal 5.46 97 655
payment to supplier: creation 46.90 119 5628
payment to supplier: removal 3.51 55 421
service: total inventory turnovers 3.92 3309 470
service: total monetary turnovers 6.07 503 728
service: aggregate perf. data 0.88 16005 106
service: refresh index statistics 0.04 251837 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-02-2021 13:28:12 to 04-02-2021 13:28:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4413
TEST_TIME 2 4399
TEST_TIME 3 4986
TEST_TIME 4 5713
TEST_TIME 5 5598
TEST_TIME 6 5997
TEST_TIME 7 6066
TEST_TIME 8 5930
TEST_TIME 9 6225
TEST_TIME 10 6247
TEST_TIME 11 6190
TEST_TIME 12 6390
TEST_TIME 13 6683
TEST_TIME 14 6961
TEST_TIME 15 7478
TEST_TIME 16 7641
TEST_TIME 17 7466
TEST_TIME 18 8002
TEST_TIME 19 7925
TEST_TIME 20 8066
TEST_TIME 21 8566
TEST_TIME 22 8603
TEST_TIME 23 8740
TEST_TIME 24 9084
TEST_TIME 25 9230
TEST_TIME 26 9486
TEST_TIME 27 11411
TEST_TIME 28 11350
TEST_TIME 29 11226
TEST_TIME 30 11022
TEST_TIME 31 10834
TEST_TIME 32 10520
TEST_TIME 33 10267
TEST_TIME 34 10504
TEST_TIME 35 10514
TEST_TIME 36 10346
TEST_TIME 37 10250
TEST_TIME 38 10477
TEST_TIME 39 10378
TEST_TIME 40 10413
TEST_TIME 41 10261
TEST_TIME 42 10159
TEST_TIME 43 10045
TEST_TIME 44 9942
TEST_TIME 45 9901
TEST_TIME 46 10062
TEST_TIME 47 10045
TEST_TIME 48 10009
TEST_TIME 49 10021
TEST_TIME 50 9917
TEST_TIME 51 9875
TEST_TIME 52 10205
TEST_TIME 53 10168
TEST_TIME 54 10095
TEST_TIME 55 10216
TEST_TIME 56 10167
TEST_TIME 57 10040
TEST_TIME 58 9915
TEST_TIME 59 9831
TEST_TIME 60 9744
TEST_TIME 61 9690
TEST_TIME 62 9620
TEST_TIME 63 9545
TEST_TIME 64 9485
TEST_TIME 65 9446
TEST_TIME 66 9402
TEST_TIME 67 9436
TEST_TIME 68 9564
TEST_TIME 69 9600
TEST_TIME 70 9620
TEST_TIME 71 9617
TEST_TIME 72 9578
TEST_TIME 73 9765
TEST_TIME 74 9690
TEST_TIME 75 9774
TEST_TIME 76 9865
TEST_TIME 77 9845
TEST_TIME 78 9742
TEST_TIME 79 9645
TEST_TIME 80 9619
TEST_TIME 81 9574
TEST_TIME 82 9532
TEST_TIME 83 9473
TEST_TIME 84 9474
TEST_TIME 85 9525
TEST_TIME 86 9513
TEST_TIME 87 9499
TEST_TIME 88 9452
TEST_TIME 89 9473
TEST_TIME 90 9484
TEST_TIME 91 9499
TEST_TIME 92 9538
TEST_TIME 93 9536
TEST_TIME 94 9461
TEST_TIME 95 9419
TEST_TIME 96 9362
TEST_TIME 97 9349
TEST_TIME 98 9306
TEST_TIME 99 9323
TEST_TIME 100 9302
TEST_TIME 101 9274
TEST_TIME 102 9227
TEST_TIME 103 9224
TEST_TIME 104 9277
TEST_TIME 105 9323
TEST_TIME 106 9360
TEST_TIME 107 9309
TEST_TIME 108 9371
TEST_TIME 109 9454
TEST_TIME 110 9476
TEST_TIME 111 9418
TEST_TIME 112 9343
TEST_TIME 113 9300
TEST_TIME 114 9252
TEST_TIME 115 9198
TEST_TIME 116 9173
TEST_TIME 117 9171
TEST_TIME 118 9159
TEST_TIME 119 9158
TEST_TIME 120 9118
TEST_TIME 121 9043

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-02-2021 13:28:16 to 04-02-2021 13:28:19.

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 1513163 1513163 0 0.00 0 15876 6 0 0
doc_list_biud 1513161 1513161 0 0.00 0 51247 33 0 0
sp_add_invoice_to_stock 5064 4909 155 3.06 27 167234 17367 0 155
sp_cancel_adding_invoice 5648 5648 0 0.00 5 62280 890 0 0
sp_cancel_client_order 1725 1725 0 0.00 4 10945 318 0 0
sp_cancel_customer_reserve 556203 556203 0 0.00 1 25648 72 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 16132 98 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 1378 55 0 0
sp_cancel_supplier_invoice 6158 6150 8 0.12 2 36768 970 0 8
sp_cancel_supplier_order 394 394 0 0.00 11 1103414 126475 0 0
sp_cancel_write_off 9783 9783 0 0.00 1 14941 123 0 0
sp_client_order 20458 20458 0 0.00 4 39499 412 0 0
sp_customer_reserve 452319 452319 0 0.00 0 127512 171 0 0
sp_fill_shopping_cart 31433 31433 0 0.00 1 14328 41 0 0
sp_fill_shopping_cart_clo_res 452309 452309 0 0.00 0 22374 17 0 0
sp_get_clo_for_invoice 4895 4895 0 0.00 0 46238 374 0 0
sp_kill_cost_storno 16507 16507 0 0.00 0 6250 55 0 0
sp_kill_qstorno_ret_qs2qd 1128733 1128733 0 0.00 0 36762 39 0 0
sp_kill_qty_storno 594658 594658 0 0.00 0 51246 83 0 0
sp_lock_selected_doc 20347 20347 0 0.00 0 10453 6 0 0
sp_make_cost_storno 27415 27415 0 0.00 0 15680 54 0 0
sp_multiply_rows_for_pdistr 27415 27415 0 0.00 0 14978 30 0 0
sp_multiply_rows_for_qdistr 20458 20458 0 0.00 0 11853 226 0 0
sp_pay_from_customer 7032 7032 0 0.00 4 15055 109 0 0
sp_pay_to_supplier 5628 5628 0 0.00 5 15692 119 0 0
sp_payment_common 12660 12660 0 0.00 1 7950 18 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 6056 274 0 0
sp_qd_handle_on_invoice_upd_sts 10543 10543 0 0.00 0 51245 380 0 0
sp_qd_handle_on_reserve_upd_sts 9860 9860 0 0.00 0 10453 46 0 0
sp_reserve_write_off 9860 9860 0 0.00 6 23773 173 0 0
sp_supplier_invoice 5452 5452 0 0.00 7 78612 3285 0 0
sp_supplier_order 5523 5523 0 0.00 5 31699 1313 0 0
srv_aggregate_perf_data 106 106 0 0.00 2953 159478 16006 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1285 145791 46163 0 0
srv_find_qd_qs_mism 1047045 1047045 0 0.00 0 15875 5 0 0
srv_make_invnt_saldo 470 470 0 0.00 33 84716 3309 0 0
srv_make_money_saldo 728 728 0 0.00 1 16040 504 0 0
srv_recalc_idx_stat 5 5 0 0.00 167246 335890 251837 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4909 4909 0 0.00 0 33326 207 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 1 4341 89 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 8865 18 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 5890 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 2606 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 2250 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 1683 19 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 19285 1541 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 6037 15 0 0
t$perf-norm:sp_client_order 20458 20458 0 0.00 0 10517 17 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 2591 6 0 0
t$perf-norm:sp_pay_from_customer 7032 7032 0 0.00 0 18282 12 0 0
t$perf-norm:sp_pay_to_supplier 5628 5628 0 0.00 0 9330 11 0 0
t$perf-norm:sp_reserve_write_off 9860 9860 0 0.00 0 11245 10 0 0
t$perf-norm:sp_supplier_invoice 5452 5452 0 0.00 0 15806 21 0 0
t$perf-norm:sp_supplier_order 5523 5523 0 0.00 0 10451 16 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 538 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1262 24 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 915 8 0 0
t$perf-norm:srv_make_money_saldo 728 728 0 0.00 0 371 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10098 9788 310 3.06 0 5683 19 0 310
v_all_customers 37058 37058 0 0.00 0 152 1 0 0
v_all_suppliers 30060 30060 0 0.00 0 140 1 0 0
v_all_wares 40916 40916 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 1186 7 0 0
v_cancel_client_order 3450 3450 0 0.00 0 3402 6 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 4151 7 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 1821 7 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 1027 8 0 0
v_cancel_supplier_invoice 738 722 16 2.16 0 524 11 0 16
v_cancel_supplier_order 788 788 0 0.00 0 3522 7 0 0
v_cancel_write_off 1910 1910 0 0.00 0 5081 17 0 0
v_max_id_clo_ord 5523 5523 0 0.00 0 1228 2 0 0
v_max_id_clo_res 993 993 0 0.00 0 5798 21 0 0
v_max_id_ord_sup 5452 5452 0 0.00 0 6979 6 0 0
v_max_non_paid_invoice 1573 1573 0 0.00 0 175 2 0 0
v_max_non_paid_realizn 3774 3774 0 0.00 0 286 2 0 0
v_min_id_clo_ord 5523 5523 0 0.00 0 2145 5 0 0
v_min_id_clo_res 993 993 0 0.00 0 4558 10 0 0
v_min_id_ord_sup 5452 5452 0 0.00 0 4991 6 0 0
v_min_non_paid_invoice 5628 5628 0 0.00 0 5124 11 0 0
v_min_non_paid_realizn 7032 7032 0 0.00 0 7948 14 0 0
v_our_firm 10374 10374 0 0.00 0 14 1 0 0
v_reserve_write_off 19720 19720 0 0.00 0 9499 7 0 0
x_lock_depdocs_on_canc_invoice 11437 11437 0 0.00 0 23396 103 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 16460 399 0 0
x_make_qstorno_1000_1200 5523 5523 0 0.00 2 31690 1236 0 0
x_make_qstorno_1200_2000 5452 5452 0 0.00 4 77228 3136 0 0
x_make_qstorno_2100_3300 452318 452318 0 0.00 0 127512 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-02-2021 13:28:20 to 04-02-2021 13:28:24.

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:57:00 12 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:01 34301 8192000000 9720790576 10019225600 799485296 8.891 100 85 170 0 828627152 7619808 21522048
10:59:39 14586 8192000000 9765611664 10072109056 840840816 9.308 100 89 178 0 873876320 11924400 21111104
11:01:09 11675 8192000000 9803021776 10118172672 872937728 9.629 100 86 172 0 912606736 20199968 19469040
11:02:32 13988 8192000000 9883899984 10216357888 924523840 10.141 100 83 166 3 990514944 48288720 17702384
11:03:49 7255 8192000000 9922402240 10266132480 957368608 10.463 100 78 156 1 1030198352 56222160 16607584
11:04:59 8299 8192000000 9967091216 10323988480 992849200 10.809 100 78 156 1 1083397152 74613248 15934704
11:06:09 2522 8192000000 9996034864 10358145024 1018012384 11.053 100 81 160 2 1109750464 74790800 16947280
11:07:14 1462 8192000000 10019707280 10385350656 1037561776 11.241 100 89 177 1 1129308384 73761264 17985344
11:08:16 11461 8192000000 10021639120 10415661056 1052284144 11.383 100 65 130 0 1157220128 92126832 12809152
11:09:35 2492 8192000000 10015435760 10405912576 1061509344 11.471 100 48 96 2 1127774304 56818528 9446432
11:10:38 3972 8192000000 10004530720 10389680128 1071184096 11.563 100 40 79 1 1117888544 37796080 8908368
11:11:43 5912 8192000000 9988978096 10386710528 1078999184 11.638 100 48 96 4 1118814208 29010064 10804960
11:12:50 32204 8192000000 10008922160 10421080064 1080844272 11.656 100 53 105 3 1126945872 35254576 10847024
11:14:23 6937 8192000000 10017437328 10424356864 1086260912 11.707 100 34 69 0 1139830448 46491568 7077968
11:15:31 6192 8192000000 9999501152 10398588928 1089827648 11.741 100 47 97 3 1116455984 16629696 9998640
11:16:39 31183 8192000000 9970684944 10373902336 1067101600 11.524 98 25 47 3 1093723328 21756496 4865232
11:18:26 6646 8192000000 9981386224 10372104192 1015603184 11.030 96 43 86 3 1059350384 34871728 8875472
11:19:35 9326 8192000000 9954383248 10345365504 1025857584 11.129 98 41 80 1 1065992832 30457504 9677744
11:20:46 6845 8192000000 9942633760 10332274688 1017425040 11.047 99 44 89 2 1037351680 10341024 9585616
11:21:53 22407 8192000000 9940607696 10342227968 1000897968 10.887 98 40 79 0 1034243072 24622352 8722752
11:23:16 6516 8192000000 9974612912 10363658240 1032685632 11.194 99 57 114 1 1061753440 16302464 12765344
11:24:23 9113 8192000000 9966488912 10370162688 1055204288 11.411 100 33 64 4 1080824992 17924896 7695808
11:25:37 8362 8192000000 9986065840 10384207872 1069470320 11.547 100 29 57 1 1110458720 34926528 6061872
11:26:47 15155 8192000000 9985669328 10388230144 1073384752 11.584 100 32 60 2 1099026048 18871600 6769696
11:28:07 41008 8192000000 10064763504 10471833600 1103455440 11.870 100 46 90 0 1182384464 69793680 9135344
11:29:52 11674 8192000000 10095190848 10500620288 1127676544 12.099 100 59 118 1 1201114560 60731808 12706208
11:31:06 9341 8192000000 10124614032 10529890304 1139908096 12.215 100 48 95 0 1231846048 82072352 9865600
11:32:23 13341 8192000000 10152133296 10583650304 1148194336 12.293 100 43 85 1 1273292000 115923872 9173792
11:33:59 4194 8192000000 10183745328 10588348416 1151863744 12.327 99 69 137 1 1272219904 105286016 15070144
11:35:12 7639 8192000000 10218748464 10639491072 1169729136 12.494 100 58 116 1 1324572928 141636688 13207104
11:36:25 5127 8192000000 10260011680 10669379584 1186060400 12.647 100 62 122 2 1362895440 163171696 13663344
11:37:36 3067 8192000000 10305340336 10717581312 1176632240 12.559 99 76 150 4 1404071472 210181024 17258208
11:38:45 1522 8192000000 10344158896 10767941632 1193526544 12.716 100 85 168 0 1448772608 236557024 18689040
11:39:50 11022 8192000000 10361538720 10774315008 1171100544 12.507 100 66 131 2 1459286352 273734768 14451040
11:41:06 1518 8192000000 10350049360 10770395136 1172570624 12.521 100 87 171 0 1447913344 254543584 20799136
11:42:11 1579 8192000000 10335026976 10737422336 1156480976 12.370 99 79 156 0 1432137936 258490000 17166960
11:43:25 4233 8192000000 10353776160 10751963136 1144273008 12.256 98 72 142 1 1446836736 286036096 16527632
11:44:33 1285 8192000000 10426390432 10824691712 1169288320 12.490 100 93 186 1 1523292816 332484512 21519984
11:45:36 7221 8192000000 10420365632 10832429056 1131993184 12.140 98 52 106 0 1502148288 357124768 13030336
11:46:51 2077 8192000000 10488236176 10894450688 1162502896 12.427 100 72 140 0 1582009840 401824752 17682192
11:47:57 9657 8192000000 10462295264 10886057984 1165638800 12.456 100 47 93 2 1565548160 387308896 12600464
11:49:12 15454 8192000000 10492720752 10921263104 1155678832 12.363 99 49 98 0 1581040896 412450768 12911296
11:50:31 16300 8192000000 10487793600 10921644032 1162145248 12.423 100 31 62 2 1582825840 412605568 8075024
11:51:52 32252 8192000000 10422576144 10915831808 1122156640 12.047 100 21 42 0 1553950208 425180576 6612992
11:53:27 10222 8192000000 10331700688 10888630272 1121530640 12.041 100 36 71 2 1452030048 322044960 8454448
11:54:39 18264 8192000000 10065603904 10477637632 1124800064 12.072 100 61 121 2 1157959648 19813296 13346288
11:56:02 107389 8192000000 10123408256 10566561792 1103867568 11.874 97 30 61 0 1164980160 55322000 5790592
11:58:53 73046 8192000000 10144292000 10574237696 1115272576 11.982 98 38 77 1 1239733648 115410224 9050848
12:01:08 37240 8192000000 10101577776 10557333504 1125084080 12.075 99 34 68 2 1206910672 74392048 7434544
12:02:50 7406 8192000000 10137464400 10570678272 1134591440 12.165 99 46 92 3 1231446352 86885600 9969312
12:03:58 145791 8192000000 10126303568 10585841664 1144431824 12.257 99 23 46 0 1297685984 147855840 5398320
12:07:25 38397 8192000000 10077623168 10557177856 1136709088 12.185 99 9 18 0 1191558736 52799120 2050528
12:09:06 45319 8192000000 10082072512 10556903424 1137243520 12.190 99 4 8 0 1173549008 35829104 476384
12:10:51 66438 8192000000 10122885104 10574872576 1145228032 12.265 99 23 46 0 1191823792 41820208 4775552
12:13:00 57262 8192000000 10095174640 10571718656 1144380176 12.257 99 14 28 1 1192084880 44693456 3011248
12:15:08 62290 8192000000 10124115024 10577096704 1137454752 12.192 99 25 50 1 1205840864 63130288 5255824
12:17:14 96031 8192000000 10106227840 10647564288 1153187696 12.339 100 11 22 0 1254429088 98858736 2382656
12:19:51 43145 8192000000 10118321200 10556055552 1106558496 11.900 96 30 58 1 1142327136 29196144 6572496
12:21:46 88547 8192000000 10082907488 10572742656 1107936720 11.913 97 15 29 1 1156955184 45899856 3118608
12:24:27 71546 8192000000 10050840496 10506788864 1116411888 11.993 99 15 30 0 1156983392 37679408 2892096
12:26:40 35315 8192000000 10019406544 10467180544 1105500416 11.890 99 16 32 0 1134321504 25688096 3132992
12:28:16 69112 8192000000 10040419584 10491731968 1066754976 11.521 95 10 20 1 1103426352 34544096 2127280
12:31:22 32755 8192000000 10134272032 10581295104 1129832752 12.120 99 24 48 0 1228230016 92863728 5533536
12:32:58 66955 8192000000 10173791024 10658795520 1103926480 11.875 97 19 38 0 1242976672 134893856 4156336
12:36:04 61282 8192000000 10066695440 10509996032 1074953616 11.599 95 26 52 1 1125249952 45048192 5248144
12:38:06 37775 8192000000 10118311760 10548117504 1111000880 11.942 98 24 48 1 1186764528 70576672 5186976
12:39:48 79175 8192000000 10119849760 10561966080 1113682480 11.967 98 15 30 2 1168310864 51521040 3107344
12:42:08 75016 8192000000 10057938832 10513317888 1059514688 11.452 94 10 20 0 1132430912 70974208 1942016
12:44:32 129554 8192000000 10080246000 10530762752 1082679840 11.673 96 21 42 1 1135349632 48445232 4224560
12:48:10 45542 8192000000 10063570352 10579357696 1098795616 11.826 99 8 16 1 1150034880 49615152 1624112
12:49:56 67201 8192000000 10108450848 10584428544 1106819248 11.902 99 19 38 0 1186679664 75295856 4564560
12:52:18 48677 8192000000 10074293968 10510430208 1115018976 11.980 99 42 84 2 1141727728 18178752 8530000
12:54:23 70852 8192000000 10094631024 10555080704 1130417984 12.125 99 36 70 3 1213773024 76218352 7136688
12:57:49 115988 8192000000 10083057360 10536792064 1111296688 11.945 98 19 38 3 1144676032 29701616 3677728
13:00:56 93773 8192000000 10074605744 10540945408 1143394720 12.247 100 21 42 0 1204541088 57234224 3912144
13:03:31 58040 8192000000 10079701600 10570731520 1139132528 12.207 100 7 14 0 1180084544 39780368 1171648
13:05:36 51613 8192000000 10059329888 10506514432 1119982592 12.027 98 15 29 2 1148130016 25131520 3015904
13:07:34 51740 8192000000 10125301600 10548314112 1132191024 12.142 98 33 66 0 1194349280 54720256 7438000
13:09:41 84159 8192000000 10156436096 10627833856 1124884400 12.073 98 18 36 1 1224250704 95190416 4175888
13:12:05 65066 8192000000 10067573248 10487447552 1055900752 11.417 93 23 46 1 1110409760 50076496 4432512
13:14:13 62193 8192000000 10131235520 10676359168 1126826288 12.091 99 21 42 1 1241108208 109904352 4377568
13:16:15 94439 8192000000 10045462848 10507304960 1081814160 11.665 95 10 20 1 1137752288 53983920 1954208
13:19:15 61010 8192000000 10050414512 10489036800 1103172304 11.868 98 15 30 1 1132710560 26104016 3434240
13:21:23 47295 8192000000 10073302768 10548154368 1127223568 12.095 100 4 8 0 1165169552 37079056 866928
13:23:12 81817 8192000000 10089567216 10586701824 1097124688 11.810 98 7 14 2 1146932032 48300928 1506416
13:26:29 38795 8192000000 9094784080 9364856832 240700800 2.854 22 8 14 1 261353504 19008400 1644304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-02-2021 13:28:26 to 04-02-2021 13:28:41.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-02-2021 13:28:42 to 04-02-2021 13:28:44.

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          232821
MON$OLDEST_ACTIVE               233119
MON$OLDEST_SNAPSHOT             233119
MON$NEXT_TRANSACTION            233119
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                       2101376
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2361 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2361 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2361 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.2361 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 ---
:52: 
:53: mscs-test-161.budzdorov.ru    Thu Feb  4 13:27:20 2021
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
--- end of diff output ---

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

04.02.21 13:28:45. 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-02-04 13:28:47 No severe PSQL-related problems occured
04.02.21 13:28:48. End of report.