Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.08.2021 07:51
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 16201928 kB
MemAvailable 31049092 kB
Buffers 0 kB
Cached 15516644 kB
SwapCached 2576 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-08-14 07:49:23
DTS_END                         2021-08-14 07:49:23.3500
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/43390
TRN_ID                          244411
ATT_ID                          650
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: 9240.08 25516 1108809
customer order: creation 176.89 399 21227
customer order: refuse 15.44 345 1853
order to supplier: creation 47.33 1433 5679
order to supplier: removal 3.41 105512 409
invoice (draft): creation 49.23 3701 5908
invoice (draft): removal 50.25 920 6030
invoice accept: apply 43.67 19289 5240
invoice accept: cancel 45.23 864 5427
customer reserve: creation 4080.92 189 489710
customer reserve: removal 4421.91 64 530629
realization accept: apply 85.78 175 10293
realization accept: cancel 86.77 120 10412
payment from customer: creation 60.91 120 7309
payment from customer: removal 5.95 103 714
payment to supplier: creation 51.18 117 6142
payment to supplier: removal 3.66 116 439
service: total inventory turnovers 3.95 3396 474
service: total monetary turnovers 6.70 504 804
service: aggregate perf. data 0.88 17491 106
service: refresh index statistics 0.03 355465 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-08-2021 07:51:42 to 14-08-2021 07:51:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7842
TEST_TIME 2 5653
TEST_TIME 3 4508
TEST_TIME 4 4684
TEST_TIME 5 4501
TEST_TIME 6 4965
TEST_TIME 7 4863
TEST_TIME 8 5287
TEST_TIME 9 5089
TEST_TIME 10 5372
TEST_TIME 11 5795
TEST_TIME 12 6798
TEST_TIME 13 6788
TEST_TIME 14 7377
TEST_TIME 15 7537
TEST_TIME 16 7753
TEST_TIME 17 8017
TEST_TIME 18 7971
TEST_TIME 19 8533
TEST_TIME 20 8479
TEST_TIME 21 8841
TEST_TIME 22 8626
TEST_TIME 23 8731
TEST_TIME 24 8709
TEST_TIME 25 9757
TEST_TIME 26 10226
TEST_TIME 27 10855
TEST_TIME 28 10694
TEST_TIME 29 10622
TEST_TIME 30 10421
TEST_TIME 31 10356
TEST_TIME 32 10169
TEST_TIME 33 10010
TEST_TIME 34 10062
TEST_TIME 35 9884
TEST_TIME 36 9803
TEST_TIME 37 9709
TEST_TIME 38 9696
TEST_TIME 39 9827
TEST_TIME 40 9790
TEST_TIME 41 9808
TEST_TIME 42 9749
TEST_TIME 43 9891
TEST_TIME 44 9871
TEST_TIME 45 9867
TEST_TIME 46 9924
TEST_TIME 47 9940
TEST_TIME 48 10010
TEST_TIME 49 9920
TEST_TIME 50 9913
TEST_TIME 51 9957
TEST_TIME 52 10076
TEST_TIME 53 10060
TEST_TIME 54 10060
TEST_TIME 55 9998
TEST_TIME 56 9987
TEST_TIME 57 9854
TEST_TIME 58 9763
TEST_TIME 59 9676
TEST_TIME 60 9656
TEST_TIME 61 9617
TEST_TIME 62 9554
TEST_TIME 63 9463
TEST_TIME 64 9472
TEST_TIME 65 9548
TEST_TIME 66 9505
TEST_TIME 67 9506
TEST_TIME 68 9557
TEST_TIME 69 9499
TEST_TIME 70 9534
TEST_TIME 71 9580
TEST_TIME 72 9549
TEST_TIME 73 9619
TEST_TIME 74 9665
TEST_TIME 75 9709
TEST_TIME 76 9646
TEST_TIME 77 9720
TEST_TIME 78 9710
TEST_TIME 79 9624
TEST_TIME 80 9537
TEST_TIME 81 9562
TEST_TIME 82 9558
TEST_TIME 83 9492
TEST_TIME 84 9464
TEST_TIME 85 9400
TEST_TIME 86 9383
TEST_TIME 87 9389
TEST_TIME 88 9356
TEST_TIME 89 9378
TEST_TIME 90 9341
TEST_TIME 91 9432
TEST_TIME 92 9418
TEST_TIME 93 9407
TEST_TIME 94 9406
TEST_TIME 95 9399
TEST_TIME 96 9421
TEST_TIME 97 9370
TEST_TIME 98 9325
TEST_TIME 99 9328
TEST_TIME 100 9317
TEST_TIME 101 9313
TEST_TIME 102 9291
TEST_TIME 103 9286
TEST_TIME 104 9274
TEST_TIME 105 9263
TEST_TIME 106 9248
TEST_TIME 107 9302
TEST_TIME 108 9301
TEST_TIME 109 9410
TEST_TIME 110 9435
TEST_TIME 111 9417
TEST_TIME 112 9442
TEST_TIME 113 9439
TEST_TIME 114 9454
TEST_TIME 115 9413
TEST_TIME 116 9370
TEST_TIME 117 9331
TEST_TIME 118 9283
TEST_TIME 119 9299
TEST_TIME 120 9248

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-08-2021 07:51:45 to 14-08-2021 07:51:49.

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 1560368 1560368 0 0.00 0 14704 7 0 0
doc_list_biud 1560368 1560368 0 0.00 0 74992 28 0 0
sp_add_invoice_to_stock 5356 5240 116 2.16 26 201917 19289 0 116
sp_cancel_adding_invoice 5427 5427 0 0.00 4 65997 864 0 0
sp_cancel_client_order 1853 1853 0 0.00 4 15151 346 0 0
sp_cancel_customer_reserve 530629 530629 0 0.00 1 74993 64 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 6364 103 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 9900 116 0 0
sp_cancel_supplier_invoice 6040 6030 10 0.16 2 41143 920 0 10
sp_cancel_supplier_order 409 409 0 0.00 9 1076549 105512 0 0
sp_cancel_write_off 10412 10412 0 0.00 1 11214 121 0 0
sp_client_order 21227 21227 0 0.00 4 43008 399 0 0
sp_customer_reserve 489710 489710 0 0.00 0 41713 189 0 0
sp_fill_shopping_cart 32790 32790 0 0.00 1 27662 40 0 0
sp_fill_shopping_cart_clo_res 489695 489695 0 0.00 0 30223 16 0 0
sp_get_clo_for_invoice 5224 5224 0 0.00 0 24727 318 0 0
sp_kill_cost_storno 16992 16992 0 0.00 0 11157 56 0 0
sp_kill_qstorno_ret_qs2qd 1078109 1078109 0 0.00 0 74990 35 0 0
sp_kill_qty_storno 570277 570277 0 0.00 0 74992 76 0 0
sp_lock_selected_doc 21582 21582 0 0.00 0 6528 4 0 0
sp_make_cost_storno 28968 28968 0 0.00 0 12629 53 0 0
sp_multiply_rows_for_pdistr 28968 28968 0 0.00 0 8456 29 0 0
sp_multiply_rows_for_qdistr 21227 21227 0 0.00 0 19589 188 0 0
sp_pay_from_customer 7309 7309 0 0.00 4 20794 121 0 0
sp_pay_to_supplier 6142 6142 0 0.00 5 13245 117 0 0
sp_payment_common 13451 13451 0 0.00 1 8981 19 0 0
sp_qd_handle_on_cancel_clo 1853 1853 0 0.00 0 15143 321 0 0
sp_qd_handle_on_invoice_upd_sts 10651 10651 0 0.00 0 72142 357 0 0
sp_qd_handle_on_reserve_upd_sts 10293 10293 0 0.00 0 16832 59 0 0
sp_reserve_write_off 10293 10293 0 0.00 6 18472 176 0 0
sp_supplier_invoice 5908 5908 0 0.00 7 70298 3701 0 0
sp_supplier_order 5679 5679 0 0.00 6 35563 1433 0 0
srv_aggregate_perf_data 106 106 0 0.00 2941 275365 17491 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2080 147338 46387 0 0
srv_find_qd_qs_mism 1058032 1058032 0 0.00 0 14704 7 0 0
srv_make_invnt_saldo 474 474 0 0.00 41 134145 3396 0 0
srv_make_money_saldo 804 804 0 0.00 1 14191 504 0 0
srv_recalc_idx_stat 4 4 0 0.00 231971 481264 355466 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 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-norm:sp_add_invoice_to_stock 5241 5241 0 0.00 0 22263 164 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 4236 76 0 0
t$perf-norm:sp_cancel_client_order 1853 1853 0 0.00 0 15943 22 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 389 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 6115 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 827 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 531 10 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 17364 1440 0 0
t$perf-norm:sp_cancel_write_off 1086 1086 0 0.00 0 1354 6 0 0
t$perf-norm:sp_client_order 21227 21227 0 0.00 0 22229 18 0 0
t$perf-norm:sp_customer_reserve 1084 1084 0 0.00 0 752 4 0 0
t$perf-norm:sp_pay_from_customer 7309 7309 0 0.00 0 5062 8 0 0
t$perf-norm:sp_pay_to_supplier 6142 6142 0 0.00 0 16591 15 0 0
t$perf-norm:sp_reserve_write_off 10293 10293 0 0.00 0 7663 11 0 0
t$perf-norm:sp_supplier_invoice 5908 5908 0 0.00 0 23254 19 0 0
t$perf-norm:sp_supplier_order 5679 5679 0 0.00 0 3389 13 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 116 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2166 36 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 931 8 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 321 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 24 7 0 0
v_add_invoice_to_stock 10680 10448 232 2.17 0 4622 17 0 232
v_all_customers 38834 38834 0 0.00 0 97 1 0 0
v_all_suppliers 32030 32030 0 0.00 0 102 1 0 0
v_all_wares 42452 42452 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 2926 13 0 0
v_cancel_client_order 3706 3706 0 0.00 0 920 3 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 2641 9 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 3444 7 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 3364 15 0 0
v_cancel_supplier_invoice 844 824 20 2.36 0 3172 19 0 20
v_cancel_supplier_order 818 818 0 0.00 0 453 4 0 0
v_cancel_write_off 2172 2172 0 0.00 0 4234 20 0 0
v_max_id_clo_ord 5667 5667 0 0.00 0 2899 2 0 0
v_max_id_clo_res 1084 1084 0 0.00 0 2721 15 0 0
v_max_id_ord_sup 5896 5896 0 0.00 0 1978 3 0 0
v_max_non_paid_invoice 1690 1690 0 0.00 0 154 2 0 0
v_max_non_paid_realizn 3852 3852 0 0.00 0 7578 8 0 0
v_min_id_clo_ord 5667 5667 0 0.00 0 3839 8 0 0
v_min_id_clo_res 1084 1084 0 0.00 0 4273 14 0 0
v_min_id_ord_sup 5896 5896 0 0.00 0 5246 9 0 0
v_min_non_paid_invoice 6142 6142 0 0.00 0 2504 10 0 0
v_min_non_paid_realizn 7309 7309 0 0.00 0 4986 13 0 0
v_our_firm 10532 10532 0 0.00 0 20 1 0 0
v_reserve_write_off 20586 20586 0 0.00 0 6059 6 0 0
x_lock_depdocs_on_canc_invoice 11045 11045 0 0.00 0 15561 105 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 14324 376 0 0
x_make_qstorno_1000_1200 5679 5679 0 0.00 2 35555 1345 0 0
x_make_qstorno_1200_2000 5908 5908 0 0.00 3 70283 3565 0 0
x_make_qstorno_2100_3300 489710 489710 0 0.00 0 41713 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-08-2021 07:51:50 to 14-08-2021 07:51:54.

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
05:19:27 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:20:28 23836 8192000000 9721953760 10016026624 789472016 8.790 100 88 175 0 818029216 7213328 21343872
05:22:14 25212 8192000000 9756574656 10064429056 834351536 9.243 100 88 176 0 869020736 13673648 20995552
05:23:42 15088 8192000000 9806749648 10127970304 865127536 9.551 100 77 152 0 913025040 30990304 16907200
05:25:07 11390 8192000000 9889358528 10229481472 934359920 10.238 100 77 153 2 1003848080 52903024 16585136
05:26:23 4688 8192000000 9955244336 10303242240 979873760 10.683 100 83 163 1 1053310752 56511536 16925456
05:27:32 4028 8192000000 9986939504 10345861120 1014427008 11.018 100 83 163 0 1100811936 69782848 16602080
05:28:37 2152 8192000000 9999003088 10366861312 1032837504 11.196 100 72 141 0 1114104064 67687344 13579216
05:29:41 1220 8192000000 10070930752 10442371072 1068822816 11.541 100 93 186 0 1182743136 93958480 19961840
05:30:43 4578 8192000000 10029771632 10414911488 1079670368 11.644 100 77 153 1 1144004176 48574592 15759216
05:31:50 19313 8192000000 9992101024 10381217792 1084654816 11.692 100 32 65 1 1112956544 20967808 7333920
05:33:11 5166 8192000000 10003205216 10399375360 1092541344 11.767 100 39 78 0 1118271024 17482704 8246976
05:34:16 7428 8192000000 10009535328 10413768704 1094471072 11.785 100 33 64 1 1123062128 21403504 7187552
05:35:24 4586 8192000000 10004273840 10408271872 1097103280 11.810 100 34 69 2 1121922688 17053616 7765792
05:36:29 56167 8192000000 10026709264 10429198336 1101548560 11.852 100 38 74 1 1135947088 25990320 8408208
05:38:29 8544 8192000000 10000119392 10411859968 1101554368 11.852 100 33 65 3 1129516144 19799792 8161984
05:39:38 6259 8192000000 10005658896 10416025600 1065236816 11.507 97 48 96 1 1098143888 22414048 10493024
05:40:49 7030 8192000000 9949192064 10332622848 1011010592 10.985 96 24 49 2 1035315088 18873888 5430608
05:42:01 30492 8192000000 9954559632 10352267264 1005592432 10.933 96 29 57 2 1029337264 17369520 6375312
05:43:33 6633 8192000000 9924549040 10312552448 1004312960 10.920 99 41 79 1 1044041648 30294080 9434608
05:44:46 12685 8192000000 9928534640 10319347712 1008478672 10.961 98 24 48 0 1032555504 18279632 5797200
05:46:00 19007 8192000000 9997963936 10401931264 1048872144 11.350 100 72 145 1 1085914176 20950880 16091152
05:47:53 7700 8192000000 9979643232 10378252288 1055464976 11.413 100 31 61 2 1078687792 16843184 6379632
05:49:03 6851 8192000000 9998350272 10394533888 1069609472 11.548 100 52 100 4 1105869488 25257120 11002896
05:50:17 18919 8192000000 10027394976 10425352192 1090098464 11.744 100 51 100 1 1140730224 39939824 10691936
05:51:36 101004 8192000000 10078149328 10485125120 1102663840 11.863 100 46 92 0 1163676992 51453728 9559424
05:54:27 9432 8192000000 10092043616 10499289088 1125700464 12.081 100 46 92 0 1201196208 65819440 9676304
05:55:49 4344 8192000000 10148229376 10556366848 1139386928 12.210 100 72 146 1 1257930464 102090032 16453504
05:56:59 43202 8192000000 10136544800 10557693952 1142854368 12.242 100 27 54 1 1250928384 101900736 6173280
05:58:49 5086 8192000000 10229841312 10643419136 1168584464 12.484 100 66 128 2 1334874048 152431616 13857968
06:00:00 10734 8192000000 10240962016 10655805440 1173216672 12.527 100 51 101 1 1345316144 160810448 11289024
06:01:16 2946 8192000000 10305010784 10715160576 1178157152 12.573 100 85 169 1 1407587872 210638576 18792144
06:02:24 3495 8192000000 10278642704 10686173184 1166979024 12.469 99 80 153 2 1370514880 186617904 16917952
06:03:32 5479 8192000000 10259685248 10681872384 1157647632 12.381 100 75 151 1 1361973968 187374352 16951984
06:04:45 2080 8192000000 10302806224 10709880832 1179595872 12.586 100 85 167 0 1401448592 203565680 18287040
06:05:51 3708 8192000000 10338461616 10749329408 1166437728 12.464 100 71 141 0 1436248320 254129328 15681264
06:06:58 2349 8192000000 10307334576 10703757312 1138641904 12.203 99 85 169 0 1390632784 232355440 19635440
06:08:04 2536 8192000000 10363952112 10762788864 1151379312 12.322 100 91 178 1 1455565728 284747968 19438448
06:09:11 6665 8192000000 10311668304 10715791360 1122583104 12.051 98 62 122 3 1394228512 256789440 14855968
06:10:21 6273 8192000000 10343368496 10765037568 1142039024 12.235 100 52 104 1 1437857584 282490880 13327680
06:11:29 12450 8192000000 10370871152 10800889856 1145560400 12.268 100 36 72 0 1471078096 316847792 8669904
06:12:46 9926 8192000000 10385223600 10815102976 1138827728 12.205 99 37 74 1 1471018000 322690624 9499648
06:13:58 23969 8192000000 10384743680 10838130688 1140946192 12.224 100 29 58 0 1489547680 340728784 7872704
06:15:28 36335 8192000000 10186877024 10648195072 1126057616 12.084 100 12 24 0 1280202528 151176080 2968832
06:17:07 33238 8192000000 10052912528 10490032128 1100869296 11.846 97 11 21 2 1120986016 17679328 2437392
06:18:47 80526 8192000000 10108390688 10563719168 1135987008 12.178 100 24 48 1 1183701632 42868000 4846624
06:21:17 46039 8192000000 10101405040 10550284288 1112624208 11.957 98 42 84 0 1152420864 30220624 9576032
06:23:05 113508 8192000000 10124710608 10558124032 1111660416 11.948 97 29 58 1 1222896976 104467360 6769200
06:26:00 18198 8192000000 10174025888 10614935552 1148189440 12.292 100 31 60 1 1267057120 112580160 6287520
06:27:19 62111 8192000000 10199991440 10699522048 1157807776 12.383 100 31 62 0 1333288928 167746960 7734192
06:29:30 34171 8192000000 10129512032 10608005120 1131166112 12.132 98 16 33 0 1242240480 107925584 3148784
06:31:05 53044 8192000000 10131333280 10587090944 1150308096 12.312 100 16 32 0 1225047488 70715712 4023680
06:32:58 43538 8192000000 10086159584 10521591808 1130449904 12.126 98 19 37 0 1162849504 28593120 3806480
06:34:45 53449 8192000000 10108079312 10563391488 1159239152 12.396 100 33 66 0 1232513840 66013648 7261040
06:36:39 73266 8192000000 10084368976 10588676096 1138311808 12.200 98 10 20 0 1207295344 67124000 1859536
06:38:52 61571 8192000000 10116007488 10558525440 1126481424 12.088 97 21 40 3 1187272384 56648112 4142848
06:40:56 48334 8192000000 10122019712 10602885120 1148086208 12.292 100 15 30 1 1209857600 58535328 3236064
06:43:32 71235 8192000000 10101125408 10538938368 1112745568 11.958 97 25 50 1 1158911440 40876848 5289024
06:45:44 111878 8192000000 10041772368 10468323328 1078156832 11.630 95 27 54 0 1123302848 40082048 5063968
06:49:23 71330 8192000000 10076771184 10548916224 1119237056 12.020 99 11 22 0 1186914960 64669024 3008880
06:51:55 55246 8192000000 10111738704 10559811584 1109661984 11.929 99 11 23 0 1200838640 88562432 2614224
06:53:51 36818 8192000000 10063377232 10586386432 1098475984 11.823 98 7 14 1 1185834448 85675424 1683040
06:55:35 52076 8192000000 10067302448 10528600064 1092664944 11.768 98 32 63 1 1140392816 40986592 6741280
06:57:28 49969 8192000000 10049479888 10510299136 1071777120 11.569 96 16 31 1 1119226912 44319520 3130272
06:59:18 63168 8192000000 10043522576 10516942848 1092714048 11.768 98 28 55 0 1191927632 93923392 5290192
07:01:26 44336 8192000000 10080410528 10508636160 1070579248 11.558 95 30 60 0 1120868384 44376896 5912240
07:03:16 112834 8192000000 10073181728 10555711488 1119083408 12.018 99 14 28 1 1212814992 90725392 3006192
07:06:10 47932 8192000000 10130756512 10608865280 1123258992 12.058 99 19 38 0 1237627200 110172864 4195344
07:08:04 88678 8192000000 10059032816 10492203008 1106673760 11.901 98 24 47 0 1129384336 17262576 5448000
07:10:39 64534 8192000000 10069264496 10540511232 1133045216 12.150 100 16 30 3 1180790592 44176320 3569056
07:12:46 57697 8192000000 10057635232 10492137472 1108408560 11.917 98 21 42 0 1149264752 36636160 4220032
07:14:45 93520 8192000000 10089735968 10585784320 1140056944 12.216 100 13 26 0 1188055200 45352848 2645408
07:17:18 43831 8192000000 10085067344 10527567872 1113546336 11.966 98 28 56 2 1146930384 27675040 5709008
07:19:09 31267 8192000000 10090192784 10531770368 1133083104 12.150 100 28 56 1 1177645952 38599520 5963328
07:20:46 74956 8192000000 10063718352 10532646912 1095401568 11.794 97 14 28 0 1132552160 34423776 2726816
07:23:01 43494 8192000000 10075913248 10508361728 1139800144 12.214 100 34 68 1 1189339632 43254992 6284496
07:24:53 17138 8192000000 10107980384 10536820736 1120897168 12.035 98 48 96 0 1185992528 55607328 9488032
07:26:13 37388 8192000000 10107646048 10543579136 1122597936 12.052 99 40 80 0 1200643824 69776944 8268944
07:28:36 99082 8192000000 10090135200 10608865280 1107543792 11.909 97 6 12 0 1180855888 72185792 1126304
07:31:15 19429 8192000000 10034636688 10474303488 1078206592 11.630 95 16 32 0 1107331808 25602128 3523088
07:32:35 32224 8192000000 10085387536 10519134208 1126610304 12.089 100 44 88 1 1169767392 34085168 9071920
07:34:17 48003 8192000000 10121984576 10559193088 1099236336 11.830 97 17 34 0 1169794592 66454688 4103568
07:36:06 42179 8192000000 10128294672 10600345600 1126217136 12.086 99 18 36 1 1209166192 78993216 3955840
07:37:49 66841 8192000000 10105409760 10649325568 1102842720 11.865 97 13 26 0 1215924768 110212272 2869776
07:39:56 47189 8192000000 10064931632 10501558272 1081203008 11.659 96 32 61 1 1112257664 24150448 6904208
07:41:45 49068 8192000000 10094988112 10543943680 1088118640 11.725 97 35 70 0 1146942496 51808896 7014960
07:43:36 147338 8192000000 10114868976 10568818688 1095849760 11.798 98 27 54 3 1162465328 60617184 5998384
07:47:04 116073 8192000000 10111263664 10610073600 1113980528 11.970 99 12 24 0 1231728832 114829504 2918800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-08-2021 07:51:56 to 14-08-2021 07:52:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 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:02, from 14-08-2021 07:52:12 to 14-08-2021 07:52:14.

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          242183
MON$OLDEST_ACTIVE               244819
MON$OLDEST_SNAPSHOT             244819
MON$NEXT_TRANSACTION            244819
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                       2045280
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             821
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Aug 14 07:49:26 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Aug 14 07:49:34 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Aug 14 07:49:43 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sat Aug 14 07:49:49 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Sat Aug 14 07:49:51 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
--- end of diff output ---

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

14.08.21 07:52:15. 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-08-14 07:52:17 No severe PSQL-related problems occured
14.08.21 07:52:18. End of report.