Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.08.2021 04:37
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 15820108 kB
MemAvailable 30906512 kB
Buffers 20 kB
Cached 15782496 kB
SwapCached 1868 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-24 04:37:07
DTS_END                         2021-08-24 04:37:07.4380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57846
TRN_ID                          227615
ATT_ID                          571
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: 9086.63 23298 1090395
customer order: creation 158.48 449 19017
customer order: refuse 13.88 312 1665
order to supplier: creation 43.45 1579 5214
order to supplier: removal 3.73 118709 448
invoice (draft): creation 42.60 3569 5112
invoice (draft): removal 54.24 993 6509
invoice accept: apply 39.33 19379 4719
invoice accept: cancel 49.13 944 5896
customer reserve: creation 3611.84 191 433421
customer reserve: removal 4797.72 73 575726
realization accept: apply 77.69 193 9323
realization accept: cancel 76.52 117 9182
payment from customer: creation 53.86 130 6463
payment from customer: removal 5.25 75 630
payment to supplier: creation 45.62 136 5474
payment to supplier: removal 3.27 102 392
service: total inventory turnovers 3.41 4068 409
service: total monetary turnovers 5.71 700 685
service: aggregate perf. data 0.88 17663 106
service: refresh index statistics 0.03 296586 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-08-2021 04:37:55 to 24-08-2021 04:37:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 13174
TEST_TIME 2 8327
TEST_TIME 3 7706
TEST_TIME 4 7492
TEST_TIME 5 7503
TEST_TIME 6 7414
TEST_TIME 7 7022
TEST_TIME 8 7370
TEST_TIME 9 7110
TEST_TIME 10 7404
TEST_TIME 11 7482
TEST_TIME 12 7542
TEST_TIME 13 7445
TEST_TIME 14 7433
TEST_TIME 15 7368
TEST_TIME 16 7942
TEST_TIME 17 7958
TEST_TIME 18 8073
TEST_TIME 19 8478
TEST_TIME 20 8828
TEST_TIME 21 8624
TEST_TIME 22 8744
TEST_TIME 23 9010
TEST_TIME 24 8973
TEST_TIME 25 9110
TEST_TIME 26 9023
TEST_TIME 27 9480
TEST_TIME 28 9624
TEST_TIME 29 11287
TEST_TIME 30 11902
TEST_TIME 31 11715
TEST_TIME 32 11530
TEST_TIME 33 11276
TEST_TIME 34 11059
TEST_TIME 35 11013
TEST_TIME 36 10930
TEST_TIME 37 10824
TEST_TIME 38 10617
TEST_TIME 39 10505
TEST_TIME 40 10361
TEST_TIME 41 10424
TEST_TIME 42 10272
TEST_TIME 43 10149
TEST_TIME 44 10097
TEST_TIME 45 10218
TEST_TIME 46 10330
TEST_TIME 47 10253
TEST_TIME 48 10177
TEST_TIME 49 10311
TEST_TIME 50 10211
TEST_TIME 51 10060
TEST_TIME 52 9932
TEST_TIME 53 9829
TEST_TIME 54 9706
TEST_TIME 55 9618
TEST_TIME 56 9683
TEST_TIME 57 9716
TEST_TIME 58 9760
TEST_TIME 59 9749
TEST_TIME 60 9682
TEST_TIME 61 9582
TEST_TIME 62 9476
TEST_TIME 63 9468
TEST_TIME 64 9394
TEST_TIME 65 9415
TEST_TIME 66 9452
TEST_TIME 67 9428
TEST_TIME 68 9384
TEST_TIME 69 9334
TEST_TIME 70 9395
TEST_TIME 71 9347
TEST_TIME 72 9369
TEST_TIME 73 9396
TEST_TIME 74 9374
TEST_TIME 75 9447
TEST_TIME 76 9442
TEST_TIME 77 9446
TEST_TIME 78 9563
TEST_TIME 79 9561
TEST_TIME 80 9449
TEST_TIME 81 9359
TEST_TIME 82 9300
TEST_TIME 83 9341
TEST_TIME 84 9287
TEST_TIME 85 9244
TEST_TIME 86 9211
TEST_TIME 87 9163
TEST_TIME 88 9108
TEST_TIME 89 9036
TEST_TIME 90 9066
TEST_TIME 91 9051
TEST_TIME 92 9045
TEST_TIME 93 9010
TEST_TIME 94 9055
TEST_TIME 95 9048
TEST_TIME 96 9102
TEST_TIME 97 9162
TEST_TIME 98 9144
TEST_TIME 99 9223
TEST_TIME 100 9210
TEST_TIME 101 9234
TEST_TIME 102 9276
TEST_TIME 103 9328
TEST_TIME 104 9301
TEST_TIME 105 9230
TEST_TIME 106 9233
TEST_TIME 107 9195
TEST_TIME 108 9149
TEST_TIME 109 9131
TEST_TIME 110 9078
TEST_TIME 111 9081
TEST_TIME 112 9068
TEST_TIME 113 9082
TEST_TIME 114 9107
TEST_TIME 115 9139
TEST_TIME 116 9115
TEST_TIME 117 9144
TEST_TIME 118 9173
TEST_TIME 119 9162
TEST_TIME 120 9097

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-08-2021 04:37:59 to 24-08-2021 04:38:02.

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 1492682 1492682 0 0.00 0 24085 7 0 0
doc_list_biud 1492676 1492676 0 0.00 0 80848 35 0 0
sp_add_invoice_to_stock 4831 4719 112 2.31 40 192947 19380 0 112
sp_cancel_adding_invoice 5896 5896 0 0.00 5 73765 944 0 0
sp_cancel_client_order 1665 1665 0 0.00 5 8415 312 0 0
sp_cancel_customer_reserve 575726 575726 0 0.00 1 80849 74 0 0
sp_cancel_pay_from_customer 630 630 0 0.00 3 1705 75 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 2 6116 102 0 0
sp_cancel_supplier_invoice 6516 6509 7 0.10 3 61875 994 0 7
sp_cancel_supplier_order 448 448 0 0.00 6 1248366 118710 0 0
sp_cancel_write_off 9182 9182 0 0.00 1 8899 117 0 0
sp_client_order 19017 19017 0 0.00 4 79087 449 0 0
sp_customer_reserve 433421 433421 0 0.00 0 94027 191 0 0
sp_fill_shopping_cart 29330 29330 0 0.00 1 15153 45 0 0
sp_fill_shopping_cart_clo_res 433406 433406 0 0.00 0 11016 14 0 0
sp_get_clo_for_invoice 4701 4701 0 0.00 0 46302 395 0 0
sp_kill_cost_storno 16100 16100 0 0.00 0 6331 63 0 0
sp_kill_qstorno_ret_qs2qd 1167591 1167591 0 0.00 0 80829 40 0 0
sp_kill_qty_storno 613448 613448 0 0.00 0 80847 85 0 0
sp_lock_selected_doc 19477 19477 0 0.00 0 6762 5 0 0
sp_make_cost_storno 25960 25960 0 0.00 0 17580 63 0 0
sp_multiply_rows_for_pdistr 25959 25959 0 0.00 0 18449 36 0 0
sp_multiply_rows_for_qdistr 19017 19017 0 0.00 0 79077 216 0 0
sp_pay_from_customer 6463 6463 0 0.00 4 18464 131 0 0
sp_pay_to_supplier 5474 5474 0 0.00 5 14839 136 0 0
sp_payment_common 11936 11936 0 0.00 1 8475 25 0 0
sp_qd_handle_on_cancel_clo 1665 1665 0 0.00 0 6020 280 0 0
sp_qd_handle_on_invoice_upd_sts 10595 10595 0 0.00 0 21858 399 0 0
sp_qd_handle_on_reserve_upd_sts 9323 9323 0 0.00 0 14473 54 0 0
sp_reserve_write_off 9323 9323 0 0.00 7 17592 193 0 0
sp_supplier_invoice 5112 5112 0 0.00 7 87569 3570 0 0
sp_supplier_order 5214 5214 0 0.00 5 66429 1579 0 0
srv_aggregate_perf_data 106 106 0 0.00 3071 219057 17663 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 923 140680 45522 0 0
srv_find_qd_qs_mism 1046595 1046595 0 0.00 0 24082 6 0 0
srv_make_invnt_saldo 409 409 0 0.00 61 122751 4069 0 0
srv_make_money_saldo 685 685 0 0.00 1 22370 701 0 0
srv_recalc_idx_stat 4 4 0 0.00 90941 488209 296586 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4719 4719 0 0.00 0 9883 165 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 1 12180 183 0 0
t$perf-norm:sp_cancel_client_order 1665 1665 0 0.00 0 8304 15 0 0
t$perf-norm:sp_cancel_customer_reserve 625 625 0 0.00 0 4355 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 630 630 0 0.00 0 2506 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 2464 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 3051 22 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 21910 1405 0 0
t$perf-norm:sp_cancel_write_off 931 931 0 0.00 0 626 5 0 0
t$perf-norm:sp_client_order 19017 19017 0 0.00 0 11256 20 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 985 5 0 0
t$perf-norm:sp_pay_from_customer 6463 6463 0 0.00 0 20340 15 0 0
t$perf-norm:sp_pay_to_supplier 5474 5474 0 0.00 0 6855 12 0 0
t$perf-norm:sp_reserve_write_off 9323 9323 0 0.00 0 8497 14 0 0
t$perf-norm:sp_supplier_invoice 5112 5112 0 0.00 0 4244 19 0 0
t$perf-norm:sp_supplier_order 5214 5214 0 0.00 0 8870 20 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 271 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 337 7 0 0
t$perf-norm:srv_make_invnt_saldo 409 409 0 0.00 0 4329 18 0 0
t$perf-norm:srv_make_money_saldo 685 685 0 0.00 0 5715 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9616 9392 224 2.32 0 6055 20 0 224
v_all_customers 34030 34030 0 0.00 0 48 1 0 0
v_all_suppliers 28746 28746 0 0.00 0 24 1 0 0
v_all_wares 38028 38028 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1830 11 0 0
v_cancel_client_order 3330 3330 0 0.00 0 3480 4 0 0
v_cancel_customer_prepayment 1260 1260 0 0.00 0 1313 7 0 0
v_cancel_customer_reserve 1250 1250 0 0.00 0 860 5 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 3226 11 0 0
v_cancel_supplier_invoice 758 744 14 1.84 0 2139 18 0 14
v_cancel_supplier_order 896 896 0 0.00 0 1766 6 0 0
v_cancel_write_off 1862 1862 0 0.00 0 971 13 0 0
v_max_id_clo_ord 5211 5211 0 0.00 0 479 1 0 0
v_max_id_clo_res 961 961 0 0.00 0 2956 16 0 0
v_max_id_ord_sup 5103 5103 0 0.00 0 4853 4 0 0
v_max_non_paid_invoice 1415 1415 0 0.00 0 1336 4 0 0
v_max_non_paid_realizn 3655 3655 0 0.00 0 575 2 0 0
v_min_id_clo_ord 5211 5211 0 0.00 0 2635 6 0 0
v_min_id_clo_res 961 961 0 0.00 0 6627 17 0 0
v_min_id_ord_sup 5103 5103 0 0.00 0 6417 8 0 0
v_min_non_paid_invoice 5474 5474 0 0.00 0 6341 14 0 0
v_min_non_paid_realizn 6462 6462 0 0.00 0 6706 19 0 0
v_our_firm 9610 9610 0 0.00 0 249 1 0 0
v_reserve_write_off 18646 18646 0 0.00 0 8390 6 0 0
x_lock_depdocs_on_canc_invoice 12033 12033 0 0.00 0 16872 115 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 9405 318 0 0
x_make_qstorno_1000_1200 5214 5214 0 0.00 2 66100 1495 0 0
x_make_qstorno_1200_2000 5112 5112 0 0.00 3 84783 3407 0 0
x_make_qstorno_2100_3300 433421 433421 0 0.00 0 94024 175 0 0

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

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
02:07:11 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 46687 8192000000 9731831344 10029568000 802775136 8.924 100 89 178 0 832768240 8196912 21796192
02:10:04 12220 8192000000 9767578784 10075766784 840832992 9.308 100 84 168 0 874673072 13830752 20009328
02:11:19 16987 8192000000 9815367040 10139832320 876522960 9.665 100 75 150 1 919571856 26284480 16764416
02:12:41 12995 8192000000 9893604736 10228572160 924732624 10.143 100 82 164 2 1003097072 60771648 17592800
02:13:58 9893 8192000000 9933969248 10278797312 969751744 10.584 100 88 176 3 1045300448 57236928 18311776
02:15:12 4260 8192000000 9986401984 10340413440 1007340800 10.950 100 90 179 0 1085858192 60246768 18270624
02:16:18 2086 8192000000 9994654400 10357862400 1032650976 11.194 100 76 152 0 1113042304 64768384 15622944
02:17:22 1658 8192000000 10039545904 10411073536 1060357392 11.460 100 93 184 2 1145939888 67142064 18440432
02:18:25 6187 8192000000 10039906656 10414600192 1070091136 11.553 100 63 124 0 1139570960 57280336 12199488
02:19:33 3017 8192000000 9999154528 10401128448 1076250752 11.612 100 44 86 0 1125774048 40559904 8963392
02:20:38 9351 8192000000 9999906352 10390364160 1084489808 11.690 100 36 72 1 1106253920 13437248 8326864
02:21:48 28286 8192000000 10009525216 10398031872 1092413520 11.766 100 51 103 3 1113654896 9276064 11965312
02:23:17 7341 8192000000 10004807920 10416672768 1092201776 11.764 100 39 73 3 1130895104 30552448 8140880
02:24:28 4032 8192000000 10019507760 10418212864 1099938288 11.837 100 54 109 3 1137143568 25246128 11959152
02:25:34 4762 8192000000 10024700016 10428354560 1103691744 11.873 100 43 82 3 1137420016 24525408 9202864
02:26:39 30457 8192000000 9993546000 10415419392 1056050592 11.419 96 22 45 2 1086788720 26131392 4606736
02:28:12 8607 8192000000 9969312384 10361712640 990630960 10.788 93 42 87 3 1036633856 36351968 9650928
02:29:21 3971 8192000000 9961574160 10346258432 1041066704 11.275 100 55 109 2 1070186848 16661552 12458592
02:30:26 59073 8192000000 9921621648 10318000128 1001922592 10.897 98 29 58 1 1036325792 28089472 6313728
02:32:27 9114 8192000000 9955102928 10343481344 1027466016 11.144 99 44 87 0 1053192640 15450224 10276400
02:33:37 6997 8192000000 9977568048 10366738432 1057180368 11.429 100 44 88 3 1090578528 22518112 10880048
02:34:45 7840 8192000000 9990429232 10385719296 1066425936 11.518 100 38 77 2 1089258928 14609840 8223152
02:35:53 24164 8192000000 9988055264 10399346688 1069229888 11.545 100 22 44 0 1107987296 33582720 5174688
02:37:20 5532 8192000000 10031401360 10426736640 1097370512 11.813 100 53 105 0 1138416048 29945216 11100320
02:38:44 7002 8192000000 10059067104 10460807168 1116500704 11.994 100 54 107 2 1170785152 43263504 11020944
02:39:54 26011 8192000000 10065674496 10479480832 1122570608 12.051 100 44 88 5 1189698160 58019712 9107840
02:41:27 9214 8192000000 10122505056 10529009664 1135033024 12.169 100 60 120 0 1225756304 78639440 12083840
02:42:39 4897 8192000000 10121074656 10533986304 1152029568 12.329 100 63 122 3 1233348496 68660128 12658800
02:43:47 5970 8192000000 10159264656 10572398592 1159581088 12.399 100 59 116 1 1270150816 98474848 12094880
02:44:56 9324 8192000000 10197098480 10612416512 1162900272 12.430 100 56 112 1 1303515632 127785056 12830304
02:46:09 21343 8192000000 10213346448 10638438400 1175787520 12.551 100 58 112 1 1311088800 122945952 12355328
02:47:34 5421 8192000000 10235103472 10656968704 1183827056 12.626 100 51 101 3 1338016128 143115936 11073136
02:48:45 11404 8192000000 10266560928 10678808576 1157096496 12.376 99 67 134 0 1360714480 188532992 15084992
02:50:01 6392 8192000000 10290970368 10700812288 1178951216 12.580 100 70 139 4 1399147200 204392288 15803696
02:51:12 7139 8192000000 10314410640 10724528128 1161050672 12.413 99 68 136 2 1404234496 227696576 15487248
02:52:25 3190 8192000000 10378352976 10790473728 1167147712 12.470 100 89 178 1 1483303584 295183360 20972512
02:53:35 1873 8192000000 10377367424 10779258880 1168803872 12.486 100 83 166 0 1473179600 285691984 18683744
02:54:42 1255 8192000000 10383920864 10784223232 1174261920 12.537 100 90 178 0 1481777376 285748848 21766608
02:55:49 923 8192000000 10417462992 10816389120 1159113280 12.395 100 92 183 1 1511458448 331630848 20714320
02:56:52 6323 8192000000 10409727248 10816503808 1144576416 12.259 99 65 130 1 1498366000 338022320 15767264
02:58:02 13292 8192000000 10440472112 10868326400 1169919168 12.496 100 49 98 2 1528205616 345938288 12348160
02:59:21 1192 8192000000 10525309968 10927411200 1180294528 12.593 100 89 176 3 1617171104 415595616 21280960
03:00:28 2416 8192000000 10475932512 10891927552 1184347648 12.631 100 65 131 1 1566732128 365591808 16792672
03:01:36 14576 8192000000 10552269520 10978676736 1183691728 12.625 100 43 86 1 1624024336 428645552 11687056
03:02:58 13502 8192000000 10537671824 10993303552 1167554464 12.474 100 39 78 0 1630450528 451973504 10922560
03:04:21 12633 8192000000 10580001488 11056488448 1137507520 12.192 100 40 80 1 1685850608 537106784 11236304
03:05:57 30876 8192000000 10428742368 10975772672 1125462128 12.079 100 22 44 1 1566054704 434204288 6388288
03:07:30 104994 8192000000 10078072592 10536312832 1101706416 11.854 97 16 31 2 1149903792 44970384 3226992
03:10:16 117197 8192000000 10078264256 10542829568 1091013424 11.752 96 22 44 0 1146176480 50702656 4460400
03:13:25 36940 8192000000 10073951216 10583326720 1113884768 11.969 99 10 20 0 1162629024 47211936 1532320
03:15:08 42367 8192000000 10069825328 10506395648 1129366384 12.115 100 27 55 0 1169382240 34593760 5422096
03:16:58 52224 8192000000 10107480176 10551271424 1140899072 12.224 100 44 84 2 1210141120 60825696 8416352
03:18:50 92592 8192000000 10099699712 10533109760 1100455360 11.842 96 23 45 1 1154206304 48880208 4870736
03:21:55 93909 8192000000 10088560832 10601861120 1111012912 11.942 98 5 10 0 1183379824 71459344 907568
03:24:30 50566 8192000000 10117246560 10566250496 1142313792 12.237 100 15 30 0 1201033808 55348480 3371536
03:26:21 88768 8192000000 10091673360 10540167168 1145212128 12.265 100 12 25 1 1187875920 40321600 2342192
03:28:54 66121 8192000000 10076529584 10511179776 1118447232 12.012 97 50 94 3 1147300288 17956112 10896944
03:31:08 94511 8192000000 10196597008 10641842176 1143840464 12.252 98 30 60 2 1238376880 88264624 6271792
03:34:04 39529 8192000000 10168706144 10677121024 1125804464 12.082 98 21 42 0 1281617472 150743872 5069136
03:36:12 30557 8192000000 10057733600 10525507584 1125703072 12.081 99 10 20 2 1162283264 34436608 2143584
03:37:44 51264 8192000000 10077339616 10540056576 1121105632 12.037 98 16 30 1 1150438960 26181520 3151808
03:39:39 61381 8192000000 10064736672 10538651648 1126989536 12.093 99 29 58 1 1178059696 45279840 5790320
03:41:41 58627 8192000000 10070835328 10565406720 1096494624 11.804 97 11 22 1 1167361712 68643392 2223696
03:43:41 36008 8192000000 10046544736 10495246336 1108013568 11.914 98 23 46 0 1129661344 16810272 4837504
03:45:17 38269 8192000000 10091941152 10537562112 1107059600 11.905 98 24 48 1 1167453360 55723936 4669824
03:47:05 53240 8192000000 10074878464 10568048640 1121614080 12.042 99 27 54 0 1180398192 53426224 5357888
03:49:21 84025 8192000000 10067837328 10547081216 1119760192 12.025 99 9 18 0 1174650480 53184112 1706176
03:51:46 52949 8192000000 10059435248 10555478016 1097888480 11.818 97 13 26 0 1172011440 71403088 2719872
03:53:47 57958 8192000000 10088880352 10515279872 1098761280 11.826 97 35 70 0 1174076416 68493520 6821616
03:55:46 95375 8192000000 10069648720 10565386240 1113200848 11.963 98 12 24 1 1179320896 63547664 2572384
03:58:26 55561 8192000000 10081695216 10525499392 1102972816 11.866 97 24 48 1 1166059936 58343424 4743696
04:00:27 64207 8192000000 10055769968 10545414144 1101319184 11.850 98 10 20 0 1135839472 32382624 2137664
04:02:33 39905 8192000000 10042720752 10496159744 1116953024 11.998 99 10 19 0 1140632272 21827344 1851904
04:04:15 57008 8192000000 10066718336 10538078208 1121496480 12.041 99 23 46 1 1158609632 32545472 4567680
04:06:12 70970 8192000000 10078099344 10565558272 1125791456 12.082 99 16 32 0 1173542432 44937024 2813952
04:08:23 68568 8192000000 10023868912 10484699136 1039715600 11.262 92 9 18 0 1070015616 28435488 1864528
04:10:42 24281 8192000000 10120407664 10556252160 1100978080 11.847 97 42 81 2 1172930608 63724880 8227648
04:12:07 62848 8192000000 10106380752 10550046720 1096429008 11.804 97 25 51 0 1213485680 111648672 5408000
04:14:20 92637 8192000000 10099867952 10545819648 1110565392 11.938 98 21 42 0 1158812016 42797408 5449216
04:16:53 47868 8192000000 10114468304 10549903360 1111049760 11.942 97 39 76 1 1175539552 56902144 7587648
04:18:47 96152 8192000000 10106186608 10605338624 1108354512 11.917 97 22 44 2 1170969376 58158368 4456496
04:21:25 140679 8192000000 10064695824 10564677632 1123181872 12.057 98 23 44 0 1166032240 37422016 5428352
04:26:00 70416 8192000000 10092453840 10591653888 1159527136 12.399 100 12 25 1 1212110208 49895920 2687152
04:28:17 71285 8192000000 10111338608 10574577664 1132988864 12.150 98 20 39 3 1180453552 43627680 3837008
04:30:30 66708 8192000000 10118605120 10616213504 1138450304 12.201 98 17 34 2 1201427328 59278704 3698320
04:32:42 117056 8192000000 10079234224 10565120000 1102908480 11.865 95 16 32 1 1170713744 64502384 3302880
04:35:53 46061 8192000000 10101450016 10550358016 1130030400 12.122 98 28 56 3 1196649888 60711360 5908128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-08-2021 04:38:09 to 24-08-2021 04:38:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-08-2021 04:38:25 to 24-08-2021 04:38:27.

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          224906
MON$OLDEST_ACTIVE               227724
MON$OLDEST_SNAPSHOT             227724
MON$NEXT_TRANSACTION            227724
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                       2075040
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 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 ---
:201: 
:202: mscs-test-161.budzdorov.ru    Tue Aug 24 04:37:08 2021
:203:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:204: 
--- end of diff output ---

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

24.08.21 04:38:28. 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-24 04:38:31 No severe PSQL-related problems occured
24.08.21 04:38:32. End of report.