Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.09.2021 13:38
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 16024224 kB
MemAvailable 30749156 kB
Buffers 0 kB
Cached 15664460 kB
SwapCached 3072 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-09-28 13:37:10
DTS_END                         2021-09-28 13:37:10.0360
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/48630
TRN_ID                          229544
ATT_ID                          618
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: 8792.89 18292 1055147
customer order: creation 168.28 439 20194
customer order: refuse 14.37 383 1724
order to supplier: creation 45.00 1459 5400
order to supplier: removal 3.22 132205 386
invoice (draft): creation 43.42 4140 5210
invoice (draft): removal 48.40 1082 5808
invoice accept: apply 41.12 19937 4934
invoice accept: cancel 43.51 955 5221
customer reserve: creation 3773.88 196 452865
customer reserve: removal 4328.99 77 519479
realization accept: apply 81.78 235 9813
realization accept: cancel 77.41 132 9289
payment from customer: creation 57.37 179 6884
payment from customer: removal 5.10 85 612
payment to supplier: creation 46.92 204 5630
payment to supplier: removal 3.46 148 415
service: total inventory turnovers 3.77 3911 452
service: total monetary turnovers 5.97 655 716
service: aggregate perf. data 0.93 14758 111
service: refresh index statistics 0.03 184655 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-09-2021 13:38:30 to 28-09-2021 13:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6559
TEST_TIME 2 4324
TEST_TIME 3 4757
TEST_TIME 4 5224
TEST_TIME 5 5325
TEST_TIME 6 5458
TEST_TIME 7 5707
TEST_TIME 8 5519
TEST_TIME 9 5494
TEST_TIME 10 5460
TEST_TIME 11 5351
TEST_TIME 12 5312
TEST_TIME 13 5429
TEST_TIME 14 5598
TEST_TIME 15 5883
TEST_TIME 16 6264
TEST_TIME 17 6337
TEST_TIME 18 6648
TEST_TIME 19 6775
TEST_TIME 20 7419
TEST_TIME 21 7479
TEST_TIME 22 7611
TEST_TIME 23 7992
TEST_TIME 24 8578
TEST_TIME 25 8513
TEST_TIME 26 8799
TEST_TIME 27 9029
TEST_TIME 28 9237
TEST_TIME 29 10018
TEST_TIME 30 10555
TEST_TIME 31 10507
TEST_TIME 32 10535
TEST_TIME 33 10420
TEST_TIME 34 10305
TEST_TIME 35 10260
TEST_TIME 36 10129
TEST_TIME 37 9910
TEST_TIME 38 9961
TEST_TIME 39 9828
TEST_TIME 40 9883
TEST_TIME 41 9890
TEST_TIME 42 9874
TEST_TIME 43 9772
TEST_TIME 44 9803
TEST_TIME 45 9703
TEST_TIME 46 9569
TEST_TIME 47 9560
TEST_TIME 48 9493
TEST_TIME 49 9407
TEST_TIME 50 9389
TEST_TIME 51 9257
TEST_TIME 52 9179
TEST_TIME 53 9228
TEST_TIME 54 9147
TEST_TIME 55 9111
TEST_TIME 56 9170
TEST_TIME 57 9213
TEST_TIME 58 9392
TEST_TIME 59 9351
TEST_TIME 60 9418
TEST_TIME 61 9528
TEST_TIME 62 9474
TEST_TIME 63 9483
TEST_TIME 64 9477
TEST_TIME 65 9641
TEST_TIME 66 9503
TEST_TIME 67 9451
TEST_TIME 68 9384
TEST_TIME 69 9346
TEST_TIME 70 9304
TEST_TIME 71 9238
TEST_TIME 72 9177
TEST_TIME 73 9154
TEST_TIME 74 9089
TEST_TIME 75 9159
TEST_TIME 76 9192
TEST_TIME 77 9151
TEST_TIME 78 9120
TEST_TIME 79 9145
TEST_TIME 80 9179
TEST_TIME 81 9177
TEST_TIME 82 9279
TEST_TIME 83 9275
TEST_TIME 84 9249
TEST_TIME 85 9333
TEST_TIME 86 9273
TEST_TIME 87 9186
TEST_TIME 88 9178
TEST_TIME 89 9123
TEST_TIME 90 9074
TEST_TIME 91 9026
TEST_TIME 92 8984
TEST_TIME 93 8988
TEST_TIME 94 8987
TEST_TIME 95 8970
TEST_TIME 96 9051
TEST_TIME 97 9052
TEST_TIME 98 9040
TEST_TIME 99 9016
TEST_TIME 100 9021
TEST_TIME 101 9059
TEST_TIME 102 9072
TEST_TIME 103 9030
TEST_TIME 104 9068
TEST_TIME 105 9165
TEST_TIME 106 9159
TEST_TIME 107 9100
TEST_TIME 108 9055
TEST_TIME 109 9028
TEST_TIME 110 9006
TEST_TIME 111 8954
TEST_TIME 112 8945
TEST_TIME 113 8891
TEST_TIME 114 8908
TEST_TIME 115 8889
TEST_TIME 116 8885
TEST_TIME 117 8880
TEST_TIME 118 8856
TEST_TIME 119 8863
TEST_TIME 120 8802

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-09-2021 13:38:33 to 28-09-2021 13:38:37.

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 1474638 1474638 0 0.00 0 36692 8 0 0
doc_list_biud 1474624 1474624 0 0.00 0 59280 34 0 0
sp_add_invoice_to_stock 5023 4934 89 1.77 33 223123 19938 0 89
sp_cancel_adding_invoice 5221 5221 0 0.00 5 89208 955 0 0
sp_cancel_client_order 1724 1724 0 0.00 4 10019 383 0 0
sp_cancel_customer_reserve 519479 519479 0 0.00 1 59282 78 0 0
sp_cancel_pay_from_customer 612 612 0 0.00 3 4469 85 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 10650 148 0 0
sp_cancel_supplier_invoice 5816 5808 8 0.13 2 44579 1083 0 8
sp_cancel_supplier_order 386 386 0 0.00 9 1198490 132205 0 0
sp_cancel_write_off 9289 9289 0 0.00 1 21678 133 0 0
sp_client_order 20194 20194 0 0.00 4 45639 439 0 0
sp_customer_reserve 452865 452865 0 0.00 0 136821 196 0 0
sp_fill_shopping_cart 30799 30799 0 0.00 1 37123 46 0 0
sp_fill_shopping_cart_clo_res 452856 452856 0 0.00 0 22590 18 0 0
sp_get_clo_for_invoice 4925 4925 0 0.00 0 34681 419 0 0
sp_kill_cost_storno 15537 15537 0 0.00 0 21675 68 0 0
sp_kill_qstorno_ret_qs2qd 1054441 1054441 0 0.00 0 59275 42 0 0
sp_kill_qty_storno 556632 556632 0 0.00 0 59279 90 0 0
sp_lock_selected_doc 20379 20379 0 0.00 0 9790 7 0 0
sp_make_cost_storno 27252 27252 0 0.00 0 36458 94 0 0
sp_multiply_rows_for_pdistr 27247 27247 0 0.00 0 30184 46 0 0
sp_multiply_rows_for_qdistr 20194 20194 0 0.00 0 26417 237 0 0
sp_pay_from_customer 6884 6884 0 0.00 4 32323 180 0 0
sp_pay_to_supplier 5630 5630 0 0.00 5 34419 204 0 0
sp_payment_common 12513 12513 0 0.00 1 31708 33 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 0 10009 345 0 0
sp_qd_handle_on_invoice_upd_sts 10140 10140 0 0.00 0 44149 399 0 0
sp_qd_handle_on_reserve_upd_sts 9806 9806 0 0.00 0 14325 59 0 0
sp_reserve_write_off 9813 9813 0 0.00 7 36592 236 0 0
sp_supplier_invoice 5210 5210 0 0.00 7 89296 4140 0 0
sp_supplier_order 5400 5400 0 0.00 5 37625 1460 0 0
srv_aggregate_perf_data 111 111 0 0.00 2783 102334 14758 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2677 143536 43913 0 0
srv_find_qd_qs_mism 1008865 1008865 0 0.00 0 29328 6 0 0
srv_make_invnt_saldo 452 452 0 0.00 32 103958 3912 0 0
srv_make_money_saldo 716 716 0 0.00 1 61973 656 0 0
srv_recalc_idx_stat 4 4 0 0.00 146817 215123 184656 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 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 4934 4934 0 0.00 0 9864 153 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 3080 95 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 2932 12 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 2957 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 612 612 0 0.00 0 4157 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 1412 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 2827 30 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 14877 1491 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 4792 13 0 0
t$perf-norm:sp_client_order 20194 20194 0 0.00 0 7749 18 0 0
t$perf-norm:sp_customer_reserve 930 930 0 0.00 0 3992 13 0 0
t$perf-norm:sp_pay_from_customer 6884 6884 0 0.00 0 4429 8 0 0
t$perf-norm:sp_pay_to_supplier 5630 5630 0 0.00 0 8013 10 0 0
t$perf-norm:sp_reserve_write_off 9813 9813 0 0.00 0 9454 10 0 0
t$perf-norm:sp_supplier_invoice 5210 5210 0 0.00 0 7486 17 0 0
t$perf-norm:sp_supplier_order 5400 5400 0 0.00 0 3042 16 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 1750 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 106 5 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 307 4 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 2701 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2 2 0 0
v_add_invoice_to_stock 10016 9838 178 1.77 0 30654 31 0 178
v_all_customers 36546 36546 0 0.00 0 127 1 0 0
v_all_suppliers 29382 29382 0 0.00 0 78 1 0 0
v_all_wares 40386 40386 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 11779 23 0 0
v_cancel_client_order 3448 3448 0 0.00 0 4385 8 0 0
v_cancel_customer_prepayment 1224 1224 0 0.00 0 2908 6 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 2924 6 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 5596 22 0 0
v_cancel_supplier_invoice 878 862 16 1.82 0 6968 25 0 16
v_cancel_supplier_order 772 772 0 0.00 0 12044 23 0 0
v_cancel_write_off 1928 1928 0 0.00 0 5147 27 0 0
v_max_id_clo_ord 5398 5398 0 0.00 0 1161 2 0 0
v_max_id_clo_res 930 930 0 0.00 0 2402 20 0 0
v_max_id_ord_sup 5208 5208 0 0.00 0 12591 6 0 0
v_max_non_paid_invoice 1545 1545 0 0.00 0 644 3 0 0
v_max_non_paid_realizn 3778 3778 0 0.00 0 1417 3 0 0
v_min_id_clo_ord 5398 5398 0 0.00 0 1274 5 0 0
v_min_id_clo_res 930 930 0 0.00 0 7350 42 0 0
v_min_id_ord_sup 5208 5208 0 0.00 0 8152 10 0 0
v_min_non_paid_invoice 5630 5630 0 0.00 0 30121 26 0 0
v_min_non_paid_realizn 6883 6883 0 0.00 0 8005 22 0 0
v_our_firm 10050 10050 0 0.00 0 19 1 0 0
v_reserve_write_off 19612 19612 0 0.00 0 28305 11 0 0
x_lock_depdocs_on_canc_invoice 10598 10598 0 0.00 0 21727 130 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 15360 493 0 0
x_make_qstorno_1000_1200 5400 5400 0 0.00 2 26769 1371 0 0
x_make_qstorno_1200_2000 5210 5210 0 0.00 3 89234 3964 0 0
x_make_qstorno_2100_3300 452865 452865 0 0.00 0 136820 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-09-2021 13:38:38 to 28-09-2021 13:38:42.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 26874 8192000000 9727092320 10026258432 802669840 8.923 100 83 166 0 830086496 7222320 20194336
11:09:47 17777 8192000000 9771277088 10080739328 839703648 9.297 100 84 168 0 871086032 11573216 19809168
11:11:13 17152 8192000000 9804103136 10120531968 868311024 9.583 100 85 170 0 911072000 22957984 19802992
11:12:35 16782 8192000000 9877947648 10210574336 917739472 10.074 100 82 164 2 987884896 52229024 17916400
11:13:59 10733 8192000000 9929496432 10270072832 952874496 10.419 100 90 180 0 1038918416 67067792 18976128
11:15:12 6725 8192000000 9984194224 10333884416 989263072 10.774 100 84 168 2 1089390048 82927472 17199504
11:16:22 4033 8192000000 9998648944 10360254464 1015808576 11.032 100 76 152 2 1109834944 78560576 15465792
11:17:28 7719 8192000000 9994939744 10362826752 1038423680 11.250 100 73 143 1 1102871616 50202320 14245616
11:18:38 1718 8192000000 10038182288 10411249664 1060015696 11.457 100 89 180 3 1146500496 67991312 18493488
11:19:40 5380 8192000000 10035694432 10421321728 1065359888 11.508 100 59 118 0 1140284896 62736432 12188576
11:20:46 5596 8192000000 9999460400 10392911872 1076254528 11.612 100 52 101 2 1115301808 27950048 11097232
11:21:55 3748 8192000000 9980788944 10390974464 1082627648 11.673 100 19 38 0 1107485728 20603264 4254816
11:23:01 54743 8192000000 10006496432 10405965824 1086809856 11.712 100 44 92 2 1114508752 18034352 9664544
11:24:56 3191 8192000000 10040657184 10442153984 1099089552 11.829 100 62 126 2 1149420688 36909520 13421616
11:26:02 4720 8192000000 10024538528 10420523008 1102642480 11.863 100 48 96 1 1136421024 23238592 10539952
11:27:08 7892 8192000000 10017674656 10427854848 1097580672 11.815 100 28 56 2 1130178512 26431200 6166640
11:28:17 38653 8192000000 10006690752 10423824384 1014697824 11.021 93 35 69 1 1053156688 31051952 7406912
11:29:56 5984 8192000000 9986613184 10377969664 1039066400 11.256 98 54 108 2 1084539680 34192576 11280704
11:31:03 4538 8192000000 9959611920 10341883904 1017642016 11.049 97 42 82 2 1041511440 15431312 8438112
11:32:07 4099 8192000000 9943871232 10322038784 1012958768 11.004 99 50 98 2 1042604464 17097008 12548688
11:33:12 25005 8192000000 9934257456 10334171136 1003795392 10.915 97 17 34 1 1026522352 18772080 3954880
11:34:38 8842 8192000000 9971680048 10364252160 1048318032 11.345 100 46 90 0 1080983456 21977184 10688240
11:35:50 3191 8192000000 9987877504 10388922368 1066232832 11.516 100 47 95 1 1103958208 27416288 10309088
11:36:56 7153 8192000000 9991202928 10396389376 1078109920 11.629 100 38 71 1 1112594480 26157968 8326592
11:38:07 128303 8192000000 10064781440 10470064128 1110170848 11.934 100 55 110 4 1157733776 35462000 12100928
11:41:19 30594 8192000000 10085055664 10506866688 1124209392 12.067 100 37 74 0 1187069056 54677968 8181696
11:42:53 13695 8192000000 10116854432 10528440320 1144218592 12.255 100 44 88 1 1219664752 65315536 10130624
11:44:23 11496 8192000000 10149557088 10574057472 1158113664 12.386 100 52 104 1 1241459984 71960976 11385344
11:45:39 10530 8192000000 10184583056 10600812544 1167109360 12.470 100 58 112 1 1301006608 121632800 12264448
11:46:57 11928 8192000000 10229662176 10646757376 1174752720 12.541 100 58 112 1 1337178992 149609648 12816624
11:48:14 8216 8192000000 10270804032 10686738432 1182677184 12.615 100 57 111 1 1389894608 194465440 12751984
11:49:29 3379 8192000000 10314676544 10731732992 1187266832 12.658 100 59 118 2 1423126928 222811616 13048480
11:50:36 6340 8192000000 10387732176 10790469632 1185109728 12.638 100 80 160 2 1483808656 280380352 18318576
11:51:47 2675 8192000000 10395376976 10804228096 1183774528 12.625 100 79 157 0 1495853488 293448640 18630320
11:52:56 4445 8192000000 10416821312 10835853312 1182258928 12.611 100 83 165 1 1529383344 327989312 19135104
11:54:06 5072 8192000000 10441885440 10855591936 1183073904 12.619 100 78 154 1 1545431552 344230000 18127648
11:55:17 3969 8192000000 10437090848 10861649920 1184181200 12.629 100 93 183 0 1530909280 325495008 21233072
11:56:27 4077 8192000000 10468670976 10871889920 1160860784 12.411 100 93 184 1 1559483040 378039760 20582496
11:57:38 12942 8192000000 10413595712 10850717696 1150115200 12.311 99 40 80 0 1520331040 359908144 10307696
11:58:54 13092 8192000000 10457169552 10900013056 1159569808 12.399 100 52 104 0 1565753184 392627872 13555504
12:00:11 15655 8192000000 10503063920 10932301824 1165572016 12.455 100 39 78 0 1602417232 425909408 10935808
12:01:35 14844 8192000000 10401266736 10856509440 1166052960 12.460 100 42 83 1 1523536464 346622544 10860960
12:02:53 12293 8192000000 10397723616 10877591552 1152121920 12.329 100 40 79 1 1522546464 360040528 10384016
12:04:12 9451 8192000000 10379634640 10848563200 1156126368 12.367 100 28 56 1 1484566912 320958016 7482528
12:05:25 25496 8192000000 10332812560 10817077248 1128607600 12.108 99 16 32 1 1427633152 294352560 4672992
12:06:51 35913 8192000000 10062698000 10503561216 1121496960 12.041 98 19 38 0 1206717024 81426544 3793520
12:08:28 25657 8192000000 10098556800 10536779776 1112175968 11.953 97 11 22 0 1155366320 41417728 1772624
12:09:54 48010 8192000000 10138150128 10568507392 1134274048 12.162 99 36 70 1 1210066944 67736736 8056160
12:11:45 130408 8192000000 10081072800 10541301760 1108840480 11.921 97 37 74 1 1208766752 91640896 8285376
12:15:18 105788 8192000000 10118988688 10554249216 1073881856 11.589 94 35 70 1 1156128720 73861040 8385824
12:18:13 34801 8192000000 10082725264 10509217792 1128482000 12.107 99 38 74 1 1175106480 38961696 7662784
12:19:49 52731 8192000000 10057195456 10509656064 1104500752 11.880 97 10 20 0 1164053104 57599744 1952608
12:21:43 52260 8192000000 10064224320 10506383360 1120245744 12.029 99 17 33 0 1147419632 23976832 3197056
12:23:40 68553 8192000000 10072730992 10527215616 1125283104 12.077 99 12 24 0 1172504896 45030384 2191408
12:26:04 15615 8192000000 10062672704 10515963904 1142358160 12.238 100 32 64 1 1167831408 18677152 6796096
12:27:20 37143 8192000000 10045048320 10489425920 1122171072 12.047 98 18 36 0 1149879744 23463792 4244880
12:29:08 58725 8192000000 10082252736 10549981184 1130922064 12.130 99 12 24 3 1174651104 41253600 2475440
12:31:07 52425 8192000000 10095847744 10543316992 1136613440 12.184 99 11 22 0 1157586672 19039360 1933872
12:33:01 25552 8192000000 10133234080 10600108032 1130139456 12.123 98 36 70 0 1219217728 81617344 7460928
12:34:32 38307 8192000000 10117575456 10573901824 1121232176 12.039 98 21 42 2 1223221104 97105664 4883264
12:36:31 37250 8192000000 10150104336 10595876864 1128192384 12.104 98 23 45 2 1219092864 85532032 5368448
12:38:12 31408 8192000000 10081152144 10569064448 1137936048 12.196 99 16 32 1 1205281840 63793136 3552656
12:39:44 46354 8192000000 10098411792 10549157888 1108097680 11.914 98 39 78 0 1166488608 50815936 7574992
12:41:37 21786 8192000000 10172922336 10610327552 1142382080 12.238 100 43 86 0 1246761680 95454256 8925344
12:42:59 143535 8192000000 10044641472 10496929792 1076131152 11.611 95 23 45 1 1110000784 29480528 4389104
12:46:23 23570 8192000000 10042391344 10474041344 1064795728 11.502 95 27 54 2 1092074048 20759872 6518448
12:47:48 56145 8192000000 10074653568 10545704960 1119489680 12.022 100 13 26 0 1189963712 67390032 3084000
12:49:51 80251 8192000000 10100181760 10585321472 1118640496 12.014 99 23 46 0 1208581968 84720048 5221424
12:52:13 55903 8192000000 10035747440 10467299328 1063487120 11.490 95 14 26 1 1106997552 40502384 3008048
12:54:15 22714 8192000000 10020465024 10451197952 1084245424 11.688 98 28 54 0 1109015360 18840224 5929712
12:55:53 52086 8192000000 10060720432 10508951552 1100876048 11.846 99 26 52 0 1171995824 66015360 5104416
12:57:47 50537 8192000000 10114805808 10577547264 1130150080 12.123 100 15 30 1 1240806192 107427456 3228656
12:59:38 41197 8192000000 10062645728 10503532544 1100697200 11.844 98 31 62 1 1134853728 27969568 6186960
13:01:27 54308 8192000000 10082688304 10561523712 1091014832 11.752 97 30 61 0 1148991264 51455280 6521152
13:03:26 112448 8192000000 10056887536 10495012864 1108824448 11.921 98 44 87 0 1152280480 33853280 9602752
13:06:19 62835 8192000000 10035665056 10490040320 1118237616 12.010 99 8 16 0 1149961808 30189520 1534672
13:08:28 56139 8192000000 10063050656 10520641536 1137935728 12.196 100 6 10 1 1171649856 32826672 887456
13:10:25 50075 8192000000 10135928448 10604314624 1144471904 12.258 100 17 33 2 1208512256 59970416 4069936
13:12:15 70376 8192000000 10103304896 10642759680 1113644720 11.967 98 21 42 1 1217263984 98947584 4671680
13:14:35 48545 8192000000 10086057104 10542460928 1101832320 11.855 97 30 59 1 1140557040 32718816 6005904
13:16:28 42546 8192000000 10079471936 10521149440 1082064528 11.667 96 37 74 1 1130252944 40969264 7219152
13:18:11 89556 8192000000 10081179840 10586624000 1116699952 11.996 99 23 46 0 1184587312 63384688 4502672
13:20:46 40133 8192000000 10154005168 10596765696 1126762800 12.091 99 34 68 1 1211783616 78165760 6855056
13:22:29 114062 8192000000 10069401776 10524549120 1080732496 11.654 95 16 32 0 1118508112 34491328 3284288
13:25:24 102556 8192000000 10093574112 10570391552 1121886688 12.045 99 24 48 1 1191469888 64481328 5101872
13:28:22 58708 8192000000 10064646624 10516971520 1131808704 12.138 100 14 28 1 1167470000 32651520 3009776
13:30:43 51041 8192000000 10112010912 10579468288 1110816880 11.940 98 28 56 0 1172214352 55670608 5726864
13:32:49 44015 8192000000 10110278016 10567602176 1138597680 12.202 100 29 58 0 1204014064 59165968 6250416
13:34:33 44844 8192000000 10081820672 10520059904 1091796240 11.760 96 19 38 0 1158757744 62920688 4040816
13:36:20 62797 8192000000 9557836544 9932427264 649490880 7.345 57 18 35 0 739152480 85753088 3908512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-09-2021 13:38:44 to 28-09-2021 13:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 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 28-09-2021 13:39:00 to 28-09-2021 13:39:02.

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          229737
MON$OLDEST_ACTIVE               230056
MON$OLDEST_SNAPSHOT             230056
MON$NEXT_TRANSACTION            230056
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                       2035936
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:216: 
:217: mscs-test-161.budzdorov.ru    Tue Sep 28 13:37:29 2021
:218:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:219: 
:220: 
:221: mscs-test-161.budzdorov.ru    Tue Sep 28 13:37:31 2021
:222:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:223: 
:224: 
:225: mscs-test-161.budzdorov.ru    Tue Sep 28 13:37:54 2021
:226:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:227: 
--- end of diff output ---

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

28.09.21 13:39:03. 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-09-28 13:39:06 No severe PSQL-related problems occured
28.09.21 13:39:07. End of report.