Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.06.2021 10:28
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 16511452 kB
MemAvailable 31459364 kB
Buffers 0 kB
Cached 15168844 kB
SwapCached 60 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-06-11 10:27:00
DTS_END                         2021-06-11 10:27:00.4930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55718
TRN_ID                          234473
ATT_ID                          653
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: 9299.34 26428 1115921
customer order: creation 168.57 372 20228
customer order: refuse 14.93 333 1791
order to supplier: creation 45.31 1385 5437
order to supplier: removal 3.49 114533 419
invoice (draft): creation 44.66 3312 5359
invoice (draft): removal 53.63 917 6435
invoice accept: apply 40.94 16630 4913
invoice accept: cancel 48.65 801 5838
customer reserve: creation 3824.85 160 458982
customer reserve: removal 4761.73 65 571407
realization accept: apply 80.97 176 9716
realization accept: cancel 84.99 114 10199
payment from customer: creation 58.58 110 7029
payment from customer: removal 5.58 63 670
payment to supplier: creation 48.33 107 5800
payment to supplier: removal 3.32 73 398
service: total inventory turnovers 3.83 3550 460
service: total monetary turnovers 6.09 523 731
service: aggregate perf. data 0.88 17623 105
service: refresh index statistics 0.03 367722 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-06-2021 10:29:13 to 11-06-2021 10:29:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3257
TEST_TIME 2 3263
TEST_TIME 3 4433
TEST_TIME 4 4397
TEST_TIME 5 4913
TEST_TIME 6 5130
TEST_TIME 7 4983
TEST_TIME 8 5058
TEST_TIME 9 5463
TEST_TIME 10 5464
TEST_TIME 11 5538
TEST_TIME 12 5582
TEST_TIME 13 5898
TEST_TIME 14 6113
TEST_TIME 15 6339
TEST_TIME 16 6598
TEST_TIME 17 7220
TEST_TIME 18 7316
TEST_TIME 19 7362
TEST_TIME 20 7964
TEST_TIME 21 8422
TEST_TIME 22 9205
TEST_TIME 23 9582
TEST_TIME 24 10115
TEST_TIME 25 10514
TEST_TIME 26 10714
TEST_TIME 27 10435
TEST_TIME 28 10504
TEST_TIME 29 10499
TEST_TIME 30 10418
TEST_TIME 31 10455
TEST_TIME 32 10562
TEST_TIME 33 10666
TEST_TIME 34 10684
TEST_TIME 35 10738
TEST_TIME 36 10551
TEST_TIME 37 10274
TEST_TIME 38 10151
TEST_TIME 39 10036
TEST_TIME 40 10010
TEST_TIME 41 10029
TEST_TIME 42 9970
TEST_TIME 43 9936
TEST_TIME 44 9945
TEST_TIME 45 9838
TEST_TIME 46 9752
TEST_TIME 47 9704
TEST_TIME 48 9772
TEST_TIME 49 9983
TEST_TIME 50 10001
TEST_TIME 51 10041
TEST_TIME 52 10008
TEST_TIME 53 10085
TEST_TIME 54 10102
TEST_TIME 55 10066
TEST_TIME 56 10027
TEST_TIME 57 10122
TEST_TIME 58 10133
TEST_TIME 59 10141
TEST_TIME 60 10228
TEST_TIME 61 10129
TEST_TIME 62 9990
TEST_TIME 63 9920
TEST_TIME 64 9843
TEST_TIME 65 9809
TEST_TIME 66 9795
TEST_TIME 67 9730
TEST_TIME 68 9685
TEST_TIME 69 9697
TEST_TIME 70 9702
TEST_TIME 71 9698
TEST_TIME 72 9611
TEST_TIME 73 9637
TEST_TIME 74 9713
TEST_TIME 75 9714
TEST_TIME 76 9785
TEST_TIME 77 9797
TEST_TIME 78 9833
TEST_TIME 79 9927
TEST_TIME 80 9880
TEST_TIME 81 9765
TEST_TIME 82 9684
TEST_TIME 83 9676
TEST_TIME 84 9598
TEST_TIME 85 9540
TEST_TIME 86 9482
TEST_TIME 87 9483
TEST_TIME 88 9427
TEST_TIME 89 9398
TEST_TIME 90 9382
TEST_TIME 91 9342
TEST_TIME 92 9495
TEST_TIME 93 9594
TEST_TIME 94 9603
TEST_TIME 95 9577
TEST_TIME 96 9657
TEST_TIME 97 9647
TEST_TIME 98 9638
TEST_TIME 99 9710
TEST_TIME 100 9648
TEST_TIME 101 9578
TEST_TIME 102 9500
TEST_TIME 103 9498
TEST_TIME 104 9469
TEST_TIME 105 9447
TEST_TIME 106 9398
TEST_TIME 107 9380
TEST_TIME 108 9351
TEST_TIME 109 9325
TEST_TIME 110 9330
TEST_TIME 111 9308
TEST_TIME 112 9320
TEST_TIME 113 9285
TEST_TIME 114 9314
TEST_TIME 115 9355
TEST_TIME 116 9341
TEST_TIME 117 9314
TEST_TIME 118 9418
TEST_TIME 119 9378
TEST_TIME 120 9300

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-06-2021 10:29:17 to 11-06-2021 10:29:20.

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 1540720 1540720 0 0.00 0 24758 6 0 0
doc_list_biud 1540720 1540720 0 0.00 0 49285 31 0 0
sp_add_invoice_to_stock 5043 4913 130 2.57 20 153962 16631 0 130
sp_cancel_adding_invoice 5838 5838 0 0.00 5 64010 802 0 0
sp_cancel_client_order 1791 1791 0 0.00 5 22133 333 0 0
sp_cancel_customer_reserve 571407 571407 0 0.00 1 28758 66 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 2085 64 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 3805 73 0 0
sp_cancel_supplier_invoice 6445 6435 10 0.15 2 29844 917 0 10
sp_cancel_supplier_order 419 419 0 0.00 13 1173176 114534 0 0
sp_cancel_write_off 10199 10199 0 0.00 1 11797 115 0 0
sp_client_order 20228 20228 0 0.00 4 24337 373 0 0
sp_customer_reserve 458982 458982 0 0.00 0 44622 160 0 0
sp_fill_shopping_cart 31018 31018 0 0.00 1 18995 44 0 0
sp_fill_shopping_cart_clo_res 458979 458979 0 0.00 0 15946 17 0 0
sp_get_clo_for_invoice 4910 4910 0 0.00 0 27004 374 0 0
sp_kill_cost_storno 17105 17105 0 0.00 0 6801 54 0 0
sp_kill_qstorno_ret_qs2qd 1159867 1159867 0 0.00 0 29838 36 0 0
sp_kill_qty_storno 610711 610711 0 0.00 0 49283 77 0 0
sp_lock_selected_doc 20331 20331 0 0.00 0 9104 5 0 0
sp_make_cost_storno 27452 27452 0 0.00 0 10508 54 0 0
sp_multiply_rows_for_pdistr 27452 27452 0 0.00 0 8792 30 0 0
sp_multiply_rows_for_qdistr 20228 20228 0 0.00 0 24330 217 0 0
sp_pay_from_customer 7029 7029 0 0.00 4 12292 110 0 0
sp_pay_to_supplier 5800 5800 0 0.00 5 12810 107 0 0
sp_payment_common 12829 12829 0 0.00 1 12282 19 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 22127 306 0 0
sp_qd_handle_on_invoice_upd_sts 10744 10744 0 0.00 0 49283 404 0 0
sp_qd_handle_on_reserve_upd_sts 9716 9716 0 0.00 0 23041 57 0 0
sp_reserve_write_off 9716 9716 0 0.00 7 23074 177 0 0
sp_supplier_invoice 5359 5359 0 0.00 8 75880 3312 0 0
sp_supplier_order 5437 5437 0 0.00 6 47313 1385 0 0
srv_aggregate_perf_data 106 105 1 0.94 2818 219184 17624 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1289 118162 47102 0 0
srv_find_qd_qs_mism 1068770 1068770 0 0.00 0 24757 5 0 0
srv_make_invnt_saldo 460 460 0 0.00 36 102944 3551 0 0
srv_make_money_saldo 731 731 0 0.00 1 11974 524 0 0
srv_recalc_idx_stat 4 4 0 0.00 267355 505919 367723 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 4913 4913 0 0.00 0 9311 166 0 0
t$perf-norm:sp_cancel_adding_invoice 356 356 0 0.00 0 2015 77 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 3879 12 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 1360 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 363 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 1858 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 1113 15 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 15272 1390 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 1512 5 0 0
t$perf-norm:sp_client_order 20228 20228 0 0.00 0 16226 19 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 3321 9 0 0
t$perf-norm:sp_pay_from_customer 7029 7029 0 0.00 0 15895 11 0 0
t$perf-norm:sp_pay_to_supplier 5800 5800 0 0.00 0 3782 8 0 0
t$perf-norm:sp_reserve_write_off 9716 9716 0 0.00 0 12961 12 0 0
t$perf-norm:sp_supplier_invoice 5359 5359 0 0.00 0 5760 20 0 0
t$perf-norm:sp_supplier_order 5437 5437 0 0.00 0 9736 18 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 934 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 62 1 0 0
t$perf-norm:srv_make_invnt_saldo 460 460 0 0.00 0 1097 7 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 4120 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10070 9810 260 2.58 0 4680 14 0 260
v_all_customers 36840 36840 0 0.00 0 19 1 0 0
v_all_suppliers 29914 29914 0 0.00 0 139 1 0 0
v_all_wares 40452 40452 0 0.00 0 90 1 0 0
v_cancel_adding_invoice 712 712 0 0.00 0 844 6 0 0
v_cancel_client_order 3582 3582 0 0.00 0 1923 4 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 1675 6 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 151 3 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 1389 6 0 0
v_cancel_supplier_invoice 764 744 20 2.61 0 2650 18 0 20
v_cancel_supplier_order 838 838 0 0.00 0 365 4 0 0
v_cancel_write_off 1988 1988 0 0.00 0 2295 13 0 0
v_max_id_clo_ord 5436 5436 0 0.00 0 1946 2 0 0
v_max_id_clo_res 978 978 0 0.00 0 1906 11 0 0
v_max_id_ord_sup 5356 5356 0 0.00 0 7332 5 0 0
v_max_non_paid_invoice 1635 1635 0 0.00 0 2578 4 0 0
v_max_non_paid_realizn 3762 3762 0 0.00 0 4957 4 0 0
v_min_id_clo_ord 5436 5436 0 0.00 0 2575 7 0 0
v_min_id_clo_res 978 978 0 0.00 0 5512 23 0 0
v_min_id_ord_sup 5356 5356 0 0.00 0 18292 11 0 0
v_min_non_paid_invoice 5800 5800 0 0.00 0 6617 13 0 0
v_min_non_paid_realizn 7029 7029 0 0.00 0 8961 11 0 0
v_our_firm 10146 10146 0 0.00 0 12 1 0 0
v_reserve_write_off 19430 19430 0 0.00 0 3114 5 0 0
x_lock_depdocs_on_canc_invoice 11901 11901 0 0.00 0 17624 105 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 8187 321 0 0
x_make_qstorno_1000_1200 5437 5437 0 0.00 2 47305 1305 0 0
x_make_qstorno_1200_2000 5359 5359 0 0.00 3 75863 3142 0 0
x_make_qstorno_2100_3300 458982 458982 0 0.00 0 43407 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-06-2021 10:29:21 to 11-06-2021 10:29:25.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:57:04 6 8192000000 8796668768 8807174144 3189024 0.038 1 1 2 0 3286640 61840 35776
07:58:05 27624 8192000000 9730211408 10029965312 804477328 8.942 100 87 174 1 833079680 7443008 21159344
07:59:45 22141 8192000000 9781370224 10092482560 855980592 9.460 100 90 180 0 890172480 13556608 20635280
08:01:11 13159 8192000000 9810591408 10131308544 880768944 9.707 100 79 158 1 916990160 18925520 17295696
08:02:34 14717 8192000000 9886729504 10221305856 927208800 10.167 100 81 162 0 994265968 49170064 17887104
08:03:53 8962 8192000000 9924901456 10272436224 966114832 10.549 100 76 152 2 1035550784 53837280 15598672
08:05:04 7028 8192000000 9962110928 10322997248 1003230784 10.910 100 66 132 0 1074958528 58232496 13495248
08:06:12 3418 8192000000 10012022912 10379636736 1029320864 11.162 100 78 156 0 1125941664 80879696 15741104
08:07:17 1194 8192000000 10037931408 10408431616 1055477568 11.413 100 92 185 0 1154003328 80403600 18122160
08:08:21 1125 8192000000 10056142064 10429149184 1069192336 11.544 100 92 185 0 1170274128 81834224 19247568
08:09:23 6775 8192000000 9998841648 10389958656 1078307168 11.631 100 47 95 1 1119116704 29916960 10892576
08:10:35 7562 8192000000 9994196016 10388975616 1083147120 11.677 100 31 60 1 1119974880 29929568 6898192
08:11:44 55935 8192000000 9993912816 10396434432 1087849136 11.722 100 20 40 1 1111083264 19086720 4147408
08:13:41 5120 8192000000 10028636048 10425298944 1094404656 11.785 100 47 92 0 1142028784 37637904 9986224
08:14:49 5524 8192000000 10039118304 10438172672 1106585200 11.900 100 46 94 2 1151352800 34967264 9800336
08:15:55 5481 8192000000 10031138656 10422939648 1108588416 11.919 100 44 86 3 1132773712 15464464 8720832
08:17:02 45417 8192000000 9995746416 10397589504 1079596576 11.644 98 36 72 0 1101652912 14167504 7888832
08:18:49 7458 8192000000 9979742816 10384400384 1046027568 11.323 97 31 63 0 1081782496 29197744 6557184
08:19:57 8870 8192000000 9966299104 10354806784 1004867088 10.926 95 32 63 3 1031279072 19433744 6978240
08:21:06 9403 8192000000 9928372224 10325323776 983852048 10.722 97 32 64 4 1010878560 19279808 7746704
08:22:17 42162 8192000000 9949329760 10341085184 1028161840 11.151 99 38 76 4 1060984112 24975456 7846816
08:23:59 8713 8192000000 9968559680 10371117056 1047103664 11.333 100 46 93 0 1076000688 18489408 10407616
08:25:09 10013 8192000000 9972420656 10382700544 1057921376 11.437 100 37 74 1 1101524640 35658288 7944976
08:26:19 8916 8192000000 9963899232 10373701632 1064699840 11.501 100 33 66 4 1092248384 19950784 7597760
08:27:29 76569 8192000000 10019405312 10424893440 1077133952 11.620 100 48 94 0 1100638992 13179552 10325488
08:29:56 50088 8192000000 10081750592 10499305472 1105092464 11.886 100 37 74 2 1179450304 67023136 7334704
08:31:59 11345 8192000000 10138066304 10540621824 1126805584 12.091 100 64 125 0 1236094992 96602752 12686656
08:33:15 15570 8192000000 10157630448 10574688256 1143296880 12.247 100 46 92 0 1261868112 108630272 9940960
08:34:35 20649 8192000000 10184113040 10597662720 1158779632 12.392 100 47 94 0 1295704080 126083264 10841184
08:36:07 18367 8192000000 10223325536 10636668928 1170003632 12.497 100 52 101 1 1317945568 137087408 10854528
08:37:29 5222 8192000000 10257854256 10668154880 1177959952 12.571 100 65 129 0 1359539040 167004704 14574384
08:38:39 4061 8192000000 10328828128 10740056064 1187761952 12.663 100 75 149 0 1424110592 219234368 17114272
08:39:51 5591 8192000000 10361517984 10779258880 1178846720 12.579 99 69 136 0 1461941136 266575680 16518736
08:41:03 4931 8192000000 10382237632 10798895104 1187022224 12.656 100 63 126 1 1481671632 280277824 14371584
08:42:13 1769 8192000000 10401760688 10803605504 1169863296 12.496 99 77 154 0 1499905712 313220576 16821840
08:43:21 2281 8192000000 10425271248 10835271680 1175549296 12.549 100 76 152 0 1522182944 328849664 17783984
08:44:31 3554 8192000000 10441260000 10856919040 1134156352 12.161 97 68 135 0 1517480800 367348464 15975984
08:45:40 1288 8192000000 10431387936 10851078144 1144638432 12.259 99 82 164 0 1521432752 357703808 19090512
08:46:50 2221 8192000000 10472127392 10877820928 1140912608 12.224 100 89 177 0 1569185184 405157840 23114736
08:47:55 2745 8192000000 10389765040 10839683072 1136690832 12.184 100 56 112 0 1521581280 369686656 15203792
08:49:03 11512 8192000000 10413376608 10849366016 1143380272 12.247 100 40 79 1 1508047568 353940736 10726560
08:50:18 13038 8192000000 10277194736 10718011392 1152032048 12.329 100 30 60 2 1385654896 225704208 7918640
08:51:42 30568 8192000000 10151953904 10602143744 1123562992 12.061 98 17 34 1 1257831104 129897872 4370240
08:53:13 51170 8192000000 10045345248 10478452736 1106077520 11.895 99 13 27 0 1176400576 67534576 2788480
08:55:04 18755 8192000000 10112766864 10555052032 1120599104 12.033 99 47 92 2 1181631504 51345552 9686848
08:56:56 44373 8192000000 10091748272 10531856384 1124706464 12.071 99 12 22 1 1159177280 32576624 1894192
08:58:42 61647 8192000000 10104105408 10590228480 1118309648 12.011 98 13 25 2 1181605216 60715392 2580176
09:00:47 108919 8192000000 10082554448 10561339392 1045447664 11.317 92 6 12 0 1099556128 53029952 1078512
09:03:57 80234 8192000000 10087114384 10526113792 1135037600 12.169 100 41 81 1 1184712032 40423120 9251312
09:06:19 24431 8192000000 10103553920 10537320448 1114909568 11.979 98 32 65 1 1174674384 52982144 6782672
09:07:45 15801 8192000000 10106477952 10547376128 1128373664 12.106 99 42 84 1 1202530576 65314416 8842496
09:09:06 21719 8192000000 10115819200 10559107072 1150864848 12.318 100 41 79 5 1216691536 57232160 8594528
09:10:32 118161 8192000000 10146861360 10633224192 1160525680 12.408 100 26 51 1 1239779072 73961216 5292176
09:13:35 48089 8192000000 10203958224 10648682496 1152843424 12.336 99 16 32 0 1289761536 133044208 3873904
09:15:31 84850 8192000000 10151740288 10661711872 1138311008 12.200 98 24 49 0 1262823008 118917824 5594176
09:17:56 62119 8192000000 10097611264 10557935616 1141940064 12.234 99 26 52 2 1191993344 44239520 5813760
09:19:59 100575 8192000000 10082949888 10567389184 1098908960 11.827 96 8 16 1 1144990480 44471456 1610064
09:22:40 29212 8192000000 10085404208 10534797312 1103005712 11.866 97 23 44 1 1145968976 38370576 4592688
09:24:31 65870 8192000000 10061687136 10525761536 1098393744 11.822 97 13 24 0 1148533584 47652784 2487056
09:26:40 99145 8192000000 10094926656 10616705024 1110788208 11.940 98 5 10 0 1154605504 42672432 1144864
09:29:39 63678 8192000000 10073892912 10529927168 1119017616 12.018 98 19 38 0 1170297520 47606448 3673456
09:31:44 62232 8192000000 10109689824 10561540096 1081304032 11.660 95 13 26 0 1153681392 69938880 2438480
09:33:46 55812 8192000000 10071082752 10565218304 1101796112 11.855 98 13 26 0 1166701456 61837696 3067648
09:35:43 49922 8192000000 10089209424 10534080512 1107744608 11.911 98 20 39 2 1163839328 51483808 4610912
09:37:48 12362 8192000000 10065878800 10500108288 1109521648 11.928 99 48 96 0 1146821200 27449840 9849712
09:39:03 46116 8192000000 10116476192 10546991104 1134331952 12.162 100 41 82 1 1209679568 67320480 8027136
09:41:14 93161 8192000000 10143196240 10600235008 1118903600 12.017 98 9 18 0 1252592048 131612496 2075952
09:43:52 39108 8192000000 10110175216 10576232448 1130143184 12.123 99 17 34 1 1199581328 66104272 3333872
09:45:32 39479 8192000000 10148885424 10611290112 1127960208 12.102 99 38 76 1 1180606688 44928560 7717920
09:47:14 83261 8192000000 10053398368 10550349824 1109935328 11.932 99 13 26 0 1163683728 50669952 3078448
09:49:37 63524 8192000000 10064476000 10514022400 1098744656 11.826 98 13 26 0 1140337168 38531920 3060592
09:51:42 53359 8192000000 10049503024 10527047680 1114691520 11.977 100 5 10 0 1155855232 40211760 951952
09:53:36 52517 8192000000 10094447072 10560499712 1126180736 12.085 100 15 30 0 1183551168 54514224 2856208
09:55:29 45676 8192000000 10119214816 10560552960 1125951680 12.083 99 19 38 1 1181229264 51292544 3985040
09:57:14 53959 8192000000 10178560784 10622431232 1136591712 12.183 98 24 48 1 1242329088 100707392 5029984
09:59:24 52395 8192000000 10153807680 10719375360 1119030032 12.018 98 11 22 0 1278130512 156402352 2698128
10:01:19 90106 8192000000 10064887920 10505863168 1114107424 11.971 98 16 32 2 1145305184 28271936 2925824
10:03:49 94040 8192000000 10083643680 10577006592 1092159808 11.763 96 20 38 1 1134372688 38217184 3995696
10:06:23 97698 8192000000 10123401552 10598162432 1120779424 12.034 97 15 29 2 1191236448 67110176 3346848
10:09:06 92214 8192000000 10077231936 10571128832 1109270096 11.926 97 17 34 0 1164661152 51964864 3426192
10:11:55 56302 8192000000 10059492976 10516357120 1102323536 11.860 97 8 14 2 1131403136 27217648 1861952
10:13:52 78301 8192000000 10032804000 10492227584 1085612976 11.701 96 8 16 0 1118671056 31392912 1665168
10:16:17 22311 8192000000 10062458672 10507714560 1127382752 12.097 100 24 47 2 1172868144 40723472 4761920
10:17:41 51861 8192000000 10086000432 10541244416 1122691744 12.052 99 33 66 0 1169861856 39847280 7322832
10:19:38 55374 8192000000 10125584352 10577190912 1106558592 11.900 97 28 56 2 1198300048 85412576 6328880
10:21:53 68057 8192000000 10076782240 10528509952 1090364528 11.746 97 21 43 1 1185319680 90337840 4617312
10:24:19 80173 8192000000 10079113536 10575708160 1118317584 12.011 99 16 32 0 1206096432 84461184 3317664
10:26:39 45023 8192000000 9415921872 9746644992 549148688 6.282 50 5 10 0 560128320 10329136 650496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-06-2021 10:29:27 to 11-06-2021 10:29:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 130 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 11-06-2021 10:29:43 to 11-06-2021 10:29: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          234697
MON$OLDEST_ACTIVE               234698
MON$OLDEST_SNAPSHOT             234698
MON$NEXT_TRANSACTION            234698
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                       2129152
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             804
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2514 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2514 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2514 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2514 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:111: 
:112: mscs-test-161.budzdorov.ru    Fri Jun 11 10:27:41 2021
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:114: 
--- end of diff output ---

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

11.06.21 10:29: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-06-11 10:29:48 No severe PSQL-related problems occured
11.06.21 10:29:49. End of report.