Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.12.2021 13:39
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16555080 kB
MemAvailable 31502628 kB
Buffers 0 kB
Cached 15167072 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.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-12-12 13:37:05
DTS_END                         2021-12-12 13:37:05.8080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58206
TRN_ID                          229791
ATT_ID                          622
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: 9255.97 20933 1110716
customer order: creation 162.81 450 19537
customer order: refuse 14.06 321 1687
order to supplier: creation 42.91 1459 5149
order to supplier: removal 3.38 105441 406
invoice (draft): creation 43.88 3491 5265
invoice (draft): removal 53.76 819 6451
invoice accept: apply 40.17 18640 4820
invoice accept: cancel 48.57 725 5828
customer reserve: creation 3769.63 183 452355
customer reserve: removal 4784.80 59 574176
realization accept: apply 79.46 187 9535
realization accept: cancel 88.43 124 10612
payment from customer: creation 58.11 112 6973
payment from customer: removal 5.40 76 648
payment to supplier: creation 47.05 123 5646
payment to supplier: removal 3.26 75 391
service: total inventory turnovers 3.73 3137 448
service: total monetary turnovers 5.64 461 677
service: aggregate perf. data 0.90 15219 108
service: refresh index statistics 0.03 267551 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-12-2021 13:39:58 to 12-12-2021 13:40:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4656
TEST_TIME 2 5409
TEST_TIME 3 5211
TEST_TIME 4 4932
TEST_TIME 5 4871
TEST_TIME 6 4888
TEST_TIME 7 5330
TEST_TIME 8 5745
TEST_TIME 9 6178
TEST_TIME 10 6687
TEST_TIME 11 7218
TEST_TIME 12 7645
TEST_TIME 13 7561
TEST_TIME 14 8105
TEST_TIME 15 8219
TEST_TIME 16 8852
TEST_TIME 17 8944
TEST_TIME 18 9595
TEST_TIME 19 9788
TEST_TIME 20 9938
TEST_TIME 21 10571
TEST_TIME 22 10464
TEST_TIME 23 10633
TEST_TIME 24 10914
TEST_TIME 25 10855
TEST_TIME 26 11461
TEST_TIME 27 11103
TEST_TIME 28 10867
TEST_TIME 29 10708
TEST_TIME 30 10562
TEST_TIME 31 10383
TEST_TIME 32 10347
TEST_TIME 33 10256
TEST_TIME 34 10297
TEST_TIME 35 10353
TEST_TIME 36 10204
TEST_TIME 37 10112
TEST_TIME 38 9895
TEST_TIME 39 9893
TEST_TIME 40 9791
TEST_TIME 41 9908
TEST_TIME 42 10120
TEST_TIME 43 10304
TEST_TIME 44 10184
TEST_TIME 45 10235
TEST_TIME 46 10171
TEST_TIME 47 10177
TEST_TIME 48 10212
TEST_TIME 49 10271
TEST_TIME 50 10228
TEST_TIME 51 10261
TEST_TIME 52 10158
TEST_TIME 53 10044
TEST_TIME 54 9965
TEST_TIME 55 9926
TEST_TIME 56 9941
TEST_TIME 57 9855
TEST_TIME 58 9782
TEST_TIME 59 9755
TEST_TIME 60 9615
TEST_TIME 61 9539
TEST_TIME 62 9626
TEST_TIME 63 9592
TEST_TIME 64 9594
TEST_TIME 65 9560
TEST_TIME 66 9607
TEST_TIME 67 9695
TEST_TIME 68 9703
TEST_TIME 69 9661
TEST_TIME 70 9888
TEST_TIME 71 9922
TEST_TIME 72 9989
TEST_TIME 73 9952
TEST_TIME 74 10013
TEST_TIME 75 9896
TEST_TIME 76 9812
TEST_TIME 77 9743
TEST_TIME 78 9757
TEST_TIME 79 9670
TEST_TIME 80 9620
TEST_TIME 81 9557
TEST_TIME 82 9504
TEST_TIME 83 9556
TEST_TIME 84 9610
TEST_TIME 85 9631
TEST_TIME 86 9583
TEST_TIME 87 9588
TEST_TIME 88 9605
TEST_TIME 89 9678
TEST_TIME 90 9711
TEST_TIME 91 9693
TEST_TIME 92 9701
TEST_TIME 93 9684
TEST_TIME 94 9727
TEST_TIME 95 9690
TEST_TIME 96 9619
TEST_TIME 97 9552
TEST_TIME 98 9522
TEST_TIME 99 9509
TEST_TIME 100 9461
TEST_TIME 101 9431
TEST_TIME 102 9388
TEST_TIME 103 9320
TEST_TIME 104 9275
TEST_TIME 105 9257
TEST_TIME 106 9216
TEST_TIME 107 9261
TEST_TIME 108 9278
TEST_TIME 109 9255
TEST_TIME 110 9248
TEST_TIME 111 9266
TEST_TIME 112 9315
TEST_TIME 113 9305
TEST_TIME 114 9356
TEST_TIME 115 9393
TEST_TIME 116 9442
TEST_TIME 117 9406
TEST_TIME 118 9354
TEST_TIME 119 9333
TEST_TIME 120 9257

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-12-2021 13:40:01 to 12-12-2021 13:40:05.

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 1529107 1529107 0 0.00 0 23906 7 0 0
doc_list_biud 1529105 1529105 0 0.00 0 37530 28 0 0
sp_add_invoice_to_stock 4943 4820 123 2.48 35 286457 18640 0 123
sp_cancel_adding_invoice 5828 5828 0 0.00 4 76702 725 0 0
sp_cancel_client_order 1687 1687 0 0.00 4 12231 321 0 0
sp_cancel_customer_reserve 574176 574176 0 0.00 1 37532 59 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 4097 76 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 4169 76 0 0
sp_cancel_supplier_invoice 6466 6451 15 0.23 2 16372 820 0 15
sp_cancel_supplier_order 406 406 0 0.00 7 890270 105442 0 0
sp_cancel_write_off 10612 10612 0 0.00 1 23908 124 0 0
sp_client_order 19537 19537 0 0.00 4 34109 451 0 0
sp_customer_reserve 452355 452355 0 0.00 0 168277 183 0 0
sp_fill_shopping_cart 29923 29923 0 0.00 1 18642 43 0 0
sp_fill_shopping_cart_clo_res 452334 452334 0 0.00 0 16918 16 0 0
sp_get_clo_for_invoice 4799 4799 0 0.00 0 20030 340 0 0
sp_kill_cost_storno 17479 17479 0 0.00 0 23902 52 0 0
sp_kill_qstorno_ret_qs2qd 1165821 1165821 0 0.00 0 37527 32 0 0
sp_kill_qty_storno 613494 613494 0 0.00 0 37530 69 0 0
sp_lock_selected_doc 19865 19865 0 0.00 0 9036 7 0 0
sp_make_cost_storno 26953 26953 0 0.00 0 20021 52 0 0
sp_multiply_rows_for_pdistr 26953 26953 0 0.00 0 8046 30 0 0
sp_multiply_rows_for_qdistr 19537 19537 0 0.00 0 34098 219 0 0
sp_pay_from_customer 6973 6973 0 0.00 4 16063 113 0 0
sp_pay_to_supplier 5646 5646 0 0.00 5 14115 124 0 0
sp_payment_common 12619 12619 0 0.00 1 8660 24 0 0
sp_qd_handle_on_cancel_clo 1687 1687 0 0.00 0 12226 281 0 0
sp_qd_handle_on_invoice_upd_sts 10627 10627 0 0.00 0 34347 294 0 0
sp_qd_handle_on_reserve_upd_sts 9535 9535 0 0.00 0 21657 54 0 0
sp_reserve_write_off 9535 9535 0 0.00 7 21695 187 0 0
sp_supplier_invoice 5265 5265 0 0.00 6 81992 3492 0 0
sp_supplier_order 5149 5149 0 0.00 6 71508 1460 0 0
srv_aggregate_perf_data 108 108 0 0.00 3029 180719 15220 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1545 153178 49306 0 0
srv_find_qd_qs_mism 1064487 1064487 0 0.00 0 12629 6 0 0
srv_make_invnt_saldo 448 448 0 0.00 34 103785 3138 0 0
srv_make_money_saldo 677 677 0 0.00 1 18059 461 0 0
srv_recalc_idx_stat 4 4 0 0.00 216933 348679 267551 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4820 4820 0 0.00 0 19954 154 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 1 2581 60 0 0
t$perf-norm:sp_cancel_client_order 1687 1687 0 0.00 0 7032 12 0 0
t$perf-norm:sp_cancel_customer_reserve 626 626 0 0.00 0 4517 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 1646 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 7079 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 4200 29 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 28335 1420 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 4377 9 0 0
t$perf-norm:sp_client_order 19537 19537 0 0.00 0 10441 18 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 2031 7 0 0
t$perf-norm:sp_pay_from_customer 6973 6973 0 0.00 0 10454 13 0 0
t$perf-norm:sp_pay_to_supplier 5646 5646 0 0.00 0 25183 11 0 0
t$perf-norm:sp_reserve_write_off 9535 9535 0 0.00 0 3538 9 0 0
t$perf-norm:sp_supplier_invoice 5265 5265 0 0.00 0 9606 19 0 0
t$perf-norm:sp_supplier_order 5149 5149 0 0.00 0 7703 15 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 385 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 86 2 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 9080 37 0 0
t$perf-norm:srv_make_money_saldo 677 677 0 0.00 0 617 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 98 26 0 0
v_add_invoice_to_stock 9844 9598 246 2.49 0 8709 24 0 246
v_all_customers 36228 36228 0 0.00 0 46 1 0 0
v_all_suppliers 28768 28768 0 0.00 0 23 1 0 0
v_all_wares 39056 39056 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 1247 9 0 0
v_cancel_client_order 3374 3374 0 0.00 0 3607 5 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 3991 8 0 0
v_cancel_customer_reserve 1252 1252 0 0.00 0 274 3 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 4132 9 0 0
v_cancel_supplier_invoice 834 804 30 3.59 0 8683 30 0 30
v_cancel_supplier_order 812 812 0 0.00 0 1938 8 0 0
v_cancel_write_off 1994 1994 0 0.00 0 8478 21 0 0
v_max_id_clo_ord 5142 5142 0 0.00 0 732 1 0 0
v_max_id_clo_res 988 988 0 0.00 0 1950 15 0 0
v_max_id_ord_sup 5253 5253 0 0.00 0 3483 5 0 0
v_max_non_paid_invoice 1657 1657 0 0.00 0 2586 4 0 0
v_max_non_paid_realizn 3562 3562 0 0.00 0 1558 3 0 0
v_min_id_clo_ord 5142 5142 0 0.00 0 2991 5 0 0
v_min_id_clo_res 988 988 0 0.00 0 2160 5 0 0
v_min_id_ord_sup 5253 5253 0 0.00 0 7554 7 0 0
v_min_non_paid_invoice 5646 5646 0 0.00 0 6843 16 0 0
v_min_non_paid_realizn 6973 6973 0 0.00 0 7989 18 0 0
v_our_firm 9650 9650 0 0.00 0 14 1 0 0
v_reserve_write_off 19070 19070 0 0.00 0 10332 9 0 0
x_lock_depdocs_on_canc_invoice 11877 11877 0 0.00 0 26871 116 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 9230 314 0 0
x_make_qstorno_1000_1200 5149 5149 0 0.00 2 71493 1391 0 0
x_make_qstorno_1200_2000 5265 5265 0 0.00 3 81980 3314 0 0
x_make_qstorno_2100_3300 452355 452355 0 0.00 0 168276 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-12-2021 13:40:06 to 12-12-2021 13:40:10.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:09 6 8192000000 8798449840 8809598976 4478816 0.054 2 1 2 0 4578208 63616 35776
11:08:10 17767 8192000000 9723113568 10024644608 810767136 9.005 100 84 169 0 838677328 7005808 20904384
11:09:31 17777 8192000000 9777703904 10085965824 847063664 9.371 100 87 174 0 879904432 12322640 20518128
11:10:53 22769 8192000000 9825387232 10146467840 889509104 9.794 100 87 174 1 931004976 21723008 19772864
11:12:20 14749 8192000000 9869246624 10202861568 921828208 10.114 100 84 166 1 979572704 40116208 17628288
11:13:37 10578 8192000000 9930672464 10274304000 956106480 10.451 100 85 169 0 1043553376 69708848 17738048
11:14:50 5418 8192000000 9954040480 10309337088 992604912 10.807 100 81 157 0 1070758960 61769696 16384352
11:15:57 3337 8192000000 9997881424 10358288384 1023081888 11.102 100 83 166 0 1114020896 74569216 16369792
11:17:02 1947 8192000000 10023438848 10388000768 1045466336 11.317 100 86 172 1 1131141216 67915616 17759264
11:18:06 1986 8192000000 10032190976 10403201024 1060839488 11.465 100 91 181 2 1146456400 67113024 18503888
11:19:08 3866 8192000000 10020305600 10415628288 1066788352 11.521 100 77 154 1 1148809696 65181360 16839984
11:20:13 14629 8192000000 9995473504 10405412864 1074370672 11.594 100 27 54 0 1112839968 32876336 5592960
11:21:29 5019 8192000000 9990110224 10387841024 1084039664 11.686 100 44 89 3 1115848528 22939392 8869472
11:22:37 35691 8192000000 9998232656 10401476608 1089357440 11.737 100 35 70 2 1115666752 18791024 7518288
11:24:14 4170 8192000000 10049339024 10453397504 1097546704 11.814 100 54 108 1 1149777952 40379440 11851808
11:25:19 7271 8192000000 10018597328 10411782144 1098941360 11.828 100 42 86 2 1131055184 22557680 9556144
11:26:30 8398 8192000000 10024783600 10428358656 1103831216 11.874 100 34 68 0 1136712496 24956000 7925280
11:27:40 17427 8192000000 9981097360 10382618624 1041429104 11.278 95 29 56 1 1053362912 6250128 5683680
11:29:00 4117 8192000000 9978505104 10362503168 1025061344 11.121 96 60 116 2 1051257104 13033360 13162400
11:30:06 7392 8192000000 9960278064 10350968832 1021488032 11.086 98 46 93 1 1055100128 23261456 10350640
11:31:14 6000 8192000000 9934696176 10313412608 1010287312 10.978 98 45 91 0 1034947568 14299616 10360640
11:32:25 32994 8192000000 9949929504 10335019008 1005378240 10.931 97 29 59 1 1029525776 17785760 6361776
11:34:03 4964 8192000000 9975344464 10364420096 1050561136 11.366 100 56 112 1 1089852512 27333840 11957536
11:35:09 4431 8192000000 9968195584 10350206976 1059659200 11.453 100 40 80 0 1077704256 7730928 10314128
11:36:15 57988 8192000000 9975961088 10370871296 1065702624 11.511 100 34 68 0 1106077248 32589040 7785584
11:38:18 29046 8192000000 10047282400 10442297344 1094032912 11.781 100 44 87 0 1145416160 42443632 8939616
11:39:53 6076 8192000000 10102411024 10502164480 1118407664 12.012 100 39 78 0 1199790768 73761120 7621984
11:41:04 14935 8192000000 10149630432 10547142656 1139693424 12.213 100 65 128 1 1249112384 95499552 13919408
11:42:25 20726 8192000000 10186345680 10593587200 1136020352 12.178 99 44 86 2 1282771808 137380048 9371408
11:43:59 13002 8192000000 10242517664 10653270016 1149070688 12.301 100 50 100 2 1347659920 187491808 11097424
11:45:22 5442 8192000000 10278991632 10691325952 1162814016 12.430 100 58 116 1 1389804400 213935904 13054480
11:46:36 6155 8192000000 10257849520 10680799232 1170452576 12.501 100 47 92 0 1360686576 179245424 10988576
11:47:48 1544 8192000000 10281548064 10691227648 1178217344 12.574 100 73 145 1 1384244864 188348352 17679168
11:48:52 8130 8192000000 10308404016 10743717888 1169609056 12.493 100 49 98 0 1416370800 235614320 11147424
11:50:08 9676 8192000000 10278904976 10698158080 1152306192 12.331 99 39 78 0 1373084688 211168464 9610032
11:51:22 4239 8192000000 10276972720 10700943360 1158831104 12.392 100 45 90 0 1387242640 217233616 11177920
11:52:32 4035 8192000000 10346616400 10743959552 1151536144 12.324 99 82 162 2 1432593888 262254976 18802768
11:53:38 7799 8192000000 10286139152 10726809600 1148129840 12.292 100 37 74 1 1394180288 237193968 8856480
11:54:51 5977 8192000000 10289819920 10722631680 1147623072 12.287 100 42 82 0 1389434624 231662320 10149232
11:56:02 18197 8192000000 10237072672 10679525376 1111450352 11.946 97 24 48 2 1315649040 198030192 6168496
11:57:28 25818 8192000000 10238551952 10682695680 1118752336 12.015 99 30 58 1 1330410864 203860176 7798352
11:59:38 25886 8192000000 10189594032 10625888256 1111718592 11.949 100 21 40 2 1267663664 151061936 4883136
12:01:05 34873 8192000000 10099836880 10574147584 1116117728 11.990 100 10 20 1 1261902208 142811040 2973440
12:02:41 27255 8192000000 10139467072 10599735296 1103894288 11.875 99 15 30 0 1233219760 125729520 3595952
12:04:09 127658 8192000000 10038462704 10464161792 1056168880 11.420 95 31 62 0 1094223184 31317888 6736416
12:07:21 58491 8192000000 10047046752 10458198016 1092069792 11.762 98 54 106 0 1137007792 34361968 10576032
12:09:21 37452 8192000000 10116954032 10546429952 1097754880 11.816 97 33 66 1 1189243616 84003776 7484960
12:11:04 89847 8192000000 10109047888 10540142592 1113812256 11.968 99 22 44 0 1237793536 119097648 4883632
12:14:05 153177 8192000000 10176366864 10640543744 1133473552 12.154 99 26 50 0 1267620320 128183056 5963712
12:17:53 42739 8192000000 10176389136 10608603136 1146717232 12.279 100 25 50 0 1320572832 167782640 6072960
12:19:43 77815 8192000000 10077054896 10588913664 1142530048 12.239 100 12 24 0 1234421792 88938528 2953216
12:22:01 63442 8192000000 10083719008 10529652736 1121294288 12.039 98 18 36 0 1148735264 23323152 4117824
12:24:06 77788 8192000000 10083441264 10586456064 1130824112 12.129 99 10 20 0 1194988976 62242288 1922576
12:26:25 66242 8192000000 10086572512 10560385024 1150387680 12.313 100 19 37 1 1215552864 61134640 4030544
12:28:31 47394 8192000000 10104589488 10532065280 1137873424 12.196 99 35 70 0 1189009376 43802928 7333024
12:30:19 69762 8192000000 10100668768 10528202752 1095213824 11.792 95 35 69 1 1148247424 46059776 6973824
12:32:41 24431 8192000000 10082759776 10544013312 1117301216 12.001 98 16 32 1 1166249728 45846944 3101568
12:34:10 45527 8192000000 10047160896 10511695872 1109243536 11.925 98 7 14 0 1140543520 29837216 1462768
12:35:56 43440 8192000000 10077947584 10524479488 1109333344 11.926 98 19 36 0 1148303072 35155360 3814368
12:38:08 70651 8192000000 10061697088 10553946112 1120639440 12.033 99 9 18 0 1179803952 57252768 1911744
12:40:20 24247 8192000000 10051776304 10489884672 1119118240 12.019 99 28 55 1 1161286976 36144656 6024080
12:41:51 37110 8192000000 10107435296 10548957184 1102608208 11.862 97 28 55 1 1181459552 72987072 5864272
12:43:31 62260 8192000000 10067751344 10581295104 1073643728 11.587 96 18 36 0 1176674672 98809552 4221392
12:45:43 42427 8192000000 10132030288 10551762944 1115969008 11.989 99 38 76 0 1207383872 83316288 8098576
12:47:26 68930 8192000000 10112640496 10615009280 1091161296 11.754 97 13 26 1 1185902016 92072816 2667904
12:49:45 74337 8192000000 10117019024 10563584000 1099261904 11.831 98 26 52 0 1175778976 70791568 5725504
12:51:59 105683 8192000000 10090239856 10528337920 1105351504 11.888 98 40 80 0 1174951120 60586480 9013136
12:55:02 74387 8192000000 10070931168 10536280064 1106452880 11.899 99 10 18 1 1160460784 52070224 1937680
12:57:17 74521 8192000000 10119163440 10580795392 1126195568 12.085 100 15 31 0 1214684144 84904880 3583696
12:59:35 78032 8192000000 10133730736 10642137088 1121797296 12.044 99 17 34 1 1270664928 144868640 3998992
13:02:10 57705 8192000000 10082559712 10528722944 1133407552 12.153 100 25 51 1 1165959824 26446032 6106240
13:04:09 54303 8192000000 10131292752 10606268416 1124471648 12.069 98 12 24 0 1217495760 90268336 2755776
13:06:05 50857 8192000000 10088273920 10532577280 1112384656 11.955 98 23 46 2 1160328784 43234896 4709232
13:08:04 52358 8192000000 10101340160 10558341120 1127194064 12.095 99 22 44 0 1167693376 36146400 4352912
13:09:57 15803 8192000000 10072112576 10508308480 1132307296 12.143 100 45 90 0 1169556928 28287824 8961808
13:11:15 15713 8192000000 10112017472 10551808000 1137565040 12.193 99 34 68 0 1199766368 55470832 6730496
13:12:35 136005 8192000000 10072563056 10525065216 1099877792 11.836 96 29 58 0 1124717024 18727216 6112016
13:16:01 76821 8192000000 10055050832 10536685568 1118618720 12.014 99 11 23 1 1170134160 49182192 2333248
13:18:40 60438 8192000000 10073547760 10533097472 1145723200 12.269 100 19 37 0 1184680448 34554944 4402304
13:20:41 72495 8192000000 10104263424 10584522752 1139372544 12.210 99 17 34 0 1186986032 44090112 3523376
13:22:54 77701 8192000000 10110792928 10582552576 1122265376 12.048 97 21 42 2 1196926112 69844400 4816336
13:25:22 53838 8192000000 10077231216 10511507456 1088295328 11.726 95 25 50 1 1139379056 45237120 5846608
13:27:19 57546 8192000000 10130361536 10574880768 1133291680 12.152 100 16 32 3 1247696512 110725680 3679152
13:29:35 47515 8192000000 10114023168 10555650048 1119721088 12.024 98 19 38 0 1194195696 70330480 4144128
13:31:26 46372 8192000000 10108283424 10537168896 1112955248 11.960 98 17 33 0 1210250048 93530976 3763824
13:33:14 138716 8192000000 10064045424 10527383552 1069873520 11.551 95 14 28 0 1126668688 54383968 2411200
13:37:01 27443 8192000000 9582230688 9962422272 694886784 7.819 62 4 8 0 721947376 26640800 419792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-12-2021 13:40:11 to 12-12-2021 13:40:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-12-2021 13:40:27 to 12-12-2021 13:40:29.

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          229114
MON$OLDEST_ACTIVE               229975
MON$OLDEST_SNAPSHOT             229975
MON$NEXT_TRANSACTION            229975
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                       2048096
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2682 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2682 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2682 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2682 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 ---
:64: 
:65: mscs-test-161.budzdorov.ru    Sun Dec 12 13:37:50 2021
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:67: 
:68: 
:69: mscs-test-161.budzdorov.ru    Sun Dec 12 13:37:50 2021
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:71: 
--- end of diff output ---

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

12.12.21 13:40:30. 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-12-12 13:40:32 No severe PSQL-related problems occured
12.12.21 13:40:34. End of report.