Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.11.2021 10: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 16000712 kB
MemAvailable 30769548 kB
Buffers 0 kB
Cached 15685808 kB
SwapCached 2456 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-11-10 10:37:08
DTS_END                         2021-11-10 10:37:08.7890
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39422
TRN_ID                          239587
ATT_ID                          662
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: 9002.89 24688 1080347
customer order: creation 174.00 417 20880
customer order: refuse 14.53 351 1743
order to supplier: creation 47.09 1350 5651
order to supplier: removal 3.36 105275 403
invoice (draft): creation 46.37 3537 5564
invoice (draft): removal 49.79 854 5975
invoice accept: apply 42.04 19117 5045
invoice accept: cancel 45.33 887 5439
customer reserve: creation 3815.63 193 457875
customer reserve: removal 4468.77 64 536252
realization accept: apply 84.24 181 10109
realization accept: cancel 80.18 119 9621
payment from customer: creation 60.69 102 7283
payment from customer: removal 6.13 96 736
payment to supplier: creation 49.97 119 5996
payment to supplier: removal 3.65 104 438
service: total inventory turnovers 3.98 3297 477
service: total monetary turnovers 6.24 531 749
service: aggregate perf. data 0.88 15966 106
service: refresh index statistics 0.04 341205 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-11-2021 10:38:21 to 10-11-2021 10:38:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7636
TEST_TIME 2 6151
TEST_TIME 3 6694
TEST_TIME 4 6183
TEST_TIME 5 5826
TEST_TIME 6 6314
TEST_TIME 7 6596
TEST_TIME 8 6466
TEST_TIME 9 7056
TEST_TIME 10 7085
TEST_TIME 11 7041
TEST_TIME 12 7170
TEST_TIME 13 7311
TEST_TIME 14 7428
TEST_TIME 15 7553
TEST_TIME 16 8306
TEST_TIME 17 8487
TEST_TIME 18 8875
TEST_TIME 19 8850
TEST_TIME 20 8943
TEST_TIME 21 9143
TEST_TIME 22 9360
TEST_TIME 23 9267
TEST_TIME 24 9820
TEST_TIME 25 10591
TEST_TIME 26 10650
TEST_TIME 27 10613
TEST_TIME 28 10622
TEST_TIME 29 10479
TEST_TIME 30 10280
TEST_TIME 31 10113
TEST_TIME 32 9876
TEST_TIME 33 9886
TEST_TIME 34 9831
TEST_TIME 35 9822
TEST_TIME 36 9822
TEST_TIME 37 9666
TEST_TIME 38 9501
TEST_TIME 39 9442
TEST_TIME 40 9378
TEST_TIME 41 9792
TEST_TIME 42 9701
TEST_TIME 43 9657
TEST_TIME 44 9790
TEST_TIME 45 9750
TEST_TIME 46 9744
TEST_TIME 47 9713
TEST_TIME 48 10015
TEST_TIME 49 9966
TEST_TIME 50 9947
TEST_TIME 51 9893
TEST_TIME 52 9801
TEST_TIME 53 9679
TEST_TIME 54 9586
TEST_TIME 55 9509
TEST_TIME 56 9639
TEST_TIME 57 9525
TEST_TIME 58 9482
TEST_TIME 59 9404
TEST_TIME 60 9309
TEST_TIME 61 9409
TEST_TIME 62 9373
TEST_TIME 63 9502
TEST_TIME 64 9548
TEST_TIME 65 9602
TEST_TIME 66 9671
TEST_TIME 67 9693
TEST_TIME 68 9654
TEST_TIME 69 9687
TEST_TIME 70 9654
TEST_TIME 71 9695
TEST_TIME 72 9608
TEST_TIME 73 9545
TEST_TIME 74 9470
TEST_TIME 75 9450
TEST_TIME 76 9379
TEST_TIME 77 9358
TEST_TIME 78 9282
TEST_TIME 79 9240
TEST_TIME 80 9261
TEST_TIME 81 9305
TEST_TIME 82 9305
TEST_TIME 83 9270
TEST_TIME 84 9272
TEST_TIME 85 9243
TEST_TIME 86 9333
TEST_TIME 87 9418
TEST_TIME 88 9423
TEST_TIME 89 9421
TEST_TIME 90 9362
TEST_TIME 91 9298
TEST_TIME 92 9247
TEST_TIME 93 9234
TEST_TIME 94 9180
TEST_TIME 95 9191
TEST_TIME 96 9153
TEST_TIME 97 9184
TEST_TIME 98 9184
TEST_TIME 99 9142
TEST_TIME 100 9158
TEST_TIME 101 9201
TEST_TIME 102 9212
TEST_TIME 103 9269
TEST_TIME 104 9259
TEST_TIME 105 9274
TEST_TIME 106 9298
TEST_TIME 107 9267
TEST_TIME 108 9309
TEST_TIME 109 9287
TEST_TIME 110 9228
TEST_TIME 111 9218
TEST_TIME 112 9186
TEST_TIME 113 9151
TEST_TIME 114 9095
TEST_TIME 115 9105
TEST_TIME 116 9082
TEST_TIME 117 9073
TEST_TIME 118 9040
TEST_TIME 119 9071
TEST_TIME 120 9009

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-11-2021 10:38:25 to 10-11-2021 10:38:28.

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 1503991 1503991 0 0.00 0 28859 7 0 0
doc_list_biud 1503989 1503989 0 0.00 0 65251 29 0 0
sp_add_invoice_to_stock 5143 5045 98 1.90 33 210280 19117 0 98
sp_cancel_adding_invoice 5439 5439 0 0.00 5 69919 887 0 0
sp_cancel_client_order 1743 1743 0 0.00 4 15324 351 0 0
sp_cancel_customer_reserve 536252 536252 0 0.00 1 65252 64 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 3 13503 97 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 6421 104 0 0
sp_cancel_supplier_invoice 5980 5975 5 0.08 2 25448 855 0 5
sp_cancel_supplier_order 403 403 0 0.00 6 916686 105276 0 0
sp_cancel_write_off 9621 9621 0 0.00 1 19499 120 0 0
sp_client_order 20880 20880 0 0.00 4 50732 418 0 0
sp_customer_reserve 457875 457875 0 0.00 0 49976 194 0 0
sp_fill_shopping_cart 32087 32087 0 0.00 1 17881 42 0 0
sp_fill_shopping_cart_clo_res 457866 457866 0 0.00 0 17080 17 0 0
sp_get_clo_for_invoice 5036 5036 0 0.00 0 35592 363 0 0
sp_kill_cost_storno 16234 16234 0 0.00 0 6413 53 0 0
sp_kill_qstorno_ret_qs2qd 1088503 1088503 0 0.00 0 65216 35 0 0
sp_kill_qty_storno 574578 574578 0 0.00 0 65250 74 0 0
sp_lock_selected_doc 21009 21009 0 0.00 0 7514 6 0 0
sp_make_cost_storno 28424 28424 0 0.00 0 15855 54 0 0
sp_multiply_rows_for_pdistr 28424 28424 0 0.00 0 9321 31 0 0
sp_multiply_rows_for_qdistr 20880 20880 0 0.00 0 50722 238 0 0
sp_pay_from_customer 7283 7283 0 0.00 4 18913 103 0 0
sp_pay_to_supplier 5996 5996 0 0.00 5 19733 119 0 0
sp_payment_common 13279 13279 0 0.00 1 12359 21 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 15316 320 0 0
sp_qd_handle_on_invoice_upd_sts 10475 10475 0 0.00 0 16255 313 0 0
sp_qd_handle_on_reserve_upd_sts 10109 10109 0 0.00 0 6703 51 0 0
sp_reserve_write_off 10109 10109 0 0.00 7 15827 181 0 0
sp_supplier_invoice 5564 5564 0 0.00 8 149727 3538 0 0
sp_supplier_order 5651 5651 0 0.00 6 23463 1351 0 0
srv_aggregate_perf_data 106 106 0 0.00 2828 256386 15966 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1346 107836 44329 0 0
srv_find_qd_qs_mism 1032064 1032064 0 0.00 0 28480 6 0 0
srv_make_invnt_saldo 477 477 0 0.00 64 140081 3298 0 0
srv_make_money_saldo 749 749 0 0.00 1 16317 531 0 0
srv_recalc_idx_stat 5 5 0 0.00 143372 486638 341205 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5045 5045 0 0.00 0 7864 145 0 0
t$perf-norm:sp_cancel_adding_invoice 456 456 0 0.00 0 2803 79 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 4412 14 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 7179 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 2425 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 672 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 1104 14 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 13185 1359 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 1910 7 0 0
t$perf-norm:sp_client_order 20880 20880 0 0.00 0 7824 16 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 520 3 0 0
t$perf-norm:sp_pay_from_customer 7283 7283 0 0.00 0 12391 10 0 0
t$perf-norm:sp_pay_to_supplier 5996 5996 0 0.00 0 10193 9 0 0
t$perf-norm:sp_reserve_write_off 10109 10109 0 0.00 0 7838 9 0 0
t$perf-norm:sp_supplier_invoice 5564 5564 0 0.00 0 10706 19 0 0
t$perf-norm:sp_supplier_order 5651 5651 0 0.00 0 7832 15 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 1902 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 151 3 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 7545 34 0 0
t$perf-norm:srv_make_money_saldo 749 749 0 0.00 0 7662 16 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10266 10070 196 1.90 0 5796 19 0 196
v_all_customers 37830 37830 0 0.00 0 90 1 0 0
v_all_suppliers 30948 30948 0 0.00 0 69 1 0 0
v_all_wares 41760 41760 0 0.00 0 126 1 0 0
v_cancel_adding_invoice 912 912 0 0.00 0 2899 16 0 0
v_cancel_client_order 3486 3486 0 0.00 0 2907 6 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 2602 7 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 3302 11 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 3958 15 0 0
v_cancel_supplier_invoice 768 758 10 1.30 0 1864 19 0 10
v_cancel_supplier_order 806 806 0 0.00 0 1574 5 0 0
v_cancel_write_off 1992 1992 0 0.00 0 3038 16 0 0
v_max_id_clo_ord 5651 5651 0 0.00 0 2154 2 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 2571 19 0 0
v_max_id_ord_sup 5556 5556 0 0.00 0 8391 5 0 0
v_max_non_paid_invoice 1729 1729 0 0.00 0 2016 4 0 0
v_max_non_paid_realizn 3944 3944 0 0.00 0 2243 4 0 0
v_min_id_clo_ord 5651 5651 0 0.00 0 6826 9 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 7687 23 0 0
v_min_id_ord_sup 5556 5556 0 0.00 0 5553 7 0 0
v_min_non_paid_invoice 5996 5996 0 0.00 0 5435 12 0 0
v_min_non_paid_realizn 7283 7283 0 0.00 0 5165 14 0 0
v_our_firm 10608 10608 0 0.00 0 15 1 0 0
v_reserve_write_off 20218 20218 0 0.00 0 5833 8 0 0
x_lock_depdocs_on_canc_invoice 11035 11035 0 0.00 0 17340 107 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 23444 358 0 0
x_make_qstorno_1000_1200 5651 5651 0 0.00 2 23450 1268 0 0
x_make_qstorno_1200_2000 5564 5564 0 0.00 4 149718 3386 0 0
x_make_qstorno_2100_3300 457875 457875 0 0.00 0 49974 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-11-2021 10:38:29 to 10-11-2021 10:38:33.

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
08:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:14 26029 8192000000 9716844832 10015150080 796614416 8.862 100 89 179 0 825743984 7378640 21750928
08:09:48 30846 8192000000 9766362256 10074972160 836819232 9.268 100 91 182 2 870543232 12156608 21567392
08:11:22 12854 8192000000 9802908864 10125680640 873659872 9.637 100 78 157 0 913472272 21973504 17838896
08:12:38 13899 8192000000 9874284976 10208571392 923811728 10.134 100 83 168 2 982993856 41129984 18052144
08:13:55 6972 8192000000 9939235888 10283577344 964346736 10.532 100 79 157 1 1046897632 66139312 16411584
08:15:05 6257 8192000000 9974268032 10328285184 997976176 10.859 100 83 167 1 1083470656 68930784 16563696
08:16:12 3184 8192000000 9984979424 10347212800 1023504848 11.106 100 83 166 0 1097474272 57612448 16356976
08:17:18 2232 8192000000 9997799504 10368536576 1045035920 11.313 100 79 158 1 1116834752 55561984 16236848
08:18:22 10124 8192000000 10021127296 10394595328 1055387584 11.412 100 77 151 1 1137119952 65256720 16475648
08:19:33 5688 8192000000 9994698032 10375766016 1063890896 11.494 100 54 108 0 1108136208 32575968 11669344
08:20:39 5256 8192000000 9984986432 10377768960 1072352592 11.575 100 25 48 0 1116073856 38948640 4772624
08:21:47 31266 8192000000 9984411632 10394562560 1076014384 11.609 100 40 78 2 1102147792 17122512 9010896
08:23:20 3161 8192000000 10015070672 10399608832 1085709824 11.702 100 59 117 0 1125548784 26722304 13116656
08:24:25 7390 8192000000 10014246624 10417111040 1091135104 11.753 100 29 59 1 1115619840 18018960 6465776
08:25:33 61576 8192000000 9993770512 10400174080 1086618048 11.710 99 31 62 2 1107081488 13962336 6501104
08:27:36 2925 8192000000 10008193216 10395963392 1085018960 11.695 100 66 130 2 1117972784 19732320 13221504
08:28:44 7439 8192000000 9941850576 10328174592 986671904 10.749 94 28 55 0 1003175408 9688352 6815152
08:29:51 15111 8192000000 9920342832 10299105280 1010761504 10.983 98 38 72 3 1032245776 12713088 8771184
08:31:53 4049 8192000000 9931943504 10304888832 990790720 10.789 97 43 87 1 1015786528 15467360 9528448
08:32:58 7433 8192000000 9953340080 10346356736 1021264368 11.084 99 55 110 0 1054097328 20713200 12119760
08:34:07 3896 8192000000 9966394128 10350235648 1056528112 11.423 100 48 97 1 1078933520 12005536 10399872
08:35:14 23364 8192000000 9983708416 10381594624 1058147056 11.439 100 36 72 1 1097999648 32470000 7382592
08:36:41 5907 8192000000 9984812400 10379735040 1070386768 11.556 100 55 111 1 1097533648 14778160 12368720
08:37:54 22558 8192000000 10035916656 10426597376 1097709152 11.816 100 53 102 2 1132032944 23798928 10524864
08:39:23 17036 8192000000 10088290944 10481340416 1107486288 11.909 100 51 101 1 1189194192 70784736 10923168
08:40:53 26602 8192000000 10078152112 10482765824 1116626864 11.995 100 45 89 1 1183473120 56927088 9919168
08:42:30 3905 8192000000 10134161328 10531106816 1130469248 12.126 100 55 107 0 1234427616 92556304 11402064
08:43:39 6299 8192000000 10139292160 10548584448 1134985984 12.168 100 45 89 0 1243320256 98267600 10066672
08:45:04 7975 8192000000 10166295984 10573721600 1154762224 12.354 100 60 120 0 1272668496 104919104 12987168
08:46:16 5642 8192000000 10199052896 10624081920 1161846992 12.421 100 56 112 0 1309667904 135795888 12025024
08:47:29 5363 8192000000 10193198016 10609442816 1163787936 12.439 100 54 107 2 1296994464 120896640 12309888
08:48:38 13327 8192000000 10206864224 10620014592 1160423200 12.407 99 55 109 2 1308918736 136476832 12018704
08:49:55 1999 8192000000 10249480224 10647855104 1156239968 12.368 100 83 164 0 1351021136 175863008 18918160
08:50:59 3859 8192000000 10236771824 10633093120 1131870048 12.139 98 67 132 1 1335036000 188058960 15106992
08:52:10 4147 8192000000 10326103440 10730393600 1156926704 12.374 100 70 140 0 1424880528 251618128 16335696
08:53:16 1369 8192000000 10345062640 10750455808 1156093120 12.367 100 79 160 0 1448778464 274001120 18684224
08:54:22 3916 8192000000 10262766432 10666917888 1134472416 12.164 100 60 119 1 1363782976 215395728 13914832
08:55:32 1344 8192000000 10320901744 10720739328 1145047824 12.263 100 82 164 0 1418679248 254540128 19091296
08:56:39 9785 8192000000 10273324128 10690949120 1119185072 12.019 98 40 81 1 1357180720 228558384 9437264
08:58:05 21022 8192000000 10321159760 10745114624 1150546032 12.315 100 44 88 1 1419547360 258187024 10814304
08:59:39 27300 8192000000 10276031152 10711904256 1117452720 12.003 98 24 48 0 1366569424 243186512 5930192
09:01:08 23456 8192000000 10250833456 10716856320 1106690576 11.901 100 22 44 0 1389718256 276847808 6179872
09:02:33 41179 8192000000 10018012592 10493935616 1083425968 11.680 98 15 30 0 1202989680 116237088 3326624
09:04:17 38726 8192000000 10029812816 10460479488 1085680560 11.702 98 13 26 0 1106710480 18386624 2643296
09:05:57 75149 8192000000 10059597696 10488311808 1085383536 11.699 98 37 72 1 1117880944 25208768 7288640
09:08:13 107836 8192000000 10038474976 10461773824 1086748160 11.712 97 33 64 0 1149242480 54819232 7675088
09:11:02 35129 8192000000 10123970592 10575257600 1140998000 12.225 100 26 51 2 1224733440 77764736 5970704
09:12:38 16381 8192000000 10095458400 10558193664 1139542080 12.211 100 22 44 0 1208035888 63837040 4656768
09:13:56 62108 8192000000 10135165808 10586849280 1147195392 12.283 100 19 38 0 1230212208 78817632 4199184
09:15:58 40619 8192000000 10166743152 10644295680 1162123680 12.423 100 20 40 0 1292630576 125234992 5271904
09:17:50 49433 8192000000 10133973792 10667229184 1153323376 12.341 100 14 28 0 1283559184 126734624 3501184
09:19:49 53679 8192000000 10107370272 10552201216 1144973312 12.262 99 35 70 1 1208752640 56286032 7493296
09:21:48 44286 8192000000 10084950160 10571210752 1145043424 12.263 99 12 24 0 1194165840 46446208 2676208
09:23:33 65903 8192000000 10164988912 10609565696 1135345984 12.172 98 15 29 1 1201524432 62977920 3200528
09:25:40 78068 8192000000 10081388592 10530996224 1112823344 11.959 97 26 53 0 1157432496 39495184 5113968
09:27:59 64934 8192000000 10093359440 10564476928 1142360560 12.238 100 17 34 1 1197085152 51144656 3579936
09:30:04 74143 8192000000 10105598512 10560446464 1131632944 12.137 98 25 50 0 1159979344 23413984 4932416
09:32:40 16985 8192000000 10154069376 10590543872 1153877072 12.346 100 45 90 1 1249641024 85613728 10150224
09:34:04 26754 8192000000 10084093616 10556997632 1157628512 12.381 100 9 17 0 1187751056 28386704 1735840
09:35:33 64155 8192000000 10072010240 10512928768 1077865424 11.627 93 13 26 0 1147069856 66567840 2636592
09:37:53 100645 8192000000 10121623840 10617339904 1086340416 11.708 95 17 34 0 1192779728 103021744 3417568
09:40:53 50452 8192000000 10073679600 10527641600 1106994368 11.904 98 12 24 0 1163527008 54201584 2331056
09:42:53 41645 8192000000 10086764992 10533203968 1089868368 11.741 96 13 26 2 1143913872 51409168 2636336
09:44:41 57120 8192000000 10076076960 10511597568 1080932320 11.656 96 25 49 2 1126345808 40711840 4701648
09:46:39 38633 8192000000 10074691712 10502844416 1090870448 11.751 97 43 84 0 1150147664 51214544 8062672
09:48:18 55349 8192000000 10053989152 10486616064 1078649936 11.635 96 20 39 1 1150113264 67565840 3897488
09:50:14 44260 8192000000 10049478912 10494513152 1109234688 11.925 99 36 71 0 1143450800 26840416 7375696
09:52:01 37032 8192000000 10029516160 10473459712 1067265872 11.526 96 23 46 0 1113491120 41076592 5148656
09:53:39 58201 8192000000 10057678352 10514235392 1095489440 11.795 99 13 25 0 1168956192 70570416 2896336
09:55:37 72955 8192000000 10112038144 10570498048 1116565984 11.995 100 25 50 0 1219214240 96583360 6064896
09:58:10 87202 8192000000 10047309424 10482352128 1088104896 11.725 97 15 30 0 1149625440 58099296 3421248
10:00:40 51265 8192000000 10121542576 10577125376 1144821696 12.261 100 23 46 0 1204846064 54844528 5179840
10:02:33 62694 8192000000 10148251872 10640269312 1142742256 12.241 100 21 40 2 1284849168 137565968 4540944
10:04:39 85936 8192000000 10081785680 10586320896 1111739152 11.949 98 13 26 0 1189213616 74916016 2558448
10:07:06 17712 8192000000 10082244384 10504359936 1139277040 12.209 100 39 78 0 1169905792 23240880 7387872
10:08:27 105059 8192000000 10095226240 10544979968 1133053904 12.150 99 43 86 1 1208075376 66256416 8765056
10:11:16 73885 8192000000 10085734880 10560421888 1134709888 12.166 99 9 16 1 1186066784 49459200 1897696
10:13:30 55364 8192000000 10079007392 10556133376 1139195632 12.208 99 14 28 0 1178679760 36814736 2669392
10:15:30 67502 8192000000 10110565296 10557853696 1145028256 12.263 99 14 28 0 1184809648 37007856 2773536
10:17:38 55233 8192000000 10122274272 10562154496 1136566752 12.183 98 24 47 2 1200317408 59136320 4614336
10:19:42 90456 8192000000 10073926496 10527227904 1109575712 11.928 97 21 42 1 1196581104 82433296 4572096
10:22:16 54297 8192000000 10071100432 10513395712 1112788240 11.959 98 33 66 1 1164296768 45351504 6157024
10:24:11 71383 8192000000 10072841328 10567700480 1117212784 12.001 98 20 40 1 1171729712 50790208 3726720
10:26:24 63177 8192000000 10098560960 10538078208 1113564080 11.966 98 38 76 0 1152297680 30959616 7773984
10:29:39 48003 8192000000 10063018320 10545852416 1126213696 12.086 99 8 16 0 1175639408 47481488 1944224
10:32:19 68395 8192000000 10025490880 10499411968 1094474976 11.785 97 14 27 1 1152302048 54630528 3196544
10:34:29 50059 8192000000 10099842256 10543255552 1141408928 12.229 100 16 32 0 1186067904 41208960 3450016
10:36:19 55082 8192000000 9544284544 9915187200 628841424 7.129 55 12 22 1 673118304 41620224 2656656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-11-2021 10:38:35 to 10-11-2021 10:38:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-11-2021 10:38:51 to 10-11-2021 10:38:53.

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          236634
MON$OLDEST_ACTIVE               239852
MON$OLDEST_SNAPSHOT             239852
MON$NEXT_TRANSACTION            239852
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                       2035168
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2656 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2656 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2656 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2656 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 ---
--- end of diff output ---

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

10.11.21 10:38:54. 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-11-10 10:38:56 No severe PSQL-related problems occured
10.11.21 10:38:57. End of report.