Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.03.2021 07:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.26
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 16947352 kB
MemAvailable 31572780 kB
Buffers 0 kB
Cached 14675692 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-03-07 07:26:56
DTS_END                         2021-03-07 07:26:56.2480
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/42056
TRN_ID                          232853
ATT_ID                          658
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: 8845.08 25974 1061410
customer order: creation 167.28 448 20074
customer order: refuse 14.30 401 1716
order to supplier: creation 44.31 1426 5317
order to supplier: removal 3.29 137521 395
invoice (draft): creation 44.37 3875 5324
invoice (draft): removal 49.98 1103 5997
invoice accept: apply 39.62 17975 4754
invoice accept: cancel 45.38 1087 5445
customer reserve: creation 3696.16 173 443539
customer reserve: removal 4462.72 82 535526
realization accept: apply 79.43 178 9531
realization accept: cancel 75.55 135 9066
payment from customer: creation 56.52 105 6782
payment from customer: removal 5.53 93 663
payment to supplier: creation 47.03 112 5644
payment to supplier: removal 3.62 110 434
service: total inventory turnovers 3.44 4083 413
service: total monetary turnovers 5.63 677 676
service: aggregate perf. data 0.92 15223 110
service: refresh index statistics 0.03 334666 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-03-2021 07:28:12 to 07-03-2021 07:28:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1442
TEST_TIME 2 1727
TEST_TIME 3 2474
TEST_TIME 4 3678
TEST_TIME 5 4115
TEST_TIME 6 3910
TEST_TIME 7 4277
TEST_TIME 8 4554
TEST_TIME 9 4447
TEST_TIME 10 4562
TEST_TIME 11 4933
TEST_TIME 12 5075
TEST_TIME 13 5270
TEST_TIME 14 5525
TEST_TIME 15 5716
TEST_TIME 16 5783
TEST_TIME 17 6275
TEST_TIME 18 6424
TEST_TIME 19 6616
TEST_TIME 20 6867
TEST_TIME 21 7126
TEST_TIME 22 7568
TEST_TIME 23 7789
TEST_TIME 24 7692
TEST_TIME 25 7763
TEST_TIME 26 7985
TEST_TIME 27 8414
TEST_TIME 28 8959
TEST_TIME 29 10157
TEST_TIME 30 10463
TEST_TIME 31 10634
TEST_TIME 32 10464
TEST_TIME 33 10527
TEST_TIME 34 10572
TEST_TIME 35 10437
TEST_TIME 36 10232
TEST_TIME 37 9999
TEST_TIME 38 9921
TEST_TIME 39 9864
TEST_TIME 40 9704
TEST_TIME 41 9638
TEST_TIME 42 9553
TEST_TIME 43 9485
TEST_TIME 44 9563
TEST_TIME 45 9585
TEST_TIME 46 9590
TEST_TIME 47 9435
TEST_TIME 48 9436
TEST_TIME 49 9343
TEST_TIME 50 9327
TEST_TIME 51 9305
TEST_TIME 52 9262
TEST_TIME 53 9340
TEST_TIME 54 9398
TEST_TIME 55 9453
TEST_TIME 56 9415
TEST_TIME 57 9516
TEST_TIME 58 9433
TEST_TIME 59 9441
TEST_TIME 60 9487
TEST_TIME 61 9687
TEST_TIME 62 9616
TEST_TIME 63 9476
TEST_TIME 64 9406
TEST_TIME 65 9357
TEST_TIME 66 9324
TEST_TIME 67 9266
TEST_TIME 68 9255
TEST_TIME 69 9195
TEST_TIME 70 9214
TEST_TIME 71 9154
TEST_TIME 72 9197
TEST_TIME 73 9186
TEST_TIME 74 9486
TEST_TIME 75 9480
TEST_TIME 76 9464
TEST_TIME 77 9413
TEST_TIME 78 9418
TEST_TIME 79 9504
TEST_TIME 80 9429
TEST_TIME 81 9322
TEST_TIME 82 9289
TEST_TIME 83 9274
TEST_TIME 84 9206
TEST_TIME 85 9175
TEST_TIME 86 9134
TEST_TIME 87 9104
TEST_TIME 88 9054
TEST_TIME 89 9047
TEST_TIME 90 9022
TEST_TIME 91 9055
TEST_TIME 92 9114
TEST_TIME 93 9098
TEST_TIME 94 9188
TEST_TIME 95 9179
TEST_TIME 96 9185
TEST_TIME 97 9197
TEST_TIME 98 9164
TEST_TIME 99 9138
TEST_TIME 100 9061
TEST_TIME 101 9013
TEST_TIME 102 8965
TEST_TIME 103 8898
TEST_TIME 104 8915
TEST_TIME 105 8923
TEST_TIME 106 8913
TEST_TIME 107 8928
TEST_TIME 108 8891
TEST_TIME 109 8912
TEST_TIME 110 8963
TEST_TIME 111 8998
TEST_TIME 112 8973
TEST_TIME 113 8983
TEST_TIME 114 8951
TEST_TIME 115 8888
TEST_TIME 116 8895
TEST_TIME 117 8870
TEST_TIME 118 8886
TEST_TIME 119 8862
TEST_TIME 120 8846

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-03-2021 07:28:15 to 07-03-2021 07:28:19.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1473842 1473842 0 0.00 0 18679 6 0 0
doc_list_biud 1473841 1473841 0 0.00 0 88204 37 0 0
sp_add_invoice_to_stock 4868 4754 114 2.34 44 194574 17976 0 114
sp_cancel_adding_invoice 5445 5445 0 0.00 4 60132 1087 0 0
sp_cancel_client_order 1716 1716 0 0.00 4 31398 401 0 0
sp_cancel_customer_reserve 535526 535526 0 0.00 1 59307 83 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 2 8331 93 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 7009 111 0 0
sp_cancel_supplier_invoice 6008 5997 11 0.18 1 88210 1104 0 11
sp_cancel_supplier_order 395 395 0 0.00 6 1268028 137521 0 0
sp_cancel_write_off 9066 9066 0 0.00 1 6215 135 0 0
sp_client_order 20074 20074 0 0.00 4 12928 449 0 0
sp_customer_reserve 443539 443539 0 0.00 0 124919 173 0 0
sp_fill_shopping_cart 30702 30702 0 0.00 1 18893 43 0 0
sp_fill_shopping_cart_clo_res 443538 443538 0 0.00 0 37410 17 0 0
sp_get_clo_for_invoice 4750 4750 0 0.00 0 36315 414 0 0
sp_kill_cost_storno 15608 15608 0 0.00 0 8326 67 0 0
sp_kill_qstorno_ret_qs2qd 1086510 1086510 0 0.00 0 88201 45 0 0
sp_kill_qty_storno 572426 572426 0 0.00 0 88204 95 0 0
sp_lock_selected_doc 19910 19910 0 0.00 0 7055 4 0 0
sp_make_cost_storno 26707 26707 0 0.00 0 17685 56 0 0
sp_multiply_rows_for_pdistr 26707 26707 0 0.00 0 8341 30 0 0
sp_multiply_rows_for_qdistr 20074 20074 0 0.00 0 12899 279 0 0
sp_pay_from_customer 6782 6782 0 0.00 4 9979 106 0 0
sp_pay_to_supplier 5644 5644 0 0.00 5 9637 113 0 0
sp_payment_common 12426 12426 0 0.00 1 5370 17 0 0
sp_qd_handle_on_cancel_clo 1716 1716 0 0.00 0 31392 372 0 0
sp_qd_handle_on_invoice_upd_sts 10195 10195 0 0.00 0 59839 450 0 0
sp_qd_handle_on_reserve_upd_sts 9531 9531 0 0.00 0 7406 46 0 0
sp_reserve_write_off 9531 9531 0 0.00 7 18747 179 0 0
sp_supplier_invoice 5324 5324 0 0.00 7 110944 3875 0 0
sp_supplier_order 5317 5317 0 0.00 6 79695 1427 0 0
srv_aggregate_perf_data 111 110 1 0.90 2522 189573 15224 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1603 142661 45124 0 0
srv_find_qd_qs_mism 1016377 1016377 0 0.00 0 10598 5 0 0
srv_make_invnt_saldo 413 413 0 0.00 51 108580 4083 0 0
srv_make_money_saldo 676 676 0 0.00 1 13100 677 0 0
srv_recalc_idx_stat 4 4 0 0.00 200839 497033 334667 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4754 4754 0 0.00 0 27968 188 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 3492 99 0 0
t$perf-norm:sp_cancel_client_order 1716 1716 0 0.00 0 9272 20 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 3625 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 212 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 10144 30 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 788 14 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 34883 1648 0 0
t$perf-norm:sp_cancel_write_off 939 939 0 0.00 0 1328 4 0 0
t$perf-norm:sp_client_order 20074 20074 0 0.00 0 43309 26 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 4647 16 0 0
t$perf-norm:sp_pay_from_customer 6782 6782 0 0.00 0 10144 13 0 0
t$perf-norm:sp_pay_to_supplier 5644 5644 0 0.00 0 3994 10 0 0
t$perf-norm:sp_reserve_write_off 9531 9531 0 0.00 0 35209 15 0 0
t$perf-norm:sp_supplier_invoice 5324 5324 0 0.00 0 5392 17 0 0
t$perf-norm:sp_supplier_order 5317 5317 0 0.00 0 8621 19 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 580 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 94 2 0 0
t$perf-norm:srv_make_invnt_saldo 413 413 0 0.00 0 3154 14 0 0
t$perf-norm:srv_make_money_saldo 676 676 0 0.00 0 5120 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 5 0 0
v_add_invoice_to_stock 9728 9500 228 2.34 0 5236 18 0 228
v_all_customers 36432 36432 0 0.00 0 22 1 0 0
v_all_suppliers 29474 29474 0 0.00 0 21 1 0 0
v_all_wares 40142 40142 0 0.00 0 100 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 6601 15 0 0
v_cancel_client_order 3432 3432 0 0.00 0 3677 6 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 3839 13 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 4305 12 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 5204 10 0 0
v_cancel_supplier_invoice 848 826 22 2.59 0 4716 29 0 22
v_cancel_supplier_order 790 790 0 0.00 0 3728 13 0 0
v_cancel_write_off 1878 1878 0 0.00 0 2755 17 0 0
v_max_id_clo_ord 5315 5315 0 0.00 0 684 1 0 0
v_max_id_clo_res 959 959 0 0.00 0 2512 12 0 0
v_max_id_ord_sup 5312 5312 0 0.00 0 10261 8 0 0
v_max_non_paid_invoice 1533 1533 0 0.00 0 851 3 0 0
v_max_non_paid_realizn 3631 3631 0 0.00 0 1017 3 0 0
v_min_id_clo_ord 5315 5315 0 0.00 0 1890 5 0 0
v_min_id_clo_res 959 959 0 0.00 0 3423 12 0 0
v_min_id_ord_sup 5311 5311 0 0.00 0 2380 4 0 0
v_min_non_paid_invoice 5644 5644 0 0.00 0 2847 10 0 0
v_min_non_paid_realizn 6782 6782 0 0.00 0 5366 11 0 0
v_our_firm 10012 10012 0 0.00 0 19 1 0 0
v_reserve_write_off 19062 19062 0 0.00 0 6616 7 0 0
x_lock_depdocs_on_canc_invoice 11029 11029 0 0.00 0 15121 107 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 9917 362 0 0
x_make_qstorno_1000_1200 5317 5317 0 0.00 2 79687 1350 0 0
x_make_qstorno_1200_2000 5324 5324 0 0.00 3 110935 3701 0 0
x_make_qstorno_2100_3300 443539 443539 0 0.00 0 124917 154 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
04:57:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
04:58:00 34371 8192000000 9717321120 10012123136 792332096 8.819 100 86 171 1 819796416 7153216 20311104
04:59:44 23573 8192000000 9751282016 10053537792 826826048 9.167 100 89 178 1 860652304 12427824 21398432
05:01:13 14351 8192000000 9796067440 10110996480 861194256 9.512 100 87 174 0 900674160 19060000 20419904
05:02:30 9024 8192000000 9870382096 10203230208 918433552 10.081 100 75 150 0 976695024 41947072 16314400
05:03:44 9483 8192000000 9925421408 10267471872 967150368 10.559 100 86 171 3 1037115744 52259248 17706128
05:04:57 5913 8192000000 9990324352 10342326272 989656688 10.778 100 89 178 1 1105395824 97794592 17944544
05:06:05 2503 8192000000 9984411168 10346893312 1022671136 11.098 100 84 168 4 1104386128 65070304 16644688
05:07:09 1488 8192000000 10024890336 10393137152 1053537824 11.395 100 86 172 2 1138736656 68019040 17179792
05:08:12 1272 8192000000 10042144480 10417795072 1072253328 11.574 100 96 192 0 1161385104 68840688 20291088
05:09:14 10269 8192000000 10016636800 10408128512 1075130720 11.601 100 32 64 1 1121224976 39130192 6964064
05:10:28 5680 8192000000 9996401264 10387800064 1085225840 11.697 100 28 57 2 1106363520 14531344 6606336
05:11:35 5806 8192000000 10006385168 10402734080 1091003200 11.752 100 33 67 0 1117625088 18936272 7685616
05:12:42 24767 8192000000 9995254784 10417774592 1093219248 11.773 100 37 74 4 1119844240 18112960 8512032
05:14:12 7675 8192000000 10024855328 10425659392 1098455456 11.823 100 40 74 1 1127698256 21485776 7757024
05:15:22 6327 8192000000 10036436944 10442629120 1103458112 11.870 100 43 83 2 1136785536 24287504 9039920
05:16:34 5323 8192000000 10027637408 10423619584 1092167360 11.763 100 56 112 2 1130138288 27025856 10945072
05:17:39 31994 8192000000 10014488224 10427154432 1082907024 11.675 99 26 52 0 1132381920 43725584 5749312
05:19:14 13190 8192000000 9963366896 10368864256 984597808 10.729 92 25 50 0 1013450944 22964256 5888880
05:20:29 9861 8192000000 9948502688 10340184064 1017486176 11.048 99 36 72 2 1048880608 23706816 7687616
05:21:39 3852 8192000000 9942250720 10313404416 1007353952 10.950 98 51 99 0 1033264992 15255952 10655088
05:22:46 25186 8192000000 9952369328 10352844800 1027360976 11.143 99 22 44 2 1062557552 30567616 4628960
05:24:15 4489 8192000000 9969852048 10363535360 1052534304 11.385 100 27 54 0 1078620368 20017984 6068080
05:25:21 3384 8192000000 9987127776 10377523200 1066344640 11.517 100 47 92 6 1090514576 13708688 10461248
05:26:29 9302 8192000000 10003019904 10407866368 1075663248 11.606 100 41 82 2 1099608816 14262544 9683024
05:27:38 85797 8192000000 10027993280 10435362816 1105697360 11.892 100 36 71 2 1131708704 18553824 7457520
05:30:15 19637 8192000000 10091787760 10500898816 1126112288 12.085 100 41 82 1 1193351536 59127936 8111312
05:31:52 7450 8192000000 10117577072 10526203904 1146674896 12.278 100 53 107 2 1224082336 64933456 12473984
05:33:07 14671 8192000000 10168468000 10601009152 1163870320 12.440 100 42 84 3 1288168272 115463296 8834656
05:34:26 13331 8192000000 10218649184 10643898368 1178089280 12.572 100 43 85 0 1320947856 133667664 9190912
05:35:44 16002 8192000000 10259135040 10676015104 1183760048 12.625 100 61 120 2 1361893248 164693408 13439792
05:37:03 6396 8192000000 10275814912 10695405568 1192511424 12.707 100 51 102 2 1388067104 183481920 12073760
05:38:14 3068 8192000000 10311658896 10723106816 1181676976 12.606 99 76 147 0 1412206912 213783920 16746016
05:39:23 8700 8192000000 10360124208 10776612864 1195186144 12.732 100 70 141 1 1461713744 250626000 15901600
05:40:44 1599 8192000000 10371790352 10795872256 1181683392 12.606 99 84 166 0 1473424768 271699616 20041760
05:41:52 12981 8192000000 10377054976 10793287680 1187192400 12.657 99 65 129 0 1478612768 276624672 14795696
05:43:12 1679 8192000000 10419392976 10824237056 1183665248 12.624 100 86 168 2 1517439360 314275392 19498720
05:44:19 2279 8192000000 10426360736 10830557184 1170389088 12.500 100 84 163 1 1524061776 335008656 18664032
05:45:26 3174 8192000000 10467860304 10869157888 1153678240 12.344 100 88 174 1 1562742272 388304224 20759808
05:46:37 2616 8192000000 10491736464 10903363584 1165462624 12.454 100 89 178 3 1588932176 401481040 21988512
05:47:44 9851 8192000000 10475159776 10915532800 1141432208 12.229 99 55 110 1 1552722576 397912320 13378048
05:49:01 20398 8192000000 10445533024 10874499072 1160482496 12.408 100 36 72 0 1542506800 372606848 9417456
05:50:34 5989 8192000000 10481373936 10906415104 1151144592 12.320 99 48 96 0 1567671120 404264192 12262336
05:51:42 9402 8192000000 10504589936 10935140352 1144634112 12.259 99 37 74 0 1599517424 444896816 9986496
05:52:54 11011 8192000000 10504805472 10972852224 1157204976 12.377 100 56 110 1 1616002272 444807520 13989776
05:54:17 26641 8192000000 10409353168 10862276608 1125060080 12.075 99 37 74 0 1490603888 355545536 9998272
05:55:54 32594 8192000000 10178156944 10694086656 1122781088 12.053 99 11 22 1 1254199520 128670160 2748272
05:57:38 53072 8192000000 10048857728 10477223936 1079167856 11.640 95 20 39 1 1105731808 22508784 4055168
05:59:33 81988 8192000000 10071499856 10556510208 1109392992 11.927 98 16 31 1 1193882560 81109568 3380000
06:01:55 59156 8192000000 10079161632 10534191104 1126907824 12.092 99 10 20 0 1183425104 54270592 2246688
06:04:01 34207 8192000000 10074155888 10508197888 1112650960 11.958 98 41 82 1 1158662288 37202656 8808672
06:05:36 58728 8192000000 10066604576 10502205440 1105877648 11.893 98 34 68 0 1150454752 37252304 7324800
06:07:36 21021 8192000000 10112057840 10556243968 1127665984 12.099 99 46 91 1 1199965200 62675984 9623232
06:09:07 52262 8192000000 10136627552 10606727168 1135900448 12.177 100 14 28 0 1237470176 97956752 3612976
06:11:00 30003 8192000000 10086666384 10543308800 1143952816 12.253 100 18 37 1 1201355072 53623552 3778704
06:12:32 84594 8192000000 10088830256 10539388928 1132988400 12.150 99 10 20 0 1180445840 45313568 2143872
06:15:04 46022 8192000000 10066934560 10518188032 1132423776 12.144 99 9 18 0 1176754016 42585680 1744560
06:16:52 43969 8192000000 10060761104 10517725184 1127302576 12.096 98 9 18 0 1155366064 26318208 1745280
06:18:36 48187 8192000000 10157705328 10594234368 1150680512 12.316 100 43 87 2 1242731952 82725376 9326064
06:20:28 134277 8192000000 10080343472 10544320512 1073005488 11.581 94 11 22 0 1114911088 39581872 2323728
06:23:43 41501 8192000000 10080341920 10508619776 1084961824 11.695 96 23 46 0 1116454864 26826224 4666816
06:25:25 47965 8192000000 10166805952 10636906496 1144170128 12.255 100 24 48 1 1272393952 122558720 5665104
06:27:26 59928 8192000000 10080935632 10596327424 1139053248 12.207 100 12 24 0 1204567632 63197664 2316720
06:29:32 80690 8192000000 10057728016 10509893632 1113610624 11.967 98 10 19 0 1141559184 26257264 1691296
06:31:53 73759 8192000000 10062767312 10544394240 1097150096 11.811 97 6 12 0 1138807232 40464256 1192880
06:34:31 74119 8192000000 10132893808 10569879552 1133825344 12.157 99 25 50 1 1195271168 56161440 5284384
06:36:47 52780 8192000000 10191910768 10627584000 1111883152 11.950 96 26 51 2 1238201568 120547312 5771104
06:38:41 74550 8192000000 10235164800 10734333952 1110890112 11.941 97 20 40 1 1317513248 201492224 5130912
06:40:57 87797 8192000000 10073553632 10499596288 1113966144 11.970 98 37 71 1 1155081776 34376368 6739264
06:43:26 17725 8192000000 10096267328 10516480000 1110008176 11.932 98 44 86 2 1159706048 40941472 8756400
06:44:46 59891 8192000000 10083124576 10536235008 1111142944 11.943 98 15 30 0 1169611936 55643424 2825568
06:46:50 80995 8192000000 10075526272 10511929344 1110945328 11.941 98 16 32 0 1158675984 44416192 3314464
06:49:21 61696 8192000000 10034377600 10505584640 1089913872 11.742 97 6 12 0 1116428256 25487792 1026592
06:53:22 84663 8192000000 10100864080 10546528256 1126022480 12.084 99 16 32 0 1185020560 55723008 3275072
06:55:49 31964 8192000000 10131986848 10574077952 1138070224 12.197 100 27 53 0 1241822912 97214272 6538416
06:57:29 81355 8192000000 10070853440 10529406976 1114019936 11.970 98 25 50 0 1233040112 113632704 5387472
06:59:51 73013 8192000000 10086485120 10549743616 1116631120 11.995 98 17 34 1 1175763120 55950544 3181456
07:02:46 27409 8192000000 10087792368 10529787904 1121799888 12.044 98 45 87 0 1175041824 43800416 9441520
07:04:18 27662 8192000000 10078015952 10519273472 1133401040 12.153 99 21 42 1 1156362816 18651984 4309792
07:05:46 104881 8192000000 10071658528 10541752320 1117169888 12.000 98 18 36 0 1163282160 42212896 3899376
07:08:32 86455 8192000000 10136839504 10586521600 1118002736 12.008 98 15 30 0 1178365248 56994032 3368480
07:10:58 48006 8192000000 10093405184 10547838976 1126697072 12.090 99 21 42 1 1205031680 74011872 4322736
07:12:57 34000 8192000000 10107230896 10561703936 1133092992 12.151 99 22 44 2 1195052864 56827792 5132080
07:14:37 91373 8192000000 10113161312 10571915264 1077840096 11.627 95 29 58 1 1161755104 77649392 6265616
07:17:11 58423 8192000000 10064461456 10518036480 1077280224 11.622 96 20 40 0 1114764608 33387776 4096608
07:19:11 16837 8192000000 10117905488 10545229824 1109747216 11.930 98 60 120 2 1185651504 63322880 12581408
07:20:31 142660 8192000000 10071430432 10523869184 1115430688 11.984 99 18 35 1 1182483808 63346992 3706128
07:23:58 57114 8192000000 10070311152 10581016576 1096692368 11.806 97 5 10 0 1141001552 43312944 996240
07:26:17 45876 8192000000 9091883136 9349304320 237668656 2.819 21 6 12 0 251837760 13062576 1106528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 114 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          233276
MON$OLDEST_ACTIVE               233277
MON$OLDEST_SNAPSHOT             233277
MON$NEXT_TRANSACTION            233277
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                       2097024
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             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:147: 
:148: mscs-test-161.budzdorov.ru    Sun Mar  7 07:27:07 2021
:149:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:150: 
--- end of diff output ---

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

07.03.21 07:28:45. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-03-07 07:28:47 No severe PSQL-related problems occured
07.03.21 07:28:49. End of report.