Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.10.2021 07:48
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 15974084 kB
MemAvailable 30900304 kB
Buffers 0 kB
Cached 15764644 kB
SwapCached 4788 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-10-27 07:47:16
DTS_END                         2021-10-27 07:46:54.1680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44394
TRN_ID                          229087
ATT_ID                          259
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: 9166.62 26235 1099994
customer order: creation 164.16 421 19699
customer order: refuse 13.92 364 1670
order to supplier: creation 44.56 1553 5347
order to supplier: removal 3.44 119824 413
invoice (draft): creation 45.28 3850 5433
invoice (draft): removal 53.91 990 6469
invoice accept: apply 40.76 19240 4891
invoice accept: cancel 48.63 917 5836
customer reserve: creation 3769.13 188 452296
customer reserve: removal 4690.56 69 562867
realization accept: apply 80.32 201 9638
realization accept: cancel 89.06 133 10687
payment from customer: creation 56.76 123 6811
payment from customer: removal 5.53 114 664
payment to supplier: creation 46.89 137 5627
payment to supplier: removal 3.43 93 412
service: total inventory turnovers 3.40 4053 408
service: total monetary turnovers 5.82 649 698
service: aggregate perf. data 1.03 12868 124
service: refresh index statistics 0.03 358915 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-10-2021 07:49:09 to 27-10-2021 07:49:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2274
TEST_TIME 2 3671
TEST_TIME 3 3773
TEST_TIME 4 4065
TEST_TIME 5 4174
TEST_TIME 6 4660
TEST_TIME 7 5521
TEST_TIME 8 5444
TEST_TIME 9 5339
TEST_TIME 10 5250
TEST_TIME 11 5542
TEST_TIME 12 5968
TEST_TIME 13 6519
TEST_TIME 14 6916
TEST_TIME 15 6928
TEST_TIME 16 7375
TEST_TIME 17 7456
TEST_TIME 18 8212
TEST_TIME 19 8093
TEST_TIME 20 8057
TEST_TIME 21 9000
TEST_TIME 22 9447
TEST_TIME 23 9343
TEST_TIME 24 9556
TEST_TIME 25 9812
TEST_TIME 26 9674
TEST_TIME 27 9942
TEST_TIME 28 11123
TEST_TIME 29 11060
TEST_TIME 30 10875
TEST_TIME 31 10559
TEST_TIME 32 10564
TEST_TIME 33 10417
TEST_TIME 34 10169
TEST_TIME 35 10179
TEST_TIME 36 10099
TEST_TIME 37 10018
TEST_TIME 38 9996
TEST_TIME 39 9942
TEST_TIME 40 9937
TEST_TIME 41 9768
TEST_TIME 42 9936
TEST_TIME 43 9945
TEST_TIME 44 9876
TEST_TIME 45 9795
TEST_TIME 46 9875
TEST_TIME 47 9841
TEST_TIME 48 9938
TEST_TIME 49 9932
TEST_TIME 50 10113
TEST_TIME 51 10002
TEST_TIME 52 10149
TEST_TIME 53 10078
TEST_TIME 54 10201
TEST_TIME 55 10145
TEST_TIME 56 9972
TEST_TIME 57 9929
TEST_TIME 58 9845
TEST_TIME 59 9836
TEST_TIME 60 9749
TEST_TIME 61 9689
TEST_TIME 62 9641
TEST_TIME 63 9571
TEST_TIME 64 9489
TEST_TIME 65 9488
TEST_TIME 66 9439
TEST_TIME 67 9522
TEST_TIME 68 9508
TEST_TIME 69 9504
TEST_TIME 70 9531
TEST_TIME 71 9608
TEST_TIME 72 9540
TEST_TIME 73 9579
TEST_TIME 74 9671
TEST_TIME 75 9846
TEST_TIME 76 9822
TEST_TIME 77 9760
TEST_TIME 78 9644
TEST_TIME 79 9574
TEST_TIME 80 9572
TEST_TIME 81 9510
TEST_TIME 82 9464
TEST_TIME 83 9390
TEST_TIME 84 9411
TEST_TIME 85 9354
TEST_TIME 86 9347
TEST_TIME 87 9301
TEST_TIME 88 9289
TEST_TIME 89 9311
TEST_TIME 90 9298
TEST_TIME 91 9419
TEST_TIME 92 9426
TEST_TIME 93 9457
TEST_TIME 94 9462
TEST_TIME 95 9489
TEST_TIME 96 9499
TEST_TIME 97 9447
TEST_TIME 98 9432
TEST_TIME 99 9372
TEST_TIME 100 9366
TEST_TIME 101 9330
TEST_TIME 102 9274
TEST_TIME 103 9242
TEST_TIME 104 9220
TEST_TIME 105 9221
TEST_TIME 106 9237
TEST_TIME 107 9191
TEST_TIME 108 9216
TEST_TIME 109 9271
TEST_TIME 110 9260
TEST_TIME 111 9258
TEST_TIME 112 9239
TEST_TIME 113 9254
TEST_TIME 114 9372
TEST_TIME 115 9362
TEST_TIME 116 9329
TEST_TIME 117 9275
TEST_TIME 118 9245
TEST_TIME 119 9198
TEST_TIME 120 9170

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-10-2021 07:49:12 to 27-10-2021 07:49:16.

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 1518110 1518110 0 0.00 0 19491 7 0 0
doc_list_biud 1518108 1518108 0 0.00 0 83235 33 0 0
sp_add_invoice_to_stock 4944 4891 53 1.07 53 212751 19240 0 53
sp_cancel_adding_invoice 5836 5836 0 0.00 5 73491 918 0 0
sp_cancel_client_order 1670 1670 0 0.00 5 17466 365 0 0
sp_cancel_customer_reserve 562867 562867 0 0.00 1 58207 69 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 8271 115 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 2371 93 0 0
sp_cancel_supplier_invoice 6471 6469 2 0.03 3 83256 991 0 2
sp_cancel_supplier_order 413 413 0 0.00 7 1068639 119825 0 0
sp_cancel_write_off 10687 10687 0 0.00 1 19453 133 0 0
sp_client_order 19699 19699 0 0.00 4 23952 422 0 0
sp_customer_reserve 452296 452296 0 0.00 0 84141 188 0 0
sp_fill_shopping_cart 30473 30473 0 0.00 1 20500 51 0 0
sp_fill_shopping_cart_clo_res 452286 452286 0 0.00 0 22725 19 0 0
sp_get_clo_for_invoice 4879 4879 0 0.00 0 23670 411 0 0
sp_kill_cost_storno 17599 17599 0 0.00 0 11969 62 0 0
sp_kill_qstorno_ret_qs2qd 1143303 1143303 0 0.00 0 83231 38 0 0
sp_kill_qty_storno 602458 602458 0 0.00 0 83234 81 0 0
sp_lock_selected_doc 20182 20182 0 0.00 0 8661 6 0 0
sp_make_cost_storno 26955 26955 0 0.00 0 19474 65 0 0
sp_multiply_rows_for_pdistr 26954 26954 0 0.00 0 10143 37 0 0
sp_multiply_rows_for_qdistr 19699 19699 0 0.00 0 23943 238 0 0
sp_pay_from_customer 6811 6811 0 0.00 4 17654 124 0 0
sp_pay_to_supplier 5627 5627 0 0.00 5 8226 137 0 0
sp_payment_common 12438 12438 0 0.00 1 15446 22 0 0
sp_qd_handle_on_cancel_clo 1670 1670 0 0.00 0 17458 330 0 0
sp_qd_handle_on_invoice_upd_sts 10714 10714 0 0.00 0 26246 380 0 0
sp_qd_handle_on_reserve_upd_sts 9638 9638 0 0.00 0 21109 59 0 0
sp_reserve_write_off 9638 9638 0 0.00 7 21129 202 0 0
sp_supplier_invoice 5433 5433 0 0.00 6 94826 3850 0 0
sp_supplier_order 5347 5347 0 0.00 6 41155 1554 0 0
srv_aggregate_perf_data 124 124 0 0.00 2355 146216 12868 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1308 118788 46628 0 0
srv_find_qd_qs_mism 1053530 1053530 0 0.00 0 18096 6 0 0
srv_make_invnt_saldo 408 408 0 0.00 83 104971 4053 0 0
srv_make_money_saldo 698 698 0 0.00 1 17841 650 0 0
srv_recalc_idx_stat 4 4 0 0.00 261885 481393 358916 0 0
t$perf-abend:sp_add_invoice_to_stock 53 53 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4891 4891 0 0.00 0 14410 205 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 4009 78 0 0
t$perf-norm:sp_cancel_client_order 1670 1670 0 0.00 0 4335 18 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 1838 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 3985 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 2183 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 1203 13 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 18429 1646 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 9876 21 0 0
t$perf-norm:sp_client_order 19699 19699 0 0.00 0 11086 21 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 4732 11 0 0
t$perf-norm:sp_pay_from_customer 6811 6811 0 0.00 0 9650 11 0 0
t$perf-norm:sp_pay_to_supplier 5627 5627 0 0.00 0 6860 20 0 0
t$perf-norm:sp_reserve_write_off 9638 9638 0 0.00 0 8420 13 0 0
t$perf-norm:sp_supplier_invoice 5433 5433 0 0.00 0 9579 20 0 0
t$perf-norm:sp_supplier_order 5347 5347 0 0.00 0 5341 20 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 532 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 3071 60 0 0
t$perf-norm:srv_make_invnt_saldo 408 408 0 0.00 0 5253 20 0 0
t$perf-norm:srv_make_money_saldo 698 698 0 0.00 0 2747 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 265 68 0 0
v_add_invoice_to_stock 9856 9750 106 1.07 0 4877 17 0 106
v_all_customers 35970 35970 0 0.00 0 25 1 0 0
v_all_suppliers 29824 29824 0 0.00 0 21 1 0 0
v_all_wares 39398 39398 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 3984 13 0 0
v_cancel_client_order 3340 3340 0 0.00 0 4060 8 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 7164 17 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 3285 9 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 2129 7 0 0
v_cancel_supplier_invoice 786 782 4 0.50 0 2484 15 0 4
v_cancel_supplier_order 826 826 0 0.00 0 1658 7 0 0
v_cancel_write_off 2022 2022 0 0.00 0 3040 14 0 0
v_max_id_clo_ord 5343 5343 0 0.00 0 716 2 0 0
v_max_id_clo_res 990 990 0 0.00 0 3633 24 0 0
v_max_id_ord_sup 5429 5429 0 0.00 0 7171 7 0 0
v_max_non_paid_invoice 1487 1487 0 0.00 0 256 2 0 0
v_max_non_paid_realizn 3587 3587 0 0.00 0 559 2 0 0
v_min_id_clo_ord 5343 5343 0 0.00 0 2878 6 0 0
v_min_id_clo_res 990 990 0 0.00 0 1936 7 0 0
v_min_id_ord_sup 5429 5429 0 0.00 0 8255 15 0 0
v_min_non_paid_invoice 5627 5627 0 0.00 0 5135 15 0 0
v_min_non_paid_realizn 6811 6811 0 0.00 0 4770 14 0 0
v_our_firm 9876 9876 0 0.00 0 15 1 0 0
v_reserve_write_off 19276 19276 0 0.00 0 4102 6 0 0
x_lock_depdocs_on_canc_invoice 11914 11914 0 0.00 0 18292 129 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 11826 338 0 0
x_make_qstorno_1000_1200 5347 5347 0 0.00 2 41138 1465 0 0
x_make_qstorno_1200_2000 5433 5433 0 0.00 3 94804 3656 0 0
x_make_qstorno_2100_3300 452296 452296 0 0.00 0 84063 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-10-2021 07:49:17 to 27-10-2021 07:49:21.

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
05:16:57 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:58 23363 8192000000 9729253504 10025107456 803309632 8.930 100 83 166 0 830997712 7713408 19974672
05:19:29 26039 8192000000 9771202880 10078507008 847095584 9.371 100 91 182 0 881527072 12878000 21553488
05:20:59 15004 8192000000 9809560544 10127601664 873630928 9.636 100 82 164 0 917662352 25369104 18662320
05:22:16 14851 8192000000 9871427920 10204205056 916793408 10.064 100 78 156 3 980440816 46914464 16732944
05:23:35 10589 8192000000 9922276288 10262704128 961273712 10.501 100 81 161 1 1034294944 55577936 17443296
05:24:48 4668 8192000000 9981809312 10333982720 1004915664 10.926 100 83 165 1 1087594832 65494112 17185056
05:25:54 2701 8192000000 10027440432 10390310912 1029522384 11.164 100 77 155 1 1134501920 89929216 15050320
05:26:59 2649 8192000000 10015850368 10382753792 1050334608 11.364 100 86 171 1 1126907888 59304768 17268512
05:28:03 1662 8192000000 10048201232 10421231616 1070387728 11.556 100 89 178 0 1165526112 76358208 18780176
05:29:06 2161 8192000000 10045314128 10425806848 1078391040 11.632 100 72 144 1 1153754256 61106704 14256512
05:30:11 9693 8192000000 10010061872 10408132608 1084472160 11.690 100 46 92 2 1120703744 27217392 9014192
05:31:22 26031 8192000000 10011247040 10399465472 1084749120 11.693 100 69 138 1 1116945408 15866304 16329984
05:32:48 4569 8192000000 10017659744 10430889984 1092929392 11.771 100 35 70 1 1138746704 38397168 7420144
05:33:53 10174 8192000000 10019158608 10430386176 1099941168 11.837 100 40 78 2 1144422384 36252256 8228960
05:35:04 6981 8192000000 10019486160 10426732544 1104773344 11.883 100 29 58 2 1126323344 15032288 6517712
05:36:16 40587 8192000000 10023575568 10450685952 1093555136 11.776 99 41 82 1 1121811008 19002752 9253120
05:38:01 4700 8192000000 9991217824 10390773760 1086984368 11.714 100 37 72 0 1109465520 13639280 8841872
05:39:06 4638 8192000000 9959847344 10341650432 1040496992 11.269 99 37 77 1 1063871616 15159296 8215328
05:40:11 5980 8192000000 9938334400 10324238336 1004612864 10.923 97 37 74 2 1035311824 21434352 9264608
05:41:19 36433 8192000000 9935669920 10324008960 939959008 10.293 91 33 66 0 963899120 17201872 6738240
05:42:57 6946 8192000000 9948789344 10339827712 1028823520 11.157 100 37 74 2 1059949360 23643584 7482256
05:44:04 7426 8192000000 9965144784 10365161472 1048676080 11.348 100 35 71 3 1079930672 22896688 8357904
05:45:14 11328 8192000000 9979403216 10378784768 1062789360 11.483 100 32 62 2 1098615248 28285680 7540208
05:46:30 50125 8192000000 10002133600 10395934720 1078665952 11.635 100 41 81 1 1111694352 24201680 8826720
05:48:35 15943 8192000000 10055988032 10455175168 1105567104 11.890 100 58 116 0 1163780944 45989280 12224560
05:49:54 29442 8192000000 10076139952 10482036736 1120301616 12.030 100 40 78 1 1198353920 69740288 8312016
05:51:31 15584 8192000000 10144989840 10555056128 1131608544 12.137 100 46 93 2 1253820272 111959216 10252512
05:52:56 17470 8192000000 10196038288 10607726592 1143362464 12.247 100 58 115 1 1303631168 147766064 12502640
05:54:22 7233 8192000000 10220729536 10629472256 1159284064 12.397 100 57 113 0 1329016944 157744432 11988448
05:55:32 5376 8192000000 10245289760 10654339072 1165222736 12.452 99 52 102 0 1350042320 172876112 11943472
05:56:43 10718 8192000000 10292092816 10697273344 1178457120 12.576 100 71 140 0 1387198080 193268928 15472032
05:58:01 2336 8192000000 10354303392 10766106624 1185216336 12.639 100 79 151 3 1456875488 253755360 17903792
05:59:06 2011 8192000000 10394001424 10823819264 1192228256 12.704 100 77 152 0 1492998032 283219760 17550016
06:00:12 3439 8192000000 10403557840 10814074880 1200531360 12.781 100 82 162 2 1508156944 288773584 18852000
06:01:21 3587 8192000000 10393492512 10806239232 1187943936 12.664 100 79 156 0 1493697920 287261792 18492192
06:02:40 1422 8192000000 10409129264 10816266240 1197162096 12.750 100 90 179 0 1506315088 287261600 21891392
06:03:50 3853 8192000000 10426837984 10829115392 1178274544 12.574 99 90 181 1 1519193408 320493056 20425808
06:04:57 10856 8192000000 10357506064 10799181824 1156530672 12.371 99 47 90 4 1451949696 283968656 11450368
06:06:13 2552 8192000000 10405455392 10816557056 1153900496 12.346 99 67 133 1 1490409488 320696192 15812800
06:07:20 6278 8192000000 10335215248 10836348928 1139941712 12.215 100 42 84 1 1458848096 308115952 10790432
06:08:28 27774 8192000000 10259911360 10740101120 1109834608 11.931 98 30 60 1 1396964320 279494944 7634768
06:10:13 1304 8192000000 10298223728 10708320256 1128441472 12.107 100 92 186 1 1395244848 245776640 21026736
06:11:15 34630 8192000000 10262491360 10737516544 1123505872 12.060 99 19 38 0 1355257056 227048448 4702736
06:13:09 32339 8192000000 10281351296 10757140480 1113123360 11.962 99 16 31 0 1410164544 292659248 4381936
06:14:48 38470 8192000000 10038778960 10514182144 1104079952 11.876 99 10 20 1 1172377216 66439344 1857920
06:17:03 70617 8192000000 10055964944 10472038400 1068100496 11.534 95 35 70 2 1108178048 32170320 7907232
06:19:20 86602 8192000000 10061364800 10479620096 1092656800 11.768 97 26 52 0 1142405936 44399232 5349904
06:21:48 39310 8192000000 10131128800 10575159296 1129383920 12.116 100 28 56 0 1229895824 94151920 6359984
06:23:27 57104 8192000000 10160400144 10604822528 1132292288 12.143 99 40 79 1 1251981840 110523056 9166496
06:25:29 108559 8192000000 10163870096 10610782208 1130573536 12.127 99 16 32 0 1217020816 82737424 3709856
06:28:21 24917 8192000000 10148606368 10664558592 1137498240 12.192 100 16 32 0 1309725488 168027360 4199888
06:31:05 49170 8192000000 10150962176 10588672000 1130029008 12.122 98 27 54 1 1215348656 79285248 6034400
06:32:59 41311 8192000000 10117375584 10544594944 1146749872 12.279 100 38 75 0 1232049328 76545680 8753776
06:34:45 52190 8192000000 10157435056 10599530496 1158064704 12.385 100 19 38 0 1205264272 42746656 4452912
06:36:38 93102 8192000000 10119906624 10572070912 1165690320 12.457 100 15 30 0 1243946848 74908368 3348160
06:39:11 73365 8192000000 10098066592 10607620096 1140477248 12.220 97 19 38 0 1193153328 48874384 3801696
06:41:24 107654 8192000000 10125701936 10584776704 1166957456 12.468 100 26 52 0 1200235904 27671504 5606944
06:44:17 38335 8192000000 10110143232 10575073280 1153831088 12.345 99 31 62 0 1201681984 41636160 6214736
06:45:56 23308 8192000000 10089086144 10534383616 1160883024 12.412 99 29 57 4 1177451056 9985872 6582160
06:47:23 59988 8192000000 10104607504 10550403072 1148436960 12.295 98 17 34 0 1186881392 34965184 3479248
06:49:30 49384 8192000000 10102674384 10572042240 1155244016 12.359 99 16 32 0 1228625744 70089664 3292064
06:51:50 37643 8192000000 10104206352 10552176640 1127031056 12.093 97 20 40 2 1185912224 55186320 3694848
06:53:28 61471 8192000000 10084282000 10536742912 1084237984 11.688 93 18 36 1 1146106576 58230496 3638096
06:55:29 30864 8192000000 10101112592 10552991744 1106449040 11.899 97 26 52 1 1180477616 68842592 5185984
06:57:01 60212 8192000000 10061972256 10487455744 1079579184 11.643 96 20 38 0 1142127520 58295440 4252896
06:59:01 37659 8192000000 10166208928 10629386240 1122534048 12.051 99 41 82 0 1255956144 124722480 8699616
07:00:44 108465 8192000000 10109110128 10646044672 1105745200 11.892 98 7 14 0 1192635072 85331888 1557984
07:03:32 111817 8192000000 10071103360 10498752512 1117281328 12.001 99 22 44 0 1180821360 58267200 5272832
07:06:25 64003 8192000000 10063522368 10507857920 1084774464 11.693 96 10 20 0 1119160880 32133280 2253136
07:09:55 67544 8192000000 10073225728 10568966144 1096252272 11.802 99 11 22 0 1150285920 51866272 2167376
07:13:17 32032 8192000000 10095217536 10532683776 1123050480 12.056 100 18 36 0 1192625568 65593888 3981200
07:14:50 69714 8192000000 10155474848 10618548224 1134792432 12.167 99 27 54 2 1264055728 122963296 6300000
07:17:02 118788 8192000000 10096697616 10553352192 1125543952 12.079 99 20 40 1 1202124352 72123504 4456896
07:20:01 63624 8192000000 10056424256 10515968000 1095314592 11.793 96 10 19 1 1135361840 38479536 1567712
07:22:05 67741 8192000000 10080446128 10510405632 1129974416 12.121 99 18 33 2 1192492896 59338064 3180416
07:24:19 79125 8192000000 10124498496 10566139904 1148167136 12.292 100 43 86 0 1206942000 50166880 8607984
07:26:39 40124 8192000000 10090842464 10557894656 1142448816 12.239 99 17 34 0 1185425648 39594000 3382832
07:28:30 47774 8192000000 10091773616 10587500544 1151794368 12.326 100 8 16 0 1200883104 47325760 1762976
07:30:19 36893 8192000000 10121586496 10606260224 1157063104 12.376 100 21 42 1 1227232176 65646992 4522080
07:31:58 87028 8192000000 10115559152 10560761856 1139490784 12.211 98 12 24 0 1200295376 58242816 2561776
07:34:34 94320 8192000000 10083188496 10514505728 1126573088 12.089 97 26 52 0 1191192800 59019184 5600528
07:37:09 56017 8192000000 10128365200 10572812288 1149209632 12.302 99 14 28 0 1199544992 47222880 3112480
07:39:06 69138 8192000000 10168997696 10601213952 1109802512 11.931 96 28 55 1 1221561552 106044208 5714832
07:41:23 79814 8192000000 10087608720 10528038912 1113354880 11.964 97 37 72 0 1148127824 27639440 7133504
07:45:03 71356 8192000000 10068791888 10555461632 1124210000 12.067 98 5 10 0 1171302480 45944048 1148432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-10-2021 07:49:23 to 27-10-2021 07:49:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 53 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-10-2021 07:49:39 to 27-10-2021 07:49:41.

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          228647
MON$OLDEST_ACTIVE               229597
MON$OLDEST_SNAPSHOT             229597
MON$NEXT_TRANSACTION            229597
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                       2051360
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.2643 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2643 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2643 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2643 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Oct 27 07:47:25 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

27.10.21 07:49:42. 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-10-27 07:49:44 No severe PSQL-related problems occured
27.10.21 07:49:45. End of report.