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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16315792 kB
MemAvailable 31517996 kB
Buffers 20 kB
Cached 15345612 kB
SwapCached 3200 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.2
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 2022-04-29 04:37:16
DTS_END                         2022-04-29 04:37:16.4850
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34440
TRN_ID                          215626
ATT_ID                          634
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: 9137.52 23266 1096502
customer order: creation 167.53 485 20103
customer order: refuse 14.34 414 1721
order to supplier: creation 44.28 1317 5314
order to supplier: removal 3.28 182246 393
invoice (draft): creation 43.27 3022 5192
invoice (draft): removal 50.09 1000 6011
invoice accept: apply 41.20 28240 4944
invoice accept: cancel 45.47 1261 5456
customer reserve: creation 3916.42 264 469970
customer reserve: removal 4528.21 103 543385
realization accept: apply 79.77 302 9572
realization accept: cancel 78.22 170 9387
payment from customer: creation 57.06 176 6847
payment from customer: removal 5.28 115 633
payment to supplier: creation 47.29 188 5675
payment to supplier: removal 3.51 123 421
service: total inventory turnovers 4.02 4126 482
service: total monetary turnovers 7.34 377 881
service: aggregate perf. data 0.92 17848 110
service: refresh index statistics 0.04 223544 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-04-2022 04:38:47 to 29-04-2022 04:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8087
TEST_TIME 2 6076
TEST_TIME 3 5639
TEST_TIME 4 6240
TEST_TIME 5 6077
TEST_TIME 6 6045
TEST_TIME 7 6184
TEST_TIME 8 6304
TEST_TIME 9 6260
TEST_TIME 10 6270
TEST_TIME 11 6390
TEST_TIME 12 6944
TEST_TIME 13 7010
TEST_TIME 14 7195
TEST_TIME 15 7401
TEST_TIME 16 7694
TEST_TIME 17 7743
TEST_TIME 18 7762
TEST_TIME 19 7681
TEST_TIME 20 7945
TEST_TIME 21 7977
TEST_TIME 22 7889
TEST_TIME 23 7809
TEST_TIME 24 7996
TEST_TIME 25 8128
TEST_TIME 26 8615
TEST_TIME 27 8843
TEST_TIME 28 9037
TEST_TIME 29 9465
TEST_TIME 30 9921
TEST_TIME 31 10180
TEST_TIME 32 10665
TEST_TIME 33 10702
TEST_TIME 34 11273
TEST_TIME 35 11313
TEST_TIME 36 11207
TEST_TIME 37 11013
TEST_TIME 38 10969
TEST_TIME 39 11025
TEST_TIME 40 10955
TEST_TIME 41 10841
TEST_TIME 42 10714
TEST_TIME 43 10522
TEST_TIME 44 10439
TEST_TIME 45 10349
TEST_TIME 46 10191
TEST_TIME 47 10186
TEST_TIME 48 10156
TEST_TIME 49 10077
TEST_TIME 50 9999
TEST_TIME 51 10036
TEST_TIME 52 9942
TEST_TIME 53 9853
TEST_TIME 54 9708
TEST_TIME 55 9827
TEST_TIME 56 9930
TEST_TIME 57 9924
TEST_TIME 58 9850
TEST_TIME 59 9858
TEST_TIME 60 9927
TEST_TIME 61 9886
TEST_TIME 62 9852
TEST_TIME 63 9944
TEST_TIME 64 9910
TEST_TIME 65 9925
TEST_TIME 66 9945
TEST_TIME 67 9961
TEST_TIME 68 9980
TEST_TIME 69 9939
TEST_TIME 70 9895
TEST_TIME 71 9815
TEST_TIME 72 9798
TEST_TIME 73 9733
TEST_TIME 74 9702
TEST_TIME 75 9662
TEST_TIME 76 9622
TEST_TIME 77 9603
TEST_TIME 78 9533
TEST_TIME 79 9439
TEST_TIME 80 9516
TEST_TIME 81 9507
TEST_TIME 82 9569
TEST_TIME 83 9538
TEST_TIME 84 9534
TEST_TIME 85 9539
TEST_TIME 86 9530
TEST_TIME 87 9534
TEST_TIME 88 9569
TEST_TIME 89 9585
TEST_TIME 90 9571
TEST_TIME 91 9567
TEST_TIME 92 9557
TEST_TIME 93 9485
TEST_TIME 94 9449
TEST_TIME 95 9454
TEST_TIME 96 9420
TEST_TIME 97 9385
TEST_TIME 98 9322
TEST_TIME 99 9281
TEST_TIME 100 9294
TEST_TIME 101 9364
TEST_TIME 102 9315
TEST_TIME 103 9301
TEST_TIME 104 9274
TEST_TIME 105 9286
TEST_TIME 106 9292
TEST_TIME 107 9289
TEST_TIME 108 9331
TEST_TIME 109 9490
TEST_TIME 110 9471
TEST_TIME 111 9429
TEST_TIME 112 9397
TEST_TIME 113 9342
TEST_TIME 114 9328
TEST_TIME 115 9286
TEST_TIME 116 9262
TEST_TIME 117 9247
TEST_TIME 118 9211
TEST_TIME 119 9217
TEST_TIME 120 9160

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-04-2022 04:38:51 to 29-04-2022 04:38: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 1527620 1527620 0 0.00 0 42926 14 0 0
doc_list_biud 1527620 1527620 0 0.00 0 48387 42 0 0
sp_add_invoice_to_stock 5074 4944 130 2.56 43 228444 28241 0 130
sp_cancel_adding_invoice 5456 5456 0 0.00 7 82513 1261 0 0
sp_cancel_client_order 1721 1721 0 0.00 6 14763 414 0 0
sp_cancel_customer_reserve 543385 543385 0 0.00 1 48389 104 0 0
sp_cancel_pay_from_customer 633 633 0 0.00 4 4992 115 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 4 5076 124 0 0
sp_cancel_supplier_invoice 6017 6011 6 0.09 3 43332 1001 0 6
sp_cancel_supplier_order 393 393 0 0.00 29 1609802 182247 0 0
sp_cancel_write_off 9387 9387 0 0.00 2 12784 170 0 0
sp_client_order 20103 20103 0 0.00 6 28607 485 0 0
sp_customer_reserve 469970 469970 0 0.00 1 138824 265 0 0
sp_fill_shopping_cart 30609 30609 0 0.00 2 21702 81 0 0
sp_fill_shopping_cart_clo_res 469962 469962 0 0.00 0 13387 20 0 0
sp_get_clo_for_invoice 4900 4900 0 0.00 0 23820 274 0 0
sp_kill_cost_storno 15897 15897 0 0.00 0 6544 72 0 0
sp_kill_qstorno_ret_qs2qd 1102561 1102561 0 0.00 0 48384 45 0 0
sp_kill_qty_storno 580817 580817 0 0.00 0 48386 103 0 0
sp_lock_selected_doc 20037 20037 0 0.00 0 9911 8 0 0
sp_make_cost_storno 26986 26986 0 0.00 0 20175 84 0 0
sp_multiply_rows_for_pdistr 26986 26986 0 0.00 0 7383 38 0 0
sp_multiply_rows_for_qdistr 20103 20103 0 0.00 0 10947 191 0 0
sp_pay_from_customer 6847 6847 0 0.00 6 20183 176 0 0
sp_pay_to_supplier 5675 5675 0 0.00 7 13786 188 0 0
sp_payment_common 12522 12522 0 0.00 2 7366 44 0 0
sp_qd_handle_on_cancel_clo 1721 1721 0 0.00 0 14731 344 0 0
sp_qd_handle_on_invoice_upd_sts 10348 10348 0 0.00 0 15548 525 0 0
sp_qd_handle_on_reserve_upd_sts 9572 9572 0 0.00 0 25291 90 0 0
sp_reserve_write_off 9572 9572 0 0.00 9 25328 302 0 0
sp_supplier_invoice 5192 5192 0 0.00 9 84477 3023 0 0
sp_supplier_order 5314 5314 0 0.00 8 29133 1317 0 0
srv_aggregate_perf_data 110 110 0 0.00 2897 182481 17848 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 926 147548 37807 0 0
srv_find_qd_qs_mism 1047434 1047434 0 0.00 0 42925 11 0 0
srv_make_invnt_saldo 482 482 0 0.00 88 94449 4126 0 0
srv_make_money_saldo 881 881 0 0.00 1 21137 377 0 0
srv_recalc_idx_stat 5 5 0 0.00 100766 402893 223545 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4944 4944 0 0.00 1 20831 971 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 1 5641 468 0 0
t$perf-norm:sp_cancel_client_order 1721 1721 0 0.00 0 1439 28 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 3532 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 633 633 0 0.00 0 1053 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 2783 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 6901 48 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 84300 7619 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 3464 21 0 0
t$perf-norm:sp_client_order 20103 20103 0 0.00 0 14389 37 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 2743 18 0 0
t$perf-norm:sp_pay_from_customer 6847 6847 0 0.00 0 20531 21 0 0
t$perf-norm:sp_pay_to_supplier 5675 5675 0 0.00 0 7885 21 0 0
t$perf-norm:sp_reserve_write_off 9572 9572 0 0.00 0 14285 24 0 0
t$perf-norm:sp_supplier_invoice 5192 5192 0 0.00 0 6645 33 0 0
t$perf-norm:sp_supplier_order 5314 5314 0 0.00 0 4491 34 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 431 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 520 22 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 1753 20 0 0
t$perf-norm:srv_make_money_saldo 881 881 0 0.00 0 1826 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 192 113 0 0
v_add_invoice_to_stock 10044 9784 260 2.58 0 2377 24 0 260
v_all_customers 36950 36950 0 0.00 0 71 4 0 0
v_all_suppliers 29342 29342 0 0.00 0 60 4 0 0
v_all_wares 40206 40206 0 0.00 0 69 4 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 670 13 0 0
v_cancel_client_order 3442 3442 0 0.00 0 1049 9 0 0
v_cancel_customer_prepayment 1266 1266 0 0.00 0 2384 13 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 1937 10 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 1848 16 0 0
v_cancel_supplier_invoice 778 766 12 1.54 0 461 20 0 12
v_cancel_supplier_order 786 786 0 0.00 0 2376 14 0 0
v_cancel_write_off 1884 1884 0 0.00 0 1281 22 0 0
v_max_id_clo_ord 5314 5314 0 0.00 0 545 5 0 0
v_max_id_clo_res 967 967 0 0.00 0 669 10 0 0
v_max_id_ord_sup 5192 5192 0 0.00 0 4755 9 0 0
v_max_non_paid_invoice 1510 1510 0 0.00 0 201 7 0 0
v_max_non_paid_realizn 3496 3496 0 0.00 0 5889 8 0 0
v_min_id_clo_ord 5314 5314 0 0.00 0 1737 8 0 0
v_min_id_clo_res 967 967 0 0.00 0 2003 12 0 0
v_min_id_ord_sup 5192 5192 0 0.00 0 4601 10 0 0
v_min_non_paid_invoice 5675 5675 0 0.00 0 5959 16 0 0
v_min_non_paid_realizn 6847 6847 0 0.00 0 7362 16 0 0
v_our_firm 9958 9958 0 0.00 0 62 4 0 0
v_reserve_write_off 19144 19144 0 0.00 0 2374 9 0 0
x_lock_depdocs_on_canc_invoice 11084 11084 0 0.00 0 19166 144 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 10989 421 0 0
x_make_qstorno_1000_1200 5314 5314 0 0.00 3 29120 1139 0 0
x_make_qstorno_1200_2000 5192 5192 0 0.00 3 84439 2813 0 0
x_make_qstorno_2100_3300 469970 469970 0 0.00 0 138822 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-04-2022 04:38:55 to 29-04-2022 04:38: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
02:07:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:21 17110 8192000000 9721559280 10016395264 800946400 8.906 100 94 188 0 831020096 7607472 22466224
02:09:48 26091 8192000000 9763565120 10071351296 834167056 9.241 100 92 184 0 869204064 13327424 21709584
02:11:21 12612 8192000000 9800211472 10118950912 862893872 9.529 100 83 166 0 909913280 28208800 18810608
02:12:35 9255 8192000000 9866301712 10197786624 908455728 9.982 100 80 160 1 976388560 50626864 17305968
02:13:56 5576 8192000000 9932578416 10278109184 958501904 10.474 100 91 180 1 1042291088 64652976 19136208
02:15:05 2375 8192000000 9958819936 10313420800 990170800 10.783 100 85 165 0 1074238864 67240112 16827952
02:16:09 3305 8192000000 10011347264 10373058560 1013042400 11.005 100 85 167 0 1119362832 90592752 15727680
02:17:15 1318 8192000000 10003890944 10373795840 1037275536 11.238 100 96 179 0 1125496784 70080224 18141024
02:18:19 896 8192000000 10045682720 10422374400 1052002304 11.380 100 96 180 0 1165083344 94669424 18411616
02:19:22 1029 8192000000 10011065568 10386792448 1058152592 11.439 100 93 183 0 1135032688 59253584 17626512
02:20:24 1192 8192000000 10033232432 10413645824 1063664960 11.492 100 96 186 0 1160822608 78573072 18584576
02:21:28 1340 8192000000 10070996304 10449707008 1069293776 11.545 100 98 194 0 1189853744 101363600 19196368
02:22:31 924 8192000000 10043420560 10426224640 1073404592 11.585 100 92 176 1 1165229440 73247200 18577648
02:23:34 1226 8192000000 10055838416 10436984832 1072067904 11.572 100 96 188 0 1176234256 85363136 18803216
02:24:36 1107 8192000000 10040094272 10425360384 1074182704 11.592 100 88 170 2 1164056208 72264624 17608880
02:25:38 1434 8192000000 10075460032 10462826496 1079254704 11.640 100 98 192 2 1193738304 95756192 18727408
02:26:40 1500 8192000000 10085412880 10471190528 1076547184 11.615 100 99 183 0 1207113840 111188976 19377680
02:27:44 1236 8192000000 10087859904 10476576768 1083824176 11.684 100 97 190 1 1210394528 107662176 18908176
02:28:46 919 8192000000 10044306208 10436317184 1080282080 11.650 100 87 162 0 1166658832 69992144 16384608
02:29:49 1120 8192000000 10040575840 10423529472 1076419152 11.613 100 81 154 1 1161793344 70263264 15110928
02:30:52 879 8192000000 10060287568 10452213760 1089385312 11.737 100 95 183 1 1182660272 73436336 19838624
02:31:54 1387 8192000000 10020367424 10402152448 1070385376 11.556 100 92 182 3 1145097984 55987424 18725184
02:32:56 4751 8192000000 9988511120 10365902848 1043683936 11.300 99 74 133 1 1109514080 52497968 13332176
02:34:02 1283 8192000000 9984336672 10355765248 1033584768 11.203 99 88 166 2 1106050048 55080544 17384736
02:35:05 1267 8192000000 10017274192 10392817664 1049644912 11.357 100 98 186 2 1142437328 74543136 18249280
02:36:07 1460 8192000000 10002042816 10378825728 1048873856 11.350 100 97 177 4 1126572784 59237744 18461184
02:37:09 704 8192000000 9997940736 10371563520 1049540736 11.356 99 89 175 0 1122592288 55234912 17816640
02:38:20 6932 8192000000 10029376368 10404446208 1070018720 11.552 100 84 156 4 1145862192 60591152 15252320
02:39:39 14898 8192000000 10048607088 10438631424 1081888224 11.665 99 62 119 0 1162984816 69523056 11573536
02:41:01 6054 8192000000 10069264288 10471530496 1104644512 11.882 100 65 117 2 1189326000 72043024 12638464
02:42:14 1945 8192000000 10132540032 10524459008 1130870416 12.130 100 91 176 0 1250906304 100586496 19449392
02:43:22 2821 8192000000 10192581344 10581467136 1131907904 12.139 100 87 169 0 1310277680 161275744 17094032
02:44:41 1374 8192000000 10198054032 10593411072 1129821184 12.120 100 85 163 2 1312898400 164692768 18384448
02:45:45 1685 8192000000 10214273968 10605629440 1136013456 12.178 100 87 173 0 1334059888 179435616 18610816
02:46:50 922 8192000000 10247650800 10639687680 1140372688 12.219 100 89 166 0 1361326352 204025040 16928624
02:47:54 1764 8192000000 10302039088 10700840960 1154177616 12.349 100 92 175 1 1412486528 239301696 19007216
02:49:00 3514 8192000000 10277645440 10716016640 1151415744 12.323 100 75 147 0 1388948128 220516784 17015600
02:50:08 1149 8192000000 10310906752 10710286336 1162323920 12.425 99 96 186 1 1418282672 233921584 22037168
02:51:12 989 8192000000 10344116112 10759663616 1169392320 12.491 100 91 176 1 1455913232 265383904 21137008
02:52:17 3088 8192000000 10319840096 10739077120 1178510816 12.576 100 80 153 4 1432948864 235597136 18840912
02:53:26 2646 8192000000 10330611008 10761588736 1184683152 12.634 100 71 134 2 1441926976 240856096 16387728
02:54:37 1027 8192000000 10373141872 10787618816 1196313856 12.742 100 86 167 1 1487146448 270847536 19985056
02:55:42 3641 8192000000 10423150432 10831921152 1208787680 12.858 100 91 178 1 1526110640 296122752 21200208
02:56:51 1108 8192000000 10487382464 10905640960 1221156688 12.972 100 96 189 0 1592558912 348523344 22878880
02:57:54 1062 8192000000 10480721248 10915139584 1229850384 13.053 100 98 187 1 1591937472 338962896 23124192
02:58:59 1015 8192000000 10544432016 10956722176 1227374832 13.030 100 97 187 0 1646964064 396780528 22808704
03:00:03 1512 8192000000 10601165584 11023990784 1228971664 13.045 100 83 162 1 1709515072 461068880 19474528
03:01:09 1349 8192000000 10603772144 11060891648 1201586720 12.791 100 86 170 0 1708700336 485688800 21424816
03:02:13 4591 8192000000 10619919104 11070812160 1202961088 12.804 100 74 145 0 1720748080 498656080 19130912
03:03:20 2396 8192000000 10609557552 11103547392 1209167840 12.861 100 76 150 1 1713194096 484075744 19950512
03:04:37 5719 8192000000 10501997920 11021381632 1199172144 12.769 100 56 107 1 1620631152 407901440 13557568
03:05:48 3838 8192000000 10522247648 10999136256 1189373968 12.678 100 74 141 2 1625444592 419506384 16564240
03:07:00 12416 8192000000 10428887648 10944221184 1184216912 12.630 100 41 82 0 1543070496 348209552 10644032
03:08:13 17782 8192000000 10373316816 10876588032 1178433824 12.576 100 25 50 0 1479565392 294273088 6858480
03:09:34 48618 8192000000 10211592704 10698817536 1108651344 11.920 95 19 36 2 1273407184 161081152 3674688
03:11:23 78012 8192000000 10087081360 10559815680 1101951536 11.856 96 18 36 0 1168711744 62541840 4218368
03:13:47 41868 8192000000 10067985152 10503032832 1136528960 12.183 100 58 111 1 1165961472 16706480 12726032
03:15:29 92504 8192000000 10098477808 10602336256 1112945936 11.960 97 9 18 0 1187545232 72147120 2452176
03:18:16 28877 8192000000 10065513664 10515451904 1133909632 12.158 99 22 43 1 1167449424 28699488 4840304
03:19:45 72309 8192000000 10067514496 10512654336 1129880464 12.120 99 23 39 3 1183687600 49106880 4700256
03:21:59 125002 8192000000 10082649824 10502049792 1089589136 11.739 95 49 93 0 1142212416 43259648 9363632
03:25:05 46848 8192000000 10087990272 10520563712 1063157152 11.487 94 32 64 0 1158483696 88154272 7172272
03:27:00 15919 8192000000 10157576672 10592493568 1124436016 12.069 100 56 105 0 1237592848 100365920 12790912
03:28:25 147548 8192000000 10154941088 10617147392 1101863168 11.855 97 15 30 1 1228206160 122456960 3886032
03:31:54 48211 8192000000 10109940192 10640138240 1120754944 12.034 100 17 34 0 1258106624 133730592 3621088
03:35:16 22600 8192000000 10096082432 10539597824 1113785360 11.968 99 43 86 0 1180087072 57665840 8635872
03:36:46 66149 8192000000 10054299152 10519453696 1109903392 11.932 99 23 46 0 1194747264 80367920 4475952
03:39:04 36609 8192000000 10117088480 10566328320 1136845424 12.186 100 37 74 0 1202795280 58876208 7073648
03:41:00 67836 8192000000 10119634768 10577969152 1119508704 12.022 98 25 50 1 1228943568 103914512 5520352
03:43:08 84161 8192000000 10140133360 10600292352 1110552944 11.938 97 27 54 0 1165673360 50262096 4858320
03:45:42 48430 8192000000 10090808624 10532016128 1116307088 11.992 97 34 68 0 1154085328 30877136 6901104
03:47:32 117432 8192000000 10054454928 10532610048 1093151136 11.773 97 19 38 0 1174799440 77278080 4370224
03:50:41 45079 8192000000 10078226768 10513399808 1122749360 12.053 100 55 103 3 1186342640 53448000 10145280
03:52:26 40779 8192000000 10101456256 10540716032 1120168304 12.029 99 29 58 0 1168800128 42878464 5753360
03:54:07 58115 8192000000 10131268448 10592399360 1131393952 12.135 99 21 42 1 1218954144 83097072 4463120
03:57:12 72976 8192000000 10115881136 10646204416 1133594752 12.155 100 5 10 0 1217430896 82919552 916592
03:59:30 93306 8192000000 10047335520 10496880640 1072042704 11.572 95 20 40 0 1102170928 26340208 3788016
04:02:04 58631 8192000000 10092876224 10550824960 1084640896 11.692 97 36 70 0 1150049088 58099568 7308624
04:04:09 45994 8192000000 10119264064 10572165120 1128200528 12.104 100 36 70 2 1225187920 88844128 8143264
04:06:09 52441 8192000000 10083868416 10556424192 1120627456 12.033 99 21 42 0 1182988304 58121472 4239376
04:08:04 21884 8192000000 10072713216 10501165056 1124567968 12.070 99 56 112 2 1162555888 26906416 11081504
04:09:28 21150 8192000000 10144213760 10573488128 1141129968 12.226 100 49 97 1 1231946192 81425472 9390752
04:10:51 95226 8192000000 10074624416 10579496960 1146928288 12.281 100 23 46 0 1242898480 90835776 5134416
04:13:29 71807 8192000000 10077027504 10532368384 1119028704 12.018 98 14 28 1 1180118992 57859536 3230752
04:15:49 57002 8192000000 10114258512 10551115776 1074784992 11.598 94 16 32 0 1147815152 69505392 3524768
04:18:02 46639 8192000000 10076486816 10577412096 1126032560 12.084 100 21 42 0 1181282864 51646832 3603472
04:19:49 22605 8192000000 10139602576 10575355904 1144148864 12.255 100 51 98 1 1235584368 81061312 10374192
04:21:15 54195 8192000000 10213796096 10656333824 1144225248 12.255 99 39 77 0 1278990240 126314192 8450800
04:23:17 41301 8192000000 10190334464 10638462976 1137681856 12.194 99 31 63 2 1282000528 137698672 6620000
04:25:00 46681 8192000000 10222199920 10732691456 1122730640 12.053 99 33 63 1 1290908192 161350768 6826784
04:26:48 69444 8192000000 10052973776 10477383680 1098587216 11.824 97 26 49 1 1144957568 41245008 5125344
04:29:00 85076 8192000000 10040410288 10484269056 1107436016 11.908 98 30 60 1 1167076112 53285568 6354528
04:31:33 90956 8192000000 10091502304 10564427776 1134052784 12.160 100 14 28 0 1204477472 67425680 2999008
04:34:43 64023 8192000000 10103784576 10567581696 1132082128 12.141 99 26 49 0 1183410288 45530592 5797568
04:37:12 19167 8192000000 9001065984 9245659136 154992592 1.856 15 6 11 1 178038624 22331008 715024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-04-2022 04:39:01 to 29-04-2022 04:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-04-2022 04:39:18 to 29-04-2022 04:39:20.

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          215625
MON$OLDEST_ACTIVE               215959
MON$OLDEST_SNAPSHOT             215959
MON$NEXT_TRANSACTION            215959
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                       2063456
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:1527: 
:1528: mscs-test-161.budzdorov.ru    Fri Apr 29 04:37:33 2022
:1529:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1530: 
:1531: 
:1532: mscs-test-161.budzdorov.ru    Fri Apr 29 04:37:33 2022
:1533:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1534: 
:1535: 
:1536: mscs-test-161.budzdorov.ru    Fri Apr 29 04:37:34 2022
:1537:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1538: 
--- end of diff output ---

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

29.04.22 04:39:21. 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
2022-04-29 04:39:23 No severe PSQL-related problems occured
29.04.22 04:39:25. End of report.