Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.02.2021 04: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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16675916 kB
MemAvailable 31097528 kB
Buffers 20 kB
Cached 14912848 kB
SwapCached 4772 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-02-14 04:26:57
DTS_END                         2021-02-14 04:26:57.1200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44706
TRN_ID                          213885
ATT_ID                          577
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: 8421.63 25552 1010595
customer order: creation 153.57 507 18428
customer order: refuse 13.52 412 1622
order to supplier: creation 39.91 1577 4789
order to supplier: removal 3.23 137884 388
invoice (draft): creation 41.38 4113 4966
invoice (draft): removal 49.55 1164 5946
invoice accept: apply 37.12 20511 4454
invoice accept: cancel 44.41 980 5329
customer reserve: creation 3452.02 198 414242
customer reserve: removal 4316.46 81 517975
realization accept: apply 75.13 194 9015
realization accept: cancel 80.69 133 9683
payment from customer: creation 52.84 112 6341
payment from customer: removal 5.14 113 617
payment to supplier: creation 44.16 114 5299
payment to supplier: removal 2.71 88 325
service: total inventory turnovers 3.54 3968 425
service: total monetary turnovers 5.32 581 638
service: aggregate perf. data 0.91 13584 109
service: refresh index statistics 0.03 324725 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-02-2021 04:27:47 to 14-02-2021 04:27:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1994
TEST_TIME 2 1888
TEST_TIME 3 3643
TEST_TIME 4 3994
TEST_TIME 5 4183
TEST_TIME 6 3856
TEST_TIME 7 4108
TEST_TIME 8 3991
TEST_TIME 9 4320
TEST_TIME 10 4461
TEST_TIME 11 4561
TEST_TIME 12 4566
TEST_TIME 13 4726
TEST_TIME 14 4809
TEST_TIME 15 5274
TEST_TIME 16 5641
TEST_TIME 17 6040
TEST_TIME 18 6163
TEST_TIME 19 6723
TEST_TIME 20 6651
TEST_TIME 21 6926
TEST_TIME 22 7596
TEST_TIME 23 7598
TEST_TIME 24 8106
TEST_TIME 25 7932
TEST_TIME 26 8085
TEST_TIME 27 8224
TEST_TIME 28 9333
TEST_TIME 29 10351
TEST_TIME 30 10115
TEST_TIME 31 9895
TEST_TIME 32 9760
TEST_TIME 33 9734
TEST_TIME 34 9766
TEST_TIME 35 9591
TEST_TIME 36 9474
TEST_TIME 37 9364
TEST_TIME 38 9313
TEST_TIME 39 9183
TEST_TIME 40 9198
TEST_TIME 41 9136
TEST_TIME 42 9025
TEST_TIME 43 8924
TEST_TIME 44 8857
TEST_TIME 45 8816
TEST_TIME 46 8819
TEST_TIME 47 8797
TEST_TIME 48 8701
TEST_TIME 49 8584
TEST_TIME 50 8463
TEST_TIME 51 8364
TEST_TIME 52 8417
TEST_TIME 53 8349
TEST_TIME 54 8432
TEST_TIME 55 8369
TEST_TIME 56 8327
TEST_TIME 57 8399
TEST_TIME 58 8336
TEST_TIME 59 8362
TEST_TIME 60 8321
TEST_TIME 61 8534
TEST_TIME 62 8475
TEST_TIME 63 8506
TEST_TIME 64 8540
TEST_TIME 65 8652
TEST_TIME 66 8644
TEST_TIME 67 8668
TEST_TIME 68 8686
TEST_TIME 69 8586
TEST_TIME 70 8525
TEST_TIME 71 8533
TEST_TIME 72 8484
TEST_TIME 73 8426
TEST_TIME 74 8365
TEST_TIME 75 8297
TEST_TIME 76 8217
TEST_TIME 77 8374
TEST_TIME 78 8339
TEST_TIME 79 8345
TEST_TIME 80 8307
TEST_TIME 81 8298
TEST_TIME 82 8361
TEST_TIME 83 8494
TEST_TIME 84 8453
TEST_TIME 85 8496
TEST_TIME 86 8568
TEST_TIME 87 8532
TEST_TIME 88 8562
TEST_TIME 89 8495
TEST_TIME 90 8448
TEST_TIME 91 8437
TEST_TIME 92 8371
TEST_TIME 93 8386
TEST_TIME 94 8437
TEST_TIME 95 8399
TEST_TIME 96 8390
TEST_TIME 97 8440
TEST_TIME 98 8422
TEST_TIME 99 8403
TEST_TIME 100 8447
TEST_TIME 101 8485
TEST_TIME 102 8495
TEST_TIME 103 8515
TEST_TIME 104 8498
TEST_TIME 105 8565
TEST_TIME 106 8606
TEST_TIME 107 8555
TEST_TIME 108 8484
TEST_TIME 109 8462
TEST_TIME 110 8424
TEST_TIME 111 8409
TEST_TIME 112 8378
TEST_TIME 113 8358
TEST_TIME 114 8344
TEST_TIME 115 8354
TEST_TIME 116 8332
TEST_TIME 117 8308
TEST_TIME 118 8325
TEST_TIME 119 8341
TEST_TIME 120 8423

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-02-2021 04:27:51 to 14-02-2021 04:27:54.

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 1394642 1394642 0 0.00 0 13380 7 0 0
doc_list_biud 1394639 1394639 0 0.00 0 78892 38 0 0
sp_add_invoice_to_stock 4545 4454 91 2.00 48 242250 20512 0 91
sp_cancel_adding_invoice 5329 5329 0 0.00 5 141019 980 0 0
sp_cancel_client_order 1622 1622 0 0.00 5 13449 413 0 0
sp_cancel_customer_reserve 517975 517975 0 0.00 1 78894 81 0 0
sp_cancel_pay_from_customer 617 617 0 0.00 3 5503 113 0 0
sp_cancel_pay_to_supplier 325 325 0 0.00 3 5089 88 0 0
sp_cancel_supplier_invoice 5954 5946 8 0.13 2 19177 1164 0 8
sp_cancel_supplier_order 388 388 0 0.00 11 1361892 137884 0 0
sp_cancel_write_off 9683 9683 0 0.00 1 24456 134 0 0
sp_client_order 18428 18428 0 0.00 4 71953 508 0 0
sp_customer_reserve 414242 414242 0 0.00 0 117777 198 0 0
sp_fill_shopping_cart 28175 28175 0 0.00 1 18617 53 0 0
sp_fill_shopping_cart_clo_res 414232 414232 0 0.00 0 30199 20 0 0
sp_get_clo_for_invoice 4443 4443 0 0.00 0 51053 507 0 0
sp_kill_cost_storno 15954 15954 0 0.00 0 12300 67 0 0
sp_kill_qstorno_ret_qs2qd 1051967 1051967 0 0.00 0 78884 44 0 0
sp_kill_qty_storno 554400 554400 0 0.00 0 78891 95 0 0
sp_lock_selected_doc 18632 18632 0 0.00 0 7289 5 0 0
sp_make_cost_storno 25098 25098 0 0.00 0 12903 55 0 0
sp_multiply_rows_for_pdistr 25097 25097 0 0.00 0 7226 32 0 0
sp_multiply_rows_for_qdistr 18428 18428 0 0.00 0 71944 268 0 0
sp_pay_from_customer 6341 6341 0 0.00 4 7976 113 0 0
sp_pay_to_supplier 5299 5299 0 0.00 5 9433 115 0 0
sp_payment_common 11640 11640 0 0.00 1 7330 19 0 0
sp_qd_handle_on_cancel_clo 1622 1622 0 0.00 0 13442 372 0 0
sp_qd_handle_on_invoice_upd_sts 9771 9771 0 0.00 0 28959 452 0 0
sp_qd_handle_on_reserve_upd_sts 9015 9015 0 0.00 0 10827 60 0 0
sp_reserve_write_off 9015 9015 0 0.00 7 12391 195 0 0
sp_supplier_invoice 4966 4966 0 0.00 8 114052 4113 0 0
sp_supplier_order 4789 4789 0 0.00 6 83527 1578 0 0
srv_aggregate_perf_data 109 109 0 0.00 2779 163485 13584 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1336 135985 48435 0 0
srv_find_qd_qs_mism 968235 968235 0 0.00 0 10564 5 0 0
srv_make_invnt_saldo 425 425 0 0.00 47 122562 3968 0 0
srv_make_money_saldo 638 638 0 0.00 1 12593 581 0 0
srv_recalc_idx_stat 4 4 0 0.00 152986 571964 324726 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4454 4454 0 0.00 0 14545 184 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 1 3868 106 0 0
t$perf-norm:sp_cancel_client_order 1622 1622 0 0.00 0 3524 12 0 0
t$perf-norm:sp_cancel_customer_reserve 617 617 0 0.00 0 2088 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 617 617 0 0.00 0 1911 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 325 325 0 0.00 0 3526 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 2320 21 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 22910 1658 0 0
t$perf-norm:sp_cancel_write_off 853 853 0 0.00 0 1321 5 0 0
t$perf-norm:sp_client_order 18428 18428 0 0.00 0 8119 20 0 0
t$perf-norm:sp_customer_reserve 868 868 0 0.00 0 3603 7 0 0
t$perf-norm:sp_pay_from_customer 6341 6341 0 0.00 0 5772 8 0 0
t$perf-norm:sp_pay_to_supplier 5299 5299 0 0.00 0 4748 8 0 0
t$perf-norm:sp_reserve_write_off 9015 9015 0 0.00 0 7736 12 0 0
t$perf-norm:sp_supplier_invoice 4966 4966 0 0.00 0 5188 16 0 0
t$perf-norm:sp_supplier_order 4789 4789 0 0.00 0 7531 21 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 334 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 175 6 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 4526 19 0 0
t$perf-norm:srv_make_money_saldo 638 638 0 0.00 0 2495 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9064 8882 182 2.00 0 7169 20 0 182
v_all_customers 33420 33420 0 0.00 0 14 1 0 0
v_all_suppliers 27502 27502 0 0.00 0 25 1 0 0
v_all_wares 36856 36856 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 506 7 0 0
v_cancel_client_order 3244 3244 0 0.00 0 7325 11 0 0
v_cancel_customer_prepayment 1234 1234 0 0.00 0 2490 9 0 0
v_cancel_customer_reserve 1234 1234 0 0.00 0 2836 5 0 0
v_cancel_payment_to_supplier 650 650 0 0.00 0 960 5 0 0
v_cancel_supplier_invoice 768 752 16 2.08 0 448 11 0 16
v_cancel_supplier_order 776 776 0 0.00 0 614 6 0 0
v_cancel_write_off 1706 1706 0 0.00 0 4729 24 0 0
v_max_id_clo_ord 4789 4789 0 0.00 0 274 1 0 0
v_max_id_clo_res 868 868 0 0.00 0 3299 11 0 0
v_max_id_ord_sup 4958 4958 0 0.00 0 4229 4 0 0
v_max_non_paid_invoice 1295 1295 0 0.00 0 413 2 0 0
v_max_non_paid_realizn 3383 3383 0 0.00 0 465 3 0 0
v_min_id_clo_ord 4789 4789 0 0.00 0 3859 10 0 0
v_min_id_clo_res 868 868 0 0.00 0 10821 22 0 0
v_min_id_ord_sup 4958 4958 0 0.00 0 6313 7 0 0
v_min_non_paid_invoice 5299 5299 0 0.00 0 4975 10 0 0
v_min_non_paid_realizn 6341 6341 0 0.00 0 3619 12 0 0
v_our_firm 9352 9352 0 0.00 0 20 1 0 0
v_reserve_write_off 18030 18030 0 0.00 0 4550 7 0 0
x_lock_depdocs_on_canc_invoice 10899 10899 0 0.00 0 28905 135 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 10990 463 0 0
x_make_qstorno_1000_1200 4789 4789 0 0.00 2 82860 1489 0 0
x_make_qstorno_1200_2000 4966 4966 0 0.00 4 114042 3906 0 0
x_make_qstorno_2100_3300 414242 414242 0 0.00 0 117737 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-02-2021 04:27:55 to 14-02-2021 04:27:59.

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
01:57:00 1228 8192000000 8968925344 9035550720 20904976 0.254 6 4 8 0 21659632 170736 583920
01:58:04 31414 8192000000 9723066848 10019753984 804308016 8.940 100 89 178 2 833530032 7711568 21510448
01:59:43 17725 8192000000 9763921488 10067689472 836769184 9.267 100 91 181 1 871273584 12631696 21872704
02:01:05 16903 8192000000 9803266800 10116186112 864209152 9.542 100 93 185 0 906572272 20328416 22034704
02:02:27 16905 8192000000 9853608496 10183630848 905088640 9.949 100 81 162 1 967930448 44692000 18149808
02:03:46 13456 8192000000 9894076512 10236690432 940561824 10.298 100 75 150 2 1007818880 51141264 16115792
02:05:06 7585 8192000000 9963091184 10312192000 986271584 10.745 100 85 170 4 1069056464 65121056 17663824
02:06:16 3515 8192000000 9994045424 10354487296 1025549440 11.126 100 90 178 0 1116064960 72247392 18268128
02:07:21 2083 8192000000 10009737888 10375536640 1048900032 11.350 100 82 164 0 1116900544 51831312 16169200
02:08:25 2570 8192000000 10035378784 10405261312 1068841040 11.541 100 94 185 1 1149455536 62022160 18592336
02:09:29 4132 8192000000 10044904864 10423042048 1072818768 11.579 100 81 155 3 1157713344 69314960 15579616
02:10:35 3657 8192000000 10010682288 10408472576 1076635472 11.615 100 40 80 0 1134612016 49993840 7982704
02:11:40 20975 8192000000 10003136048 10387976192 1080284672 11.650 100 58 111 3 1111393728 18602256 12506800
02:13:02 5785 8192000000 10023459472 10419810304 1092344112 11.765 100 31 62 3 1125146000 26570800 6231088
02:14:09 6066 8192000000 10016514368 10418618368 1097430960 11.813 100 33 66 1 1131151104 27232368 6487776
02:15:15 4640 8192000000 10015701664 10408042496 1099974272 11.837 100 52 105 3 1131395488 19965440 11455776
02:16:21 36100 8192000000 10020871024 10427449344 1091143200 11.754 99 39 78 0 1131148704 31850096 8155408
02:17:58 8503 8192000000 10001701216 10407051264 1094428480 11.785 100 37 74 0 1142053920 39831200 7794240
02:19:07 6923 8192000000 9988490096 10394935296 1054637520 11.405 97 30 61 1 1089397104 27432688 7326896
02:20:16 7948 8192000000 9965239120 10352513024 1035551392 11.222 99 27 54 0 1063852640 21629024 6672224
02:21:27 21864 8192000000 9945962544 10351009792 986687600 10.749 94 23 44 1 1023538064 31805952 5044512
02:22:50 4689 8192000000 9960558320 10344710144 1034546304 11.212 100 46 94 2 1071386800 27436688 9403808
02:23:59 10259 8192000000 9956031472 10345594880 1037024256 11.236 100 50 100 1 1069391968 20919248 11448464
02:25:13 40516 8192000000 9972757488 10354745344 1054555616 11.404 100 66 132 1 1083749888 14878240 14316032
02:27:06 5003 8192000000 10002450608 10385518592 1075476496 11.604 100 52 105 1 1106306592 20128912 10701184
02:28:25 9666 8192000000 10041179616 10430558208 1088855568 11.732 100 63 124 0 1144251584 43303872 12092144
02:29:38 12386 8192000000 10068832976 10467061760 1105653840 11.891 100 59 118 2 1180362832 62506480 12202512
02:30:54 55554 8192000000 10111136176 10522464256 1119191744 12.019 100 35 70 0 1228675424 102216496 7267184
02:33:23 10636 8192000000 10172766912 10583506944 1141985424 12.234 100 46 90 1 1271589344 119706784 9897136
02:34:40 14904 8192000000 10214921312 10620727296 1158542560 12.390 100 51 102 2 1315903696 146397824 10963312
02:36:01 4593 8192000000 10261453488 10662277120 1170721856 12.504 100 79 152 3 1364493184 176708736 17062592
02:37:10 5725 8192000000 10292066992 10698231808 1172838720 12.523 100 78 152 3 1393265568 203211696 17215152
02:38:19 8340 8192000000 10320510352 10733719552 1181935392 12.608 100 63 123 2 1424075056 228764816 13374848
02:39:36 2299 8192000000 10399316352 10803765248 1195686128 12.736 100 79 157 1 1499753232 285950848 18116256
02:40:42 4861 8192000000 10417368272 10834747392 1189287200 12.677 100 66 132 0 1523880080 318109824 16483056
02:41:51 1813 8192000000 10398729872 10814279680 1183953904 12.627 100 74 146 0 1501817888 300925360 16938624
02:43:11 1332 8192000000 10417761104 10827956224 1186880688 12.654 100 83 164 1 1514897552 308036384 19980480
02:44:20 5886 8192000000 10428833424 10845683712 1196604352 12.745 100 83 166 1 1529646576 314073152 18969072
02:45:32 3909 8192000000 10420645824 10867539968 1197157792 12.750 100 59 118 0 1530682160 319095072 14429296
02:46:42 5286 8192000000 10479149280 10898894848 1183812832 12.626 99 71 143 1 1571498208 370677136 17008240
02:47:53 2869 8192000000 10504543280 10918731776 1183575408 12.624 100 76 152 0 1598522592 396046448 18900736
02:49:00 19840 8192000000 10412510528 10875064320 1179300080 12.584 100 36 72 2 1539844080 351004640 9539360
02:50:23 12307 8192000000 10434720176 10924752896 1177808944 12.570 100 37 74 0 1544900544 357610544 9481056
02:51:46 13373 8192000000 10443188032 10911145984 1155761808 12.364 100 34 68 0 1557550048 392692672 9095568
02:53:04 17532 8192000000 10455892160 10904612864 1109801792 11.931 98 38 76 0 1534601888 414194704 10605392
02:54:34 10739 8192000000 10320504000 10782531584 1118149808 12.010 99 24 48 0 1435492192 310823520 6518864
02:55:49 23834 8192000000 10079474272 10499424256 1135094032 12.169 100 47 90 3 1178431952 33692576 9645344
02:57:21 119934 8192000000 10100502224 10545405952 1072029296 11.571 95 26 52 2 1142127744 64571216 5527232
03:00:30 35465 8192000000 10027521792 10492317696 1084356176 11.689 97 17 34 0 1118720592 30650864 3713552
03:02:17 73483 8192000000 10051318784 10471604224 1075781200 11.607 96 37 73 1 1106317632 23363968 7172464
03:04:31 95585 8192000000 10103990352 10554638336 1133865632 12.158 100 25 50 2 1197400368 58350416 5184320
03:07:09 107651 8192000000 10079315840 10526314496 1093701296 11.778 96 16 33 1 1122916224 26045280 3169648
03:10:39 14874 8192000000 10054443152 10488795136 1112306544 11.954 99 26 53 1 1153057840 35316384 5434912
03:11:57 38510 8192000000 10049732848 10498584576 1108887824 11.922 100 32 62 2 1171739696 56506960 6344912
03:13:48 62351 8192000000 10063518352 10506461184 1097744624 11.816 98 26 52 0 1150585520 46791392 6049504
03:15:51 78456 8192000000 10134266016 10577883136 1134079136 12.160 100 24 48 0 1228445456 88952416 5413904
03:18:50 62775 8192000000 10082967568 10534440960 1108311296 11.916 98 17 34 0 1186076480 74151376 3613808
03:21:00 54217 8192000000 10048565344 10522718208 1127220288 12.095 100 15 30 0 1160780736 30127392 3433056
03:22:55 97698 8192000000 10085885712 10575151104 1097336432 11.812 97 18 36 2 1171571600 69642336 4592832
03:25:49 108746 8192000000 10117288272 10618265600 1126813184 12.091 99 22 43 1 1218554096 87043136 4697776
03:28:43 90249 8192000000 10097379168 10534866944 1130847488 12.129 99 31 62 0 1171226480 33844640 6534352
03:31:13 95296 8192000000 10074692416 10559447040 1133841120 12.158 99 10 20 0 1207613936 71469728 2303088
03:33:51 105638 8192000000 10123740976 10610688000 1127268400 12.096 98 19 38 2 1182065376 51101104 3695872
03:36:54 61686 8192000000 10091144224 10603601920 1149267280 12.303 100 18 36 1 1212867984 59911792 3688912
03:38:59 32735 8192000000 10070124864 10536706048 1145122240 12.264 100 9 18 0 1166010992 18895328 1993424
03:40:32 87987 8192000000 10143699440 10601750528 1135279728 12.171 98 19 38 1 1212682320 73263648 4138944
03:44:31 18582 8192000000 10066383200 10507726848 1144450624 12.257 100 22 43 1 1169274032 20183600 4639808
03:45:50 51966 8192000000 10136555936 10585358336 1134992368 12.168 99 27 54 2 1207832752 67155840 5684544
03:47:46 96788 8192000000 10149103520 10656571392 1131236752 12.133 98 13 26 1 1260139312 125661120 3241440
03:50:28 69870 8192000000 10107493216 10581999616 1102903008 11.865 96 15 30 0 1169183536 63329088 2951440
03:52:38 53931 8192000000 10050384336 10486542336 1049926464 11.360 94 8 16 0 1095083296 43684688 1472144
03:54:35 92377 8192000000 10085721984 10526736384 1119509200 12.022 100 36 72 0 1211285808 84320848 7455760
03:57:19 48526 8192000000 10072979504 10545483776 1122299152 12.049 99 8 16 0 1158599312 34678976 1621184
03:59:08 56398 8192000000 10103672528 10629685248 1136021744 12.178 100 14 29 0 1232752720 93774800 2956176
04:01:07 48912 8192000000 10053249792 10489643008 1068713696 11.540 94 13 26 1 1097514208 26425568 2374944
04:02:58 71759 8192000000 10067397792 10538487808 1104917408 11.884 98 11 22 0 1170962752 64083280 1962064
04:05:13 58570 8192000000 10112736464 10551529472 1099659744 11.834 97 27 54 1 1171931744 66086496 6185504
04:07:22 82350 8192000000 10121822368 10561384448 1110489184 11.937 97 18 36 0 1150189472 35995408 3704880
04:09:48 32207 8192000000 10107650352 10533965824 1132947552 12.149 100 31 62 1 1183648240 44777360 5923328
04:11:22 79679 8192000000 10086324960 10566307840 1148390832 12.294 100 11 22 0 1203609248 53089648 2128768
04:13:42 135985 8192000000 10055912864 10503868416 1109761760 11.930 97 22 44 0 1158308384 44068688 4477936
04:16:59 41558 8192000000 10059505664 10517143552 1109706080 11.930 98 11 22 0 1146680000 34250544 2723376
04:18:41 35150 8192000000 10038920304 10488692736 1116331792 11.992 99 13 25 0 1146815120 27613040 2870288
04:20:36 69698 8192000000 10088606848 10559483904 1130663056 12.128 99 19 38 2 1177035856 42314480 4058320
04:22:46 78607 8192000000 10164832224 10606534656 1150381296 12.313 100 22 44 0 1216133008 60933664 4818048
04:25:16 103945 8192000000 9554278480 9948270592 661127248 7.467 58 6 12 0 704278000 41907872 1242880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-02-2021 04:28:01 to 14-02-2021 04:28:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-02-2021 04:28:17 to 14-02-2021 04:28:19.

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          214083
MON$OLDEST_ACTIVE               214084
MON$OLDEST_SNAPSHOT             214084
MON$NEXT_TRANSACTION            214084
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                       2067936
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:401: 
:402: mscs-test-161.budzdorov.ru    Sun Feb 14 04:27:03 2021
:403:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:404: 
--- end of diff output ---

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

14.02.21 04:28:20. 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-02-14 04:28:22 No severe PSQL-related problems occured
14.02.21 04:28:24. End of report.