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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16198576 kB
MemAvailable 31168604 kB
Buffers 0 kB
Cached 15552172 kB
SwapCached 2528 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-07-08 22:38:20
DTS_END                         2022-07-08 22:38:20.0580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55400
TRN_ID                          239454
ATT_ID                          707
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: 9339.43 19531 1120732
customer order: creation 173.46 358 20815
customer order: refuse 14.91 374 1789
order to supplier: creation 46.02 1216 5522
order to supplier: removal 3.40 95987 408
invoice (draft): creation 46.12 3312 5534
invoice (draft): removal 52.93 777 6352
invoice accept: apply 42.16 16541 5059
invoice accept: cancel 47.93 717 5751
customer reserve: creation 3898.18 163 467782
customer reserve: removal 4717.70 54 566124
realization accept: apply 83.08 156 9969
realization accept: cancel 84.84 101 10181
payment from customer: creation 58.36 107 7003
payment from customer: removal 5.94 56 713
payment to supplier: creation 48.73 126 5848
payment to supplier: removal 3.46 64 415
service: total inventory turnovers 5.02 967 602
service: total monetary turnovers 6.34 33 761
service: aggregate perf. data 0.83 22275 100
service: refresh index statistics 0.03 247235 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-07-2022 22:40:11 to 08-07-2022 22:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3238
TEST_TIME 2 4193
TEST_TIME 3 5468
TEST_TIME 4 5698
TEST_TIME 5 5609
TEST_TIME 6 5653
TEST_TIME 7 5330
TEST_TIME 8 5601
TEST_TIME 9 5437
TEST_TIME 10 5566
TEST_TIME 11 6026
TEST_TIME 12 6133
TEST_TIME 13 6659
TEST_TIME 14 6673
TEST_TIME 15 6853
TEST_TIME 16 6930
TEST_TIME 17 7056
TEST_TIME 18 7378
TEST_TIME 19 7680
TEST_TIME 20 7723
TEST_TIME 21 8194
TEST_TIME 22 8645
TEST_TIME 23 8930
TEST_TIME 24 9293
TEST_TIME 25 9330
TEST_TIME 26 9424
TEST_TIME 27 10381
TEST_TIME 28 10871
TEST_TIME 29 10616
TEST_TIME 30 10377
TEST_TIME 31 10312
TEST_TIME 32 10600
TEST_TIME 33 10511
TEST_TIME 34 10585
TEST_TIME 35 10380
TEST_TIME 36 10313
TEST_TIME 37 10143
TEST_TIME 38 10044
TEST_TIME 39 10130
TEST_TIME 40 9945
TEST_TIME 41 9919
TEST_TIME 42 9757
TEST_TIME 43 9698
TEST_TIME 44 9947
TEST_TIME 45 9769
TEST_TIME 46 9835
TEST_TIME 47 9738
TEST_TIME 48 9687
TEST_TIME 49 9652
TEST_TIME 50 9570
TEST_TIME 51 9421
TEST_TIME 52 9516
TEST_TIME 53 9525
TEST_TIME 54 9518
TEST_TIME 55 9533
TEST_TIME 56 9456
TEST_TIME 57 9532
TEST_TIME 58 9643
TEST_TIME 59 9587
TEST_TIME 60 9619
TEST_TIME 61 9746
TEST_TIME 62 9705
TEST_TIME 63 9704
TEST_TIME 64 9774
TEST_TIME 65 9793
TEST_TIME 66 9835
TEST_TIME 67 9822
TEST_TIME 68 9703
TEST_TIME 69 9665
TEST_TIME 70 9567
TEST_TIME 71 9564
TEST_TIME 72 9570
TEST_TIME 73 9541
TEST_TIME 74 9507
TEST_TIME 75 9456
TEST_TIME 76 9376
TEST_TIME 77 9426
TEST_TIME 78 9438
TEST_TIME 79 9403
TEST_TIME 80 9448
TEST_TIME 81 9482
TEST_TIME 82 9523
TEST_TIME 83 9524
TEST_TIME 84 9569
TEST_TIME 85 9542
TEST_TIME 86 9536
TEST_TIME 87 9568
TEST_TIME 88 9549
TEST_TIME 89 9678
TEST_TIME 90 9707
TEST_TIME 91 9634
TEST_TIME 92 9559
TEST_TIME 93 9517
TEST_TIME 94 9484
TEST_TIME 95 9512
TEST_TIME 96 9487
TEST_TIME 97 9437
TEST_TIME 98 9432
TEST_TIME 99 9412
TEST_TIME 100 9398
TEST_TIME 101 9419
TEST_TIME 102 9422
TEST_TIME 103 9411
TEST_TIME 104 9451
TEST_TIME 105 9450
TEST_TIME 106 9491
TEST_TIME 107 9502
TEST_TIME 108 9498
TEST_TIME 109 9483
TEST_TIME 110 9572
TEST_TIME 111 9565
TEST_TIME 112 9596
TEST_TIME 113 9525
TEST_TIME 114 9455
TEST_TIME 115 9442
TEST_TIME 116 9437
TEST_TIME 117 9396
TEST_TIME 118 9388
TEST_TIME 119 9380
TEST_TIME 120 9340

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-07-2022 22:40:15 to 08-07-2022 22:40:18.

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 1555147 1555147 0 0.00 0 18541 6 0 0
doc_list_biud 1555146 1555146 0 0.00 0 76849 25 0 0
sp_add_invoice_to_stock 5201 5059 142 2.73 38 151442 16542 0 142
sp_cancel_adding_invoice 5751 5751 0 0.00 3 81827 718 0 0
sp_cancel_client_order 1789 1789 0 0.00 4 18059 374 0 0
sp_cancel_customer_reserve 566124 566124 0 0.00 0 76851 54 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 2 5027 56 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 3424 65 0 0
sp_cancel_supplier_invoice 6359 6352 7 0.11 1 17451 777 0 7
sp_cancel_supplier_order 408 408 0 0.00 6 1085366 95987 0 0
sp_cancel_write_off 10181 10181 0 0.00 1 14453 102 0 0
sp_client_order 20815 20815 0 0.00 3 76233 358 0 0
sp_customer_reserve 467782 467782 0 0.00 0 135904 164 0 0
sp_fill_shopping_cart 31868 31868 0 0.00 1 15968 39 0 0
sp_fill_shopping_cart_clo_res 467778 467778 0 0.00 0 22565 15 0 0
sp_get_clo_for_invoice 5053 5053 0 0.00 0 22936 333 0 0
sp_kill_cost_storno 17060 17060 0 0.00 0 6959 48 0 0
sp_kill_qstorno_ret_qs2qd 1149189 1149189 0 0.00 0 76843 30 0 0
sp_kill_qty_storno 605624 605624 0 0.00 0 76849 64 0 0
sp_lock_selected_doc 20806 20806 0 0.00 0 7417 5 0 0
sp_make_cost_storno 27870 27870 0 0.00 0 18532 51 0 0
sp_multiply_rows_for_pdistr 27870 27870 0 0.00 0 9033 26 0 0
sp_multiply_rows_for_qdistr 20815 20815 0 0.00 0 76222 177 0 0
sp_pay_from_customer 7003 7003 0 0.00 3 17521 108 0 0
sp_pay_to_supplier 5848 5848 0 0.00 4 14191 127 0 0
sp_payment_common 12851 12851 0 0.00 1 14190 28 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 18052 352 0 0
sp_qd_handle_on_invoice_upd_sts 10801 10801 0 0.00 0 23688 293 0 0
sp_qd_handle_on_reserve_upd_sts 9969 9969 0 0.00 0 11747 55 0 0
sp_reserve_write_off 9969 9969 0 0.00 6 14314 157 0 0
sp_supplier_invoice 5534 5534 0 0.00 7 79751 3312 0 0
sp_supplier_order 5522 5522 0 0.00 5 66151 1216 0 0
srv_aggregate_perf_data 100 100 0 0.00 2184 251740 22275 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1465 160132 46450 0 0
srv_find_qd_qs_mism 1073399 1073399 0 0.00 0 16970 5 0 0
srv_make_invnt_saldo 602 602 0 0.00 38 52296 967 0 0
srv_make_money_saldo 761 761 0 0.00 1 7247 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 179750 300286 247235 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5059 5059 0 0.00 0 13447 162 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 1 6953 77 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 6357 15 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 2363 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 4032 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 216 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 1690 16 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 20377 1480 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 3216 7 0 0
t$perf-norm:sp_client_order 20815 20815 0 0.00 0 7672 17 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 900 5 0 0
t$perf-norm:sp_pay_from_customer 7003 7003 0 0.00 0 6293 12 0 0
t$perf-norm:sp_pay_to_supplier 5848 5848 0 0.00 0 7604 15 0 0
t$perf-norm:sp_reserve_write_off 9969 9969 0 0.00 0 13198 13 0 0
t$perf-norm:sp_supplier_invoice 5534 5534 0 0.00 0 5263 16 0 0
t$perf-norm:sp_supplier_order 5522 5522 0 0.00 0 4199 17 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 354 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 1000 19 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 2366 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10380 10096 284 2.73 0 14795 17 0 284
v_all_customers 37930 37930 0 0.00 0 23 0 0 0
v_all_suppliers 30520 30520 0 0.00 0 24 1 0 0
v_all_wares 41630 41630 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 1584 7 0 0
v_cancel_client_order 3578 3578 0 0.00 0 1973 4 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 2998 5 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 1047 4 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 3378 7 0 0
v_cancel_supplier_invoice 890 876 14 1.57 0 723 15 0 14
v_cancel_supplier_order 816 816 0 0.00 0 1316 6 0 0
v_cancel_write_off 2006 2006 0 0.00 0 3579 13 0 0
v_max_id_clo_ord 5522 5522 0 0.00 0 174 1 0 0
v_max_id_clo_res 956 956 0 0.00 0 7372 21 0 0
v_max_id_ord_sup 5531 5531 0 0.00 0 6073 11 0 0
v_max_non_paid_invoice 1641 1641 0 0.00 0 1639 5 0 0
v_max_non_paid_realizn 3679 3679 0 0.00 0 6861 8 0 0
v_min_id_clo_ord 5522 5522 0 0.00 0 1936 6 0 0
v_min_id_clo_res 956 956 0 0.00 0 4892 11 0 0
v_min_id_ord_sup 5531 5531 0 0.00 0 6152 7 0 0
v_min_non_paid_invoice 5848 5848 0 0.00 0 8460 18 0 0
v_min_non_paid_realizn 7003 7003 0 0.00 0 10986 20 0 0
v_our_firm 10348 10348 0 0.00 0 22 1 0 0
v_reserve_write_off 19938 19938 0 0.00 0 3804 5 0 0
x_lock_depdocs_on_canc_invoice 11665 11665 0 0.00 0 22291 106 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 3923 288 0 0
x_make_qstorno_1000_1200 5522 5522 0 0.00 1 66101 1149 0 0
x_make_qstorno_1200_2000 5534 5534 0 0.00 4 79742 3155 0 0
x_make_qstorno_2100_3300 467782 467782 0 0.00 0 135903 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-07-2022 22:40:19 to 08-07-2022 22:40: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
20:08:23 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:24 26252 8192000000 9910314688 10220732416 990536080 10.787 100 92 182 1 1021139616 6349824 24253712
20:10:56 8344 8192000000 9945535600 10267435008 1029203776 11.161 100 84 168 0 1059065056 8057472 21803808
20:12:10 12293 8192000000 9977700512 10308988928 1063080928 11.486 100 85 170 0 1093961360 9273216 21607216
20:13:27 8466 8192000000 10024261120 10367860736 1111307424 11.945 100 80 160 0 1141041488 10295248 19438816
20:14:39 5058 8192000000 10077301392 10432618496 1164493568 12.445 100 80 159 0 1194635616 11994800 18147248
20:15:49 4853 8192000000 10109324976 10474790912 1196058928 12.740 100 79 158 0 1227541968 13626160 17856880
20:16:55 2304 8192000000 10141651040 10515898368 1232314176 13.075 100 80 160 0 1262051232 12619488 17117568
20:18:00 6652 8192000000 10169563520 10548383744 1256229552 13.295 100 82 162 1 1287679184 14029040 17420592
20:19:09 1782 8192000000 10202974656 10590183424 1291478080 13.618 100 84 168 1 1324033792 13934016 18621696
20:20:12 23723 8192000000 10202596368 10599026688 1296738400 13.666 100 46 94 2 1316319344 8891056 10689888
20:21:37 2831 8192000000 10202620368 10609893376 1308544256 13.773 100 41 83 0 1325462336 7676512 9241568
20:22:42 11533 8192000000 10197521184 10617139200 1316226368 13.843 100 11 23 0 1324318080 5469776 2621936
20:23:55 32901 8192000000 10210698672 10630172672 1320307680 13.879 100 48 96 0 1338661680 7117504 11236496
20:25:32 7377 8192000000 10215379600 10637062144 1330628816 13.973 100 24 47 0 1342366512 5993520 5744176
20:26:40 9410 8192000000 10233149936 10653954048 1341629408 14.072 100 24 46 0 1352805664 5686256 5490000
20:27:51 66554 8192000000 10208719584 10631294976 1277350656 13.489 96 28 56 0 1289110576 5563376 6196544
20:29:58 7560 8192000000 10149421344 10556137472 1243416544 13.178 98 23 46 1 1254431776 5565392 5449840
20:31:14 10619 8192000000 10113811744 10508795904 1161497920 12.417 94 21 43 1 1171465312 5455648 4511744
20:32:27 52660 8192000000 10147755488 10553827328 1247055568 13.211 99 40 78 1 1261588912 5673376 8859968
20:34:25 8687 8192000000 10191728448 10593087488 1281977824 13.531 100 34 68 0 1297062768 6616496 8468448
20:35:35 7015 8192000000 10180259248 10596012032 1294550688 13.646 100 24 46 1 1304638032 5260960 4826384
20:36:44 13748 8192000000 10190289216 10609819648 1305326880 13.744 100 15 30 0 1313795536 5106368 3362288
20:37:58 45483 8192000000 10222587584 10633535488 1318859200 13.866 100 47 91 0 1334910032 5827344 10223488
20:39:47 9282 8192000000 10238730832 10654515200 1338830976 14.047 100 43 85 0 1356669936 8790928 9048032
20:41:01 29768 8192000000 10261522080 10682077184 1356826752 14.209 100 38 74 1 1375342336 9894640 8620944
20:42:36 12457 8192000000 10274191040 10699444224 1372203568 14.347 100 36 71 0 1390048912 9564208 8281136
20:44:02 2790 8192000000 10298825824 10716745728 1383673008 14.449 100 63 123 0 1411536944 13653312 14210624
20:45:14 12095 8192000000 10303263344 10727804928 1387994496 14.488 100 50 94 1 1414275488 14873648 11407344
20:46:29 13320 8192000000 10317936064 10744393728 1399429184 14.590 100 50 96 1 1433163184 22084432 11649568
20:47:48 21557 8192000000 10325695648 10753019904 1403309136 14.624 100 50 100 0 1440084672 25044528 11731008
20:49:16 8887 8192000000 10345764624 10776031232 1418378992 14.758 100 53 106 2 1458763120 26572320 13811808
20:50:28 1586 8192000000 10349124976 10762985472 1403659472 14.628 99 85 166 1 1450407040 27420128 19327440
20:51:35 1542 8192000000 10371632656 10791370752 1425632224 14.823 100 81 159 2 1479662368 34597152 19432992
20:52:44 1464 8192000000 10365876576 10776113152 1413634320 14.716 100 86 168 0 1465212912 30696000 20882592
20:53:52 2304 8192000000 10361749872 10774589440 1411410848 14.696 100 79 158 0 1466465888 35966512 19088528
20:55:00 11584 8192000000 10326744240 10734411776 1372791136 14.352 100 64 128 0 1428361440 39615168 15955136
20:56:18 7585 8192000000 10332344192 10748346368 1383835280 14.451 100 56 110 0 1440252368 41655248 14761840
20:57:33 8689 8192000000 10357302736 10778673152 1399033248 14.586 100 41 82 1 1463517088 52787088 11696752
20:58:53 3983 8192000000 10346768960 10764693504 1385835664 14.469 100 52 102 1 1449929408 50345424 13748320
21:00:11 9357 8192000000 10343775520 10762268672 1373212592 14.356 99 53 106 1 1434686528 47995968 13477968
21:01:27 22206 8192000000 10303219904 10744766464 1357950736 14.219 99 26 52 0 1409461632 43990352 7520544
21:03:12 21021 8192000000 10292757552 10728099840 1349435184 14.142 99 18 36 0 1383742656 28936848 5370624
21:04:35 31372 8192000000 10311335472 10753335296 1365234512 14.284 100 25 50 0 1416464640 43990160 7239968
21:06:56 21058 8192000000 10277716688 10719858688 1362257536 14.258 100 24 48 1 1375986688 8258240 5470912
21:08:25 9632 8192000000 10307063952 10731020288 1374183968 14.365 100 72 140 2 1398355344 7832288 16339088
21:09:38 87838 8192000000 10278449104 10727038976 1349918128 14.147 97 20 41 1 1364282688 10580192 3784368
21:12:08 60268 8192000000 10292734288 10752876544 1358458064 14.224 98 16 34 1 1376412912 14345312 3609536
21:14:16 21537 8192000000 10290495024 10744786944 1374086896 14.364 99 24 49 0 1396350000 17011648 5251456
21:15:45 29564 8192000000 10323130080 10764992512 1405992496 14.648 100 21 42 0 1425287120 14531312 4763312
21:17:18 66159 8192000000 10315605200 10777010176 1391689024 14.521 99 13 22 0 1412991408 18911216 2391168
21:19:24 124753 8192000000 10310037984 10770759680 1372249408 14.347 98 18 37 1 1402122000 24896736 4975856
21:22:32 160131 8192000000 10293388208 10759966720 1361575056 14.251 97 19 38 0 1374066112 8267232 4223824
21:26:14 43324 8192000000 10309148240 10773200896 1407240864 14.659 100 27 49 1 1421184208 8142560 5800784
21:28:00 64879 8192000000 10312022624 10776092672 1409240512 14.677 100 18 34 1 1431108336 18072896 3794928
21:30:21 80041 8192000000 10325912272 10804199424 1413580256 14.716 100 14 28 0 1433492736 16667600 3244880
21:32:42 77216 8192000000 10310910000 10774757376 1357153568 14.212 96 17 34 0 1372749392 11991104 3604720
21:35:32 76802 8192000000 10303177056 10765516800 1363014688 14.264 97 11 22 0 1380404016 14722656 2666672
21:37:53 33893 8192000000 10319203792 10766512128 1387747024 14.486 99 38 76 0 1409495248 13290624 8457600
21:39:32 67039 8192000000 10283005904 10732285952 1271622256 13.436 91 13 26 0 1291570016 17178416 2769344
21:41:43 70315 8192000000 10288400208 10745999360 1336187200 14.023 97 15 30 2 1353011936 13970480 2854256
21:43:54 68054 8192000000 10266711216 10721071104 1288739136 13.593 94 13 26 0 1312039936 20447440 2853360
21:46:02 102978 8192000000 10246634016 10691776512 1281361392 13.525 94 23 47 0 1293888672 7260640 5266640
21:48:49 16061 8192000000 10252494400 10674970624 1313710688 13.820 97 70 137 0 1339794128 10802976 15280464
21:50:06 47345 8192000000 10247742912 10701963264 1339373616 14.052 99 22 45 0 1353388096 8974896 5039584
21:51:58 43522 8192000000 10249340896 10703704064 1330565248 13.972 98 16 30 0 1342563856 9038048 2960560
21:53:45 46090 8192000000 10245242016 10697568256 1346932336 14.120 100 10 20 0 1359082064 9888672 2261056
21:55:32 53251 8192000000 10247035376 10712707072 1333261152 13.997 98 10 20 0 1347305104 11887024 2156928
21:57:26 38485 8192000000 10290995280 10740170752 1365661888 14.288 99 28 54 0 1382523088 10857728 6003472
21:59:28 110115 8192000000 10277472784 10746925056 1343530176 14.089 97 11 23 1 1361958224 15586736 2841312
22:02:23 59898 8192000000 10279993488 10730119168 1348549440 14.134 97 15 30 1 1363693536 12203152 2940944
22:04:30 78192 8192000000 10305032880 10764681216 1376965936 14.389 99 29 58 1 1399855664 16510816 6378912
22:06:51 66792 8192000000 10309392640 10779074560 1366103056 14.292 98 10 20 0 1398512992 30253392 2156544
22:08:59 91957 8192000000 10294608064 10744147968 1391986096 14.524 100 23 46 0 1404592640 7202528 5404016
22:11:36 45692 8192000000 10300469760 10751303680 1375083264 14.373 98 33 66 0 1395173824 11942288 8148272
22:13:23 58644 8192000000 10294445856 10764034048 1385462688 14.465 99 8 16 0 1399759120 12445024 1851408
22:15:33 36671 8192000000 10301088656 10752589824 1397361776 14.571 99 17 34 0 1408482016 7559088 3561152
22:17:10 94993 8192000000 10317426192 10785660928 1407371280 14.661 100 7 14 0 1417975408 9204416 1399712
22:19:45 21010 8192000000 10304742992 10753949696 1354169936 14.185 96 28 54 0 1366421920 6256112 5995872
22:21:10 79273 8192000000 10298954896 10757627904 1346424336 14.115 96 15 30 0 1360342128 10723280 3194512
22:23:37 74864 8192000000 10295727568 10755026944 1341762464 14.073 96 24 48 0 1361372768 14342016 5268288
22:25:52 82971 8192000000 10301143920 10749505536 1367154176 14.302 99 18 36 0 1381257600 9966112 4137312
22:28:15 18256 8192000000 10285617680 10729877504 1344057136 14.094 98 22 44 0 1363628336 14203552 5367648
22:29:38 87830 8192000000 10256778848 10716672000 1307215424 13.761 95 21 42 0 1328743056 16876048 4651584
22:33:07 55689 8192000000 10271423184 10731081728 1373762208 14.361 100 28 56 0 1392460112 12111376 6586528
22:35:03 56669 8192000000 10257763856 10720940032 1329261712 13.960 97 13 26 0 1348528640 16032768 3234160
22:37:02 64663 8192000000 10240892320 10694459392 1305904432 13.749 96 16 32 0 1322101792 12108960 4088400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-07-2022 22:40:25 to 08-07-2022 22:40:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-07-2022 22:40:41 to 08-07-2022 22:40:43.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          238686
MON$OLDEST_ACTIVE               239562
MON$OLDEST_SNAPSHOT             239562
MON$NEXT_TRANSACTION            239562
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-03 16:41:55.3470
MON$PAGES                       2024256
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:146: 
:147: mscs-test-161.budzdorov.ru    Fri Jul  8 22:38:35 2022
:148:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:149: 
:150: 
:151: mscs-test-161.budzdorov.ru    Fri Jul  8 22:38:37 2022
:152:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:153: 
--- end of diff output ---

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

08.07.22 22:40:44. There are 3 logs in /var/tmp/logs-oltp30 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-07-08 22:40:46 No severe PSQL-related problems occured
08.07.22 22:40:47. End of report.