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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15749216 kB
MemAvailable 30630912 kB
Buffers 0 kB
Cached 15938268 kB
SwapCached 3716 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-20 10:37:09
DTS_END                         2021-10-20 10:37:09.7440
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/50388
TRN_ID                          235990
ATT_ID                          608
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: 8901.96 25234 1068235
customer order: creation 169.62 446 20354
customer order: refuse 14.73 355 1767
order to supplier: creation 45.55 1503 5466
order to supplier: removal 3.48 108525 418
invoice (draft): creation 44.83 3656 5379
invoice (draft): removal 50.03 945 6004
invoice accept: apply 40.52 20155 4862
invoice accept: cancel 45.35 958 5442
customer reserve: creation 3741.33 199 448959
customer reserve: removal 4455.17 67 534620
realization accept: apply 82.33 230 9880
realization accept: cancel 82.22 120 9866
payment from customer: creation 59.03 145 7084
payment from customer: removal 5.88 87 706
payment to supplier: creation 47.43 153 5692
payment to supplier: removal 3.60 63 432
service: total inventory turnovers 3.65 3987 438
service: total monetary turnovers 6.25 548 750
service: aggregate perf. data 0.93 15266 112
service: refresh index statistics 0.03 347264 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-10-2021 10:38:10 to 20-10-2021 10:38:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7118
TEST_TIME 2 6128
TEST_TIME 3 5764
TEST_TIME 4 6154
TEST_TIME 5 6686
TEST_TIME 6 6404
TEST_TIME 7 6336
TEST_TIME 8 6097
TEST_TIME 9 6006
TEST_TIME 10 6426
TEST_TIME 11 6917
TEST_TIME 12 6979
TEST_TIME 13 7403
TEST_TIME 14 7625
TEST_TIME 15 7924
TEST_TIME 16 8544
TEST_TIME 17 8467
TEST_TIME 18 8448
TEST_TIME 19 8779
TEST_TIME 20 8823
TEST_TIME 21 8880
TEST_TIME 22 8928
TEST_TIME 23 8852
TEST_TIME 24 8979
TEST_TIME 25 9558
TEST_TIME 26 9797
TEST_TIME 27 10025
TEST_TIME 28 10424
TEST_TIME 29 11518
TEST_TIME 30 11181
TEST_TIME 31 11274
TEST_TIME 32 11189
TEST_TIME 33 11061
TEST_TIME 34 10947
TEST_TIME 35 10857
TEST_TIME 36 10852
TEST_TIME 37 10696
TEST_TIME 38 10590
TEST_TIME 39 10627
TEST_TIME 40 10457
TEST_TIME 41 10276
TEST_TIME 42 10201
TEST_TIME 43 10224
TEST_TIME 44 10105
TEST_TIME 45 9958
TEST_TIME 46 9844
TEST_TIME 47 9706
TEST_TIME 48 9826
TEST_TIME 49 9993
TEST_TIME 50 9865
TEST_TIME 51 9759
TEST_TIME 52 9632
TEST_TIME 53 9499
TEST_TIME 54 9528
TEST_TIME 55 9461
TEST_TIME 56 9465
TEST_TIME 57 9445
TEST_TIME 58 9362
TEST_TIME 59 9416
TEST_TIME 60 9502
TEST_TIME 61 9435
TEST_TIME 62 9479
TEST_TIME 63 9587
TEST_TIME 64 9596
TEST_TIME 65 9545
TEST_TIME 66 9545
TEST_TIME 67 9600
TEST_TIME 68 9536
TEST_TIME 69 9587
TEST_TIME 70 9572
TEST_TIME 71 9462
TEST_TIME 72 9348
TEST_TIME 73 9382
TEST_TIME 74 9306
TEST_TIME 75 9243
TEST_TIME 76 9201
TEST_TIME 77 9174
TEST_TIME 78 9191
TEST_TIME 79 9226
TEST_TIME 80 9261
TEST_TIME 81 9246
TEST_TIME 82 9251
TEST_TIME 83 9270
TEST_TIME 84 9306
TEST_TIME 85 9264
TEST_TIME 86 9316
TEST_TIME 87 9329
TEST_TIME 88 9338
TEST_TIME 89 9264
TEST_TIME 90 9190
TEST_TIME 91 9131
TEST_TIME 92 9108
TEST_TIME 93 9122
TEST_TIME 94 9047
TEST_TIME 95 9031
TEST_TIME 96 9082
TEST_TIME 97 9063
TEST_TIME 98 9072
TEST_TIME 99 9101
TEST_TIME 100 9112
TEST_TIME 101 9116
TEST_TIME 102 9126
TEST_TIME 103 9104
TEST_TIME 104 9132
TEST_TIME 105 9163
TEST_TIME 106 9186
TEST_TIME 107 9199
TEST_TIME 108 9224
TEST_TIME 109 9142
TEST_TIME 110 9097
TEST_TIME 111 9081
TEST_TIME 112 9057
TEST_TIME 113 9034
TEST_TIME 114 9035
TEST_TIME 115 8977
TEST_TIME 116 8955
TEST_TIME 117 8953
TEST_TIME 118 8940
TEST_TIME 119 8973
TEST_TIME 120 8903

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-10-2021 10:38:14 to 20-10-2021 10:38:17.

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 1485556 1485556 0 0.00 0 25996 7 0 0
doc_list_biud 1485555 1485555 0 0.00 0 38671 31 0 0
sp_add_invoice_to_stock 4980 4862 118 2.36 30 177482 20155 0 118
sp_cancel_adding_invoice 5442 5442 0 0.00 6 78878 958 0 0
sp_cancel_client_order 1767 1767 0 0.00 4 28530 355 0 0
sp_cancel_customer_reserve 534620 534620 0 0.00 1 19545 68 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 3 4224 87 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 966 64 0 0
sp_cancel_supplier_invoice 6015 6004 11 0.18 2 19702 946 0 11
sp_cancel_supplier_order 418 418 0 0.00 7 1129442 108525 0 0
sp_cancel_write_off 9866 9866 0 0.00 1 18383 120 0 0
sp_client_order 20354 20354 0 0.00 4 47434 446 0 0
sp_customer_reserve 448959 448959 0 0.00 0 57103 199 0 0
sp_fill_shopping_cart 31187 31187 0 0.00 1 14211 45 0 0
sp_fill_shopping_cart_clo_res 448956 448956 0 0.00 0 16067 15 0 0
sp_get_clo_for_invoice 4857 4857 0 0.00 0 32327 360 0 0
sp_kill_cost_storno 16446 16446 0 0.00 0 17252 57 0 0
sp_kill_qstorno_ret_qs2qd 1085528 1085528 0 0.00 0 19688 37 0 0
sp_kill_qty_storno 572854 572854 0 0.00 0 38670 80 0 0
sp_lock_selected_doc 20486 20486 0 0.00 0 10583 6 0 0
sp_make_cost_storno 27513 27513 0 0.00 0 20462 77 0 0
sp_multiply_rows_for_pdistr 27513 27513 0 0.00 0 9717 33 0 0
sp_multiply_rows_for_qdistr 20354 20354 0 0.00 0 47412 230 0 0
sp_pay_from_customer 7084 7084 0 0.00 4 15832 145 0 0
sp_pay_to_supplier 5692 5692 0 0.00 5 18435 153 0 0
sp_payment_common 12776 12776 0 0.00 1 12317 33 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 28511 328 0 0
sp_qd_handle_on_invoice_upd_sts 10299 10299 0 0.00 0 38670 399 0 0
sp_qd_handle_on_reserve_upd_sts 9880 9880 0 0.00 0 17850 72 0 0
sp_reserve_write_off 9880 9880 0 0.00 7 26016 230 0 0
sp_supplier_invoice 5379 5379 0 0.00 8 79719 3657 0 0
sp_supplier_order 5466 5466 0 0.00 6 46953 1503 0 0
srv_aggregate_perf_data 112 112 0 0.00 2915 170473 15267 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1452 141066 43480 0 0
srv_find_qd_qs_mism 1022255 1022255 0 0.00 0 24058 6 0 0
srv_make_invnt_saldo 438 438 0 0.00 51 91410 3988 0 0
srv_make_money_saldo 750 750 0 0.00 1 14675 549 0 0
srv_recalc_idx_stat 4 4 0 0.00 218321 457540 347264 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4862 4862 0 0.00 0 18393 187 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 9452 117 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 3156 17 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 1704 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 859 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 451 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 450 12 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 22382 1522 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 4067 11 0 0
t$perf-norm:sp_client_order 20354 20354 0 0.00 0 10490 19 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 429 4 0 0
t$perf-norm:sp_pay_from_customer 7084 7084 0 0.00 0 7747 12 0 0
t$perf-norm:sp_pay_to_supplier 5692 5692 0 0.00 0 5484 9 0 0
t$perf-norm:sp_reserve_write_off 9880 9880 0 0.00 0 13557 13 0 0
t$perf-norm:sp_supplier_invoice 5379 5379 0 0.00 0 8488 22 0 0
t$perf-norm:sp_supplier_order 5466 5466 0 0.00 0 7936 22 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 485 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 135 3 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 289 6 0 0
t$perf-norm:srv_make_money_saldo 750 750 0 0.00 0 2909 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9950 9714 236 2.37 0 6624 18 0 236
v_all_customers 37158 37158 0 0.00 0 15 1 0 0
v_all_suppliers 30010 30010 0 0.00 0 17 1 0 0
v_all_wares 40706 40706 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 1604 10 0 0
v_cancel_client_order 3534 3534 0 0.00 0 2475 5 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 3638 12 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 1219 7 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 730 4 0 0
v_cancel_supplier_invoice 746 724 22 2.94 0 5478 24 0 22
v_cancel_supplier_order 836 836 0 0.00 0 1457 6 0 0
v_cancel_write_off 1966 1966 0 0.00 0 1805 15 0 0
v_max_id_clo_ord 5464 5464 0 0.00 0 5142 2 0 0
v_max_id_clo_res 995 995 0 0.00 0 1686 12 0 0
v_max_id_ord_sup 5370 5370 0 0.00 0 7880 5 0 0
v_max_non_paid_invoice 1521 1521 0 0.00 0 368 3 0 0
v_max_non_paid_realizn 3759 3759 0 0.00 0 9243 8 0 0
v_min_id_clo_ord 5464 5464 0 0.00 0 6157 7 0 0
v_min_id_clo_res 994 994 0 0.00 0 1816 7 0 0
v_min_id_ord_sup 5370 5370 0 0.00 0 6189 8 0 0
v_min_non_paid_invoice 5692 5692 0 0.00 0 7477 21 0 0
v_min_non_paid_realizn 7084 7084 0 0.00 0 7609 24 0 0
v_our_firm 10198 10198 0 0.00 0 20 1 0 0
v_reserve_write_off 19760 19760 0 0.00 0 4023 6 0 0
x_lock_depdocs_on_canc_invoice 11084 11084 0 0.00 0 14509 119 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 16562 385 0 0
x_make_qstorno_1000_1200 5466 5466 0 0.00 2 46946 1414 0 0
x_make_qstorno_1200_2000 5379 5379 0 0.00 3 79264 3491 0 0
x_make_qstorno_2100_3300 448959 448959 0 0.00 0 57101 182 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 20-10-2021 10:38:18 to 20-10-2021 10:38:23.

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
08:07:13 6 8192000000 8803136816 8816287744 7955744 0.097 3 1 2 0 8153216 110064 87408
08:08:14 39971 8192000000 9718009184 10017234944 792585360 8.821 100 79 158 0 819475488 7693136 19196992
08:09:57 31161 8192000000 9760229744 10067943424 832943296 9.229 100 91 182 1 869580976 14916560 21721120
08:11:35 17351 8192000000 9818159808 10135855104 863566240 9.536 100 84 167 1 914566352 31830112 19170000
08:13:06 13967 8192000000 9891740672 10226356224 925538400 10.151 100 85 169 0 1001968816 58087296 18343120
08:14:22 6928 8192000000 9942534128 10288386048 958895600 10.478 100 87 174 0 1060624816 83401808 18327408
08:15:33 4287 8192000000 9968645024 10320547840 989518320 10.777 100 88 176 0 1083549312 76716384 17314608
08:16:38 3821 8192000000 10001530400 10363658240 1025916384 11.129 100 77 154 1 1116863984 75216112 15731488
08:17:43 2173 8192000000 10046137200 10415620096 1059766768 11.454 100 91 182 0 1152934528 73885472 19282288
08:18:46 22705 8192000000 9999377984 10379309056 1071289152 11.564 100 62 124 1 1111618000 26728736 13600112
08:20:09 4427 8192000000 9991936704 10388844544 1075486752 11.604 100 38 74 2 1108155456 23956736 8711968
08:21:17 4151 8192000000 10017872144 10412982272 1092575728 11.767 100 66 133 4 1135059184 28734208 13749248
08:22:24 3071 8192000000 10023269920 10419765248 1097431936 11.813 100 55 112 0 1136753552 27331712 11989904
08:23:27 40394 8192000000 10016172112 10408488960 1101399520 11.851 100 57 116 2 1129138304 13537744 14201040
08:25:20 3530 8192000000 10020657680 10422738944 1102194160 11.858 100 53 106 0 1137508704 24278416 11036128
08:26:28 7286 8192000000 10004484736 10406658048 1076184176 11.611 98 43 85 2 1106197136 20498720 9514240
08:27:36 9513 8192000000 9992283248 10402349056 1079390448 11.642 99 24 47 0 1108686608 24240848 5055312
08:28:45 72156 8192000000 9940628448 10330742784 909307568 9.990 86 18 36 0 930905936 17648752 3949616
08:30:58 8462 8192000000 9920873568 10308190208 987918880 10.761 96 31 62 0 1006726368 12012608 6794880
08:32:07 9965 8192000000 9932651936 10317115392 1015258592 11.026 99 45 90 3 1050129184 24342800 10527792
08:33:23 6398 8192000000 9961178976 10350252032 1045659616 11.319 100 38 75 1 1079691472 25470560 8561296
08:34:33 43587 8192000000 9950844976 10354466816 1050533040 11.366 100 28 56 0 1079527424 23012880 5981504
08:36:17 7677 8192000000 9993963040 10391535616 1065832736 11.512 100 43 86 0 1100345376 25766160 8746480
08:37:32 6102 8192000000 10019083712 10408681472 1088520208 11.729 100 54 107 0 1119481552 19482672 11478672
08:38:47 22089 8192000000 10067632400 10477715456 1100965856 11.847 100 61 122 0 1189718928 76087024 12666048
08:40:12 14320 8192000000 10059928192 10462371840 1111241120 11.944 100 46 92 1 1175623648 54937376 9445152
08:41:35 10504 8192000000 10108047600 10520854528 1120572576 12.032 100 53 106 3 1218440576 86833712 11034288
08:42:48 15565 8192000000 10109937328 10515517440 1136019792 12.178 100 50 99 0 1213129648 66504976 10604880
08:44:14 19303 8192000000 10138899408 10552983552 1145446672 12.267 100 50 100 1 1238908240 82917344 10544224
08:45:43 13565 8192000000 10166010448 10574585856 1146988432 12.281 99 51 101 1 1266050400 107524688 11537280
08:47:00 5620 8192000000 10198906528 10608427008 1144108144 12.254 99 57 115 0 1301991808 144761376 13122288
08:48:12 2631 8192000000 10229337360 10647236608 1163391680 12.435 100 64 128 0 1345320688 167439056 14489952
08:49:21 2063 8192000000 10262168400 10663784448 1153569440 12.343 100 75 147 1 1368365200 198482688 16313072
08:50:28 1450 8192000000 10264458592 10659078144 1152814000 12.336 100 82 165 0 1361220096 189977264 18428832
08:51:35 1730 8192000000 10284521824 10680102912 1156804144 12.373 100 91 178 3 1379097856 202957984 19335728
08:52:41 3753 8192000000 10285843008 10687197184 1141189824 12.227 100 81 162 0 1393003584 233118672 18695088
08:53:51 11434 8192000000 10252401552 10676375552 1137326368 12.190 100 51 102 0 1358386272 208998704 12061200
08:55:04 4427 8192000000 10257903056 10660982784 1129370608 12.115 99 59 115 0 1352196624 209456464 13369552
08:56:18 8469 8192000000 10291164656 10744881152 1133953152 12.159 99 30 60 1 1387820112 246503424 7363536
08:57:28 6412 8192000000 10283688400 10691645440 1135431120 12.173 100 56 111 1 1381735584 232870720 13433744
08:58:40 10171 8192000000 10338687040 10760212480 1160203920 12.405 100 40 79 0 1439314032 269070208 10039904
09:00:24 6958 8192000000 10375346272 10795474944 1145966288 12.272 99 44 88 2 1470421920 312929680 11525952
09:01:41 7538 8192000000 10426321728 10845704192 1169784352 12.495 100 57 113 2 1520456112 336708608 13963152
09:03:06 10633 8192000000 10352568160 10777178112 1144215104 12.255 99 31 62 0 1452328832 299983344 8130384
09:04:48 25170 8192000000 10392267280 10836557824 1139230176 12.208 100 19 38 1 1474936512 330554720 5151616
09:07:25 41746 8192000000 10136102928 10564681728 1146648272 12.278 100 44 89 0 1209164304 52894672 9621360
09:09:13 71991 8192000000 10133707472 10601365504 1157230880 12.377 100 23 47 1 1230563152 68948288 4383984
09:11:36 141066 8192000000 10092130976 10563551232 1129037888 12.112 97 22 45 1 1175541152 42128880 4374384
09:15:23 113295 8192000000 10093099488 10574274560 1163208672 12.433 100 15 30 0 1219038128 53088656 2740800
09:18:55 92976 8192000000 10086330560 10561916928 1146777344 12.279 99 38 76 0 1185479680 30564880 8137456
09:21:30 37145 8192000000 10083582464 10533068800 1160295728 12.406 100 14 28 0 1181420336 18107424 3017184
09:23:08 26046 8192000000 10127373712 10553253888 1163952736 12.440 100 45 86 1 1215414592 42997344 8464512
09:24:35 12404 8192000000 10203373328 10642845696 1166347808 12.463 99 49 96 1 1281994080 104973840 10672432
09:25:49 55877 8192000000 10132383152 10706358272 1159158208 12.395 100 9 18 0 1255260976 94072464 2030304
09:27:49 40691 8192000000 10064472688 10518241280 1139898992 12.215 98 21 42 1 1172486864 27902880 4684992
09:29:30 106214 8192000000 10151059520 10616201216 1159771200 12.401 100 23 46 0 1248501808 83596768 5133840
09:32:54 54418 8192000000 10063280000 10525777920 1137511616 12.192 99 9 18 0 1165519744 25839136 2168992
09:34:57 46145 8192000000 10122918336 10570002432 1149287520 12.303 99 24 46 2 1193548512 38737168 5523824
09:36:55 43838 8192000000 10112012656 10576629760 1131753424 12.138 98 30 60 1 1203782032 65206096 6822512
09:38:40 43180 8192000000 10120005104 10588704768 1134751248 12.166 97 20 40 0 1209410368 69658144 5000976
09:40:34 63626 8192000000 10074121312 10538258432 1084307152 11.688 94 15 30 0 1131489504 44143168 3039184
09:42:38 19493 8192000000 10106885424 10531360768 1127592016 12.099 99 55 110 2 1190675328 52160928 10922384
09:43:58 19360 8192000000 10085113984 10518388736 1090785872 11.750 97 40 80 0 1165907808 67144704 7977232
09:45:19 22961 8192000000 10075297472 10497015808 1114711248 11.977 99 37 72 1 1150232272 27992192 7528832
09:46:52 94417 8192000000 10077054080 10522144768 1101740432 11.854 99 23 46 2 1152603776 46512784 4350560
09:50:24 65126 8192000000 10060356736 10520358912 1123519344 12.060 100 18 36 0 1182956672 55524800 3912528
09:52:30 74187 8192000000 10115161232 10563891200 1114667600 11.977 98 15 32 0 1180690240 62622816 3399824
09:54:44 81204 8192000000 10123991216 10626387968 1115995872 11.989 98 21 42 1 1238884752 118046272 4842608
09:57:06 52164 8192000000 10066878592 10531278848 1128787056 12.110 100 20 40 0 1182129664 48735008 4607600
09:58:59 52534 8192000000 10056827552 10495426560 1095115312 11.791 97 15 31 0 1130571888 32281392 3175184
10:00:59 48572 8192000000 10058703328 10528706560 1113462752 11.965 99 9 18 0 1154318256 39264736 1590768
10:02:54 43672 8192000000 10081347664 10578743296 1107519296 11.909 98 32 64 0 1171174608 57375264 6280048
10:04:44 77297 8192000000 10062348240 10530672640 1097615936 11.815 98 13 26 1 1134774848 34689776 2469136
10:07:06 46871 8192000000 10059638960 10493816832 1114863680 11.978 99 31 62 3 1148317968 26094016 7360272
10:08:54 60944 8192000000 10155629280 10621030400 1120071472 12.028 98 31 62 1 1231062576 104617440 6373664
10:10:56 96535 8192000000 10121395648 10629107712 1128745456 12.110 99 12 24 0 1214087936 82866288 2476192
10:13:33 79330 8192000000 10116322832 10570510336 1131631008 12.137 99 17 35 0 1172244512 37131760 3481744
10:15:58 46996 8192000000 10064785744 10496249856 1101593728 11.853 97 12 24 0 1160443120 56155872 2693520
10:17:46 53267 8192000000 10057983840 10509099008 1112432640 11.955 98 27 54 2 1158691120 40549040 5709440
10:19:40 74940 8192000000 10096776208 10554281984 1107529264 11.909 97 32 65 0 1162491360 47773856 7188240
10:21:55 86789 8192000000 10085282880 10574987264 1126450480 12.088 99 4 8 0 1162768800 35634224 684096
10:24:23 70583 8192000000 10089097376 10585391104 1111542192 11.947 98 11 22 0 1149336160 35763280 2030688
10:26:34 93062 8192000000 10075834224 10523619328 1121520752 12.041 98 26 52 0 1165731376 38930688 5279936
10:29:10 55498 8192000000 10060490256 10533355520 1118083888 12.009 99 10 20 0 1163844048 43509152 2251008
10:31:10 35000 8192000000 10071098560 10540224512 1134289888 12.162 100 18 34 1 1180916816 42934784 3692144
10:33:00 67753 8192000000 10050714112 10496606208 1077384416 11.623 96 9 18 0 1128718128 49449056 1884656
10:35:49 63497 8192000000 10040461920 10482954240 1073552816 11.586 96 11 22 0 1106468080 30638592 2276672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-10-2021 10:38:24 to 20-10-2021 10:38:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2021 10:38:40 to 20-10-2021 10:38:42.

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          234754
MON$OLDEST_ACTIVE               236120
MON$OLDEST_SNAPSHOT             236120
MON$NEXT_TRANSACTION            236120
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                       2048672
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             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:383: 
:384: mscs-test-161.budzdorov.ru    Wed Oct 20 10:37:12 2021
:385:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:386: 
:387: 
:388: mscs-test-161.budzdorov.ru    Wed Oct 20 10:37:13 2021
:389:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:390: 
:391: 
:392: mscs-test-161.budzdorov.ru    Wed Oct 20 10:37:16 2021
:393:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:394: 
--- end of diff output ---

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

20.10.21 10:38:43. 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-20 10:38:46 No severe PSQL-related problems occured
20.10.21 10:38:47. End of report.